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/04/22 04:09:10 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_21) - Build #
2758 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/2758/
Java: 32bit/jdk1.7.0_21 -server -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest.testStatistics
Error Message:
IOException occured when talking to server at: https://127.0.0.1:58748/solr
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:58748/solr
at __randomizedtesting.SeedInfo.seed([6E2C2EACA27364BD:99851DC80E5EBE55]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java: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:826)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:601)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:722)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
... 46 more
Build Log:
[...truncated 10185 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest
[junit4:junit4] 1> 48365 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.SolrJettyTestBase ? Randomized ssl (true) and clientAuth (false)
[junit4:junit4] 1> 48366 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ####initCore
[junit4:junit4] 1> 48368 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ####initCore end
[junit4:junit4] 1> 48369 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.eclipse.jetty.server.Server ? jetty-8.1.8.v20121106
[junit4:junit4] 1> 48381 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-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> 48387 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.eclipse.jetty.server.AbstractConnector ? Started SslSelectChannelConnector@127.0.0.1:58748
[junit4:junit4] 1> 48388 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? SolrDispatchFilter.init()
[junit4:junit4] 1> 48388 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.SolrResourceLoader ? JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 1> 48389 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.SolrResourceLoader ? using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr
[junit4:junit4] 1> 48389 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.CoreContainer ? looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\solr.xml
[junit4:junit4] 1> 48391 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.CoreContainer ? New CoreContainer 18776035
[junit4:junit4] 1> 48392 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.CoreContainer ? Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\'
[junit4:junit4] 1> 48393 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.SolrResourceLoader ? new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\'
[junit4:junit4] 1> 48633 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting socketTimeout to: 0
[junit4:junit4] 1> 48633 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting urlScheme to: http://
[junit4:junit4] 1> 48634 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting connTimeout to: 0
[junit4:junit4] 1> 48636 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maxConnectionsPerHost to: 20
[junit4:junit4] 1> 48636 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting corePoolSize to: 0
[junit4:junit4] 1> 48637 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maximumPoolSize to: 2147483647
[junit4:junit4] 1> 48637 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maxThreadIdleTime to: 5
[junit4:junit4] 1> 48637 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting sizeOfQueue to: -1
[junit4:junit4] 1> 48638 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting fairnessPolicy to: false
[junit4:junit4] 1> 48639 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-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> 48643 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.CoreContainer ? Registering Log Listener
[junit4:junit4] 1> 48651 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.CoreContainer ? Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1
[junit4:junit4] 1> 48652 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrResourceLoader ? new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\'
[junit4:junit4] 1> 48692 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrConfig ? Adding specified lib dirs to ClassLoader
[junit4:junit4] 1> 48693 [coreLoadExecutor-71-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\contrib\extraction\lib).
[junit4:junit4] 1> 48696 [coreLoadExecutor-71-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4:junit4] 1> 48697 [coreLoadExecutor-71-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\contrib\clustering\lib).
[junit4:junit4] 1> 48697 [coreLoadExecutor-71-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4:junit4] 1> 48699 [coreLoadExecutor-71-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\contrib\langid\lib).
[junit4:junit4] 1> 48700 [coreLoadExecutor-71-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4:junit4] 1> 48701 [coreLoadExecutor-71-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\contrib\velocity\lib).
[junit4:junit4] 1> 48701 [coreLoadExecutor-71-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4:junit4] 1> 48702 [coreLoadExecutor-71-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\non\existent\dir\yields\warning).
[junit4:junit4] 1> 48763 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrConfig ? Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 1> 48877 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.Config ? Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 1> 48878 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.schema.IndexSchema ? Reading Solr Schema from schema.xml
[junit4:junit4] 1> 48901 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.schema.IndexSchema ? Schema name=example
[junit4:junit4] 1> 49892 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.schema.IndexSchema ? unique key field: id
[junit4:junit4] 1> 49918 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider ? Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 49928 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider ? Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 49973 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 49974 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\
[junit4:junit4] 1> 49974 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.JmxMonitoredMap ? JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@d46725
[junit4:junit4] 1> 49976 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4] 1> 49976 [coreLoadExecutor-71-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> 49976 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 49977 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory ? return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\
[junit4:junit4] 1> 49978 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\index/
[junit4:junit4] 1> 49979 [coreLoadExecutor-71-thread-1] WARN org.apache.solr.core.SolrCore ? [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\index' doesn't exist. Creating new index...
[junit4:junit4] 1> 49979 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory ? return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\index
[junit4:junit4] 1> 49981 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 1> 49983 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? newest commit = 1[segments_1]
[junit4:junit4] 1> 49985 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? created json: solr.JSONResponseWriter
[junit4:junit4] 1> 49985 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4] 1> 49985 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? created velocity: solr.VelocityResponseWriter
[junit4:junit4] 1> 49987 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? created xslt: solr.XSLTResponseWriter
[junit4:junit4] 1> 49987 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.response.XSLTResponseWriter ? xsltCacheLifetimeSeconds=5
[junit4:junit4] 1> 49990 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /select: solr.SearchHandler
[junit4:junit4] 1> 49992 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /query: solr.SearchHandler
[junit4:junit4] 1> 49993 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /get: solr.RealTimeGetHandler
[junit4:junit4] 1> 49994 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /browse: solr.SearchHandler
[junit4:junit4] 1> 49994 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update: solr.UpdateRequestHandler
[junit4:junit4] 1> 49995 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4] 1> 49996 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/csv: solr.CSVRequestHandler
[junit4:junit4] 1> 49996 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 49997 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 49997 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 49997 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 49998 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 49999 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 50000 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /admin/: solr.admin.AdminHandlers
[junit4:junit4] 1> 50001 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /admin/ping: solr.PingRequestHandler
[junit4:junit4] 1> 50002 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 1> 50004 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /replication: solr.ReplicationHandler
[junit4:junit4] 1> 50004 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 50005 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /spell: solr.SearchHandler
[junit4:junit4] 1> 50005 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 50005 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /tvrh: solr.SearchHandler
[junit4:junit4] 1> 50006 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 50007 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /terms: solr.SearchHandler
[junit4:junit4] 1> 50007 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 50008 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /elevate: solr.SearchHandler
[junit4:junit4] 1> 50021 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 50024 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 50027 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 50040 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@526fc5 main
[junit4:junit4] 1> 50040 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.update.UpdateHandler ? Clearing tlog files, tlogDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\tlog
[junit4:junit4] 1> 50042 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? Hard AutoCommit: if uncommited for 15000ms;
[junit4:junit4] 1> 50042 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.SolrCore ? Soft AutoCommit: disabled
[junit4:junit4] 1> 50043 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Initializing spell checkers
[junit4:junit4] 1> 50044 [coreLoadExecutor-71-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> 50044 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? No queryConverter defined, using default converter
[junit4:junit4] 1> 50046 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.handler.component.QueryElevationComponent ? Loading QueryElevation from: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\conf\elevate.xml
[junit4:junit4] 1> 50079 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.handler.ReplicationHandler ? Commits will be reserved for 10000
[junit4:junit4] 1> 50087 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@526fc5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 50089 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=null path=null params={event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1
[junit4:junit4] 1> 50089 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 50089 [searcherExecutor-72-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Loading spell index for spellchecker: default
[junit4:junit4] 1> 50090 [searcherExecutor-72-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Loading spell index for spellchecker: wordbreak
[junit4:junit4] 1> 50092 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@526fc5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 50096 [coreLoadExecutor-71-thread-1] INFO org.apache.solr.core.CoreContainer ? registering core: collection1
[junit4:junit4] 1> 50099 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4] 1> 50100 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? SolrDispatchFilter.init() done
[junit4:junit4] 1> 50101 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.SolrJettyTestBase ? Jetty Assigned Port#58748
[junit4:junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-SolrExampleStreamingTest-1366596175387
[junit4:junit4] 1> 50118 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testWaitOptions
[junit4:junit4] 1> 50118 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 50127 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? blocking for commit/optimize
[junit4:junit4] 1> 50195 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 1> 50196 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? newest commit = 1[segments_1]
[junit4:junit4] 1> 50199 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 50202 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, segments_2, _0.fdx, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4] 1> 50203 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? newest commit = 2[_0.fnm, _0_Lucene41_0.doc, segments_2, _0.fdx, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4] 1> 50207 [qtp30596145-103] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1aa936 main
[junit4:junit4] 1> 50208 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1aa936 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4] 1> 50208 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 50208 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 50212 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1aa936 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4] 1> 50213 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[42 (1432979953117298688)],commit=} 0 21
[junit4:junit4] 1> 50214 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? blocking for commit/optimize
[junit4:junit4] 1> 50215 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1091424
[junit4:junit4] 1> 50224 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 50228 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, segments_2, _0.fdx, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_3,generation=3,filenames=[_1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4] 1> 50228 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? newest commit = 3[_1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4] 1> 50231 [qtp30596145-104] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1fc98a8 main
[junit4:junit4] 1> 50231 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 50231 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1fc98a8 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4:junit4] 1> 50232 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 50234 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1fc98a8 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4:junit4] 1> 50492 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 50493 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_3,generation=3,filenames=[_1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_4,generation=4,filenames=[_1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0.fdx, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt, segments_4]
[junit4:junit4] 1> 50496 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? newest commit = 4[_1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0.fdx, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt, segments_4]
[junit4:junit4] 1> 50497 [qtp30596145-104] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1b9c888 main
[junit4:junit4] 1> 50497 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 50498 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=false&commit=true&wt=javabin&version=2&softCommit=false} {add=[43 (1432979953147707392)],commit=,commit=} 0 277
[junit4:junit4] 1> 50497 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1b9c888 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4:junit4] 1> 50499 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 50500 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 43 is 200
[junit4:junit4] 1> 50501 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1091424
[junit4:junit4] 1> 50503 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1b9c888 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4:junit4] 1> 50508 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 50512 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_4,generation=4,filenames=[_1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0.fdx, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt, segments_4]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_5,generation=5,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2_Lucene41_0.tip, _2.fdx, _1.fnm, _2.fnm, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0.fdx, segments_5, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4] 1> 50512 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? newest commit = 5[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2_Lucene41_0.tip, _2.fdx, _1.fnm, _2.fnm, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0.fdx, segments_5, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4] 1> 50514 [qtp30596145-105] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1f81429 main
[junit4:junit4] 1> 50514 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 50514 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1f81429 main{StandardDirectoryReader(segments_5:7:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4:junit4] 1> 50515 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 50517 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1f81429 main{StandardDirectoryReader(segments_5:7:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4:junit4] 1> 50518 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[44 (1432979953446551552)],commit=} 0 12
[junit4:junit4] 1> 50519 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? blocking for commit/optimize
[junit4:junit4] 1> 50519 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1f397ef
[junit4:junit4] 1> 50525 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 50528 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_5,generation=5,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2_Lucene41_0.tip, _2.fdx, _1.fnm, _2.fnm, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0.fdx, segments_5, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_6,generation=6,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fdt, _3.fnm, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _3_Lucene41_0.doc, _1.fdt, _0_Lucene41_0.doc, _1.si, _3.si, _0.fdx, segments_6, _0.fdt]
[junit4:junit4] 1> 50530 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? newest commit = 6[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fdt, _3.fnm, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _3_Lucene41_0.doc, _1.fdt, _0_Lucene41_0.doc, _1.si, _3.si, _0.fdx, segments_6, _0.fdt]
[junit4:junit4] 1> 50530 [qtp30596145-107] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7e5a67 main
[junit4:junit4] 1> 50532 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 50532 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7e5a67 main{StandardDirectoryReader(segments_6:9:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4:junit4] 1> 50533 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 50535 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7e5a67 main{StandardDirectoryReader(segments_6:9:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4:junit4] 1> 50794 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 50796 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_6,generation=6,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fdt, _3.fnm, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _3_Lucene41_0.doc, _1.fdt, _0_Lucene41_0.doc, _1.si, _3.si, _0.fdx, segments_6, _0.fdt]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_7,generation=7,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fdt, _3.fnm, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, segments_7, _3_Lucene41_0.doc, _1.fdt, _0_Lucene41_0.doc, _1.si, _3.si, _0.fdx, _0.fdt]
[junit4:junit4] 1> 50796 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? newest commit = 7[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fdt, _3.fnm, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, segments_7, _3_Lucene41_0.doc, _1.fdt, _0_Lucene41_0.doc, _1.si, _3.si, _0.fdx, _0.fdt]
[junit4:junit4] 1> 50797 [qtp30596145-107] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1da44f5 main
[junit4:junit4] 1> 50797 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 50798 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=false&commit=true&wt=javabin&version=2&softCommit=false} {add=[45 (1432979953465425920)],commit=,commit=} 0 276
[junit4:junit4] 1> 50797 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1da44f5 main{StandardDirectoryReader(segments_6:9:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4:junit4] 1> 50799 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 50799 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 45 is 200
[junit4:junit4] 1> 50800 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1f397ef
[junit4:junit4] 1> 50801 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1da44f5 main{StandardDirectoryReader(segments_6:9:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4:junit4] 1> 50807 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 50816 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_7,generation=7,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fdt, _3.fnm, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, segments_7, _3_Lucene41_0.doc, _1.fdt, _0_Lucene41_0.doc, _1.si, _3.si, _0.fdx, _0.fdt]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_8,generation=8,filenames=[_5.si, _5.fdt, _5.fnm, _5_Lucene41_0.tim, segments_8, _5_Lucene41_0.tip, _5_Lucene41_0.doc, _5.fdx]
[junit4:junit4] 1> 50817 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? newest commit = 8[_5.si, _5.fdt, _5.fnm, _5_Lucene41_0.tim, segments_8, _5_Lucene41_0.tip, _5_Lucene41_0.doc, _5.fdx]
[junit4:junit4] 1> 50819 [qtp30596145-108] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1e8cca4 main
[junit4:junit4] 1> 50821 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1e8cca4 main{StandardDirectoryReader(segments_8:13:nrt _5(5.0):C5)}
[junit4:junit4] 1> 50821 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 50821 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 50823 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1e8cca4 main{StandardDirectoryReader(segments_8:13:nrt _5(5.0):C5)}
[junit4:junit4] 1> 50879 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {add=[46 (1432979953760075776)],optimize=} 0 74
[junit4:junit4] 1> 50881 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? blocking for commit/optimize
[junit4:junit4] 1> 50881 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@d33a88
[junit4:junit4] 1> 50887 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 50901 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_8,generation=8,filenames=[_5.si, _5.fdt, _5.fnm, _5_Lucene41_0.tim, segments_8, _5_Lucene41_0.tip, _5_Lucene41_0.doc, _5.fdx]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_9,generation=9,filenames=[segments_9, _7.fnm, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc]
[junit4:junit4] 1> 50901 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? newest commit = 9[segments_9, _7.fnm, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc]
[junit4:junit4] 1> 50902 [qtp30596145-103] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@b00a25 main
[junit4:junit4] 1> 50904 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 50904 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@b00a25 main{StandardDirectoryReader(segments_9:17:nrt _7(5.0):C6)}
[junit4:junit4] 1> 50905 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 50907 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@b00a25 main{StandardDirectoryReader(segments_9:17:nrt _7(5.0):C6)}
[junit4:junit4] 1> 51158 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 51159 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_9,generation=9,filenames=[segments_9, _7.fnm, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_a,generation=10,filenames=[_7.fnm, segments_a, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc]
[junit4:junit4] 1> 51160 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? newest commit = 10[_7.fnm, segments_a, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc]
[junit4:junit4] 1> 51161 [qtp30596145-103] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@15a015d main
[junit4:junit4] 1> 51162 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 51162 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@15a015d main{StandardDirectoryReader(segments_9:17:nrt _7(5.0):C6)}
[junit4:junit4] 1> 51163 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 51163 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=false&maxSegments=1&wt=javabin&version=2} {add=[47 (1432979953845010432)],optimize=,optimize=} 0 278
[junit4:junit4] 1> 51165 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@15a015d main{StandardDirectoryReader(segments_9:17:nrt _7(5.0):C6)}
[junit4:junit4] 1> 51165 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 47 is 200
[junit4:junit4] 1> 51166 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@d33a88
[junit4:junit4] 1> 51173 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 51179 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_a,generation=10,filenames=[_7.fnm, segments_a, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_b,generation=11,filenames=[_9_Lucene41_0.doc, segments_b, _9.fnm, _9.fdt, _9.si, _9_Lucene41_0.tip, _9.fdx, _9_Lucene41_0.tim]
[junit4:junit4] 1> 51179 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? newest commit = 11[_9_Lucene41_0.doc, segments_b, _9.fnm, _9.fdt, _9.si, _9_Lucene41_0.tip, _9.fdx, _9_Lucene41_0.tim]
[junit4:junit4] 1> 51181 [qtp30596145-105] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1acd1f6 main
[junit4:junit4] 1> 51182 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 51183 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1acd1f6 main{StandardDirectoryReader(segments_b:21:nrt _9(5.0):C7)}
[junit4:junit4] 1> 51183 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 51185 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1acd1f6 main{StandardDirectoryReader(segments_b:21:nrt _9(5.0):C7)}
[junit4:junit4] 1> 51186 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {add=[48 (1432979954143854592)],optimize=} 0 15
[junit4:junit4] 1> 51197 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@10c9136
[junit4:junit4] 1> 51204 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 51212 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_b,generation=11,filenames=[_9_Lucene41_0.doc, segments_b, _9.fnm, _9.fdt, _9.si, _9_Lucene41_0.tip, _9.fdx, _9_Lucene41_0.tim]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_c,generation=12,filenames=[segments_c, _b.si, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx]
[junit4:junit4] 1> 51212 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? newest commit = 12[segments_c, _b.si, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx]
[junit4:junit4] 1> 51214 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5e27fa main
[junit4:junit4] 1> 51215 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5e27fa main{StandardDirectoryReader(segments_c:25:nrt _b(5.0):C8)}
[junit4:junit4] 1> 51215 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 51215 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 51217 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5e27fa main{StandardDirectoryReader(segments_c:25:nrt _b(5.0):C8)}
[junit4:junit4] 1> 51472 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 51473 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_c,generation=12,filenames=[segments_c, _b.si, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_d,generation=13,filenames=[_b.si, segments_d, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx]
[junit4:junit4] 1> 51474 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? newest commit = 13[_b.si, segments_d, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx]
[junit4:junit4] 1> 51474 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@101e528 main
[junit4:junit4] 1> 51475 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 51475 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@101e528 main{StandardDirectoryReader(segments_c:25:nrt _b(5.0):C8)}
[junit4:junit4] 1> 51476 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 51476 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=false&maxSegments=1&wt=javabin&version=2} {add=[49 (1432979954175311872)],optimize=,optimize=} 0 275
[junit4:junit4] 1> 51477 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 49 is 200
[junit4:junit4] 1> 51479 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@101e528 main{StandardDirectoryReader(segments_c:25:nrt _b(5.0):C8)}
[junit4:junit4] 1> 51479 [concurrentUpdateScheduler-4-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@10c9136
[junit4:junit4] 1> 51483 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 51483 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_d,generation=13,filenames=[_b.si, segments_d, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_e,generation=14,filenames=[segments_e, _b.si, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx]
[junit4:junit4] 1> 51484 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? newest commit = 14[segments_e, _b.si, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx]
[junit4:junit4] 1> 51485 [qtp30596145-107] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@130dba main
[junit4:junit4] 1> 51485 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 51485 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@130dba main{StandardDirectoryReader(segments_c:25:nrt _b(5.0):C8)}
[junit4:junit4] 1> 51488 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 51489 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@130dba main{StandardDirectoryReader(segments_c:25:nrt _b(5.0):C8)}
[junit4:junit4] 1> 51490 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4] 1> 51492 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testWaitOptions
[junit4:junit4] 1> 51508 [TEST-SolrExampleStreamingTest.testSetNullUpdateOrder-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testSetNullUpdateOrder
[junit4:junit4] 1> 51508 [TEST-SolrExampleStreamingTest.testSetNullUpdateOrder-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 51511 [concurrentUpdateScheduler-5-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1c27e11
[junit4:junit4] 1> 51833 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdateOrder (1432979954555944960)]} 0 270
[junit4:junit4] 1> 51834 [concurrentUpdateScheduler-5-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: testSetNullUpdateOrder is 200
[junit4:junit4] 1> 51835 [concurrentUpdateScheduler-5-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1c27e11
[junit4:junit4] 1> 51841 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 51844 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_e,generation=14,filenames=[segments_e, _b.si, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_f,generation=15,filenames=[_c.fdt, segments_f, _c.si, _b.si, _c_Lucene41_0.doc, _c_Lucene41_0.tim, _b_Lucene41_0.tip, _c_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _c.fnm, _c.fdx, _b.fdx]
[junit4:junit4] 1> 51846 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? newest commit = 15[_c.fdt, segments_f, _c.si, _b.si, _c_Lucene41_0.doc, _c_Lucene41_0.tim, _b_Lucene41_0.tip, _c_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _c.fnm, _c.fdx, _b.fdx]
[junit4:junit4] 1> 51847 [qtp30596145-107] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1d7bb6f main
[junit4:junit4] 1> 51848 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 51848 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1d7bb6f main{StandardDirectoryReader(segments_f:27:nrt _b(5.0):C8 _c(5.0):C1)}
[junit4:junit4] 1> 51849 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 51850 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1d7bb6f main{StandardDirectoryReader(segments_f:27:nrt _b(5.0):C8 _c(5.0):C1)}
[junit4:junit4] 1> 51852 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 12
[junit4:junit4] 1> 51853 [concurrentUpdateScheduler-5-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@52b2e7
[junit4:junit4] 1> 52128 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdateOrder (1432979954867372032)]} 0 272
[junit4:junit4] 1> 52130 [concurrentUpdateScheduler-5-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: testSetNullUpdateOrder is 200
[junit4:junit4] 1> 52130 [concurrentUpdateScheduler-5-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@52b2e7
[junit4:junit4] 1> 52134 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 52138 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_f,generation=15,filenames=[_c.fdt, segments_f, _c.si, _b.si, _c_Lucene41_0.doc, _c_Lucene41_0.tim, _b_Lucene41_0.tip, _c_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _c.fnm, _c.fdx, _b.fdx]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_g,generation=16,filenames=[_b_Lucene41_0.tip, _d.fnm, _b_Lucene41_0.tim, segments_g, _d.fdx, _d.fdt, _b.si, _b.fnm, _d_Lucene41_0.tim, _d.si, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _b_Lucene41_0.doc, _b.fdt, _b.fdx]
[junit4:junit4] 1> 52140 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? newest commit = 16[_b_Lucene41_0.tip, _d.fnm, _b_Lucene41_0.tim, segments_g, _d.fdx, _d.fdt, _b.si, _b.fnm, _d_Lucene41_0.tim, _d.si, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _b_Lucene41_0.doc, _b.fdt, _b.fdx]
[junit4:junit4] 1> 52141 [qtp30596145-103] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@e6f61c main
[junit4:junit4] 1> 52143 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 52143 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@e6f61c main{StandardDirectoryReader(segments_g:31:nrt _b(5.0):C8 _d(5.0):C1)}
[junit4:junit4] 1> 52143 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 52146 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@e6f61c main{StandardDirectoryReader(segments_g:31:nrt _b(5.0):C8 _d(5.0):C1)}
[junit4:junit4] 1> 52147 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 13
[junit4:junit4] 1> 52156 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&wt=xml&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 52159 [TEST-SolrExampleStreamingTest.testSetNullUpdateOrder-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testSetNullUpdateOrder
[junit4:junit4] 1> 52170 [TEST-SolrExampleStreamingTest.testStatistics-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testStatistics
[junit4:junit4] 1> 52176 [qtp30596145-105] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7494d6 realtime
[junit4:junit4] 1> 52177 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432979955195576320)} 0 3
[junit4:junit4] 1> 52181 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 52181 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_g,generation=16,filenames=[_b_Lucene41_0.tip, _d.fnm, _b_Lucene41_0.tim, segments_g, _d.fdx, _d.fdt, _b.si, _b.fnm, _d_Lucene41_0.tim, _d.si, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _b_Lucene41_0.doc, _b.fdt, _b.fdx]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_h,generation=17,filenames=[segments_h]
[junit4:junit4] 1> 52182 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? newest commit = 17[segments_h]
[junit4:junit4] 1> 52182 [qtp30596145-107] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1777863 main
[junit4:junit4] 1> 52185 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 52185 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1777863 main{StandardDirectoryReader(segments_g:33:nrt)}
[junit4:junit4] 1> 52185 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 52187 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1777863 main{StandardDirectoryReader(segments_g:33:nrt)}
[junit4:junit4] 1> 52189 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 9
[junit4:junit4] 1> 52193 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 52195 [concurrentUpdateScheduler-5-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@19206a1
[junit4:junit4] 1> 52196 [concurrentUpdateScheduler-5-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7e9976
[junit4:junit4] 1> 52201 [concurrentUpdateScheduler-5-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1ac05da
[junit4:junit4] 1> 52205 [concurrentUpdateScheduler-5-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1ac05da
[junit4:junit4] 1> 52214 [concurrentUpdateScheduler-5-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@56c543
[junit4:junit4] 1> 52214 [concurrentUpdateScheduler-5-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@56c543
[junit4:junit4] 1> 52218 [concurrentUpdateScheduler-5-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@b0be30
[junit4:junit4] 1> 52218 [concurrentUpdateScheduler-5-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@b0be30
[junit4:junit4] 1> 52205 [concurrentUpdateScheduler-5-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@13b00b2
[junit4:junit4] 1> 52227 [concurrentUpdateScheduler-5-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@13b00b2
[junit4:junit4] 1> 52202 [concurrentUpdateScheduler-5-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@15aac89
[junit4:junit4] 1> 52228 [concurrentUpdateScheduler-5-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@15aac89
[junit4:junit4] 1> 52232 [concurrentUpdateScheduler-5-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7bed26
[junit4:junit4] 1> 52232 [concurrentUpdateScheduler-5-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7bed26
[junit4:junit4] 1> 52494 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc0 (1432979955232276480), doc2 (1432979955235422208), doc3 (1432979955236470784), doc4 (1432979955238567936), doc5 (1432979955239616512), doc6 (1432979955252199424), doc7 (1432979955254296576), doc8 (1432979955255345152), doc9 (1432979955255345153)]} 0 285
[junit4:junit4] 1> 52495 [concurrentUpdateScheduler-5-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: doc0 is 200
[junit4:junit4] 1> 52496 [concurrentUpdateScheduler-5-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@19206a1
[junit4:junit4] 1> 52497 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc1 (1432979955259539456)]} 0 263
[junit4:junit4] 1> 52498 [concurrentUpdateScheduler-5-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: doc1 is 200
[junit4:junit4] 1> 52499 [concurrentUpdateScheduler-5-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7e9976
[junit4:junit4] 1> 52505 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 52509 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_h,generation=17,filenames=[segments_h]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_i,generation=18,filenames=[_e.nvd, _e_Lucene41_0.doc, _e.si, _e.fdt, _e.nvm, _e.fdx, _e_Lucene41_0.tim, segments_i, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4] 1> 52509 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? newest commit = 18[_e.nvd, _e_Lucene41_0.doc, _e.si, _e.fdt, _e.nvm, _e.fdx, _e_Lucene41_0.tim, segments_i, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4] 1> 52518 [qtp30596145-105] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1357b1b main
[junit4:junit4] 1> 52519 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1357b1b main{StandardDirectoryReader(segments_i:35:nrt _e(5.0):C10)}
[junit4:junit4] 1> 52519 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 52520 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 52521 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1357b1b main{StandardDirectoryReader(segments_i:35:nrt _e(5.0):C10)}
[junit4:junit4] 1> 52522 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 17
[junit4:junit4] 1> 52528 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=10 status=0 QTime=0
[junit4:junit4] 1> 52538 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=xml&version=2.2&rows=0} hits=10 status=0 QTime=1
[junit4:junit4] 1> 52551 [qtp30596145-108] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@12158ce realtime
[junit4:junit4] 1> 52553 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432979955586695168)} 0 6
[junit4:junit4] 1> 67681 [commitScheduler-74-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> 67681 [commitScheduler-74-thread-1] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_i,generation=18,filenames=[_e.nvd, _e_Lucene41_0.doc, _e.si, _e.fdt, _e.nvm, _e.fdx, _e_Lucene41_0.tim, segments_i, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_j,generation=19,filenames=[segments_j]
[junit4:junit4] 1> 67682 [commitScheduler-74-thread-1] INFO org.apache.solr.core.SolrCore ? newest commit = 19[segments_j]
[junit4:junit4] 1> 67684 [commitScheduler-74-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 252591 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 252591 [TEST-SolrExampleStreamingTest.testStatistics-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testStatistics
[junit4:junit4] 1> 252592 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_j,generation=19,filenames=[segments_j]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_k,generation=20,filenames=[segments_k]
[junit4:junit4] 1> 252593 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? newest commit = 20[segments_k]
[junit4:junit4] 1> 252594 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@438789 main
[junit4:junit4] 1> 252595 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 252596 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@438789 main{StandardDirectoryReader(segments_i:37:nrt)}
[junit4:junit4] 1> 252597 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 252599 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@438789 main{StandardDirectoryReader(segments_i:37:nrt)}
[junit4:junit4] 1> 252600 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 9
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleStreamingTest -Dtests.method=testStatistics -Dtests.seed=6E2C2EACA27364BD -Dtests.slow=true -Dtests.locale=da_DK -Dtests.timezone=Africa/Banjul -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR 200s | SolrExampleStreamingTest.testStatistics <<<
[junit4:junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:58748/solr
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([6E2C2EACA27364BD:99851DC80E5EBE55]:0)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java: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:826)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:722)
[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> 252627 [TEST-SolrExampleStreamingTest.testUnicode-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUnicode
[junit4:junit4] 1> 252638 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980165395218432)} 0 2
[junit4:junit4] 1> 252648 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@bdad46
[junit4:junit4] 1> 252902 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1432980165414092800), 1 (1432980165415141376), 2 (1432980165419335680), 3 (1432980165420384256), 4 (1432980165421432832)]} 0 249
[junit4:junit4] 1> 252905 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 0 is 200
[junit4:junit4] 1> 252906 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@bdad46
[junit4:junit4] 1> 252910 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 252914 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_k,generation=20,filenames=[segments_k]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_l,generation=21,filenames=[_f_Lucene41_0.tip, _f.fdx, _f_Lucene41_0.doc, _f.fnm, _f_Lucene41_0.tim, segments_l, _f.si, _f.fdt]
[junit4:junit4] 1> 252915 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? newest commit = 21[_f_Lucene41_0.tip, _f.fdx, _f_Lucene41_0.doc, _f.fnm, _f_Lucene41_0.tim, segments_l, _f.si, _f.fdt]
[junit4:junit4] 1> 252917 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@b36b89 main
[junit4:junit4] 1> 252918 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@b36b89 main{StandardDirectoryReader(segments_l:39:nrt _f(5.0):C5)}
[junit4:junit4] 1> 252918 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 252918 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 252920 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@b36b89 main{StandardDirectoryReader(segments_l:39:nrt _f(5.0):C5)}
[junit4:junit4] 1> 252921 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 252926 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=5} hits=5 status=0 QTime=0
[junit4:junit4] 1> 252936 [qtp30596145-108] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@78f749 realtime
[junit4:junit4] 1> 252937 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980165707694080)} 0 3
[junit4:junit4] 1> 252964 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1b31662
[junit4:junit4] 1> 253219 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1432980165746491392)]} 0 250
[junit4:junit4] 1> 253222 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 0 is 200
[junit4:junit4] 1> 253222 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1b31662
[junit4:junit4] 1> 253226 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 253229 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_l,generation=21,filenames=[_f_Lucene41_0.tip, _f.fdx, _f_Lucene41_0.doc, _f.fnm, _f_Lucene41_0.tim, segments_l, _f.si, _f.fdt]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_m,generation=22,filenames=[_g.fdt, _g.si, _g_Lucene41_0.tip, _g.fnm, _g.fdx, _g_Lucene41_0.doc, _g_Lucene41_0.tim, segments_m]
[junit4:junit4] 1> 253229 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? newest commit = 22[_g.fdt, _g.si, _g_Lucene41_0.tip, _g.fnm, _g.fdx, _g_Lucene41_0.doc, _g_Lucene41_0.tim, segments_m]
[junit4:junit4] 1> 253231 [qtp30596145-104] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3af355 main
[junit4:junit4] 1> 253232 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3af355 main{StandardDirectoryReader(segments_m:43:nrt _g(5.0):C1)}
[junit4:junit4] 1> 253232 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 253233 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 253234 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3af355 main{StandardDirectoryReader(segments_m:43:nrt _g(5.0):C1)}
[junit4:junit4] 1> 253236 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4] 1> 253241 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=1} hits=1 status=0 QTime=0
[junit4:junit4] 1> 253248 [qtp30596145-103] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@762258 realtime
[junit4:junit4] 1> 253249 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980166034849792)} 0 3
[junit4:junit4] 1> 253251 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1c8eaba
[junit4:junit4] 1> 253503 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1432980166043238400), 1 (1432980166045335552), 2 (1432980166045335553), 3 (1432980166046384128)]} 0 249
[junit4:junit4] 1> 253505 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 0 is 200
[junit4:junit4] 1> 253506 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1c8eaba
[junit4:junit4] 1> 253510 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 253513 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_m,generation=22,filenames=[_g.fdt, _g.si, _g_Lucene41_0.tip, _g.fnm, _g.fdx, _g_Lucene41_0.doc, _g_Lucene41_0.tim, segments_m]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_n,generation=23,filenames=[_h_Lucene41_0.tip, _h.fnm, _h.fdt, _h_Lucene41_0.tim, _h.si, _h.fdx, _h_Lucene41_0.doc, segments_n]
[junit4:junit4] 1> 253514 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? newest commit = 23[_h_Lucene41_0.tip, _h.fnm, _h.fdt, _h_Lucene41_0.tim, _h.si, _h.fdx, _h_Lucene41_0.doc, segments_n]
[junit4:junit4] 1> 253515 [qtp30596145-105] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2b2cf2 main
[junit4:junit4] 1> 253516 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2b2cf2 main{StandardDirectoryReader(segments_n:47:nrt _h(5.0):C4)}
[junit4:junit4] 1> 253516 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 253517 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 253519 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2b2cf2 main{StandardDirectoryReader(segments_n:47:nrt _h(5.0):C4)}
[junit4:junit4] 1> 253520 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4] 1> 253525 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=4} hits=4 status=0 QTime=0
[junit4:junit4] 1> 253541 [qtp30596145-104] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@184251a realtime
[junit4:junit4] 1> 253542 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980166343131136)} 0 3
[junit4:junit4] 1> 253543 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@9ffad9
[junit4:junit4] 1> 253798 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1432980166351519744), 1 (1432980166352568320), 2 (1432980166352568321), 3 (1432980166353616896), 4 (1432980166354665472)]} 0 250
[junit4:junit4] 1> 253800 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 0 is 200
[junit4:junit4] 1> 253800 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@9ffad9
[junit4:junit4] 1> 253804 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 253810 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_n,generation=23,filenames=[_h_Lucene41_0.tip, _h.fnm, _h.fdt, _h_Lucene41_0.tim, _h.si, _h.fdx, _h_Lucene41_0.doc, segments_n]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_o,generation=24,filenames=[_i_Lucene41_0.doc, _i_Lucene41_0.tim, segments_o, _i_Lucene41_0.tip, _i.fdx, _i.fnm, _i.si, _i.fdt]
[junit4:junit4] 1> 253811 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? newest commit = 24[_i_Lucene41_0.doc, _i_Lucene41_0.tim, segments_o, _i_Lucene41_0.tip, _i.fdx, _i.fnm, _i.si, _i.fdt]
[junit4:junit4] 1> 253813 [qtp30596145-108] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1f14163 main
[junit4:junit4] 1> 253814 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1f14163 main{StandardDirectoryReader(segments_o:51:nrt _i(5.0):C5)}
[junit4:junit4] 1> 253814 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 253815 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 253817 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1f14163 main{StandardDirectoryReader(segments_o:51:nrt _i(5.0):C5)}
[junit4:junit4] 1> 253818 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 14
[junit4:junit4] 1> 253823 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=5} hits=5 status=0 QTime=1
[junit4:junit4] 1> 253827 [TEST-SolrExampleStreamingTest.testUnicode-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUnicode
[junit4:junit4] 1> 253841 [TEST-SolrExampleStreamingTest.testAddDelete-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAddDelete
[junit4:junit4] 1> 253848 [qtp30596145-107] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5a89d2 realtime
[junit4:junit4] 1> 253850 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980166663995392)} 0 5
[junit4:junit4] 1> 253852 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@e54d8
[junit4:junit4] 1> 254105 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1432980166674481152)]} 0 250
[junit4:junit4] 1> 254106 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 0 & 222 is 200
[junit4:junit4] 1> 254107 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@e54d8
[junit4:junit4] 1> 254112 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 254116 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_o,generation=24,filenames=[_i_Lucene41_0.doc, _i_Lucene41_0.tim, segments_o, _i_Lucene41_0.tip, _i.fdx, _i.fnm, _i.si, _i.fdt]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_p,generation=25,filenames=[_j.fdx, _j.si, _j.fdt, _j_Lucene41_0.tip, _j.fnm, segments_p, _j_Lucene41_0.doc, _j_Lucene41_0.tim]
[junit4:junit4] 1> 254117 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? newest commit = 25[_j.fdx, _j.si, _j.fdt, _j_Lucene41_0.tip, _j.fnm, segments_p, _j_Lucene41_0.doc, _j_Lucene41_0.tim]
[junit4:junit4] 1> 254119 [qtp30596145-103] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@ffed11 main
[junit4:junit4] 1> 254120 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@ffed11 main{StandardDirectoryReader(segments_p:55:nrt _j(5.0):C1)}
[junit4:junit4] 1> 254121 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 254121 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 254122 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@ffed11 main{StandardDirectoryReader(segments_p:55:nrt _j(5.0):C1)}
[junit4:junit4] 1> 254124 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 12
[junit4:junit4] 1> 254129 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 254136 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1432980166965985280)]} 0 3
[junit4:junit4] 1> 254141 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 254143 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_p,generation=25,filenames=[_j.fdx, _j.si, _j.fdt, _j_Lucene41_0.tip, _j.fnm, segments_p, _j_Lucene41_0.doc, _j_Lucene41_0.tim]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_q,generation=26,filenames=[segments_q]
[junit4:junit4] 1> 254144 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? newest commit = 26[segments_q]
[junit4:junit4] 1> 254145 [qtp30596145-105] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@9f4814 main
[junit4:junit4] 1> 254146 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@9f4814 main{StandardDirectoryReader(segments_q:57:nrt)}
[junit4:junit4] 1> 254146 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 254147 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 254147 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@9f4814 main{StandardDirectoryReader(segments_q:57:nrt)}
[junit4:junit4] 1> 254149 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 254154 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 254157 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1354a9a
[junit4:junit4] 1> 254410 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1432980166995345408)]} 0 250
[junit4:junit4] 1> 254418 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 0 & 222 is 200
[junit4:junit4] 1> 254419 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1354a9a
[junit4:junit4] 1> 254422 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 254426 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_q,generation=26,filenames=[segments_q]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_r,generation=27,filenames=[_k_Lucene41_0.tim, segments_r, _k.fdt, _k_Lucene41_0.tip, _k.fdx, _k_Lucene41_0.doc, _k.fnm, _k.si]
[junit4:junit4] 1> 254426 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? newest commit = 27[_k_Lucene41_0.tim, segments_r, _k.fdt, _k_Lucene41_0.tip, _k.fdx, _k_Lucene41_0.doc, _k.fnm, _k.si]
[junit4:junit4] 1> 254427 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@c2dc00 main
[junit4:junit4] 1> 254429 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 254430 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@c2dc00 main{StandardDirectoryReader(segments_r:59:nrt _k(5.0):C1)}
[junit4:junit4] 1> 254430 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 254432 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@c2dc00 main{StandardDirectoryReader(segments_r:59:nrt _k(5.0):C1)}
[junit4:junit4] 1> 254432 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4] 1> 254438 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 254447 [qtp30596145-105] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1856359 realtime
[junit4:junit4] 1> 254449 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=id:"0\ \&\ 222" (-1432980167292092416)} 0 5
[junit4:junit4] 1> 254455 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 254457 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_r,generation=27,filenames=[_k_Lucene41_0.tim, segments_r, _k.fdt, _k_Lucene41_0.tip, _k.fdx, _k_Lucene41_0.doc, _k.fnm, _k.si]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_s,generation=28,filenames=[segments_s]
[junit4:junit4] 1> 254458 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? newest commit = 28[segments_s]
[junit4:junit4] 1> 254459 [qtp30596145-107] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@565a48 main
[junit4:junit4] 1> 254459 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@565a48 main{StandardDirectoryReader(segments_r:61:nrt)}
[junit4:junit4] 1> 254459 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 254460 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 254461 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@565a48 main{StandardDirectoryReader(segments_r:61:nrt)}
[junit4:junit4] 1> 254462 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 7
[junit4:junit4] 1> 254470 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 254473 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1414ae3
[junit4:junit4] 1> 254474 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@cddbc2
[junit4:junit4] 1> 254475 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@16fb5b3
[junit4:junit4] 1> 254478 [concurrentUpdateScheduler-5-thread-10] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1db9b75
[junit4:junit4] 1> 254480 [concurrentUpdateScheduler-5-thread-10] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1db9b75
[junit4:junit4] 1> 254730 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1432980167327744000)]} 0 252
[junit4:junit4] 1> 254731 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 0 & 222 is 200
[junit4:junit4] 1> 254732 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1414ae3
[junit4:junit4] 1> 254738 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 & 222 (1432980167338229760)]} 0 249
[junit4:junit4] 1> 254739 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 1 & 222 is 200
[junit4:junit4] 1> 254741 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@cddbc2
[junit4:junit4] 1> 254742 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 & 222 (1432980167342424064)]} 0 250
[junit4:junit4] 1> 254744 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 2 & 222 is 200
[junit4:junit4] 1> 254745 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@16fb5b3
[junit4:junit4] 1> 254751 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 254754 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_s,generation=28,filenames=[segments_s]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_t,generation=29,filenames=[_l_Lucene41_0.tip, _l_Lucene41_0.tim, segments_t, _l_Lucene41_0.doc, _l.fnm, _l.si, _l.fdx, _l.fdt]
[junit4:junit4] 1> 254755 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? newest commit = 29[_l_Lucene41_0.tip, _l_Lucene41_0.tim, segments_t, _l_Lucene41_0.doc, _l.fnm, _l.si, _l.fdx, _l.fdt]
[junit4:junit4] 1> 254757 [qtp30596145-103] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7a7ebe main
[junit4:junit4] 1> 254758 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7a7ebe main{StandardDirectoryReader(segments_t:63:nrt _l(5.0):C3)}
[junit4:junit4] 1> 254758 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 254760 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 254761 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7a7ebe main{StandardDirectoryReader(segments_t:63:nrt _l(5.0):C3)}
[junit4:junit4] 1> 254762 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 254769 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=3 status=0 QTime=1
[junit4:junit4] 1> 254780 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1432980167640219648), 1 & 222 (-1432980167642316800), 2 & 222 (-1432980167643365376)]} 0 4
[junit4:junit4] 1> 254785 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 254787 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_t,generation=29,filenames=[_l_Lucene41_0.tip, _l_Lucene41_0.tim, segments_t, _l_Lucene41_0.doc, _l.fnm, _l.si, _l.fdx, _l.fdt]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_u,generation=30,filenames=[segments_u]
[junit4:junit4] 1> 254788 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? newest commit = 30[segments_u]
[junit4:junit4] 1> 254790 [qtp30596145-104] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@489ba2 main
[junit4:junit4] 1> 254791 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@489ba2 main{StandardDirectoryReader(segments_u:65:nrt)}
[junit4:junit4] 1> 254791 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 254792 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 254793 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@489ba2 main{StandardDirectoryReader(segments_u:65:nrt)}
[junit4:junit4] 1> 254794 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 9
[junit4:junit4] 1> 254802 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 254804 [TEST-SolrExampleStreamingTest.testAddDelete-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAddDelete
[junit4:junit4] 1> 254821 [TEST-SolrExampleStreamingTest.testErrorHandling-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testErrorHandling
[junit4:junit4] 1> 254828 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching ignore_exception
[junit4:junit4] 1> 254829 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/analysis/field params={analysis.fieldtype=int&wt=xml&qt=/analysis/field&analysis.fieldvalue=ignore_exception&version=2.2} status=400 QTime=2
[junit4:junit4] 1> 254837 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 2
[junit4:junit4] 1> 254839 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching ignore_exception
[junit4:junit4] 1> 254886 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@89dbcf
[junit4:junit4] 1> 254890 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 0
[junit4:junit4] 1> 254891 [qtp30596145-107] ERROR org.apache.solr.core.SolrCore ? org.apache.solr.common.SolrException: Document contains multiple values for uniqueKey field: id=[DOCID, DOCID2]
[junit4:junit4] 1> at org.apache.solr.update.AddUpdateCommand.getIndexedId(AddUpdateCommand.java:91)
[junit4:junit4] 1> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:517)
[junit4:junit4] 1> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:396)
[junit4:junit4] 1> at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:100)
[junit4:junit4] 1> at org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:246)
[junit4:junit4] 1> at org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:173)
[junit4:junit4] 1> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:92)
[junit4:junit4] 1> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4:junit4] 1> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4:junit4] 1> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1816)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:642)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:141)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4] 1> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
[junit4:junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:275)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1074)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
[junit4:junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4] 1> at org.eclipse.jetty.server.Server.handle(Server.java:365)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:937)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:998)
[junit4:junit4] 1> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:948)
[junit4:junit4] 1> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4] 1> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4] 1> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 1>
[junit4:junit4] 1> 255139 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: DOCID is 400
[junit4:junit4] 1> 255140 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@89dbcf
[junit4:junit4] 1> 255141 [TEST-SolrExampleStreamingTest.testErrorHandling-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testErrorHandling
[junit4:junit4] 1> 255153 [TEST-SolrExampleStreamingTest.testAddRetrieve-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAddRetrieve
[junit4:junit4] 1> 255161 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980168038678528)} 0 4
[junit4:junit4] 1> 255163 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4724b7
[junit4:junit4] 1> 255415 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1432980168048115712), id2 (1432980168051261440)]} 0 250
[junit4:junit4] 1> 255417 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: id1 is 200
[junit4:junit4] 1> 255418 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4724b7
[junit4:junit4] 1> 255421 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 255427 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_u,generation=30,filenames=[segments_u]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_v,generation=31,filenames=[_m.si, segments_v, _m_Lucene41_0.tim, _m.nvd, _m.fdt, _m_Lucene41_0.doc, _m_Lucene41_0.tip, _m_Lucene41_0.pos, _m.fdx, _m.nvm, _m.fnm]
[junit4:junit4] 1> 255428 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? newest commit = 31[_m.si, segments_v, _m_Lucene41_0.tim, _m.nvd, _m.fdt, _m_Lucene41_0.doc, _m_Lucene41_0.tip, _m_Lucene41_0.pos, _m.fdx, _m.nvm, _m.fnm]
[junit4:junit4] 1> 255430 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@12358b5 main
[junit4:junit4] 1> 255431 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@12358b5 main{StandardDirectoryReader(segments_v:67:nrt _m(5.0):C2)}
[junit4:junit4] 1> 255432 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 255432 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 255434 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@12358b5 main{StandardDirectoryReader(segments_v:67:nrt _m(5.0):C2)}
[junit4:junit4] 1> 255435 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 14
[junit4:junit4] 1> 255441 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=xml&version=2.2} hits=2 status=0 QTime=1
[junit4:junit4] 1> 255445 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@9017ad
[junit4:junit4] 1> 255720 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1432980168346959872), id2 (1432980168351154176)]} 0 271
[junit4:junit4] 1> 255721 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: id1 is 200
[junit4:junit4] 1> 255721 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@9017ad
[junit4:junit4] 1> 255725 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 255729 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_v,generation=31,filenames=[_m.si, segments_v, _m_Lucene41_0.tim, _m.nvd, _m.fdt, _m_Lucene41_0.doc, _m_Lucene41_0.tip, _m_Lucene41_0.pos, _m.fdx, _m.nvm, _m.fnm]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_w,generation=32,filenames=[_n_Lucene41_0.doc, _n_Lucene41_0.tim, _n.si, _n_Lucene41_0.pos, _n_Lucene41_0.tip, _n.fdt, _n.nvm, _n.fdx, _n.fnm, _n.nvd, segments_w]
[junit4:junit4] 1> 255730 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? newest commit = 32[_n_Lucene41_0.doc, _n_Lucene41_0.tim, _n.si, _n_Lucene41_0.pos, _n_Lucene41_0.tip, _n.fdt, _n.nvm, _n.fdx, _n.fnm, _n.nvd, segments_w]
[junit4:junit4] 1> 255732 [qtp30596145-108] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@18f9114 main
[junit4:junit4] 1> 255733 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@18f9114 main{StandardDirectoryReader(segments_w:71:nrt _n(5.0):C2)}
[junit4:junit4] 1> 255733 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 255733 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 255735 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@18f9114 main{StandardDirectoryReader(segments_w:71:nrt _n(5.0):C2)}
[junit4:junit4] 1> 255736 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 255740 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=xml&version=2.2} hits=2 status=0 QTime=0
[junit4:junit4] 1> 255747 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h?llo&wt=xml&version=2.2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 255749 [TEST-SolrExampleStreamingTest.testAddRetrieve-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAddRetrieve
[junit4:junit4] 1> 255759 [TEST-SolrExampleStreamingTest.testRealtimeGet-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testRealtimeGet
[junit4:junit4] 1> 255765 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@899db0 realtime
[junit4:junit4] 1> 255766 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980168674115584)} 0 3
[junit4:junit4] 1> 255767 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@cfb92c
[junit4:junit4] 1> 256021 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[DOCID (1432980168682504192)]} 0 250
[junit4:junit4] 1> 256022 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: DOCID is 200
[junit4:junit4] 1> 256023 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@cfb92c
[junit4:junit4] 1> 256026 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 256030 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_w,generation=32,filenames=[_n_Lucene41_0.doc, _n_Lucene41_0.tim, _n.si, _n_Lucene41_0.pos, _n_Lucene41_0.tip, _n.fdt, _n.nvm, _n.fdx, _n.fnm, _n.nvd, segments_w]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_x,generation=33,filenames=[_o.fdt, _o_Lucene41_0.tim, _o.nvm, _o.fnm, _o.fdx, _o_Lucene41_0.tip, _o.si, _o.nvd, _o_Lucene41_0.doc, segments_x, _o_Lucene41_0.pos]
[junit4:junit4] 1> 256032 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? newest commit = 33[_o.fdt, _o_Lucene41_0.tim, _o.nvm, _o.fnm, _o.fdx, _o_Lucene41_0.tip, _o.si, _o.nvd, _o_Lucene41_0.doc, segments_x, _o_Lucene41_0.pos]
[junit4:junit4] 1> 256035 [qtp30596145-108] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@11ae0df main
[junit4:junit4] 1> 256038 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 256039 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@11ae0df main{StandardDirectoryReader(segments_x:75:nrt _o(5.0):C1)}
[junit4:junit4] 1> 256039 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 256041 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@11ae0df main{StandardDirectoryReader(segments_x:75:nrt _o(5.0):C1)}
[junit4:junit4] 1> 256042 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 16
[junit4:junit4] 1> 256046 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=javabin&qt=/get&version=2} status=0 QTime=0
[junit4:junit4] 1> 256051 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=xml&qt=/get&version=2.2} status=0 QTime=0
[junit4:junit4] 1> 256052 [TEST-SolrExampleStreamingTest.testRealtimeGet-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testRealtimeGet
[junit4:junit4] 1> 256063 [TEST-SolrExampleStreamingTest.testPivotFacets-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPivotFacets
[junit4:junit4] 1> 256069 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1ab207e realtime
[junit4:junit4] 1> 256070 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980168992882688)} 0 3
[junit4:junit4] 1> 256075 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 256076 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_x,generation=33,filenames=[_o.fdt, _o_Lucene41_0.tim, _o.nvm, _o.fnm, _o.fdx, _o_Lucene41_0.tip, _o.si, _o.nvd, _o_Lucene41_0.doc, segments_x, _o_Lucene41_0.pos]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_y,generation=34,filenames=[segments_y]
[junit4:junit4] 1> 256076 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? newest commit = 34[segments_y]
[junit4:junit4] 1> 256077 [qtp30596145-104] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@12258dc main
[junit4:junit4] 1> 256078 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@12258dc main{StandardDirectoryReader(segments_x:77:nrt)}
[junit4:junit4] 1> 256078 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 256078 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 256084 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@12258dc main{StandardDirectoryReader(segments_x:77:nrt)}
[junit4:junit4] 1> 256085 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4] 1> 256090 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 256092 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@195d4e0
[junit4:junit4] 1> 256347 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1432980169025388544), 2 (1432980169027485696), 3 (1432980169028534272), 4 (1432980169029582848), 5 (1432980169029582849), 6 (1432980169030631424), 7 (1432980169031680000), 8 (1432980169032728576), 9 (1432980169032728577), 10 (1432980169033777152), ... (12 adds)]} 0 250
[junit4:junit4] 1> 256349 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 1 is 200
[junit4:junit4] 1> 256349 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@195d4e0
[junit4:junit4] 1> 256353 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 256357 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_y,generation=34,filenames=[segments_y]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_z,generation=35,filenames=[_p.nvm, _p.fdx, _p_Lucene41_0.doc, _p_Lucene41_0.tim, _p.nvd, _p.fnm, _p.fdt, _p_Lucene41_0.pos, _p_Lucene41_0.tip, _p.si, segments_z]
[junit4:junit4] 1> 256357 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? newest commit = 35[_p.nvm, _p.fdx, _p_Lucene41_0.doc, _p_Lucene41_0.tim, _p.nvd, _p.fnm, _p.fdt, _p_Lucene41_0.pos, _p_Lucene41_0.tip, _p.si, segments_z]
[junit4:junit4] 1> 256359 [qtp30596145-105] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@93c73f main
[junit4:junit4] 1> 256359 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@93c73f main{StandardDirectoryReader(segments_z:79:nrt _p(5.0):C12)}
[junit4:junit4] 1> 256360 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 256361 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@93c73f main{StandardDirectoryReader(segments_z:79:nrt _p(5.0):C12)}
[junit4:junit4] 1> 256363 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 256364 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 256369 [qtp30596145-103] 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> 256370 [qtp30596145-103] 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> 256372 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet.missing=false&facet=true&facet.mincount=0&q=*:*&wt=xml&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2.2&rows=0} hits=12 status=0 QTime=3
[junit4:junit4] 1> 256382 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=xml&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2.2&rows=0} hits=11 status=0 QTime=3
[junit4:junit4] 1> 256385 [TEST-SolrExampleStreamingTest.testPivotFacets-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPivotFacets
[junit4:junit4] 1> 256397 [TEST-SolrExampleStreamingTest.testFaceting-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testFaceting
[junit4:junit4] 1> 256403 [qtp30596145-104] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3d434c realtime
[junit4:junit4] 1> 256404 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980169344155648)} 0 2
[junit4:junit4] 1> 256409 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 256409 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_z,generation=35,filenames=[_p.nvm, _p.fdx, _p_Lucene41_0.doc, _p_Lucene41_0.tim, _p.nvd, _p.fnm, _p.fdt, _p_Lucene41_0.pos, _p_Lucene41_0.tip, _p.si, segments_z]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_10,generation=36,filenames=[segments_10]
[junit4:junit4] 1> 256410 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? newest commit = 36[segments_10]
[junit4:junit4] 1> 256411 [qtp30596145-107] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1919151 main
[junit4:junit4] 1> 256412 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 256413 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1919151 main{StandardDirectoryReader(segments_z:81:nrt)}
[junit4:junit4] 1> 256413 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 256415 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1919151 main{StandardDirectoryReader(segments_z:81:nrt)}
[junit4:junit4] 1> 256415 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 7
[junit4:junit4] 1> 256420 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 256570 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1db7229
[junit4:junit4] 1> 256825 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1432980169526607872), 2 (1432980169528705024), 3 (1432980169529753600), 4 (1432980169530802176), 5 (1432980169531850752), 6 (1432980169532899328), 7 (1432980169533947904), 8 (1432980169533947905), 9 (1432980169534996480), 10 (1432980169534996481)]} 0 249
[junit4:junit4] 1> 256827 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 1 is 200
[junit4:junit4] 1> 256827 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1db7229
[junit4:junit4] 1> 256831 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 256834 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_10,generation=36,filenames=[segments_10]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_11,generation=37,filenames=[_q.si, _q_Lucene41_0.pos, _q.nvm, _q_Lucene41_0.tip, segments_11, _q_Lucene41_0.tim, _q.fdx, _q_Lucene41_0.doc, _q.fnm, _q.fdt, _q.nvd]
[junit4:junit4] 1> 256835 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? newest commit = 37[_q.si, _q_Lucene41_0.pos, _q.nvm, _q_Lucene41_0.tip, segments_11, _q_Lucene41_0.tim, _q.fdx, _q_Lucene41_0.doc, _q.fnm, _q.fdt, _q.nvd]
[junit4:junit4] 1> 256836 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1b74002 main
[junit4:junit4] 1> 256837 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1b74002 main{StandardDirectoryReader(segments_11:83:nrt _q(5.0):C10)}
[junit4:junit4] 1> 256837 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 256839 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 256840 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1b74002 main{StandardDirectoryReader(segments_11:83:nrt _q(5.0):C10)}
[junit4:junit4] 1> 256841 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4] 1> 256846 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=0,phase1=0,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4] 1> 256847 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=xml&version=2.2&rows=0} hits=10 status=0 QTime=1
[junit4:junit4] 1> 256854 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=xml&fq=features:two&version=2.2&rows=0} hits=5 status=0 QTime=1
[junit4:junit4] 1> 256861 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=4&q=*:*&facet.field=features&wt=xml&fq=features:two&version=2.2&rows=0} hits=5 status=0 QTime=1
[junit4:junit4] 1> 256868 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=-1&q=*:*&facet.field=features&wt=xml&fq=features:two&version=2.2&rows=0} hits=5 status=0 QTime=2
[junit4:junit4] 1> 256871 [TEST-SolrExampleStreamingTest.testFaceting-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testFaceting
[junit4:junit4] 1> 256888 [TEST-SolrExampleStreamingTest.testStreamingRequest-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testStreamingRequest
[junit4:junit4] 1> 256896 [qtp30596145-103] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1d3dcb3 realtime
[junit4:junit4] 1> 256897 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980169860055040)} 0 4
[junit4:junit4] 1> 256910 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 256911 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_11,generation=37,filenames=[_q.si, _q_Lucene41_0.pos, _q.nvm, _q_Lucene41_0.tip, segments_11, _q_Lucene41_0.tim, _q.fdx, _q_Lucene41_0.doc, _q.fnm, _q.fdt, _q.nvd]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_12,generation=38,filenames=[segments_12]
[junit4:junit4] 1> 256911 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? newest commit = 38[segments_12]
[junit4:junit4] 1> 256913 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@106ff1d main
[junit4:junit4] 1> 256914 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 256916 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@106ff1d main{StandardDirectoryReader(segments_11:85:nrt)}
[junit4:junit4] 1> 256916 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 256918 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@106ff1d main{StandardDirectoryReader(segments_11:85:nrt)}
[junit4:junit4] 1> 256919 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 9
[junit4:junit4] 1> 256929 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 256931 [TEST-SolrExampleStreamingTest.testStreamingRequest-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? blocking for commit/optimize
[junit4:junit4] 1> 256952 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 256961 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_12,generation=38,filenames=[segments_12]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_13,generation=39,filenames=[_r.fdt, _r.fnm, _r_Lucene41_0.pos, _r.fdx, _r_Lucene41_0.tip, _r.nvd, _r_Lucene41_0.tim, segments_13, _r.si, _r_Lucene41_0.doc, _r.nvm]
[junit4:junit4] 1> 256962 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? newest commit = 39[_r.fdt, _r.fnm, _r_Lucene41_0.pos, _r.fdx, _r_Lucene41_0.tip, _r.nvd, _r_Lucene41_0.tim, segments_13, _r.si, _r_Lucene41_0.doc, _r.nvm]
[junit4:junit4] 1> 256964 [qtp30596145-107] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1657ce6 main
[junit4:junit4] 1> 256966 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 256967 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1657ce6 main{StandardDirectoryReader(segments_13:87:nrt _r(5.0):C10)}
[junit4:junit4] 1> 256968 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 256970 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1657ce6 main{StandardDirectoryReader(segments_13:87:nrt _r(5.0):C10)}
[junit4:junit4] 1> 256971 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {add=[0 (1432980169908289536), 1 (1432980169911435264), 2 (1432980169912483840), 3 (1432980169914580992), 4 (1432980169915629568), 5 (1432980169917726720), 6 (1432980169917726721), 7 (1432980169918775296), 8 (1432980169919823872), 9 (1432980169919823873)],commit=} 0 32
[junit4:junit4] 1> 256980 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=xml&version=2.2} hits=10 status=0 QTime=1
[junit4:junit4] 1> 256986 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4:junit4] 1> 256989 [TEST-SolrExampleStreamingTest.testStreamingRequest-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testStreamingRequest
[junit4:junit4] 1> 257007 [TEST-SolrExampleStreamingTest.testLukeHandler-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testLukeHandler
[junit4:junit4] 1> 257015 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@190327e realtime
[junit4:junit4] 1> 257016 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980169983787008)} 0 4
[junit4:junit4] 1> 257021 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1791125
[junit4:junit4] 1> 257022 [concurrentUpdateScheduler-5-thread-10] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@154b6be
[junit4:junit4] 1> 257023 [concurrentUpdateScheduler-5-thread-11] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@d2ffa8
[junit4:junit4] 1> 257063 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1e67c7
[junit4:junit4] 1> 257068 [concurrentUpdateScheduler-5-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1e67c7
[junit4:junit4] 1> 257059 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1a06318
[junit4:junit4] 1> 257069 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1a06318
[junit4:junit4] 1> 257066 [concurrentUpdateScheduler-5-thread-11] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@d2ffa8
[junit4:junit4] 1> 257317 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID1 (1432980170040410112)]} 0 251
[junit4:junit4] 1> 257319 [concurrentUpdateScheduler-5-thread-10] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: ID1 is 200
[junit4:junit4] 1> 257320 [concurrentUpdateScheduler-5-thread-10] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@154b6be
[junit4:junit4] 1> 257336 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID0 (1432980170035167232), ID2 (1432980170036215808), ID3 (1432980170037264384), ID4 (1432980170039361536)]} 0 275
[junit4:junit4] 1> 257338 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: ID0 is 200
[junit4:junit4] 1> 257339 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1791125
[junit4:junit4] 1> 257343 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257346 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_13,generation=39,filenames=[_r.fdt, _r.fnm, _r_Lucene41_0.pos, _r.fdx, _r_Lucene41_0.tip, _r.nvd, _r_Lucene41_0.tim, segments_13, _r.si, _r_Lucene41_0.doc, _r.nvm]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_14,generation=40,filenames=[_s_Lucene41_0.doc, _s.fdx, _s_Lucene41_0.tim, _s.fdt, _s.si, _s.fnm, segments_14, _s_Lucene41_0.tip]
[junit4:junit4] 1> 257346 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? newest commit = 40[_s_Lucene41_0.doc, _s.fdx, _s_Lucene41_0.tim, _s.fdt, _s.si, _s.fnm, segments_14, _s_Lucene41_0.tip]
[junit4:junit4] 1> 257348 [qtp30596145-108] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3b2d0b main
[junit4:junit4] 1> 257349 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3b2d0b main{StandardDirectoryReader(segments_14:91:nrt _s(5.0):C5)}
[junit4:junit4] 1> 257349 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 257349 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 257351 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3b2d0b main{StandardDirectoryReader(segments_14:91:nrt _s(5.0):C5)}
[junit4:junit4] 1> 257353 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 257358 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=5 status=0 QTime=0
[junit4:junit4] 1> 257374 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/luke params={wt=xml&version=2.2} status=0 QTime=1
[junit4:junit4] 1> 257382 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=xml&version=2.2} status=0 QTime=2
[junit4:junit4] 1> 257501 [TEST-SolrExampleStreamingTest.testLukeHandler-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testLukeHandler
[junit4:junit4] 1> 257513 [TEST-SolrExampleStreamingTest.testPivotFacetsMissing-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPivotFacetsMissing
[junit4:junit4] 1> 257518 [qtp30596145-104] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@137d775 realtime
[junit4:junit4] 1> 257519 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980170512269312)} 0 3
[junit4:junit4] 1> 257524 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257526 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_14,generation=40,filenames=[_s_Lucene41_0.doc, _s.fdx, _s_Lucene41_0.tim, _s.fdt, _s.si, _s.fnm, segments_14, _s_Lucene41_0.tip]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_15,generation=41,filenames=[segments_15]
[junit4:junit4] 1> 257526 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? newest commit = 41[segments_15]
[junit4:junit4] 1> 257527 [qtp30596145-108] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@532199 main
[junit4:junit4] 1> 257529 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 257530 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@532199 main{StandardDirectoryReader(segments_14:93:nrt)}
[junit4:junit4] 1> 257530 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 257539 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@532199 main{StandardDirectoryReader(segments_14:93:nrt)}
[junit4:junit4] 1> 257540 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 16
[junit4:junit4] 1> 257546 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=2
[junit4:junit4] 1> 257608 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@c4ca30
[junit4:junit4] 1> 257864 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1432980170616078336), 2 (1432980170619224064), 3 (1432980170620272640), 4 (1432980170621321216), 5 (1432980170621321217), 6 (1432980170621321218), 7 (1432980170621321219), 8 (1432980170621321220), 9 (1432980170622369792), 10 (1432980170623418368), ... (12 adds)]} 0 249
[junit4:junit4] 1> 257866 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 1 is 200
[junit4:junit4] 1> 257866 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@c4ca30
[junit4:junit4] 1> 257870 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257875 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_15,generation=41,filenames=[segments_15]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_16,generation=42,filenames=[_t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _t.nvd, _t.nvm, _t.fdx, _t_Lucene41_0.pos, _t.fdt, segments_16]
[junit4:junit4] 1> 257875 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? newest commit = 42[_t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _t.nvd, _t.nvm, _t.fdx, _t_Lucene41_0.pos, _t.fdt, segments_16]
[junit4:junit4] 1> 257876 [qtp30596145-107] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@125fbe main
[junit4:junit4] 1> 257877 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@125fbe main{StandardDirectoryReader(segments_16:95:nrt _t(5.0):C12)}
[junit4:junit4] 1> 257877 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 257879 [qtp30596145-107] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 257879 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@125fbe main{StandardDirectoryReader(segments_16:95:nrt _t(5.0):C12)}
[junit4:junit4] 1> 257880 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4] 1> 257885 [qtp30596145-104] 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> 257886 [qtp30596145-104] 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> 257891 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet.missing=true&facet=true&facet.mincount=0&q=*:*&wt=xml&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2.2&rows=0} hits=12 status=0 QTime=6
[junit4:junit4] 1> 257901 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=xml&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2.2&rows=0} hits=11 status=0 QTime=2
[junit4:junit4] 1> 257905 [TEST-SolrExampleStreamingTest.testPivotFacetsMissing-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPivotFacetsMissing
[junit4:junit4] 1> 257916 [TEST-SolrExampleStreamingTest.testUpdateMultiValuedField-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateMultiValuedField
[junit4:junit4] 1> 257917 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@e1a351
[junit4:junit4] 1> 258170 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1432980170934845440)]} 0 251
[junit4:junit4] 1> 258172 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 123 is 200
[junit4:junit4] 1> 258173 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@e1a351
[junit4:junit4] 1> 258177 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258180 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_16,generation=42,filenames=[_t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _t.nvd, _t.nvm, _t.fdx, _t_Lucene41_0.pos, _t.fdt, segments_16]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_17,generation=43,filenames=[_t_Lucene41_0.tim, _t_Lucene41_0.tip, _u_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _u_Lucene41_0.tim, _u_Lucene41_0.doc, _u.fdx, _t.si, _u.fnm, _t.nvd, _t.nvm, _t.fdx, _u.si, _u.fdt, segments_17, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4] 1> 258181 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? newest commit = 43[_t_Lucene41_0.tim, _t_Lucene41_0.tip, _u_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _u_Lucene41_0.tim, _u_Lucene41_0.doc, _u.fdx, _t.si, _u.fnm, _t.nvd, _t.nvm, _t.fdx, _u.si, _u.fdt, segments_17, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4] 1> 258182 [qtp30596145-106] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@162a46c main
[junit4:junit4] 1> 258183 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@162a46c main{StandardDirectoryReader(segments_17:97:nrt _t(5.0):C12 _u(5.0):C1)}
[junit4:junit4] 1> 258183 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 258185 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 258185 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@162a46c main{StandardDirectoryReader(segments_17:97:nrt _t(5.0):C12 _u(5.0):C1)}
[junit4:junit4] 1> 258186 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 9
[junit4:junit4] 1> 258190 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=xml&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 258193 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@118c09a
[junit4:junit4] 1> 258445 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1432980171225300992)]} 0 249
[junit4:junit4] 1> 258447 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 123 is 200
[junit4:junit4] 1> 258448 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@118c09a
[junit4:junit4] 1> 258453 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258456 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_17,generation=43,filenames=[_t_Lucene41_0.tim, _t_Lucene41_0.tip, _u_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _u_Lucene41_0.tim, _u_Lucene41_0.doc, _u.fdx, _t.si, _u.fnm, _t.nvd, _t.nvm, _t.fdx, _u.si, _u.fdt, segments_17, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_18,generation=44,filenames=[_t_Lucene41_0.tim, _v.fdx, _v.fdt, _t_Lucene41_0.tip, _v.si, _t.fnm, _t_Lucene41_0.doc, _v_Lucene41_0.tip, _t.si, _v_Lucene41_0.doc, _v_Lucene41_0.tim, _t.nvd, _t.nvm, _t.fdx, _v.fnm, _t_Lucene41_0.pos, _t.fdt, segments_18]
[junit4:junit4] 1> 258458 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? newest commit = 44[_t_Lucene41_0.tim, _v.fdx, _v.fdt, _t_Lucene41_0.tip, _v.si, _t.fnm, _t_Lucene41_0.doc, _v_Lucene41_0.tip, _t.si, _v_Lucene41_0.doc, _v_Lucene41_0.tim, _t.nvd, _t.nvm, _t.fdx, _v.fnm, _t_Lucene41_0.pos, _t.fdt, segments_18]
[junit4:junit4] 1> 258459 [qtp30596145-104] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1a7a5bd main
[junit4:junit4] 1> 258462 [qtp30596145-104] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 258464 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1a7a5bd main{StandardDirectoryReader(segments_18:101:nrt _t(5.0):C12 _v(5.0):C1)}
[junit4:junit4] 1> 258465 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 258467 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1a7a5bd main{StandardDirectoryReader(segments_18:101:nrt _t(5.0):C12 _v(5.0):C1)}
[junit4:junit4] 1> 258468 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 15
[junit4:junit4] 1> 258476 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=xml&version=2.2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 258487 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@17238dd
[junit4:junit4] 1> 258743 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1432980171537776640)]} 0 250
[junit4:junit4] 1> 258745 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: 123 is 200
[junit4:junit4] 1> 258745 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@17238dd
[junit4:junit4] 1> 258749 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258753 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_18,generation=44,filenames=[_t_Lucene41_0.tim, _v.fdx, _v.fdt, _t_Lucene41_0.tip, _v.si, _t.fnm, _t_Lucene41_0.doc, _v_Lucene41_0.tip, _t.si, _v_Lucene41_0.doc, _v_Lucene41_0.tim, _t.nvd, _t.nvm, _t.fdx, _v.fnm, _t_Lucene41_0.pos, _t.fdt, segments_18]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_19,generation=45,filenames=[_t_Lucene41_0.tim, _w.si, _t_Lucene41_0.tip, _w.fdt, _t.fnm, _t_Lucene41_0.doc, _w.fdx, _w_Lucene41_0.doc, _t.si, _w_Lucene41_0.tip, _w_Lucene41_0.tim, _t.nvd, _t.nvm, _t.fdx, _w.fnm, _t_Lucene41_0.pos, _t.fdt, segments_19]
[junit4:junit4] 1> 258754 [qtp30596145-108] INFO org.apache.solr.core.SolrCore ? newest commit = 45[_t_Lucene41_0.tim, _w.si, _t_Lucene41_0.tip, _w.fdt, _t.fnm, _t_Lucene41_0.doc, _w.fdx, _w_Lucene41_0.doc, _t.si, _w_Lucene41_0.tip, _w_Lucene41_0.tim, _t.nvd, _t.nvm, _t.fdx, _w.fnm, _t_Lucene41_0.pos, _t.fdt, segments_19]
[junit4:junit4] 1> 258756 [qtp30596145-108] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1c02f5 main
[junit4:junit4] 1> 258757 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1c02f5 main{StandardDirectoryReader(segments_19:105:nrt _t(5.0):C12 _w(5.0):C1)}
[junit4:junit4] 1> 258757 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 258758 [qtp30596145-108] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 258758 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1c02f5 main{StandardDirectoryReader(segments_19:105:nrt _t(5.0):C12 _w(5.0):C1)}
[junit4:junit4] 1> 258760 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 258765 [qtp30596145-107] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=xml&version=2.2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 258766 [TEST-SolrExampleStreamingTest.testUpdateMultiValuedField-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateMultiValuedField
[junit4:junit4] 1> 258777 [TEST-SolrExampleStreamingTest.testCommitWithinOnAdd-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testCommitWithinOnAdd
[junit4:junit4] 1> 258784 [qtp30596145-104] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1194cf5 realtime
[junit4:junit4] 1> 258785 [qtp30596145-104] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1432980171837669376)} 0 5
[junit4:junit4] 1> 258789 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258790 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_19,generation=45,filenames=[_t_Lucene41_0.tim, _w.si, _t_Lucene41_0.tip, _w.fdt, _t.fnm, _t_Lucene41_0.doc, _w.fdx, _w_Lucene41_0.doc, _t.si, _w_Lucene41_0.tip, _w_Lucene41_0.tim, _t.nvd, _t.nvm, _t.fdx, _w.fnm, _t_Lucene41_0.pos, _t.fdt, segments_19]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_1a,generation=46,filenames=[segments_1a]
[junit4:junit4] 1> 258791 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? newest commit = 46[segments_1a]
[junit4:junit4] 1> 258792 [qtp30596145-105] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1a0f05 main
[junit4:junit4] 1> 258793 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1a0f05 main{StandardDirectoryReader(segments_19:107:nrt)}
[junit4:junit4] 1> 258793 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 258793 [qtp30596145-105] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 258793 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1a0f05 main{StandardDirectoryReader(segments_19:107:nrt)}
[junit4:junit4] 1> 258795 [qtp30596145-105] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 6
[junit4:junit4] 1> 258800 [qtp30596145-106] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 258803 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@d04187
[junit4:junit4] 1> 258806 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 259055 [qtp30596145-108] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id3 (1432980171865980928)]} 0 249
[junit4:junit4] 1> 259056 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: id3 is 200
[junit4:junit4] 1> 259057 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@d04187
[junit4:junit4] 1> 259330 [commitScheduler-75-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> 259335 [commitScheduler-75-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@13ee2cd main
[junit4:junit4] 1> 259336 [commitScheduler-75-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 259336 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@13ee2cd main{StandardDirectoryReader(segments_1a:109:nrt _x(5.0):C1)}
[junit4:junit4] 1> 259337 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 259341 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@13ee2cd main{StandardDirectoryReader(segments_1a:109:nrt _x(5.0):C1)}
[junit4:junit4] 1> 259812 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 259814 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@19c0a9a
[junit4:junit4] 1> 260067 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id4 (1432980172926091264)]} 0 250
[junit4:junit4] 1> 260069 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: id4 is 200
[junit4:junit4] 1> 260070 [concurrentUpdateScheduler-5-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@19c0a9a
[junit4:junit4] 1> 260319 [commitScheduler-75-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> 260324 [commitScheduler-75-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@bee01a main
[junit4:junit4] 1> 260326 [commitScheduler-75-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 260326 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@bee01a m
[...truncated too long message...]
.
[junit4:junit4] 1> 265952 [qtp30596145-106] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 265953 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1ab4bca main{StandardDirectoryReader(segments_1z:165:nrt _1d(5.0):C1)}
[junit4:junit4] 1> 265954 [qtp30596145-106] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 14
[junit4:junit4] 1> 265959 [qtp30596145-104] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 265962 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@fcce18
[junit4:junit4] 1> 266217 [qtp30596145-107] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1432980179372736512)]} 0 251
[junit4:junit4] 1> 266219 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? Status for: unique is 200
[junit4:junit4] 1> 266220 [concurrentUpdateScheduler-5-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer ? finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@fcce18
[junit4:junit4] 1> 266223 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 266230 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_1z,generation=71,filenames=[_1d_Lucene41_0.pos, _1d_Lucene41_0.doc, _1d_Lucene41_0.tip, _1d.nvm, _1d.fnm, segments_1z, _1d.si, _1d.fdx, _1d.nvd, _1d_Lucene41_0.tim, _1d.fdt]
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4daf4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@e82e7a),segFN=segments_20,generation=72,filenames=[_1e_Lucene41_0.tip, _1e.nvd, _1e_Lucene41_0.pos, _1e.fdx, segments_20, _1e.fdt, _1e.si, _1e_Lucene41_0.doc, _1e.nvm, _1e_Lucene41_0.tim, _1e.fnm]
[junit4:junit4] 1> 266231 [qtp30596145-103] INFO org.apache.solr.core.SolrCore ? newest commit = 72[_1e_Lucene41_0.tip, _1e.nvd, _1e_Lucene41_0.pos, _1e.fdx, segments_20, _1e.fdt, _1e.si, _1e_Lucene41_0.doc, _1e.nvm, _1e_Lucene41_0.tim, _1e.fnm]
[junit4:junit4] 1> 266232 [qtp30596145-103] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1711043 main
[junit4:junit4] 1> 266233 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1711043 main{StandardDirectoryReader(segments_20:169:nrt _1e(5.0):C1)}
[junit4:junit4] 1> 266233 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 266237 [qtp30596145-103] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 266237 [searcherExecutor-72-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1711043 main{StandardDirectoryReader(segments_20:169:nrt _1e(5.0):C1)}
[junit4:junit4] 1> 266238 [qtp30596145-103] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 15
[junit4:junit4] 1> 266247 [qtp30596145-105] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 266249 [TEST-SolrExampleStreamingTest.testUpdateField-seed#[6E2C2EACA27364BD]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateField
[junit4:junit4] 1> 266262 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.CoreContainer ? Shutting down CoreContainer instance=18776035
[junit4:junit4] 1> 266273 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.SolrCore ? [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1a7a90f
[junit4:junit4] 1> 266293 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.update.UpdateHandler ? closing DirectUpdateHandler2{commits=66,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=132,cumulative_deletesById=5,cumulative_deletesByQuery=27,cumulative_errors=1}
[junit4:junit4] 1> 266294 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.update.SolrCoreState ? Closing SolrCoreState
[junit4:junit4] 1> 266294 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.update.DefaultSolrCoreState ? SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 1> 266295 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.update.DefaultSolrCoreState ? closing IndexWriter with IndexWriterCloser
[junit4:junit4] 1> 266302 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.SolrCore ? [collection1] Closing main searcher on request.
[junit4:junit4] 1> 266303 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 1> 266304 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\;done=false>>]
[junit4:junit4] 1> 266304 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\
[junit4:junit4] 1> 266305 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\index;done=false>>]
[junit4:junit4] 1> 266306 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingTest-1366596175387\index
[junit4:junit4] 1> 266307 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler ? stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 1> 266360 [SUITE-SolrExampleStreamingTest-seed#[6E2C2EACA27364BD]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=da_DK, timezone=Africa/Banjul
[junit4:junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_21 (32-bit)/cpus=2,threads=11,free=16752120,total=61480960
[junit4:junit4] 2> NOTE: All tests run in this JVM: [SolrExampleStreamingBinaryTest, SolrParamTest, DocumentAnalysisResponseTest, TermsResponseTest, MultiCoreEmbeddedTest, AnlysisResponseBaseTest, TestCoreAdmin, BasicHttpSolrServerTest, SolrDocumentTest, TestXMLEscaping, TestUpdateRequestCodec, SolrExampleStreamingTest]
[junit4:junit4] Completed in 218.06s, 27 tests, 1 error <<< FAILURES!
[...truncated 105 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:378: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:358: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:194: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:438: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1231: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:875: There were test failures: 45 suites, 268 tests, 1 error, 1 ignored (1 assumption)
Total time: 68 minutes 51 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_21 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure