lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.6.0_43) - Build # 4932 - Failure!
Date Tue, 02 Apr 2013 13:42:52 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4932/
Java: 64bit/jdk1.6.0_43 -XX:+UseConcMarkSweepGC

1 tests failed.
REGRESSION:  org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest.testWaitOptions

Error Message:
IOException occured when talking to server at: https://127.0.0.1:60180/solr

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:60180/solr
	at __randomizedtesting.SeedInfo.seed([D62E0EEE93C2243F:906F8355B0C46ADC]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:416)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	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.embedded.SolrExampleStreamingTest.testWaitOptions(SolrExampleStreamingTest.java:103)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	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:662)
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:353)
	... 46 more




Build Log:
[...truncated 9762 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest
[junit4:junit4]   1> 54276 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.SolrJettyTestBase  – Randomized ssl (true) and clientAuth (false)
[junit4:junit4]   1> 54276 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.SolrTestCaseJ4  – ####initCore
[junit4:junit4]   1> 54277 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.SolrTestCaseJ4  – ####initCore end
[junit4:junit4]   1> 54282 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.eclipse.jetty.server.Server  – jetty-8.1.8.v20121106
[junit4:junit4]   1> 54506 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.eclipse.jetty.util.ssl.SslContextFactory  – Enabled Protocols [SSLv2Hello, SSLv3, TLSv1] of [SSLv2Hello, SSLv3, TLSv1]
[junit4:junit4]   1> 54508 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.eclipse.jetty.server.AbstractConnector  – Started SslSelectChannelConnector@127.0.0.1:60180
[junit4:junit4]   1> 54509 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  – SolrDispatchFilter.init()
[junit4:junit4]   1> 54510 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.SolrResourceLoader  – JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> 54510 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.SolrResourceLoader  – using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4:junit4]   1> 54511 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.CoreContainer  – looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/solr.xml
[junit4:junit4]   1> 54511 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.CoreContainer  – New CoreContainer 1094864774
[junit4:junit4]   1> 54511 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.CoreContainer  – Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4:junit4]   1> 54512 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.SolrResourceLoader  – new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4:junit4]   1> 54535 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting socketTimeout to: 0
[junit4:junit4]   1> 54536 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting urlScheme to: http://
[junit4:junit4]   1> 54536 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting connTimeout to: 0
[junit4:junit4]   1> 54537 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> 54537 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting corePoolSize to: 0
[junit4:junit4]   1> 54537 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> 54538 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> 54538 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting sizeOfQueue to: -1
[junit4:junit4]   1> 54539 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting fairnessPolicy to: false
[junit4:junit4]   1> 54539 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-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> 54552 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.CoreContainer  – Registering Log Listener
[junit4:junit4]   1> 54572 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.CoreContainer  – Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1
[junit4:junit4]   1> 54572 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrResourceLoader  – new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/'
[junit4:junit4]   1> 54609 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrConfig  – Adding specified lib dirs to ClassLoader
[junit4:junit4]   1> 54672 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrConfig  – Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> 54769 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.Config  – Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> 54770 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.schema.IndexSchema  – Reading Solr Schema from schema.xml
[junit4:junit4]   1> 54784 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.schema.IndexSchema  – Schema name=example
[junit4:junit4]   1> 55325 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.schema.IndexSchema  – unique key field: id
[junit4:junit4]   1> 55344 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.schema.FileExchangeRateProvider  – Reloading exchange rates from file currency.xml
[junit4:junit4]   1> 55349 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.schema.FileExchangeRateProvider  – Reloading exchange rates from file currency.xml
[junit4:junit4]   1> 55414 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> 55414 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154/
[junit4:junit4]   1> 55415 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.JmxMonitoredMap  – JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4eb3c24f
[junit4:junit4]   1> 55416 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   1> 55416 [coreLoadExecutor-109-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> 55417 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> 55423 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.CachingDirectoryFactory  – return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154
[junit4:junit4]   1> 55424 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154/index/
[junit4:junit4]   1> 55424 [coreLoadExecutor-109-thread-1] WARN  org.apache.solr.core.SolrCore  – [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154/index' doesn't exist. Creating new index...
[junit4:junit4]   1> 55428 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.CachingDirectoryFactory  – return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154/index
[junit4:junit4]   1> 55534 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 55534 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – newest commit = 1[segments_1]
[junit4:junit4]   1> 55535 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – created json: solr.JSONResponseWriter
[junit4:junit4]   1> 55535 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4]   1> 55536 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – created velocity: solr.VelocityResponseWriter
[junit4:junit4]   1> 55536 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – created xslt: solr.XSLTResponseWriter
[junit4:junit4]   1> 55536 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.response.XSLTResponseWriter  – xsltCacheLifetimeSeconds=5
[junit4:junit4]   1> 55543 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /select: solr.SearchHandler
[junit4:junit4]   1> 55544 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /query: solr.SearchHandler
[junit4:junit4]   1> 55544 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> 55545 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /browse: solr.SearchHandler
[junit4:junit4]   1> 55545 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> 55546 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4]   1> 55546 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /update/csv: solr.CSVRequestHandler
[junit4:junit4]   1> 55547 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   1> 55547 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   1> 55547 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4]   1> 55548 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4]   1> 55548 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   1> 55549 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   1> 55549 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> 55550 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /admin/ping: solr.PingRequestHandler
[junit4:junit4]   1> 55550 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   1> 55551 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /replication: solr.ReplicationHandler
[junit4:junit4]   1> 55551 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 55551 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /spell: solr.SearchHandler
[junit4:junit4]   1> 55552 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 55552 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /tvrh: solr.SearchHandler
[junit4:junit4]   1> 55552 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 55553 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /terms: solr.SearchHandler
[junit4:junit4]   1> 55553 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 55553 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /elevate: solr.SearchHandler
[junit4:junit4]   1> 55555 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  – xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 55556 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  – xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 55557 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  – xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 55562 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@c9d77c2 main
[junit4:junit4]   1> 55562 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.update.UpdateHandler  – Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154/tlog
[junit4:junit4]   1> 55563 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – Hard AutoCommit: if uncommited for 15000ms; 
[junit4:junit4]   1> 55563 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.SolrCore  – Soft AutoCommit: disabled
[junit4:junit4]   1> 55564 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  – Initializing spell checkers
[junit4:junit4]   1> 55564 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.spelling.DirectSolrSpellChecker  – init: {name=default,field=name,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4:junit4]   1> 55564 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  – No queryConverter defined, using default converter
[junit4:junit4]   1> 55565 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.handler.component.QueryElevationComponent  – Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4:junit4]   1> 55575 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.handler.ReplicationHandler  – Commits will be reserved for  10000
[junit4:junit4]   1> 55575 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@c9d77c2 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> 55577 [searcherExecutor-110-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> 55577 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 55577 [searcherExecutor-110-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  – Loading spell index for spellchecker: default
[junit4:junit4]   1> 55578 [searcherExecutor-110-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  – Loading spell index for spellchecker: wordbreak
[junit4:junit4]   1> 55579 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@c9d77c2 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> 55582 [coreLoadExecutor-109-thread-1] INFO  org.apache.solr.core.CoreContainer  – registering core: collection1
[junit4:junit4]   1> 55583 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  – user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1
[junit4:junit4]   1> 55584 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  – SolrDispatchFilter.init() done
[junit4:junit4]   1> 55584 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.SolrJettyTestBase  – Jetty Assigned Port#60180
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/./solrtest-SolrExampleStreamingTest-1364909943154
[junit4:junit4]   1> 55595 [TEST-SolrExampleStreamingTest.testCommitWithinOnDelete-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testCommitWithinOnDelete
[junit4:junit4]   1> 55597 [TEST-SolrExampleStreamingTest.testCommitWithinOnDelete-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.client.solrj.impl.HttpClientUtil  – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> 55701 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 55702 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 1[segments_1]
[junit4:junit4]   1> 55703 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@3482c596 realtime
[junit4:junit4]   1> 55704 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431211810044772352)} 0 5
[junit4:junit4]   1> 55709 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 55722 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   1> 55723 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 2[segments_2]
[junit4:junit4]   1> 55724 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@11a9281f main
[junit4:junit4]   1> 55725 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 55725 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@11a9281f main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4]   1> 55726 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 55731 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@11a9281f main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4]   1> 55732 [qtp755696446-221] 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 23
[junit4:junit4]   1> 55737 [qtp755696446-219] 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> 55742 [concurrentUpdateScheduler-1-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@21df71f3
[junit4:junit4]   1> 55998 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id3 (1431211810097201152)]} 0 250
[junit4:junit4]   1> 56000 [concurrentUpdateScheduler-1-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: id3 is 200
[junit4:junit4]   1> 56001 [concurrentUpdateScheduler-1-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@21df71f3
[junit4:junit4]   1> 56004 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 56032 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_3,generation=3,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_Lucene41_0.pos, _0.nvd, _0.fdx, _0.si, _0.nvm, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip, segments_3]
[junit4:junit4]   1> 56033 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – newest commit = 3[_0.fnm, _0_Lucene41_0.doc, _0_Lucene41_0.pos, _0.nvd, _0.fdx, _0.si, _0.nvm, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip, segments_3]
[junit4:junit4]   1> 56037 [qtp755696446-222] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@d3ceb07 main
[junit4:junit4]   1> 56037 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 56038 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@d3ceb07 main{StandardDirectoryReader(segments_3:3:nrt _0(4.3):C1)}
[junit4:junit4]   1> 56038 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 56041 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@d3ceb07 main{StandardDirectoryReader(segments_3:3:nrt _0(4.3):C1)}
[junit4:junit4]   1> 56041 [qtp755696446-222] 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 37
[junit4:junit4]   1> 56046 [qtp755696446-219] 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=1 
[junit4:junit4]   1> 56052 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[id3 (-1431211810413871104)]} 0 1
[junit4:junit4]   1> 56057 [qtp755696446-222] 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=1 
[junit4:junit4]   1> 56313 [qtp755696446-221] 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=1 
[junit4:junit4]   1> 56568 [qtp755696446-220] 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> 56823 [qtp755696446-221] 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=1 
[junit4:junit4]   1> 57052 [commitScheduler-113-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> 57053 [commitScheduler-113-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@66f40ddf main
[junit4:junit4]   1> 57054 [commitScheduler-113-thread-1] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 57054 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@66f40ddf main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   1> 57054 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 57056 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@66f40ddf main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   1> 57078 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 57079 [TEST-SolrExampleStreamingTest.testCommitWithinOnDelete-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testCommitWithinOnDelete
[junit4:junit4]   1> 57089 [TEST-SolrExampleStreamingTest.testPivotFacetsMissing-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testPivotFacetsMissing
[junit4:junit4]   1> 57092 [qtp755696446-221] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431211811505438720)} 0 0
[junit4:junit4]   1> 57096 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 57112 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_3,generation=3,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_Lucene41_0.pos, _0.nvd, _0.fdx, _0.si, _0.nvm, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip, segments_3]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   1> 57113 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 4[segments_4]
[junit4:junit4]   1> 57115 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@4188f17b main
[junit4:junit4]   1> 57115 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 57115 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@4188f17b main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   1> 57116 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 57118 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@4188f17b main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   1> 57119 [qtp755696446-219] 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 23
[junit4:junit4]   1> 57124 [qtp755696446-220] 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> 57126 [concurrentUpdateScheduler-1-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5a5e3da
[junit4:junit4]   1> 57380 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1431211811546333184), 2 (1431211811548430336), 3 (1431211811549478912), 4 (1431211811550527488), 5 (1431211811552624640), 6 (1431211811552624641), 7 (1431211811553673216), 8 (1431211811554721792), 9 (1431211811554721793), 10 (1431211811555770368), ... (12 adds)]} 0 250
[junit4:junit4]   1> 57381 [concurrentUpdateScheduler-1-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 1 is 200
[junit4:junit4]   1> 57382 [concurrentUpdateScheduler-1-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5a5e3da
[junit4:junit4]   1> 57384 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 57442 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_5,generation=5,filenames=[_1.fdx, _1_Lucene41_0.pos, _1.nvm, _1_Lucene41_0.doc, _1.fdt, _1.si, _1_Lucene41_0.tim, _1.nvd, _1_Lucene41_0.tip, segments_5, _1.fnm]
[junit4:junit4]   1> 57443 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 5[_1.fdx, _1_Lucene41_0.pos, _1.nvm, _1_Lucene41_0.doc, _1.fdt, _1.si, _1_Lucene41_0.tim, _1.nvd, _1_Lucene41_0.tip, segments_5, _1.fnm]
[junit4:junit4]   1> 57446 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@30799905 main
[junit4:junit4]   1> 57447 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 57447 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@30799905 main{StandardDirectoryReader(segments_5:7:nrt _1(4.3):C12)}
[junit4:junit4]   1> 57448 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 57451 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@30799905 main{StandardDirectoryReader(segments_5:7:nrt _1(4.3):C12)}
[junit4:junit4]   1> 57451 [qtp755696446-219] 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 67
[junit4:junit4]   1> 57457 [qtp755696446-220] 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> 57458 [qtp755696446-220] 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> 57460 [qtp755696446-220] 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=4 
[junit4:junit4]   1> 57471 [qtp755696446-221] 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=1 
[junit4:junit4]   1> 57474 [TEST-SolrExampleStreamingTest.testPivotFacetsMissing-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testPivotFacetsMissing
[junit4:junit4]   1> 57482 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testWaitOptions
[junit4:junit4]   1> 57483 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.client.solrj.impl.HttpClientUtil  – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> 57485 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – blocking for commit/optimize
[junit4:junit4]   1> 57537 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 57562 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_5,generation=5,filenames=[_1.fdx, _1_Lucene41_0.pos, _1.nvm, _1_Lucene41_0.doc, _1.fdt, _1.si, _1_Lucene41_0.tim, _1.nvd, _1_Lucene41_0.tip, segments_5, _1.fnm]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_6,generation=6,filenames=[_2.si, _1.nvm, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2_Lucene41_0.tip, _2.fdx, _1.fnm, _2.fnm, _1_Lucene41_0.pos, _1.fdx, _1.fdt, _1.si, segments_6]
[junit4:junit4]   1> 57563 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 6[_2.si, _1.nvm, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2_Lucene41_0.tip, _2.fdx, _1.fnm, _2.fnm, _1_Lucene41_0.pos, _1.fdx, _1.fdt, _1.si, segments_6]
[junit4:junit4]   1> 57566 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@6ba520c main
[junit4:junit4]   1> 57567 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 57567 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@6ba520c main{StandardDirectoryReader(segments_6:9:nrt _1(4.3):C12 _2(4.3):C1)}
[junit4:junit4]   1> 57567 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 57570 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@6ba520c main{StandardDirectoryReader(segments_6:9:nrt _1(4.3):C12 _2(4.3):C1)}
[junit4:junit4]   1> 57570 [qtp755696446-221] 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 (1431211811971006464)],commit=} 0 34
[junit4:junit4]   1> 57572 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – blocking for commit/optimize
[junit4:junit4]   1> 57572 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@eb9012c
[junit4:junit4]   1> 57578 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 57602 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_6,generation=6,filenames=[_2.si, _1.nvm, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2_Lucene41_0.tip, _2.fdx, _1.fnm, _2.fnm, _1_Lucene41_0.pos, _1.fdx, _1.fdt, _1.si, segments_6]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_7,generation=7,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _1.nvm, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _1_Lucene41_0.pos, segments_7, _3_Lucene41_0.doc, _1.fdt, _1.si, _3.si]
[junit4:junit4]   1> 57603 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – newest commit = 7[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _1.nvm, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _1_Lucene41_0.pos, segments_7, _3_Lucene41_0.doc, _1.fdt, _1.si, _3.si]
[junit4:junit4]   1> 57606 [qtp755696446-222] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@9b82279 main
[junit4:junit4]   1> 57607 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 57607 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@9b82279 main{StandardDirectoryReader(segments_7:11:nrt _1(4.3):C12 _2(4.3):C1 _3(4.3):C1)}
[junit4:junit4]   1> 57607 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 57610 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@9b82279 main{StandardDirectoryReader(segments_7:11:nrt _1(4.3):C12 _2(4.3):C1 _3(4.3):C1)}
[junit4:junit4]   1> 57826 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 57842 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_7,generation=7,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _1.nvm, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _1_Lucene41_0.pos, segments_7, _3_Lucene41_0.doc, _1.fdt, _1.si, _3.si]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_8,generation=8,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _1.nvm, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _1_Lucene41_0.pos, segments_8, _3_Lucene41_0.doc, _1.fdt, _1.si, _3.si]
[junit4:junit4]   1> 57843 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – newest commit = 8[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _1.nvm, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _1_Lucene41_0.pos, segments_8, _3_Lucene41_0.doc, _1.fdt, _1.si, _3.si]
[junit4:junit4]   1> 57844 [qtp755696446-222] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@7d582c6 main
[junit4:junit4]   1> 57845 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 57845 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@7d582c6 main{StandardDirectoryReader(segments_7:11:nrt _1(4.3):C12 _2(4.3):C1 _3(4.3):C1)}
[junit4:junit4]   1> 57845 [qtp755696446-222] 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 (1431211812012949504)],commit=,commit=} 0 269
[junit4:junit4]   1> 57845 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 57847 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 43 is 200
[junit4:junit4]   1> 57848 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@eb9012c
[junit4:junit4]   1> 57848 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@7d582c6 main{StandardDirectoryReader(segments_7:11:nrt _1(4.3):C12 _2(4.3):C1 _3(4.3):C1)}
[junit4:junit4]   1> 57852 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 57892 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_8,generation=8,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _1.nvm, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _1_Lucene41_0.pos, segments_8, _3_Lucene41_0.doc, _1.fdt, _1.si, _3.si]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_9,generation=9,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _4_Lucene41_0.doc, _4.fnm, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _4.fdx, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _1.nvm, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _4.si, _4.fdt, _2_Lucene41_0.doc, _4_Lucene41_0.tip, _2.fdx, _4_Lucene41_0.tim, _2.fnm, segments_9, _3.fdx, _1.fdx, _1_Lucene41_0.pos, _3_Lucene41_0.doc, _1.fdt, _1.si, _3.si]
[junit4:junit4]   1> 57893 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 9[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _4_Lucene41_0.doc, _4.fnm, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _4.fdx, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _1.nvm, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _4.si, _4.fdt, _2_Lucene41_0.doc, _4_Lucene41_0.tip, _2.fdx, _4_Lucene41_0.tim, _2.fnm, segments_9, _3.fdx, _1.fdx, _1_Lucene41_0.pos, _3_Lucene41_0.doc, _1.fdt, _1.si, _3.si]
[junit4:junit4]   1> 57896 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@171591e3 main
[junit4:junit4]   1> 57897 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 57897 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@171591e3 main{StandardDirectoryReader(segments_9:13:nrt _1(4.3):C12 _2(4.3):C1 _3(4.3):C1 _4(4.3):C1)}
[junit4:junit4]   1> 57898 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 57900 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@171591e3 main{StandardDirectoryReader(segments_9:13:nrt _1(4.3):C12 _2(4.3):C1 _3(4.3):C1 _4(4.3):C1)}
[junit4:junit4]   1> 57900 [qtp755696446-220] 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 (1431211812301307904)],commit=} 0 49
[junit4:junit4]   1> 57902 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – blocking for commit/optimize
[junit4:junit4]   1> 57902 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@91cd9ec
[junit4:junit4]   1> 57907 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 57932 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_9,generation=9,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _4_Lucene41_0.doc, _4.fnm, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _4.fdx, _3_Lucene41_0.tip, _3_Lucene41_0.tim, _1.nvm, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _4.si, _4.fdt, _2_Lucene41_0.doc, _4_Lucene41_0.tip, _2.fdx, _4_Lucene41_0.tim, _2.fnm, segments_9, _3.fdx, _1.fdx, _1_Lucene41_0.pos, _3_Lucene41_0.doc, _1.fdt, _1.si, _3.si]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_a,generation=10,filenames=[_2.si, _5.fdt, _1_Lucene41_0.doc, _5_Lucene41_0.tim, _2_Lucene41_0.tim, _4_Lucene41_0.doc, _5_Lucene41_0.tip, _4.fnm, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _4.fdx, _3_Lucene41_0.tip, _5_Lucene41_0.doc, _3_Lucene41_0.tim, _1.nvm, _5.fnm, segments_a, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _4.si, _4.fdt, _2_Lucene41_0.doc, _4_Lucene41_0.tip, _2.fdx, _4_Lucene41_0.tim, _2.fnm, _5.si, _3.fdx, _1.fdx, _1_Lucene41_0.pos, _3_Lucene41_0.doc, _1.fdt, _1.si, _5.fdx, _3.si]
[junit4:junit4]   1> 57933 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 10[_2.si, _5.fdt, _1_Lucene41_0.doc, _5_Lucene41_0.tim, _2_Lucene41_0.tim, _4_Lucene41_0.doc, _5_Lucene41_0.tip, _4.fnm, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _4.fdx, _3_Lucene41_0.tip, _5_Lucene41_0.doc, _3_Lucene41_0.tim, _1.nvm, _5.fnm, segments_a, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _4.si, _4.fdt, _2_Lucene41_0.doc, _4_Lucene41_0.tip, _2.fdx, _4_Lucene41_0.tim, _2.fnm, _5.si, _3.fdx, _1.fdx, _1_Lucene41_0.pos, _3_Lucene41_0.doc, _1.fdt, _1.si, _5.fdx, _3.si]
[junit4:junit4]   1> 57936 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@674612ad main
[junit4:junit4]   1> 57937 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 57937 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@674612ad main{StandardDirectoryReader(segments_a:15:nrt _1(4.3):C12 _2(4.3):C1 _3(4.3):C1 _4(4.3):C1 _5(4.3):C1)}
[junit4:junit4]   1> 57938 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 57939 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@674612ad main{StandardDirectoryReader(segments_a:15:nrt _1(4.3):C12 _2(4.3):C1 _3(4.3):C1 _4(4.3):C1 _5(4.3):C1)}
[junit4:junit4]   1> 58155 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 58173 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_a,generation=10,filenames=[_2.si, _5.fdt, _1_Lucene41_0.doc, _5_Lucene41_0.tim, _2_Lucene41_0.tim, _4_Lucene41_0.doc, _5_Lucene41_0.tip, _4.fnm, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _4.fdx, _3_Lucene41_0.tip, _5_Lucene41_0.doc, _3_Lucene41_0.tim, _1.nvm, _5.fnm, segments_a, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _4.si, _4.fdt, _2_Lucene41_0.doc, _4_Lucene41_0.tip, _2.fdx, _4_Lucene41_0.tim, _2.fnm, _5.si, _3.fdx, _1.fdx, _1_Lucene41_0.pos, _3_Lucene41_0.doc, _1.fdt, _1.si, _5.fdx, _3.si]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_b,generation=11,filenames=[_2.si, _5.fdt, _1_Lucene41_0.doc, _5_Lucene41_0.tim, _2_Lucene41_0.tim, _4_Lucene41_0.doc, _5_Lucene41_0.tip, _4.fnm, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _4.fdx, _3_Lucene41_0.tip, _5_Lucene41_0.doc, _3_Lucene41_0.tim, _1.nvm, _5.fnm, segments_b, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _4.si, _4.fdt, _2_Lucene41_0.doc, _4_Lucene41_0.tip, _2.fdx, _4_Lucene41_0.tim, _2.fnm, _5.si, _3.fdx, _1.fdx, _1_Lucene41_0.pos, _3_Lucene41_0.doc, _1.fdt, _1.si, _5.fdx, _3.si]
[junit4:junit4]   1> 58173 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 11[_2.si, _5.fdt, _1_Lucene41_0.doc, _5_Lucene41_0.tim, _2_Lucene41_0.tim, _4_Lucene41_0.doc, _5_Lucene41_0.tip, _4.fnm, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _4.fdx, _3_Lucene41_0.tip, _5_Lucene41_0.doc, _3_Lucene41_0.tim, _1.nvm, _5.fnm, segments_b, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _4.si, _4.fdt, _2_Lucene41_0.doc, _4_Lucene41_0.tip, _2.fdx, _4_Lucene41_0.tim, _2.fnm, _5.si, _3.fdx, _1.fdx, _1_Lucene41_0.pos, _3_Lucene41_0.doc, _1.fdt, _1.si, _5.fdx, _3.si]
[junit4:junit4]   1> 58174 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@500dffbf main
[junit4:junit4]   1> 58175 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 58176 [qtp755696446-219] 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 (1431211812358979584)],commit=,commit=} 0 271
[junit4:junit4]   1> 58175 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@500dffbf main{StandardDirectoryReader(segments_a:15:nrt _1(4.3):C12 _2(4.3):C1 _3(4.3):C1 _4(4.3):C1 _5(4.3):C1)}
[junit4:junit4]   1> 58176 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 58176 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 45 is 200
[junit4:junit4]   1> 58177 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@91cd9ec
[junit4:junit4]   1> 58178 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@500dffbf main{StandardDirectoryReader(segments_a:15:nrt _1(4.3):C12 _2(4.3):C1 _3(4.3):C1 _4(4.3):C1 _5(4.3):C1)}
[junit4:junit4]   1> 58181 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 58212 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_b,generation=11,filenames=[_2.si, _5.fdt, _1_Lucene41_0.doc, _5_Lucene41_0.tim, _2_Lucene41_0.tim, _4_Lucene41_0.doc, _5_Lucene41_0.tip, _4.fnm, _3.fnm, _3.fdt, _2_Lucene41_0.tip, _1.fnm, _4.fdx, _3_Lucene41_0.tip, _5_Lucene41_0.doc, _3_Lucene41_0.tim, _1.nvm, _5.fnm, segments_b, _1_Lucene41_0.tim, _1.nvd, _2.fdt, _1_Lucene41_0.tip, _4.si, _4.fdt, _2_Lucene41_0.doc, _4_Lucene41_0.tip, _2.fdx, _4_Lucene41_0.tim, _2.fnm, _5.si, _3.fdx, _1.fdx, _1_Lucene41_0.pos, _3_Lucene41_0.doc, _1.fdt, _1.si, _5.fdx, _3.si]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_c,generation=12,filenames=[_7.fnm, segments_c, _7.nvd, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.pos, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc, _7.nvm]
[junit4:junit4]   1> 58213 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – newest commit = 12[_7.fnm, segments_c, _7.nvd, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.pos, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc, _7.nvm]
[junit4:junit4]   1> 58220 [qtp755696446-222] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@60ac8067 main
[junit4:junit4]   1> 58221 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 58221 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@60ac8067 main{StandardDirectoryReader(segments_c:19:nrt _7(4.3):C17)}
[junit4:junit4]   1> 58221 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 58224 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@60ac8067 main{StandardDirectoryReader(segments_c:19:nrt _7(4.3):C17)}
[junit4:junit4]   1> 58227 [qtp755696446-222] 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 (1431211812646289408)],optimize=} 0 47
[junit4:junit4]   1> 58229 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – blocking for commit/optimize
[junit4:junit4]   1> 58229 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1f54362f
[junit4:junit4]   1> 58234 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=true,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 58342 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_c,generation=12,filenames=[_7.fnm, segments_c, _7.nvd, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.pos, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc, _7.nvm]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_d,generation=13,filenames=[_9_Lucene41_0.doc, _9.nvd, segments_d, _9.fnm, _9.fdt, _9.si, _9_Lucene41_0.pos, _9.nvm, _9_Lucene41_0.tip, _9.fdx, _9_Lucene41_0.tim]
[junit4:junit4]   1> 58343 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 13[_9_Lucene41_0.doc, _9.nvd, segments_d, _9.fnm, _9.fdt, _9.si, _9_Lucene41_0.pos, _9.nvm, _9_Lucene41_0.tip, _9.fdx, _9_Lucene41_0.tim]
[junit4:junit4]   1> 58349 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@37420057 main
[junit4:junit4]   1> 58350 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 58350 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@37420057 main{StandardDirectoryReader(segments_d:23:nrt _9(4.3):C18)}
[junit4:junit4]   1> 58351 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 58353 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@37420057 main{StandardDirectoryReader(segments_d:23:nrt _9(4.3):C18)}
[junit4:junit4]   1> 58482 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=true,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 58512 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_d,generation=13,filenames=[_9_Lucene41_0.doc, _9.nvd, segments_d, _9.fnm, _9.fdt, _9.si, _9_Lucene41_0.pos, _9.nvm, _9_Lucene41_0.tip, _9.fdx, _9_Lucene41_0.tim]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_e,generation=14,filenames=[segments_e, _9_Lucene41_0.doc, _9.nvd, _9.fnm, _9.fdt, _9.si, _9_Lucene41_0.pos, _9.nvm, _9_Lucene41_0.tip, _9.fdx, _9_Lucene41_0.tim]
[junit4:junit4]   1> 58513 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 14[segments_e, _9_Lucene41_0.doc, _9.nvd, _9.fnm, _9.fdt, _9.si, _9_Lucene41_0.pos, _9.nvm, _9_Lucene41_0.tip, _9.fdx, _9_Lucene41_0.tim]
[junit4:junit4]   1> 58514 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@33da0e8 main
[junit4:junit4]   1> 58516 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 58516 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@33da0e8 main{StandardDirectoryReader(segments_d:23:nrt _9(4.3):C18)}
[junit4:junit4]   1> 58517 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 58516 [qtp755696446-220] 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 (1431211812701863936)],optimize=,optimize=} 0 284
[junit4:junit4]   1> 58518 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 47 is 200
[junit4:junit4]   1> 58519 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1f54362f
[junit4:junit4]   1> 58519 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@33da0e8 main{StandardDirectoryReader(segments_d:23:nrt _9(4.3):C18)}
[junit4:junit4]   1> 58524 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 58562 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_e,generation=14,filenames=[segments_e, _9_Lucene41_0.doc, _9.nvd, _9.fnm, _9.fdt, _9.si, _9_Lucene41_0.pos, _9.nvm, _9_Lucene41_0.tip, _9.fdx, _9_Lucene41_0.tim]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_f,generation=15,filenames=[_b_Lucene41_0.pos, segments_f, _b.si, _b_Lucene41_0.tip, _b.nvm, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx, _b.nvd]
[junit4:junit4]   1> 58564 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – newest commit = 15[_b_Lucene41_0.pos, segments_f, _b.si, _b_Lucene41_0.tip, _b.nvm, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx, _b.nvd]
[junit4:junit4]   1> 58570 [qtp755696446-222] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@3907f838 main
[junit4:junit4]   1> 58571 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@3907f838 main{StandardDirectoryReader(segments_f:27:nrt _b(4.3):C19)}
[junit4:junit4]   1> 58571 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 58574 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 58574 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@3907f838 main{StandardDirectoryReader(segments_f:27:nrt _b(4.3):C19)}
[junit4:junit4]   1> 58579 [qtp755696446-222] 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 (1431211813005950976)],optimize=} 0 57
[junit4:junit4]   1> 58580 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@362f58d7
[junit4:junit4]   1> 58585 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=true,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 58662 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_f,generation=15,filenames=[_b_Lucene41_0.pos, segments_f, _b.si, _b_Lucene41_0.tip, _b.nvm, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, _b.fdx, _b.nvd]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_g,generation=16,filenames=[_d.fdt, _d.nvd, _d.si, _d_Lucene41_0.tim, _d.nvm, _d.fnm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _d_Lucene41_0.pos, segments_g, _d.fdx]
[junit4:junit4]   1> 58663 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 16[_d.fdt, _d.nvd, _d.si, _d_Lucene41_0.tim, _d.nvm, _d.fnm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _d_Lucene41_0.pos, segments_g, _d.fdx]
[junit4:junit4]   1> 58670 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@691269e5 main
[junit4:junit4]   1> 58671 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@691269e5 main{StandardDirectoryReader(segments_g:31:nrt _d(4.3):C20)}
[junit4:junit4]   1> 58671 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 58674 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 58674 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@691269e5 main{StandardDirectoryReader(segments_g:31:nrt _d(4.3):C20)}
[junit4:junit4]   1> 58833 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=true,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 58862 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_g,generation=16,filenames=[_d.fdt, _d.nvd, _d.si, _d_Lucene41_0.tim, _d.nvm, _d.fnm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _d_Lucene41_0.pos, segments_g, _d.fdx]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_h,generation=17,filenames=[_d.fdt, _d.nvd, _d.si, _d_Lucene41_0.tim, _d.nvm, _d.fnm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _d_Lucene41_0.pos, _d.fdx, segments_h]
[junit4:junit4]   1> 58863 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 17[_d.fdt, _d.nvd, _d.si, _d_Lucene41_0.tim, _d.nvm, _d.fnm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _d_Lucene41_0.pos, _d.fdx, segments_h]
[junit4:junit4]   1> 58864 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@101b2f74 main
[junit4:junit4]   1> 58865 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 58865 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@101b2f74 main{StandardDirectoryReader(segments_g:31:nrt _d(4.3):C20)}
[junit4:junit4]   1> 58866 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 58866 [qtp755696446-221] 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 (1431211813069914112)],optimize=,optimize=} 0 283
[junit4:junit4]   1> 58869 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@101b2f74 main{StandardDirectoryReader(segments_g:31:nrt _d(4.3):C20)}
[junit4:junit4]   1> 58869 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 49 is 200
[junit4:junit4]   1> 58871 [concurrentUpdateScheduler-2-thread-1] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@362f58d7
[junit4:junit4]   1> 259130 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 259130 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testWaitOptions
[junit4:junit4]   1> 259145 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_h,generation=17,filenames=[_d.fdt, _d.nvd, _d.si, _d_Lucene41_0.tim, _d.nvm, _d.fnm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _d_Lucene41_0.pos, _d.fdx, segments_h]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_i,generation=18,filenames=[_d.fdt, _d.nvd, _d.si, _d_Lucene41_0.tim, _d.nvm, _d.fnm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _d_Lucene41_0.pos, segments_i, _d.fdx]
[junit4:junit4]   1> 259146 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 18[_d.fdt, _d.nvd, _d.si, _d_Lucene41_0.tim, _d.nvm, _d.fnm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _d_Lucene41_0.pos, segments_i, _d.fdx]
[junit4:junit4]   1> 259147 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@206fa88c main
[junit4:junit4]   1> 259147 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleStreamingTest -Dtests.method=testWaitOptions -Dtests.seed=D62E0EEE93C2243F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en_IN -Dtests.timezone=Asia/Nicosia -Dtests.file.encoding=UTF-8
[junit4:junit4]   1> 259148 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@206fa88c main{StandardDirectoryReader(segments_g:31:nrt _d(4.3):C20)}
[junit4:junit4] ERROR    202s J1 | SolrExampleStreamingTest.testWaitOptions <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:60180/solr
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([D62E0EEE93C2243F:906F8355B0C46ADC]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:416)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[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.embedded.SolrExampleStreamingTest.testWaitOptions(SolrExampleStreamingTest.java:103)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:662)
[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:353)
[junit4:junit4]    > 	... 46 more
[junit4:junit4]   1> 259177 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 259179 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@206fa88c main{StandardDirectoryReader(segments_g:31:nrt _d(4.3):C20)}
[junit4:junit4]   1> 259180 [qtp755696446-221] 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 50
[junit4:junit4]   1> 259183 [TEST-SolrExampleStreamingTest.testAddDelete-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testAddDelete
[junit4:junit4]   1> 259191 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@14dda791 realtime
[junit4:junit4]   1> 259191 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212023420551168)} 0 2
[junit4:junit4]   1> 259193 [concurrentUpdateScheduler-1-thread-2] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@54e3a4a8
[junit4:junit4]   1> 259446 [qtp755696446-221] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1431212023426842624)]} 0 250
[junit4:junit4]   1> 259447 [concurrentUpdateScheduler-1-thread-2] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 & 222 is 200
[junit4:junit4]   1> 259448 [concurrentUpdateScheduler-1-thread-2] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@54e3a4a8
[junit4:junit4]   1> 259450 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 259475 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_i,generation=18,filenames=[_d.fdt, _d.nvd, _d.si, _d_Lucene41_0.tim, _d.nvm, _d.fnm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _d_Lucene41_0.pos, segments_i, _d.fdx]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_j,generation=19,filenames=[_e_Lucene41_0.doc, _e.si, _e.fdt, _e.fdx, _e_Lucene41_0.tim, _e.fnm, segments_j, _e_Lucene41_0.tip]
[junit4:junit4]   1> 259475 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 19[_e_Lucene41_0.doc, _e.si, _e.fdt, _e.fdx, _e_Lucene41_0.tim, _e.fnm, segments_j, _e_Lucene41_0.tip]
[junit4:junit4]   1> 259478 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@70cd7d24 main
[junit4:junit4]   1> 259479 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@70cd7d24 main{StandardDirectoryReader(segments_j:35:nrt _e(4.3):C1)}
[junit4:junit4]   1> 259480 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 259480 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 259482 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@70cd7d24 main{StandardDirectoryReader(segments_j:35:nrt _e(4.3):C1)}
[junit4:junit4]   1> 259484 [qtp755696446-219] 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 34
[junit4:junit4]   1> 259487 [qtp755696446-220] 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> 259492 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1431212023736172544)]} 0 1
[junit4:junit4]   1> 259496 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 259545 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_j,generation=19,filenames=[_e_Lucene41_0.doc, _e.si, _e.fdt, _e.fdx, _e_Lucene41_0.tim, _e.fnm, segments_j, _e_Lucene41_0.tip]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_k,generation=20,filenames=[segments_k]
[junit4:junit4]   1> 259546 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 20[segments_k]
[junit4:junit4]   1> 259547 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@5efd28f7 main
[junit4:junit4]   1> 259548 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@5efd28f7 main{StandardDirectoryReader(segments_k:37:nrt)}
[junit4:junit4]   1> 259548 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 259548 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 259550 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@5efd28f7 main{StandardDirectoryReader(segments_k:37:nrt)}
[junit4:junit4]   1> 259551 [qtp755696446-221] 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 55
[junit4:junit4]   1> 259555 [qtp755696446-219] 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> 259557 [concurrentUpdateScheduler-1-thread-2] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5ab9c149
[junit4:junit4]   1> 259810 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1431212023808524288)]} 0 251
[junit4:junit4]   1> 259811 [concurrentUpdateScheduler-1-thread-2] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 & 222 is 200
[junit4:junit4]   1> 259811 [concurrentUpdateScheduler-1-thread-2] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5ab9c149
[junit4:junit4]   1> 259814 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 259845 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_k,generation=20,filenames=[segments_k]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),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> 259845 [qtp755696446-221] 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> 259848 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@34196273 main
[junit4:junit4]   1> 259848 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@34196273 main{StandardDirectoryReader(segments_l:39:nrt _f(4.3):C1)}
[junit4:junit4]   1> 259848 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 259849 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 259851 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@34196273 main{StandardDirectoryReader(segments_l:39:nrt _f(4.3):C1)}
[junit4:junit4]   1> 259851 [qtp755696446-221] 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 37
[junit4:junit4]   1> 259855 [qtp755696446-222] 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> 259860 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@3fc52f9e realtime
[junit4:junit4]   1> 259861 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=id:"0\ \&\ 222" (-1431212024122048512)} 0 2
[junit4:junit4]   1> 259864 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 259895 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),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.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   1> 259896 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 22[segments_m]
[junit4:junit4]   1> 259897 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@488ac464 main
[junit4:junit4]   1> 259897 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@488ac464 main{StandardDirectoryReader(segments_l:41:nrt)}
[junit4:junit4]   1> 259897 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 259898 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 259900 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@488ac464 main{StandardDirectoryReader(segments_l:41:nrt)}
[junit4:junit4]   1> 259901 [qtp755696446-221] 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 37
[junit4:junit4]   1> 259905 [qtp755696446-219] 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> 259906 [concurrentUpdateScheduler-1-thread-2] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5f62d3f4
[junit4:junit4]   1> 259907 [concurrentUpdateScheduler-1-thread-6] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4981ab45
[junit4:junit4]   1> 259907 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@52ee41b
[junit4:junit4]   1> 259907 [concurrentUpdateScheduler-1-thread-4] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6a4e18a8
[junit4:junit4]   1> 259908 [concurrentUpdateScheduler-1-thread-4] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6a4e18a8
[junit4:junit4]   1> 259906 [concurrentUpdateScheduler-1-thread-3] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@cac98e5
[junit4:junit4]   1> 259909 [concurrentUpdateScheduler-1-thread-3] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@cac98e5
[junit4:junit4]   1> 260159 [qtp755696446-221] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1431212024175525888)]} 0 249
[junit4:junit4]   1> 260161 [concurrentUpdateScheduler-1-thread-2] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 & 222 is 200
[junit4:junit4]   1> 260161 [concurrentUpdateScheduler-1-thread-2] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5f62d3f4
[junit4:junit4]   1> 260164 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 & 222 (1431212024180768768)]} 0 250
[junit4:junit4]   1> 260165 [concurrentUpdateScheduler-1-thread-6] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 1 & 222 is 200
[junit4:junit4]   1> 260166 [concurrentUpdateScheduler-1-thread-6] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4981ab45
[junit4:junit4]   1> 260170 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 & 222 (1431212024182865920)]} 0 250
[junit4:junit4]   1> 260171 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 2 & 222 is 200
[junit4:junit4]   1> 260172 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@52ee41b
[junit4:junit4]   1> 260175 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 260205 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_n,generation=23,filenames=[_g.fdt, _g.si, _g_Lucene41_0.tip, _g.fnm, _g.fdx, _g_Lucene41_0.doc, _g_Lucene41_0.tim, segments_n]
[junit4:junit4]   1> 260206 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 23[_g.fdt, _g.si, _g_Lucene41_0.tip, _g.fnm, _g.fdx, _g_Lucene41_0.doc, _g_Lucene41_0.tim, segments_n]
[junit4:junit4]   1> 260208 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@45f97b85 main
[junit4:junit4]   1> 260209 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@45f97b85 main{StandardDirectoryReader(segments_n:43:nrt _g(4.3):C3)}
[junit4:junit4]   1> 260209 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 260209 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 260211 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@45f97b85 main{StandardDirectoryReader(segments_n:43:nrt _g(4.3):C3)}
[junit4:junit4]   1> 260212 [qtp755696446-221] 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 37
[junit4:junit4]   1> 260216 [qtp755696446-219] 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> 260221 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1431212024500584448), 1 & 222 (-1431212024501633024), 2 & 222 (-1431212024501633025)]} 0 1
[junit4:junit4]   1> 260225 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 260255 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_n,generation=23,filenames=[_g.fdt, _g.si, _g_Lucene41_0.tip, _g.fnm, _g.fdx, _g_Lucene41_0.doc, _g_Lucene41_0.tim, segments_n]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_o,generation=24,filenames=[segments_o]
[junit4:junit4]   1> 260256 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 24[segments_o]
[junit4:junit4]   1> 260257 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@e5a0fc1 main
[junit4:junit4]   1> 260258 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@e5a0fc1 main{StandardDirectoryReader(segments_o:45:nrt)}
[junit4:junit4]   1> 260258 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 260258 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 260260 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@e5a0fc1 main{StandardDirectoryReader(segments_o:45:nrt)}
[junit4:junit4]   1> 260261 [qtp755696446-220] 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 36
[junit4:junit4]   1> 260265 [qtp755696446-219] 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> 260267 [TEST-SolrExampleStreamingTest.testAddDelete-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testAddDelete
[junit4:junit4]   1> 260275 [TEST-SolrExampleStreamingTest.testQueryWithParams-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testQueryWithParams
[junit4:junit4]   1> 260278 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=query&debug=true&wt=xml&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 260281 [TEST-SolrExampleStreamingTest.testQueryWithParams-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testQueryWithParams
[junit4:junit4]   1> 260288 [TEST-SolrExampleStreamingTest.testUnicode-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testUnicode
[junit4:junit4]   1> 260292 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212024575033344)} 0 1
[junit4:junit4]   1> 260294 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@13f287e8
[junit4:junit4]   1> 260548 [qtp755696446-221] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212024582373376), 1 (1431212024582373377), 2 (1431212024587616256), 3 (1431212024587616257), 4 (1431212024588664832), 5 (1431212024588664833), 6 (1431212024588664834), 7 (1431212024589713408)]} 0 251
[junit4:junit4]   1> 260549 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 260549 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@13f287e8
[junit4:junit4]   1> 260552 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 260595 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_o,generation=24,filenames=[segments_o]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_p,generation=25,filenames=[_h_Lucene41_0.tip, _h.fnm, _h.fdt, _h_Lucene41_0.tim, segments_p, _h.si, _h.fdx, _h_Lucene41_0.doc]
[junit4:junit4]   1> 260596 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 25[_h_Lucene41_0.tip, _h.fnm, _h.fdt, _h_Lucene41_0.tim, segments_p, _h.si, _h.fdx, _h_Lucene41_0.doc]
[junit4:junit4]   1> 260598 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@71290aac main
[junit4:junit4]   1> 260599 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@71290aac main{StandardDirectoryReader(segments_p:47:nrt _h(4.3):C8)}
[junit4:junit4]   1> 260599 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 260599 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 260601 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@71290aac main{StandardDirectoryReader(segments_p:47:nrt _h(4.3):C8)}
[junit4:junit4]   1> 260602 [qtp755696446-219] 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 50
[junit4:junit4]   1> 260606 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=8} hits=8 status=0 QTime=1 
[junit4:junit4]   1> 260613 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@13b3f83 realtime
[junit4:junit4]   1> 260613 [qtp755696446-221] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212024911626240)} 0 1
[junit4:junit4]   1> 260615 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@58c6b216
[junit4:junit4]   1> 260869 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212024918966272), 1 (1431212024920014848), 2 (1431212024921063424), 3 (1431212024921063425), 4 (1431212024921063426), 5 (1431212024922112000), 6 (1431212024922112001), 7 (1431212024922112002), 8 (1431212024923160576), 9 (1431212024923160577), ... (19 adds)]} 0 250
[junit4:junit4]   1> 260870 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 260871 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@58c6b216
[junit4:junit4]   1> 260873 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 260905 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_p,generation=25,filenames=[_h_Lucene41_0.tip, _h.fnm, _h.fdt, _h_Lucene41_0.tim, segments_p, _h.si, _h.fdx, _h_Lucene41_0.doc]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_q,generation=26,filenames=[_i_Lucene41_0.doc, _i_Lucene41_0.tim, segments_q, _i_Lucene41_0.tip, _i.fdx, _i.fnm, _i.si, _i.fdt]
[junit4:junit4]   1> 260906 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 26[_i_Lucene41_0.doc, _i_Lucene41_0.tim, segments_q, _i_Lucene41_0.tip, _i.fdx, _i.fnm, _i.si, _i.fdt]
[junit4:junit4]   1> 260908 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@caf2020 main
[junit4:junit4]   1> 260910 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 260910 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@caf2020 main{StandardDirectoryReader(segments_q:51:nrt _i(4.3):C19)}
[junit4:junit4]   1> 260910 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 260912 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@caf2020 main{StandardDirectoryReader(segments_q:51:nrt _i(4.3):C19)}
[junit4:junit4]   1> 260913 [qtp755696446-220] 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 40
[junit4:junit4]   1> 260917 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=19} hits=19 status=0 QTime=0 
[junit4:junit4]   1> 260928 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@d883a4c realtime
[junit4:junit4]   1> 260929 [qtp755696446-219] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212025241927680)} 0 2
[junit4:junit4]   1> 260932 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6aab5a84
[junit4:junit4]   1> 261185 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212025250316288), 1 (1431212025251364864), 2 (1431212025251364865), 3 (1431212025251364866), 4 (1431212025252413440), 5 (1431212025252413441), 6 (1431212025253462016), 7 (1431212025253462017), 8 (1431212025253462018), 9 (1431212025254510592)]} 0 250
[junit4:junit4]   1> 261186 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 261186 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6aab5a84
[junit4:junit4]   1> 261189 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 261225 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_q,generation=26,filenames=[_i_Lucene41_0.doc, _i_Lucene41_0.tim, segments_q, _i_Lucene41_0.tip, _i.fdx, _i.fnm, _i.si, _i.fdt]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_r,generation=27,filenames=[_j.fdx, _j.si, _j.fdt, segments_r, _j_Lucene41_0.tip, _j.fnm, _j_Lucene41_0.doc, _j_Lucene41_0.tim]
[junit4:junit4]   1> 261226 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 27[_j.fdx, _j.si, _j.fdt, segments_r, _j_Lucene41_0.tip, _j.fnm, _j_Lucene41_0.doc, _j_Lucene41_0.tim]
[junit4:junit4]   1> 261228 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1dcd6a33 main
[junit4:junit4]   1> 261229 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1dcd6a33 main{StandardDirectoryReader(segments_r:55:nrt _j(4.3):C10)}
[junit4:junit4]   1> 261229 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 261230 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 261232 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1dcd6a33 main{StandardDirectoryReader(segments_r:55:nrt _j(4.3):C10)}
[junit4:junit4]   1> 261233 [qtp755696446-221] 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 44
[junit4:junit4]   1> 261237 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=10} hits=10 status=0 QTime=0 
[junit4:junit4]   1> 261244 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@7e9caac realtime
[junit4:junit4]   1> 261244 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212025573277696)} 0 2
[junit4:junit4]   1> 261246 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5f8e643
[junit4:junit4]   1> 261501 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212025581666304), 1 (1431212025581666305), 2 (1431212025582714880), 3 (1431212025582714881), 4 (1431212025583763456), 5 (1431212025583763457), 6 (1431212025583763458), 7 (1431212025584812032), 8 (1431212025584812033), 9 (1431212025584812034), ... (26 adds)]} 0 251
[junit4:junit4]   1> 261502 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 261503 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5f8e643
[junit4:junit4]   1> 261506 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 261535 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_r,generation=27,filenames=[_j.fdx, _j.si, _j.fdt, segments_r, _j_Lucene41_0.tip, _j.fnm, _j_Lucene41_0.doc, _j_Lucene41_0.tim]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_s,generation=28,filenames=[segments_s, _k_Lucene41_0.tim, _k.fdt, _k_Lucene41_0.tip, _k.fdx, _k_Lucene41_0.doc, _k.fnm, _k.si]
[junit4:junit4]   1> 261536 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 28[segments_s, _k_Lucene41_0.tim, _k.fdt, _k_Lucene41_0.tip, _k.fdx, _k_Lucene41_0.doc, _k.fnm, _k.si]
[junit4:junit4]   1> 261538 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@610859c0 main
[junit4:junit4]   1> 261539 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@610859c0 main{StandardDirectoryReader(segments_s:59:nrt _k(4.3):C26)}
[junit4:junit4]   1> 261539 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 261540 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 261542 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@610859c0 main{StandardDirectoryReader(segments_s:59:nrt _k(4.3):C26)}
[junit4:junit4]   1> 261543 [qtp755696446-219] 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 38
[junit4:junit4]   1> 261547 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=26} hits=26 status=0 QTime=0 
[junit4:junit4]   1> 261560 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@6a9c1826 realtime
[junit4:junit4]   1> 261561 [qtp755696446-221] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212025903579136)} 0 3
[junit4:junit4]   1> 261563 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@2df59d78
[junit4:junit4]   1> 261817 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212025913016320), 1 (1431212025914064896), 2 (1431212025914064897), 3 (1431212025915113472), 4 (1431212025915113473), 5 (1431212025915113474), 6 (1431212025916162048), 7 (1431212025916162049), 8 (1431212025916162050), 9 (1431212025917210624), ... (25 adds)]} 0 251
[junit4:junit4]   1> 261818 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 261819 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@2df59d78
[junit4:junit4]   1> 261822 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 261855 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_s,generation=28,filenames=[segments_s, _k_Lucene41_0.tim, _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.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),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> 261856 [qtp755696446-220] 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> 261860 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@549e3d80 main
[junit4:junit4]   1> 261861 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@549e3d80 main{StandardDirectoryReader(segments_t:63:nrt _l(4.3):C25)}
[junit4:junit4]   1> 261862 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 261862 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 261863 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@549e3d80 main{StandardDirectoryReader(segments_t:63:nrt _l(4.3):C25)}
[junit4:junit4]   1> 261865 [qtp755696446-220] 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 43
[junit4:junit4]   1> 261868 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=25} hits=25 status=0 QTime=0 
[junit4:junit4]   1> 261879 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@4cafad79 realtime
[junit4:junit4]   1> 261879 [qtp755696446-219] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212026238074880)} 0 2
[junit4:junit4]   1> 261881 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@21a60a44
[junit4:junit4]   1> 262135 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212026246463488), 1 (1431212026247512064), 2 (1431212026247512065), 3 (1431212026248560640), 4 (1431212026248560641), 5 (1431212026248560642), 6 (1431212026249609216), 7 (1431212026249609217), 8 (1431212026249609218), 9 (1431212026250657792), ... (25 adds)]} 0 251
[junit4:junit4]   1> 262136 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 262137 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@21a60a44
[junit4:junit4]   1> 262140 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 262175 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),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.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_u,generation=30,filenames=[_m.si, _m_Lucene41_0.tim, segments_u, _m.fdt, _m_Lucene41_0.doc, _m_Lucene41_0.tip, _m.fdx, _m.fnm]
[junit4:junit4]   1> 262176 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 30[_m.si, _m_Lucene41_0.tim, segments_u, _m.fdt, _m_Lucene41_0.doc, _m_Lucene41_0.tip, _m.fdx, _m.fnm]
[junit4:junit4]   1> 262178 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@44a52b30 main
[junit4:junit4]   1> 262179 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@44a52b30 main{StandardDirectoryReader(segments_u:67:nrt _m(4.3):C25)}
[junit4:junit4]   1> 262180 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 262180 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 262182 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@44a52b30 main{StandardDirectoryReader(segments_u:67:nrt _m(4.3):C25)}
[junit4:junit4]   1> 262183 [qtp755696446-221] 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 43
[junit4:junit4]   1> 262187 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=25} hits=25 status=0 QTime=0 
[junit4:junit4]   1> 262200 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1759817d realtime
[junit4:junit4]   1> 262201 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212026574667776)} 0 3
[junit4:junit4]   1> 262203 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@75444184
[junit4:junit4]   1> 262457 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212026584104960), 1 (1431212026585153536), 2 (1431212026586202112), 3 (1431212026586202113), 4 (1431212026586202114), 5 (1431212026587250688), 6 (1431212026587250689), 7 (1431212026587250690), 8 (1431212026588299264), 9 (1431212026588299265)]} 0 250
[junit4:junit4]   1> 262458 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 262459 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@75444184
[junit4:junit4]   1> 262461 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 262495 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_u,generation=30,filenames=[_m.si, _m_Lucene41_0.tim, segments_u, _m.fdt, _m_Lucene41_0.doc, _m_Lucene41_0.tip, _m.fdx, _m.fnm]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_v,generation=31,filenames=[_n_Lucene41_0.doc, segments_v, _n_Lucene41_0.tim, _n.si, _n_Lucene41_0.tip, _n.fdt, _n.fdx, _n.fnm]
[junit4:junit4]   1> 262496 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 31[_n_Lucene41_0.doc, segments_v, _n_Lucene41_0.tim, _n.si, _n_Lucene41_0.tip, _n.fdt, _n.fdx, _n.fnm]
[junit4:junit4]   1> 262498 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@4b3788e1 main
[junit4:junit4]   1> 262499 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 262499 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@4b3788e1 main{StandardDirectoryReader(segments_v:71:nrt _n(4.3):C10)}
[junit4:junit4]   1> 262500 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 262502 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@4b3788e1 main{StandardDirectoryReader(segments_v:71:nrt _n(4.3):C10)}
[junit4:junit4]   1> 262503 [qtp755696446-221] 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 42
[junit4:junit4]   1> 262507 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=10} hits=10 status=0 QTime=0 
[junit4:junit4]   1> 262514 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1855590e realtime
[junit4:junit4]   1> 262515 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212026904969216)} 0 2
[junit4:junit4]   1> 262516 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@b49b29d
[junit4:junit4]   1> 262770 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212026912309248), 1 (1431212026913357824), 2 (1431212026914406400), 3 (1431212026914406401), 4 (1431212026915454976), 5 (1431212026915454977), 6 (1431212026916503552), 7 (1431212026916503553), 8 (1431212026917552128), 9 (1431212026917552129), ... (11 adds)]} 0 250
[junit4:junit4]   1> 262771 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 262771 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@b49b29d
[junit4:junit4]   1> 262774 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 262805 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_v,generation=31,filenames=[_n_Lucene41_0.doc, segments_v, _n_Lucene41_0.tim, _n.si, _n_Lucene41_0.tip, _n.fdt, _n.fdx, _n.fnm]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_w,generation=32,filenames=[_o.fdt, _o_Lucene41_0.tim, _o.fnm, _o.fdx, _o_Lucene41_0.tip, _o.si, _o_Lucene41_0.doc, segments_w]
[junit4:junit4]   1> 262806 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 32[_o.fdt, _o_Lucene41_0.tim, _o.fnm, _o.fdx, _o_Lucene41_0.tip, _o.si, _o_Lucene41_0.doc, segments_w]
[junit4:junit4]   1> 262808 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@744e390a main
[junit4:junit4]   1> 262809 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 262809 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@744e390a main{StandardDirectoryReader(segments_w:75:nrt _o(4.3):C11)}
[junit4:junit4]   1> 262810 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 262812 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@744e390a main{StandardDirectoryReader(segments_w:75:nrt _o(4.3):C11)}
[junit4:junit4]   1> 262813 [qtp755696446-220] 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 39
[junit4:junit4]   1> 262817 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=11} hits=11 status=0 QTime=0 
[junit4:junit4]   1> 262824 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1c714963 realtime
[junit4:junit4]   1> 262825 [qtp755696446-219] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212027230027776)} 0 2
[junit4:junit4]   1> 262827 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5189112b
[junit4:junit4]   1> 263081 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212027238416384), 1 (1431212027239464960), 2 (1431212027240513536), 3 (1431212027240513537), 4 (1431212027241562112), 5 (1431212027241562113), 6 (1431212027242610688), 7 (1431212027242610689), 8 (1431212027243659264), 9 (1431212027243659265), ... (26 adds)]} 0 251
[junit4:junit4]   1> 263082 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 263083 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5189112b
[junit4:junit4]   1> 263085 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 263115 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_w,generation=32,filenames=[_o.fdt, _o_Lucene41_0.tim, _o.fnm, _o.fdx, _o_Lucene41_0.tip, _o.si, _o_Lucene41_0.doc, segments_w]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_x,generation=33,filenames=[_p.fdx, _p_Lucene41_0.doc, _p_Lucene41_0.tim, _p.fnm, _p.fdt, _p_Lucene41_0.tip, _p.si, segments_x]
[junit4:junit4]   1> 263116 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 33[_p.fdx, _p_Lucene41_0.doc, _p_Lucene41_0.tim, _p.fnm, _p.fdt, _p_Lucene41_0.tip, _p.si, segments_x]
[junit4:junit4]   1> 263118 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@56b2d6bd main
[junit4:junit4]   1> 263119 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 263119 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@56b2d6bd main{StandardDirectoryReader(segments_x:79:nrt _p(4.3):C26)}
[junit4:junit4]   1> 263120 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 263122 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@56b2d6bd main{StandardDirectoryReader(segments_x:79:nrt _p(4.3):C26)}
[junit4:junit4]   1> 263123 [qtp755696446-221] 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 38
[junit4:junit4]   1> 263127 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=26} hits=26 status=0 QTime=0 
[junit4:junit4]   1> 263137 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@43e3cae realtime
[junit4:junit4]   1> 263138 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212027558232064)} 0 2
[junit4:junit4]   1> 263139 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@d1d1bb6
[junit4:junit4]   1> 263392 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212027565572096), 1 (1431212027566620672), 2 (1431212027567669248), 3 (1431212027567669249), 4 (1431212027568717824), 5 (1431212027568717825), 6 (1431212027569766400), 7 (1431212027569766401)]} 0 249
[junit4:junit4]   1> 263394 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 263395 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@d1d1bb6
[junit4:junit4]   1> 263397 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 263445 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_x,generation=33,filenames=[_p.fdx, _p_Lucene41_0.doc, _p_Lucene41_0.tim, _p.fnm, _p.fdt, _p_Lucene41_0.tip, _p.si, segments_x]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_y,generation=34,filenames=[_q.si, _q_Lucene41_0.tip, _q_Lucene41_0.tim, _q.fdx, _q_Lucene41_0.doc, _q.fnm, _q.fdt, segments_y]
[junit4:junit4]   1> 263446 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 34[_q.si, _q_Lucene41_0.tip, _q_Lucene41_0.tim, _q.fdx, _q_Lucene41_0.doc, _q.fnm, _q.fdt, segments_y]
[junit4:junit4]   1> 263448 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@52508a76 main
[junit4:junit4]   1> 263449 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 263450 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@52508a76 main{StandardDirectoryReader(segments_y:83:nrt _q(4.3):C8)}
[junit4:junit4]   1> 263450 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 263453 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@52508a76 main{StandardDirectoryReader(segments_y:83:nrt _q(4.3):C8)}
[junit4:junit4]   1> 263455 [qtp755696446-219] 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 58
[junit4:junit4]   1> 263459 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=8} hits=8 status=0 QTime=1 
[junit4:junit4]   1> 263467 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1e6c6735 realtime
[junit4:junit4]   1> 263468 [qtp755696446-221] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212027904262144)} 0 3
[junit4:junit4]   1> 263470 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5eebd82d
[junit4:junit4]   1> 263723 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212027912650752), 1 (1431212027913699328), 2 (1431212027914747904), 3 (1431212027914747905)]} 0 250
[junit4:junit4]   1> 263725 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 263725 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5eebd82d
[junit4:junit4]   1> 263728 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 263755 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_y,generation=34,filenames=[_q.si, _q_Lucene41_0.tip, _q_Lucene41_0.tim, _q.fdx, _q_Lucene41_0.doc, _q.fnm, _q.fdt, segments_y]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_z,generation=35,filenames=[_r.fdt, _r.fnm, _r.fdx, _r_Lucene41_0.tip, _r_Lucene41_0.tim, _r.si, _r_Lucene41_0.doc, segments_z]
[junit4:junit4]   1> 263756 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 35[_r.fdt, _r.fnm, _r.fdx, _r_Lucene41_0.tip, _r_Lucene41_0.tim, _r.si, _r_Lucene41_0.doc, segments_z]
[junit4:junit4]   1> 263758 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@79436fa7 main
[junit4:junit4]   1> 263759 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@79436fa7 main{StandardDirectoryReader(segments_z:87:nrt _r(4.3):C4)}
[junit4:junit4]   1> 263759 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 263760 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 263765 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@79436fa7 main{StandardDirectoryReader(segments_z:87:nrt _r(4.3):C4)}
[junit4:junit4]   1> 263766 [qtp755696446-219] 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 38
[junit4:junit4]   1> 263771 [qtp755696446-220] 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=1 
[junit4:junit4]   1> 263778 [qtp755696446-222] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@2a21ecee realtime
[junit4:junit4]   1> 263779 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212028229320704)} 0 3
[junit4:junit4]   1> 263781 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@194a12de
[junit4:junit4]   1> 264035 [qtp755696446-219] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1431212028238757888), 1 (1431212028239806464), 2 (1431212028239806465), 3 (1431212028239806466), 4 (1431212028240855040), 5 (1431212028240855041), 6 (1431212028240855042), 7 (1431212028241903616), 8 (1431212028241903617), 9 (1431212028242952192), ... (14 adds)]} 0 251
[junit4:junit4]   1> 264036 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 0 is 200
[junit4:junit4]   1> 264036 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@194a12de
[junit4:junit4]   1> 264039 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 264075 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_z,generation=35,filenames=[_r.fdt, _r.fnm, _r.fdx, _r_Lucene41_0.tip, _r_Lucene41_0.tim, _r.si, _r_Lucene41_0.doc, segments_z]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_10,generation=36,filenames=[_s_Lucene41_0.doc, _s.fdx, _s_Lucene41_0.tim, _s.fdt, segments_10, _s.si, _s.fnm, _s_Lucene41_0.tip]
[junit4:junit4]   1> 264076 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 36[_s_Lucene41_0.doc, _s.fdx, _s_Lucene41_0.tim, _s.fdt, segments_10, _s.si, _s.fnm, _s_Lucene41_0.tip]
[junit4:junit4]   1> 264078 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@15674a26 main
[junit4:junit4]   1> 264079 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 264080 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@15674a26 main{StandardDirectoryReader(segments_10:91:nrt _s(4.3):C14)}
[junit4:junit4]   1> 264080 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 264082 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@15674a26 main{StandardDirectoryReader(segments_10:91:nrt _s(4.3):C14)}
[junit4:junit4]   1> 264083 [qtp755696446-220] 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 44
[junit4:junit4]   1> 264087 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=14} hits=14 status=0 QTime=0 
[junit4:junit4]   1> 264092 [TEST-SolrExampleStreamingTest.testUnicode-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testUnicode
[junit4:junit4]   1> 264100 [TEST-SolrExampleStreamingTest.testExampleConfig-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testExampleConfig
[junit4:junit4]   1> 264106 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@23e77920 realtime
[junit4:junit4]   1> 264107 [qtp755696446-221] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212028573253632)} 0 3
[junit4:junit4]   1> 264109 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@31c1364a
[junit4:junit4]   1> 264361 [qtp755696446-219] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1112211111 (1431212028580593664)]} 0 250
[junit4:junit4]   1> 264363 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 1112211111 is 200
[junit4:junit4]   1> 264363 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@31c1364a
[junit4:junit4]   1> 264366 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 264385 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_10,generation=36,filenames=[_s_Lucene41_0.doc, _s.fdx, _s_Lucene41_0.tim, _s.fdt, segments_10, _s.si, _s.fnm, _s_Lucene41_0.tip]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_11,generation=37,filenames=[_t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, segments_11, _t.nvd, _t.nvm, _t.fdx, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4]   1> 264386 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 37[_t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, segments_11, _t.nvd, _t.nvm, _t.fdx, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4]   1> 264389 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@5a428d73 main
[junit4:junit4]   1> 264390 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 264390 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@5a428d73 main{StandardDirectoryReader(segments_11:95:nrt _t(4.3):C1)}
[junit4:junit4]   1> 264391 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 264392 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@5a428d73 main{StandardDirectoryReader(segments_11:95:nrt _t(4.3):C1)}
[junit4:junit4]   1> 264394 [qtp755696446-220] 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 28
[junit4:junit4]   1> 264398 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 264415 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_11,generation=37,filenames=[_t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, segments_11, _t.nvd, _t.nvm, _t.fdx, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_12,generation=38,filenames=[_t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _t.nvd, _t.nvm, _t.fdx, segments_12, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4]   1> 264416 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – newest commit = 38[_t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _t.nvd, _t.nvm, _t.fdx, segments_12, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4]   1> 264417 [qtp755696446-222] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@4c269c40 main
[junit4:junit4]   1> 264418 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 264418 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@4c269c40 main{StandardDirectoryReader(segments_11:95:nrt _t(4.3):C1)}
[junit4:junit4]   1> 264419 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 264421 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@4c269c40 main{StandardDirectoryReader(segments_11:95:nrt _t(4.3):C1)}
[junit4:junit4]   1> 264421 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=xml&version=2.2} {optimize=} 0 24
[junit4:junit4]   1> 264425 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   1> 264427 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@68ffad78
[junit4:junit4]   1> 264680 [qtp755696446-219] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 (1431212028915089408), 3 (1431212028917186560), 4 (1431212028918235136), 5 (1431212028919283712)]} 0 250
[junit4:junit4]   1> 264681 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 2 is 200
[junit4:junit4]   1> 264682 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@68ffad78
[junit4:junit4]   1> 264685 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 264705 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_12,generation=38,filenames=[_t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _t.nvd, _t.nvm, _t.fdx, segments_12, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_13,generation=39,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.nvm, _u.fdx, _u.nvd, _t.si, _u.fnm, _u_Lucene41_0.pos, _t.nvd, segments_13, _t.nvm, _t.fdx, _u.si, _u.fdt, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4]   1> 264706 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – newest commit = 39[_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.nvm, _u.fdx, _u.nvd, _t.si, _u.fnm, _u_Lucene41_0.pos, _t.nvd, segments_13, _t.nvm, _t.fdx, _u.si, _u.fdt, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4]   1> 264709 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@55000c0f main
[junit4:junit4]   1> 264709 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@55000c0f main{StandardDirectoryReader(segments_13:97:nrt _t(4.3):C1 _u(4.3):C4)}
[junit4:junit4]   1> 264709 [qtp755696446-221] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 264710 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 264712 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@55000c0f main{StandardDirectoryReader(segments_13:97:nrt _t(4.3):C1 _u(4.3):C4)}
[junit4:junit4]   1> 264712 [qtp755696446-221] 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 27
[junit4:junit4]   1> 264716 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 264745 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_13,generation=39,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.nvm, _u.fdx, _u.nvd, _t.si, _u.fnm, _u_Lucene41_0.pos, _t.nvd, segments_13, _t.nvm, _t.fdx, _u.si, _u.fdt, _t_Lucene41_0.pos, _t.fdt]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_14,generation=40,filenames=[_v.nvd, _v_Lucene41_0.tip, _v.fdx, _v.fdt, _v_Lucene41_0.doc, _v.si, _v_Lucene41_0.tim, _v_Lucene41_0.pos, _v.nvm, _v.fnm, segments_14]
[junit4:junit4]   1> 264746 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 40[_v.nvd, _v_Lucene41_0.tip, _v.fdx, _v.fdt, _v_Lucene41_0.doc, _v.si, _v_Lucene41_0.tim, _v_Lucene41_0.pos, _v.nvm, _v.fnm, segments_14]
[junit4:junit4]   1> 264750 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@780b00f9 main
[junit4:junit4]   1> 264751 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 264751 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@780b00f9 main{StandardDirectoryReader(segments_14:99:nrt _v(4.3):C5)}
[junit4:junit4]   1> 264752 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 264753 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@780b00f9 main{StandardDirectoryReader(segments_14:99:nrt _v(4.3):C5)}
[junit4:junit4]   1> 264756 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=xml&version=2.2} {optimize=} 0 40
[junit4:junit4]   1> 264763 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=xml&version=2.2} hits=5 status=0 QTime=3 
[junit4:junit4]   1> 264771 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=xml&fq=inStock:true&version=2.2} hits=2 status=0 QTime=1 
[junit4:junit4]   1> 264777 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&q=foo&wt=xml&fq={!field+f%3DinStock}true&fq={!term+f%3Dname}hoss&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 264778 [TEST-SolrExampleStreamingTest.testExampleConfig-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testExampleConfig
[junit4:junit4]   1> 264787 [TEST-SolrExampleStreamingTest.testSetNullUpdates-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testSetNullUpdates
[junit4:junit4]   1> 264787 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7bdcaab3
[junit4:junit4]   1> 265040 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdates (1431212029292576768)]} 0 250
[junit4:junit4]   1> 265041 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: testSetNullUpdates is 200
[junit4:junit4]   1> 265042 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7bdcaab3
[junit4:junit4]   1> 265045 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 265075 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_14,generation=40,filenames=[_v.nvd, _v_Lucene41_0.tip, _v.fdx, _v.fdt, _v_Lucene41_0.doc, _v.si, _v_Lucene41_0.tim, _v_Lucene41_0.pos, _v.nvm, _v.fnm, segments_14]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_15,generation=41,filenames=[_v.nvd, _w.si, _v.fdt, _v.fdx, _w.fdt, _v.si, _w.fdx, _v_Lucene41_0.pos, _v.nvm, _w_Lucene41_0.doc, _v_Lucene41_0.tip, _v_Lucene41_0.doc, _w_Lucene41_0.tip, _w_Lucene41_0.tim, _v_Lucene41_0.tim, segments_15, _v.fnm, _w.fnm]
[junit4:junit4]   1> 265076 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – newest commit = 41[_v.nvd, _w.si, _v.fdt, _v.fdx, _w.fdt, _v.si, _w.fdx, _v_Lucene41_0.pos, _v.nvm, _w_Lucene41_0.doc, _v_Lucene41_0.tip, _v_Lucene41_0.doc, _w_Lucene41_0.tip, _w_Lucene41_0.tim, _v_Lucene41_0.tim, segments_15, _v.fnm, _w.fnm]
[junit4:junit4]   1> 265079 [qtp755696446-222] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@7550004f main
[junit4:junit4]   1> 265080 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 265080 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@7550004f main{StandardDirectoryReader(segments_15:101:nrt _v(4.3):C5 _w(4.3):C1)}
[junit4:junit4]   1> 265081 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 265083 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@7550004f main{StandardDirectoryReader(segments_15:101:nrt _v(4.3):C5 _w(4.3):C1)}
[junit4:junit4]   1> 265086 [qtp755696446-222] 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 42
[junit4:junit4]   1> 265087 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@52ccef60
[junit4:junit4]   1> 265340 [qtp755696446-221] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdates (1431212029609246720)]} 0 250
[junit4:junit4]   1> 265341 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: testSetNullUpdates is 200
[junit4:junit4]   1> 265342 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@52ccef60
[junit4:junit4]   1> 265345 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 265365 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_15,generation=41,filenames=[_v.nvd, _w.si, _v.fdt, _v.fdx, _w.fdt, _v.si, _w.fdx, _v_Lucene41_0.pos, _v.nvm, _w_Lucene41_0.doc, _v_Lucene41_0.tip, _v_Lucene41_0.doc, _w_Lucene41_0.tip, _w_Lucene41_0.tim, _v_Lucene41_0.tim, segments_15, _v.fnm, _w.fnm]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_16,generation=42,filenames=[_v.nvd, _x.fnm, _x.fdt, _v.fdt, _v.fdx, _x.si, _v.si, _x.fdx, _v_Lucene41_0.pos, _v.nvm, _v_Lucene41_0.tip, _x_Lucene41_0.doc, _v_Lucene41_0.doc, _x_Lucene41_0.tip, _x_Lucene41_0.tim, _v_Lucene41_0.tim, _v.fnm, segments_16]
[junit4:junit4]   1> 265366 [qtp755696446-220] INFO  org.apache.solr.core.SolrCore  – newest commit = 42[_v.nvd, _x.fnm, _x.fdt, _v.fdt, _v.fdx, _x.si, _v.si, _x.fdx, _v_Lucene41_0.pos, _v.nvm, _v_Lucene41_0.tip, _x_Lucene41_0.doc, _v_Lucene41_0.doc, _x_Lucene41_0.tip, _x_Lucene41_0.tim, _v_Lucene41_0.tim, _v.fnm, segments_16]
[junit4:junit4]   1> 265368 [qtp755696446-220] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@239190ce main
[junit4:junit4]   1> 265369 [qtp755696446-220] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 265369 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@239190ce main{StandardDirectoryReader(segments_16:105:nrt _v(4.3):C5 _x(4.3):C1)}
[junit4:junit4]   1> 265370 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 265372 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@239190ce main{StandardDirectoryReader(segments_16:105:nrt _v(4.3):C5 _x(4.3):C1)}
[junit4:junit4]   1> 265373 [qtp755696446-220] 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 28
[junit4:junit4]   1> 265377 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   1> 265378 [TEST-SolrExampleStreamingTest.testSetNullUpdates-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testSetNullUpdates
[junit4:junit4]   1> 265387 [TEST-SolrExampleStreamingTest.testRealtimeGet-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testRealtimeGet
[junit4:junit4]   1> 265391 [qtp755696446-221] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@39ab88b realtime
[junit4:junit4]   1> 265391 [qtp755696446-221] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212029921722368)} 0 2
[junit4:junit4]   1> 265393 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@22c9af86
[junit4:junit4]   1> 265645 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[DOCID (1431212029926965248)]} 0 250
[junit4:junit4]   1> 265647 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: DOCID is 200
[junit4:junit4]   1> 265647 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@22c9af86
[junit4:junit4]   1> 265650 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 265675 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_16,generation=42,filenames=[_v.nvd, _x.fnm, _x.fdt, _v.fdt, _v.fdx, _x.si, _v.si, _x.fdx, _v_Lucene41_0.pos, _v.nvm, _v_Lucene41_0.tip, _x_Lucene41_0.doc, _v_Lucene41_0.doc, _x_Lucene41_0.tip, _x_Lucene41_0.tim, _v_Lucene41_0.tim, _v.fnm, segments_16]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_17,generation=43,filenames=[_y.fdt, _y.nvd, _y_Lucene41_0.tip, _y.si, _y.fdx, _y_Lucene41_0.pos, _y.nvm, _y.fnm, segments_17, _y_Lucene41_0.doc, _y_Lucene41_0.tim]
[junit4:junit4]   1> 265676 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 43[_y.fdt, _y.nvd, _y_Lucene41_0.tip, _y.si, _y.fdx, _y_Lucene41_0.pos, _y.nvm, _y.fnm, segments_17, _y_Lucene41_0.doc, _y_Lucene41_0.tim]
[junit4:junit4]   1> 265680 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@446b78ba main
[junit4:junit4]   1> 265681 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@446b78ba main{StandardDirectoryReader(segments_17:109:nrt _y(4.3):C1)}
[junit4:junit4]   1> 265681 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 265682 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 265684 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@446b78ba main{StandardDirectoryReader(segments_17:109:nrt _y(4.3):C1)}
[junit4:junit4]   1> 265686 [qtp755696446-219] 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 36
[junit4:junit4]   1> 265690 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=javabin&qt=/get&version=2} status=0 QTime=1 
[junit4:junit4]   1> 265693 [qtp755696446-220] 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> 265695 [TEST-SolrExampleStreamingTest.testRealtimeGet-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testRealtimeGet
[junit4:junit4]   1> 265703 [TEST-SolrExampleStreamingTest.testPivotFacets-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testPivotFacets
[junit4:junit4]   1> 265707 [qtp755696446-222] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1e4e8204 realtime
[junit4:junit4]   1> 265708 [qtp755696446-222] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1431212030253072384)} 0 3
[junit4:junit4]   1> 265712 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 265745 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_17,generation=43,filenames=[_y.fdt, _y.nvd, _y_Lucene41_0.tip, _y.si, _y.fdx, _y_Lucene41_0.pos, _y.nvm, _y.fnm, segments_17, _y_Lucene41_0.doc, _y_Lucene41_0.tim]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_18,generation=44,filenames=[segments_18]
[junit4:junit4]   1> 265746 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 44[segments_18]
[junit4:junit4]   1> 265747 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@9b3f577 main
[junit4:junit4]   1> 265748 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@9b3f577 main{StandardDirectoryReader(segments_17:111:nrt)}
[junit4:junit4]   1> 265748 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 265748 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 265750 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@9b3f577 main{StandardDirectoryReader(segments_17:111:nrt)}
[junit4:junit4]   1> 265751 [qtp755696446-219] 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 39
[junit4:junit4]   1> 265755 [qtp755696446-221] 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> 265756 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@17c6f9c5
[junit4:junit4]   1> 266010 [qtp755696446-220] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1431212030309695488), 2 (1431212030310744064), 3 (1431212030311792640), 4 (1431212030311792641), 5 (1431212030312841216), 6 (1431212030312841217), 7 (1431212030313889792), 8 (1431212030313889793), 9 (1431212030314938368), 10 (1431212030314938369), ... (12 adds)]} 0 250
[junit4:junit4]   1> 266011 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 1 is 200
[junit4:junit4]   1> 266011 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@17c6f9c5
[junit4:junit4]   1> 266014 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 266035 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_18,generation=44,filenames=[segments_18]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_19,generation=45,filenames=[_z.fdt, _z.nvm, _z_Lucene41_0.pos, _z.fdx, _z_Lucene41_0.tim, _z_Lucene41_0.doc, _z.si, _z_Lucene41_0.tip, _z.nvd, _z.fnm, segments_19]
[junit4:junit4]   1> 266036 [qtp755696446-219] INFO  org.apache.solr.core.SolrCore  – newest commit = 45[_z.fdt, _z.nvm, _z_Lucene41_0.pos, _z.fdx, _z_Lucene41_0.tim, _z_Lucene41_0.doc, _z.si, _z_Lucene41_0.tip, _z.nvd, _z.fnm, segments_19]
[junit4:junit4]   1> 266039 [qtp755696446-219] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1d434058 main
[junit4:junit4]   1> 266039 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1d434058 main{StandardDirectoryReader(segments_19:113:nrt _z(4.3):C12)}
[junit4:junit4]   1> 266039 [qtp755696446-219] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 266040 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 266042 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1d434058 main{StandardDirectoryReader(segments_19:113:nrt _z(4.3):C12)}
[junit4:junit4]   1> 266042 [qtp755696446-219] 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 28
[junit4:junit4]   1> 266047 [qtp755696446-221] INFO  org.apache.solr.core.SolrCore  – UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   1> 266047 [qtp755696446-221] 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> 266049 [qtp755696446-221] 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> 266057 [qtp755696446-220] 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> 266059 [TEST-SolrExampleStreamingTest.testPivotFacets-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testPivotFacets
[junit4:junit4]   1> 266066 [TEST-SolrExampleStreamingTest.testUpdateMultiValuedField-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testUpdateMultiValuedField
[junit4:junit4]   1> 266067 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@590e635c
[junit4:junit4]   1> 266320 [qtp755696446-219] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1431212030634754048)]} 0 250
[junit4:junit4]   1> 266321 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – Status for: 123 is 200
[junit4:junit4]   1> 266322 [concurrentUpdateScheduler-1-thread-5] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@590e635c
[junit4:junit4]   1> 266324 [qtp755696446-222] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 266345 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_19,generation=45,filenames=[_z.fdt, _z.nvm, _z_Lucene41_0.pos, _z.fdx, _z_Lucene41_0.tim, _z_Lucene41_0.doc, _z.si, _z_Lucene41_0.tip, _z.nvd, _z.fnm, segments_19]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_1a,generation=46,filenames=[_z.fdt, _10.fnm, _10.fdt, _z.fdx, _z_Lucene41_0.tim, _10_Lucene41_0.tip, _z.si, _z.nvd, _10_Lucene41_0.tim, _z.fnm, _10.fdx, segments_1a, _10.si, _z.nvm, _z_Lucene41_0.pos, _10_Lucene41_0.doc, _z_Lucene41_0.doc, _z_Lucene41_0.tip]
[junit4:junit4]   1> 266346 [qtp755696446-222] INFO  org.apache.solr.core.SolrCore  – newest commit = 46[_z.fdt, _10.fnm, _10.fdt, _z.fdx, _z_Lucene41_0.tim, _10_Lucene41_0.tip, _z.si, _z.nvd, _10_Lucene41_0.tim, _z.fnm, _10.fdx, segments_1a, _10.si, _z.nvm, _z_Lucene41_0.pos, _10_Lucene41_0.doc, _z_Lucene41_0.doc, _z_Lucene41_0.tip]
[junit4:junit4]   1> 266400 [qtp755696446-222] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@6b118a2d main
[junit4:junit4

[...truncated too long message...]

/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_29,generation=81,filenames=[segments_29]
[junit4:junit4]   1> 275676 [qtp755696446-242] INFO  org.apache.solr.core.SolrCore  – newest commit = 81[segments_29]
[junit4:junit4]   1> 275677 [qtp755696446-242] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@710c5bc8 main
[junit4:junit4]   1> 275678 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@710c5bc8 main{StandardDirectoryReader(segments_28:217:nrt)}
[junit4:junit4]   1> 275678 [qtp755696446-242] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 275678 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 275680 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@710c5bc8 main{StandardDirectoryReader(segments_28:217:nrt)}
[junit4:junit4]   1> 275682 [qtp755696446-242] 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 32
[junit4:junit4]   1> 275685 [qtp755696446-219] 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> 275686 [TEST-SolrExampleStreamingTest.testChineseDefaults-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer  – blocking for commit/optimize
[junit4:junit4]   1> 275690 [qtp755696446-241] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 275725 [qtp755696446-241] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_29,generation=81,filenames=[segments_29]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/index28292829tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@e722ed1),segFN=segments_2a,generation=82,filenames=[_1v.fdx, _1v_Lucene41_0.pos, segments_2a, _1v_Lucene41_0.doc, _1v_Lucene41_0.tim, _1v.nvd, _1v.si, _1v_Lucene41_0.tip, _1v.fdt, _1v.nvm, _1v.fnm]
[junit4:junit4]   1> 275726 [qtp755696446-241] INFO  org.apache.solr.core.SolrCore  – newest commit = 82[_1v.fdx, _1v_Lucene41_0.pos, segments_2a, _1v_Lucene41_0.doc, _1v_Lucene41_0.tim, _1v.nvd, _1v.si, _1v_Lucene41_0.tip, _1v.fdt, _1v.nvm, _1v.fnm]
[junit4:junit4]   1> 275728 [qtp755696446-241] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@65dd340f main
[junit4:junit4]   1> 275729 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@65dd340f main{StandardDirectoryReader(segments_2a:219:nrt _1v(4.3):C1)}
[junit4:junit4]   1> 275729 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 275729 [qtp755696446-241] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 275731 [searcherExecutor-110-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@65dd340f main{StandardDirectoryReader(segments_2a:219:nrt _1v(4.3):C1)}
[junit4:junit4]   1> 275732 [qtp755696446-241] 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=[42 (1431212040719958016)],commit=} 0 44
[junit4:junit4]   1> 275736 [qtp755696446-222] 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> 275737 [TEST-SolrExampleStreamingTest.testChineseDefaults-seed#[D62E0EEE93C2243F]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testChineseDefaults
[junit4:junit4]   1> 275946 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.CoreContainer  – Shutting down CoreContainer instance=1094864774
[junit4:junit4]   1> 275948 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.SolrCore  – [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@e97b646
[junit4:junit4]   1> 275969 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.update.UpdateHandler  – closing DirectUpdateHandler2{commits=76,autocommit maxTime=15000ms,autocommits=0,soft autocommits=3,optimizes=8,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=318,cumulative_deletesById=5,cumulative_deletesByQuery=36,cumulative_errors=1}
[junit4:junit4]   1> 275971 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.update.SolrCoreState  – Closing SolrCoreState
[junit4:junit4]   1> 275971 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.update.DefaultSolrCoreState  – SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> 275972 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.update.DefaultSolrCoreState  – closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> 275976 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.SolrCore  – [collection1] Closing main searcher on request.
[junit4:junit4]   1> 275977 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  – Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> 275977 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  – looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154;done=false>>]
[junit4:junit4]   1> 275978 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  – Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154
[junit4:junit4]   1> 275978 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  – looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154/index;done=false>>]
[junit4:junit4]   1> 275979 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  – Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleStreamingTest-1364909943154/index
[junit4:junit4]   1> 275979 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.eclipse.jetty.server.handler.ContextHandler  – stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> 276031 [SUITE-SolrExampleStreamingTest-seed#[D62E0EEE93C2243F]-worker] INFO  org.apache.solr.SolrTestCaseJ4  – ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=en_IN, timezone=Asia/Nicosia
[junit4:junit4]   2> NOTE: Linux 3.2.0-39-generic amd64/Sun Microsystems Inc. 1.6.0_43 (64-bit)/cpus=8,threads=11,free=48793392,total=85000192
[junit4:junit4]   2> NOTE: All tests run in this JVM: [MultiCoreExampleJettyTest, IteratorChainTest, FacetFieldTest, TestSpellCheckResponse, MultiCoreEmbeddedTest, FieldAnalysisResponseTest, TestNamedListCodec, NamedListTest, SolrQueryTest, SolrExampleBinaryTest, CloudSolrServerTest, SolrExampleStreamingTest]
[junit4:junit4] Completed on J1 in 221.79s, 27 tests, 1 error <<< FAILURES!

[...truncated 10 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:381: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:361: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:196: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:448: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1234: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:878: There were test failures: 45 suites, 266 tests, 1 error

Total time: 49 minutes 23 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.6.0_43 -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message