You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/06/04 21:13:14 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 532 -
Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/532/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest.testUpdateField
Error Message:
IOException occured when talking to server at: https://127.0.0.1:51417/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:51417/solr/collection1
at __randomizedtesting.SeedInfo.seed([54CE8E97A3525C11:1BD24C3637A4CD7]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
at org.apache.solr.client.solrj.SolrExampleTests.testUpdateField(SolrExampleTests.java:1370)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:601)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:722)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
... 45 more
Build Log:
[...truncated 10376 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest
[junit4:junit4] 1> 73297 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.SolrJettyTestBase ? Randomized ssl (true) and clientAuth (false)
[junit4:junit4] 1> 73301 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ####initCore
[junit4:junit4] 1> 73325 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ####initCore end
[junit4:junit4] 1> 73552 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.eclipse.jetty.server.Server ? jetty-8.1.10.v20130312
[junit4:junit4] 1> 74491 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.eclipse.jetty.util.ssl.SslContextFactory ? Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4:junit4] 1> 74500 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.eclipse.jetty.server.AbstractConnector ? Started SslSelectChannelConnector@127.0.0.1:51417
[junit4:junit4] 1> 74514 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? SolrDispatchFilter.init()
[junit4:junit4] 1> 74524 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrResourceLoader ? JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 1> 74526 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrResourceLoader ? using system property solr.solr.home: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr
[junit4:junit4] 1> 74527 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.CoreContainer ? looking for solr config file: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/solr.xml
[junit4:junit4] 1> 74528 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.CoreContainer ? New CoreContainer 1413728603
[junit4:junit4] 1> 74529 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.CoreContainer ? Loading CoreContainer using Solr Home: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/'
[junit4:junit4] 1> 74530 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrResourceLoader ? new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/'
[junit4:junit4] 1> 74995 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr
[junit4:junit4] 1> 74998 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn
[junit4:junit4] 1> 75002 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/prop-base
[junit4:junit4] 1> 75044 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/props
[junit4:junit4] 1> 75089 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/text-base
[junit4:junit4] 1> 75094 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/tmp
[junit4:junit4] 1> 75107 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/tmp/prop-base
[junit4:junit4] 1> 75109 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/tmp/props
[junit4:junit4] 1> 75111 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/tmp/text-base
[junit4:junit4] 1> 75125 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Discovered properties file /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/core.properties, adding to cores
[junit4:junit4] 1> 75143 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting socketTimeout to: 0
[junit4:junit4] 1> 75145 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting urlScheme to: http://
[junit4:junit4] 1> 75147 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting connTimeout to: 0
[junit4:junit4] 1> 75147 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maxConnectionsPerHost to: 20
[junit4:junit4] 1> 75148 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting corePoolSize to: 0
[junit4:junit4] 1> 75149 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maximumPoolSize to: 2147483647
[junit4:junit4] 1> 75150 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maxThreadIdleTime to: 5
[junit4:junit4] 1> 75151 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting sizeOfQueue to: -1
[junit4:junit4] 1> 75152 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting fairnessPolicy to: false
[junit4:junit4] 1> 75152 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-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> 75186 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.logging.LogWatcher ? Registering Log Listener
[junit4:junit4] 1> 75202 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.CoreContainer ? Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1
[junit4:junit4] 1> 75204 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrResourceLoader ? new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/'
[junit4:junit4] 1> 75320 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrConfig ? Adding specified lib dirs to ClassLoader
[junit4:junit4] 1> 75322 [coreLoadExecutor-11-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4:junit4] 1> 75357 [coreLoadExecutor-11-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 75359 [coreLoadExecutor-11-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4:junit4] 1> 75360 [coreLoadExecutor-11-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 75362 [coreLoadExecutor-11-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4:junit4] 1> 75362 [coreLoadExecutor-11-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 75367 [coreLoadExecutor-11-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4:junit4] 1> 75368 [coreLoadExecutor-11-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 75369 [coreLoadExecutor-11-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4:junit4] 1> 75735 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrConfig ? Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 1> 75986 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.Config ? Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 1> 75989 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.schema.IndexSchema ? Reading Solr Schema from schema.xml
[junit4:junit4] 1> 76067 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.schema.IndexSchema ? [collection1] Schema name=example
[junit4:junit4] 1> 78126 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.schema.IndexSchema ? unique key field: id
[junit4:junit4] 1> 78198 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider ? Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 78216 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider ? Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 78479 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 78481 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901/
[junit4:junit4] 1> 78483 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.JmxMonitoredMap ? JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d79eb02
[junit4:junit4] 1> 78485 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4] 1> 78487 [coreLoadExecutor-11-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> 78488 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 78497 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory ? return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901
[junit4:junit4] 1> 78498 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901/index/
[junit4:junit4] 1> 78499 [coreLoadExecutor-11-thread-1] WARN org.apache.solr.core.SolrCore ? [collection1] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901/index' doesn't exist. Creating new index...
[junit4:junit4] 1> 78500 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory ? return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901/index
[junit4:junit4] 1> 78511 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1,generation=1}
[junit4:junit4] 1> 78512 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? newest commit generation = 1
[junit4:junit4] 1> 78516 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? created json: solr.JSONResponseWriter
[junit4:junit4] 1> 78516 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4] 1> 78516 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? created velocity: solr.VelocityResponseWriter
[junit4:junit4] 1> 78517 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? created xslt: solr.XSLTResponseWriter
[junit4:junit4] 1> 78517 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.response.XSLTResponseWriter ? xsltCacheLifetimeSeconds=5
[junit4:junit4] 1> 78521 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /select: solr.SearchHandler
[junit4:junit4] 1> 78522 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /query: solr.SearchHandler
[junit4:junit4] 1> 78523 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /get: solr.RealTimeGetHandler
[junit4:junit4] 1> 78524 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /browse: solr.SearchHandler
[junit4:junit4] 1> 78525 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update: solr.UpdateRequestHandler
[junit4:junit4] 1> 78527 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4] 1> 78528 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/csv: solr.CSVRequestHandler
[junit4:junit4] 1> 78535 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 78536 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 78537 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 78538 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 78539 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 78540 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 78541 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /admin/: solr.admin.AdminHandlers
[junit4:junit4] 1> 78542 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /admin/ping: solr.PingRequestHandler
[junit4:junit4] 1> 78543 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 1> 78545 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /replication: solr.ReplicationHandler
[junit4:junit4] 1> 78545 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 78546 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /spell: solr.SearchHandler
[junit4:junit4] 1> 78547 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 78548 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /tvrh: solr.SearchHandler
[junit4:junit4] 1> 78548 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 78549 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /terms: solr.SearchHandler
[junit4:junit4] 1> 78549 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 78550 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /elevate: solr.SearchHandler
[junit4:junit4] 1> 78628 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 78632 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 78635 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 78651 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@379159fa main
[junit4:junit4] 1> 78652 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.update.UpdateHandler ? Clearing tlog files, tlogDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901/tlog
[junit4:junit4] 1> 78655 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? Hard AutoCommit: if uncommited for 15000ms;
[junit4:junit4] 1> 78655 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.SolrCore ? Soft AutoCommit: disabled
[junit4:junit4] 1> 78657 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Initializing spell checkers
[junit4:junit4] 1> 78658 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.spelling.DirectSolrSpellChecker ? init: {name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4:junit4] 1> 78659 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? No queryConverter defined, using default converter
[junit4:junit4] 1> 78671 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.handler.component.QueryElevationComponent ? Loading QueryElevation from: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/conf/elevate.xml
[junit4:junit4] 1> 78731 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.handler.ReplicationHandler ? Commits will be reserved for 10000
[junit4:junit4] 1> 78737 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@379159fa main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 78769 [searcherExecutor-12-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=4
[junit4:junit4] 1> 78776 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 78777 [searcherExecutor-12-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Loading spell index for spellchecker: default
[junit4:junit4] 1> 78778 [searcherExecutor-12-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Loading spell index for spellchecker: wordbreak
[junit4:junit4] 1> 78783 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@379159fa main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 78789 [coreLoadExecutor-11-thread-1] INFO org.apache.solr.core.CoreContainer ? registering core: collection1
[junit4:junit4] 1> 78792 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? user.dir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
[junit4:junit4] 1> 78793 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? SolrDispatchFilter.init() done
[junit4:junit4] 1> 78794 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.SolrJettyTestBase ? Jetty Assigned Port#51417
[junit4:junit4] 2> Creating dataDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-SolrExampleJettyTest-1370372635901
[junit4:junit4] 1> 78821 [TEST-SolrExampleJettyTest.testErrorHandling-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testErrorHandling
[junit4:junit4] 1> 78823 [TEST-SolrExampleJettyTest.testErrorHandling-seed#[54CE8E97A3525C11]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 79469 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching ignore_exception
[junit4:junit4] 1> 79470 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/analysis/field params={analysis.fieldtype=int&wt=javabin&qt=/analysis/field&analysis.fieldvalue=ignore_exception&version=2} status=400 QTime=3
[junit4:junit4] 1> 79508 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 2
[junit4:junit4] 1> 79511 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching ignore_exception
[junit4:junit4] 1> 79534 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 0
[junit4:junit4] 1> 79541 [qtp462151375-53] ERROR org.apache.solr.core.SolrCore ? org.apache.solr.common.SolrException: Document contains multiple values for uniqueKey field: id=[DOCID, DOCID2]
[junit4:junit4] 1> at org.apache.solr.update.AddUpdateCommand.getIndexedId(AddUpdateCommand.java:91)
[junit4:junit4] 1> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:533)
[junit4:junit4] 1> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:399)
[junit4:junit4] 1> at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:100)
[junit4:junit4] 1> at org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:246)
[junit4:junit4] 1> at org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:173)
[junit4:junit4] 1> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:92)
[junit4:junit4] 1> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4:junit4] 1> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4:junit4] 1> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1843)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:656)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:359)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:155)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4] 1> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4:junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4:junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4] 1> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4:junit4] 1> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4:junit4] 1> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4] 1> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4] 1> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 1>
[junit4:junit4] 1> 79550 [TEST-SolrExampleJettyTest.testErrorHandling-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testErrorHandling
[junit4:junit4] 1> 79588 [TEST-SolrExampleJettyTest.testQueryWithParams-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testQueryWithParams
[junit4:junit4] 1> 79611 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=query&debug=true&wt=javabin&version=2} hits=0 status=0 QTime=6
[junit4:junit4] 1> 79618 [TEST-SolrExampleJettyTest.testQueryWithParams-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testQueryWithParams
[junit4:junit4] 1> 79649 [TEST-SolrExampleJettyTest.testPingHandler-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPingHandler
[junit4:junit4] 1> 79665 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1,generation=1}
[junit4:junit4] 1> 79667 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 1
[junit4:junit4] 1> 79672 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7205b5ec realtime
[junit4:junit4] 1> 79674 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939863718887424)} 0 14
[junit4:junit4] 1> 79686 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 79693 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1,generation=1}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_2,generation=2}
[junit4:junit4] 1> 79695 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? newest commit generation = 2
[junit4:junit4] 1> 79696 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@c52d244 main
[junit4:junit4] 1> 79697 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 79698 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@c52d244 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 79699 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 79702 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@c52d244 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 79703 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4] 1> 79713 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 79720 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 79721 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=2
[junit4:junit4] 1> 79723 [TEST-SolrExampleJettyTest.testPingHandler-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPingHandler
[junit4:junit4] 1> 79748 [TEST-SolrExampleJettyTest.testGetEmptyResults-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testGetEmptyResults
[junit4:junit4] 1> 79756 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939863817453568)} 0 2
[junit4:junit4] 1> 79775 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 79781 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_2,generation=2}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_3,generation=3}
[junit4:junit4] 1> 79783 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 3
[junit4:junit4] 1> 79784 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3be1ea76 main
[junit4:junit4] 1> 79786 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3be1ea76 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 79787 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 79793 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3be1ea76 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 79795 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 79796 [qtp462151375-55] 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 21
[junit4:junit4] 1> 79847 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1436939863898193920)]} 0 15
[junit4:junit4] 1> 79860 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id2 (1436939863925456896)]} 0 3
[junit4:junit4] 1> 79868 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 79879 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_3,generation=3}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_4,generation=4}
[junit4:junit4] 1> 79881 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? newest commit generation = 4
[junit4:junit4] 1> 79891 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@16caac05 main
[junit4:junit4] 1> 79897 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 79897 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@16caac05 main{StandardDirectoryReader(segments_4:3:nrt _0(5.0):C2)}
[junit4:junit4] 1> 79905 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 79911 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@16caac05 main{StandardDirectoryReader(segments_4:3:nrt _0(5.0):C2)}
[junit4:junit4] 1> 79913 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 45
[junit4:junit4] 1> 79928 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=foofoofoo&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4:junit4] 1> 79943 [TEST-SolrExampleJettyTest.testGetEmptyResults-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testGetEmptyResults
[junit4:junit4] 1> 79978 [TEST-SolrExampleJettyTest.testSetNullUpdateOrder-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testSetNullUpdateOrder
[junit4:junit4] 1> 79996 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1436939864057577472)]} 0 12
[junit4:junit4] 1> 80004 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 80018 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_4,generation=4}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_5,generation=5}
[junit4:junit4] 1> 80019 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 5
[junit4:junit4] 1> 80074 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@179211ea main
[junit4:junit4] 1> 80078 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 80079 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@179211ea main{StandardDirectoryReader(segments_5:5:nrt _0(5.0):C2 _1(5.0):C1)}
[junit4:junit4] 1> 80079 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 80083 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@179211ea main{StandardDirectoryReader(segments_5:5:nrt _0(5.0):C2 _1(5.0):C1)}
[junit4:junit4] 1> 80085 [qtp462151375-56] 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 80
[junit4:junit4] 1> 80103 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1436939864180260864)]} 0 11
[junit4:junit4] 1> 80110 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 80117 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_5,generation=5}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_6,generation=6}
[junit4:junit4] 1> 80119 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? newest commit generation = 6
[junit4:junit4] 1> 80122 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2931f965 main
[junit4:junit4] 1> 80123 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 80125 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2931f965 main{StandardDirectoryReader(segments_6:9:nrt _0(5.0):C2 _2(5.0):C1)}
[junit4:junit4] 1> 80126 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 80131 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2931f965 main{StandardDirectoryReader(segments_6:9:nrt _0(5.0):C2 _2(5.0):C1)}
[junit4:junit4] 1> 80133 [qtp462151375-52] 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 23
[junit4:junit4] 1> 80142 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 80151 [TEST-SolrExampleJettyTest.testSetNullUpdateOrder-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testSetNullUpdateOrder
[junit4:junit4] 1> 80202 [TEST-SolrExampleJettyTest.testMultiContentStreamRequest-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testMultiContentStreamRequest
[junit4:junit4] 1> 80226 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7c32d1a3 realtime
[junit4:junit4] 1> 80228 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939864308187136)} 0 5
[junit4:junit4] 1> 80270 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 80273 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_6,generation=6}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_7,generation=7}
[junit4:junit4] 1> 80273 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 7
[junit4:junit4] 1> 80276 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@215fbb66 main
[junit4:junit4] 1> 80277 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 80283 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@215fbb66 main{StandardDirectoryReader(segments_6:11:nrt)}
[junit4:junit4] 1> 80288 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 80296 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@215fbb66 main{StandardDirectoryReader(segments_6:11:nrt)}
[junit4:junit4] 1> 80298 [qtp462151375-55] 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 28
[junit4:junit4] 1> 80361 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 80566 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 80742 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_7,generation=7}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_8,generation=8}
[junit4:junit4] 1> 80744 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 8
[junit4:junit4] 1> 80753 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@14cc2c8 main
[junit4:junit4] 1> 80755 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 80755 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@14cc2c8 main{StandardDirectoryReader(segments_8:13:nrt _3(5.0):C5)}
[junit4:junit4] 1> 80757 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 80762 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@14cc2c8 main{StandardDirectoryReader(segments_8:13:nrt _3(5.0):C5)}
[junit4:junit4] 1> 80766 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&echoParams=ALL&commit=true&a=?&wt=javabin&version=2&softCommit=false} {add=[SP2514N (1436939864555651072), 6H500F0 (1436939864589205504), TWINX2048-3200PRO (1436939864607031296), VS1GB400C3 (1436939864645828608), VDBDB1A16 (1436939864651071488)],commit=} 0 310
[junit4:junit4] 1> 80791 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=5
[junit4:junit4] 1> 80799 [TEST-SolrExampleJettyTest.testMultiContentStreamRequest-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testMultiContentStreamRequest
[junit4:junit4] 1> 80834 [TEST-SolrExampleJettyTest.testChineseDefaults-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testChineseDefaults
[junit4:junit4] 1> 80851 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2cfb40ec realtime
[junit4:junit4] 1> 80858 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939864964595712)} 0 10
[junit4:junit4] 1> 80887 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 80920 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_8,generation=8}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_9,generation=9}
[junit4:junit4] 1> 80921 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? newest commit generation = 9
[junit4:junit4] 1> 80947 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1bc12fb3 main
[junit4:junit4] 1> 80950 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 80951 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1bc12fb3 main{StandardDirectoryReader(segments_8:15:nrt)}
[junit4:junit4] 1> 80952 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 80958 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1bc12fb3 main{StandardDirectoryReader(segments_8:15:nrt)}
[junit4:junit4] 1> 80982 [qtp462151375-52] 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 96
[junit4:junit4] 1> 80996 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 81053 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 81060 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_9,generation=9}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_a,generation=10}
[junit4:junit4] 1> 81062 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 10
[junit4:junit4] 1> 81101 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@63664cd8 main
[junit4:junit4] 1> 81103 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 81104 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@63664cd8 main{StandardDirectoryReader(segments_a:17:nrt _4(5.0):C1)}
[junit4:junit4] 1> 81105 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 81112 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@63664cd8 main{StandardDirectoryReader(segments_a:17:nrt _4(5.0):C1)}
[junit4:junit4] 1> 81114 [qtp462151375-54] 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 (1436939865172213760)],commit=} 0 67
[junit4:junit4] 1> 81127 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=????&wt=javabin&version=2} hits=1 status=0 QTime=2
[junit4:junit4] 1> 81136 [TEST-SolrExampleJettyTest.testChineseDefaults-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testChineseDefaults
[junit4:junit4] 1> 81167 [TEST-SolrExampleJettyTest.testRealtimeGet-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testRealtimeGet
[junit4:junit4] 1> 81192 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5180a0e5 realtime
[junit4:junit4] 1> 81194 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939865320062976)} 0 6
[junit4:junit4] 1> 81223 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[DOCID (1436939865341034496)]} 0 15
[junit4:junit4] 1> 81233 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 81239 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_a,generation=10}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_b,generation=11}
[junit4:junit4] 1> 81240 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 11
[junit4:junit4] 1> 81244 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1f16ec10 main
[junit4:junit4] 1> 81248 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1f16ec10 main{StandardDirectoryReader(segments_b:21:nrt _5(5.0):C1)}
[junit4:junit4] 1> 81249 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 81256 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1f16ec10 main{StandardDirectoryReader(segments_b:21:nrt _5(5.0):C1)}
[junit4:junit4] 1> 81258 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 81262 [qtp462151375-53] 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 29
[junit4:junit4] 1> 81277 [qtp462151375-55] 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> 81290 [qtp462151375-56] 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> 81296 [TEST-SolrExampleJettyTest.testRealtimeGet-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testRealtimeGet
[junit4:junit4] 1> 81335 [TEST-SolrExampleJettyTest.testCommitWithinOnAdd-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testCommitWithinOnAdd
[junit4:junit4] 1> 81346 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@555ac023 realtime
[junit4:junit4] 1> 81348 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939865481543680)} 0 6
[junit4:junit4] 1> 81370 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 81372 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_b,generation=11}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_c,generation=12}
[junit4:junit4] 1> 81373 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? newest commit generation = 12
[junit4:junit4] 1> 81375 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2fca033e main
[junit4:junit4] 1> 81377 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 81378 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2fca033e main{StandardDirectoryReader(segments_b:23:nrt)}
[junit4:junit4] 1> 81379 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 81386 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2fca033e main{StandardDirectoryReader(segments_b:23:nrt)}
[junit4:junit4] 1> 81389 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 19
[junit4:junit4] 1> 81398 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 81415 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1436939865553895424)]} 0 4
[junit4:junit4] 1> 81428 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 81915 [commitScheduler-15-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> 81927 [commitScheduler-15-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@33d81deb main
[junit4:junit4] 1> 81929 [commitScheduler-15-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 81930 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@33d81deb main{StandardDirectoryReader(segments_c:25:nrt _6(5.0):C1)}
[junit4:junit4] 1> 81931 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 81937 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@33d81deb main{StandardDirectoryReader(segments_c:25:nrt _6(5.0):C1)}
[junit4:junit4] 1> 82442 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 82453 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id4 (1436939866643365888)]} 0 3
[junit4:junit4] 1> 82954 [commitScheduler-15-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> 82967 [commitScheduler-15-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6f134746 main
[junit4:junit4] 1> 82969 [commitScheduler-15-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 82969 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6f134746 main{StandardDirectoryReader(segments_c:27:nrt _6(5.0):C1 _7(5.0):C1)}
[junit4:junit4] 1> 82970 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 82975 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6f134746 main{StandardDirectoryReader(segments_c:27:nrt _6(5.0):C1 _7(5.0):C1)}
[junit4:junit4] 1> 83463 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id4&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 83474 [TEST-SolrExampleJettyTest.testCommitWithinOnAdd-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testCommitWithinOnAdd
[junit4:junit4] 1> 83517 [TEST-SolrExampleJettyTest.testAugmentFields-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAugmentFields
[junit4:junit4] 1> 83528 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6f236810 realtime
[junit4:junit4] 1> 83530 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939867769536512)} 0 6
[junit4:junit4] 1> 83544 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1436939867788410880)]} 0 3
[junit4:junit4] 1> 83555 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 83567 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_c,generation=12}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_d,generation=13}
[junit4:junit4] 1> 83574 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 13
[junit4:junit4] 1> 83579 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4bb95ca4 main
[junit4:junit4] 1> 83584 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4bb95ca4 main{StandardDirectoryReader(segments_d:31:nrt _8(5.0):C1)}
[junit4:junit4] 1> 83584 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 83591 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4bb95ca4 main{StandardDirectoryReader(segments_d:31:nrt _8(5.0):C1)}
[junit4:junit4] 1> 83593 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 83595 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 40
[junit4:junit4] 1> 83615 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222 (1436939867860762624)]} 0 4
[junit4:junit4] 1> 83632 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 83654 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_d,generation=13}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_e,generation=14}
[junit4:junit4] 1> 83656 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 14
[junit4:junit4] 1> 83660 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@61cf692a main
[junit4:junit4] 1> 83662 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 83663 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@61cf692a main{StandardDirectoryReader(segments_e:33:nrt _8(5.0):C1 _9(5.0):C1)}
[junit4:junit4] 1> 83665 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 83669 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@61cf692a main{StandardDirectoryReader(segments_e:33:nrt _8(5.0):C1 _9(5.0):C1)}
[junit4:junit4] 1> 83671 [qtp462151375-55] 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 39
[junit4:junit4] 1> 83696 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&fl=id,price,[docid],[explain+style%3Dnl],score,aaa:[value+v%3Daaa],ten:[value+v%3D10+t%3Dint]&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=3
[junit4:junit4] 1> 83711 [TEST-SolrExampleJettyTest.testAugmentFields-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAugmentFields
[junit4:junit4] 1> 83741 [TEST-SolrExampleJettyTest.testSetNullUpdates-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testSetNullUpdates
[junit4:junit4] 1> 83775 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1436939868024340480)]} 0 9
[junit4:junit4] 1> 83796 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 83835 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_e,generation=14}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_f,generation=15}
[junit4:junit4] 1> 83836 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 15
[junit4:junit4] 1> 83854 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5ad3c0d4 main
[junit4:junit4] 1> 83861 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5ad3c0d4 main{StandardDirectoryReader(segments_f:35:nrt _8(5.0):C1 _9(5.0):C1 _a(5.0):C1)}
[junit4:junit4] 1> 83862 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 83864 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 83873 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5ad3c0d4 main{StandardDirectoryReader(segments_f:35:nrt _8(5.0):C1 _9(5.0):C1 _a(5.0):C1)}
[junit4:junit4] 1> 83875 [qtp462151375-54] 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 79
[junit4:junit4] 1> 83904 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1436939868163801088)]} 0 6
[junit4:junit4] 1> 83920 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 83930 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_f,generation=15}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_g,generation=16}
[junit4:junit4] 1> 83932 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? newest commit generation = 16
[junit4:junit4] 1> 83944 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@343827c3 main
[junit4:junit4] 1> 83953 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 83954 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@343827c3 main{StandardDirectoryReader(segments_g:39:nrt _8(5.0):C1 _9(5.0):C1 _b(5.0):C1)}
[junit4:junit4] 1> 83955 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 83959 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@343827c3 main{StandardDirectoryReader(segments_g:39:nrt _8(5.0):C1 _9(5.0):C1 _b(5.0):C1)}
[junit4:junit4] 1> 83963 [qtp462151375-57] 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 43
[junit4:junit4] 1> 83972 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 83978 [TEST-SolrExampleJettyTest.testSetNullUpdates-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testSetNullUpdates
[junit4:junit4] 1> 83998 [TEST-SolrExampleJettyTest.testStatistics-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testStatistics
[junit4:junit4] 1> 84007 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1caa04ff realtime
[junit4:junit4] 1> 84009 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939868272852992)} 0 5
[junit4:junit4] 1> 84030 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 84032 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_g,generation=16}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_h,generation=17}
[junit4:junit4] 1> 84033 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 17
[junit4:junit4] 1> 84042 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7915b3e main
[junit4:junit4] 1> 84045 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 84047 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7915b3e main{StandardDirectoryReader(segments_g:41:nrt)}
[junit4:junit4] 1> 84047 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 84053 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7915b3e main{StandardDirectoryReader(segments_g:41:nrt)}
[junit4:junit4] 1> 84057 [qtp462151375-54] 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 27
[junit4:junit4] 1> 84067 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 84082 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1436939868347301888)]} 0 6
[junit4:junit4] 1> 84099 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1436939868370370560)]} 0 2
[junit4:junit4] 1> 84111 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1436939868384002048)]} 0 3
[junit4:junit4] 1> 84121 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1436939868394487808)]} 0 2
[junit4:junit4] 1> 84131 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1436939868403924992)]} 0 2
[junit4:junit4] 1> 84154 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1436939868429090816)]} 0 2
[junit4:junit4] 1> 84165 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1436939868440625152)]} 0 1
[junit4:junit4] 1> 84184 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1436939868460548096)]} 0 2
[junit4:junit4] 1> 84193 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1436939868469985280)]} 0 2
[junit4:junit4] 1> 84200 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1436939868477325312)]} 0 1
[junit4:junit4] 1> 84207 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 84214 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_h,generation=17}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_i,generation=18}
[junit4:junit4] 1> 84215 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 18
[junit4:junit4] 1> 84218 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@539d5db9 main
[junit4:junit4] 1> 84219 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 84220 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@539d5db9 main{StandardDirectoryReader(segments_i:43:nrt _c(5.0):C10)}
[junit4:junit4] 1> 84221 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 84224 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@539d5db9 main{StandardDirectoryReader(segments_i:43:nrt _c(5.0):C10)}
[junit4:junit4] 1> 84225 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4] 1> 84245 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4:junit4] 1> 84365 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=1
[junit4:junit4] 1> 84379 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6c5c9f4a realtime
[junit4:junit4] 1> 84381 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939868661874688)} 0 7
[junit4:junit4] 1> 84392 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 84393 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_i,generation=18}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_j,generation=19}
[junit4:junit4] 1> 84394 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 19
[junit4:junit4] 1> 84397 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@33aed797 main
[junit4:junit4] 1> 84399 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 84400 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@33aed797 main{StandardDirectoryReader(segments_i:45:nrt)}
[junit4:junit4] 1> 84401 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 84405 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@33aed797 main{StandardDirectoryReader(segments_i:45:nrt)}
[junit4:junit4] 1> 84407 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4:junit4] 1> 84419 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 84433 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc10 (1436939868718497792)]} 0 5
[junit4:junit4] 1> 84457 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc11 (1436939868742615040)]} 0 7
[junit4:junit4] 1> 84470 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc12 (1436939868759392256)]} 0 3
[junit4:junit4] 1> 84480 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc13 (1436939868770926592)]} 0 2
[junit4:junit4] 1> 84490 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc14 (1436939868781412352)]} 0 2
[junit4:junit4] 1> 84513 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 84528 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_j,generation=19}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_k,generation=20}
[junit4:junit4] 1> 84531 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? newest commit generation = 20
[junit4:junit4] 1> 84533 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4c3524ea main
[junit4:junit4] 1> 84534 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4c3524ea main{StandardDirectoryReader(segments_k:47:nrt _d(5.0):C5)}
[junit4:junit4] 1> 84535 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 84540 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 84542 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4c3524ea main{StandardDirectoryReader(segments_k:47:nrt _d(5.0):C5)}
[junit4:junit4] 1> 84544 [qtp462151375-57] 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 32
[junit4:junit4] 1> 84550 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0
[junit4:junit4] 1> 84559 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=5 status=0 QTime=1
[junit4:junit4] 1> 84575 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2ab4b8ed realtime
[junit4:junit4] 1> 84576 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939868869492736)} 0 3
[junit4:junit4] 1> 84595 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 84602 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_k,generation=20}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_l,generation=21}
[junit4:junit4] 1> 84603 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 21
[junit4:junit4] 1> 84605 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7f78be49 main
[junit4:junit4] 1> 84608 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 84611 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7f78be49 main{StandardDirectoryReader(segments_k:49:nrt)}
[junit4:junit4] 1> 84612 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 84618 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7f78be49 main{StandardDirectoryReader(segments_k:49:nrt)}
[junit4:junit4] 1> 84676 [qtp462151375-56] 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 82
[junit4:junit4] 1> 84699 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 84750 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1436939869023633408)]} 0 40
[junit4:junit4] 1> 84776 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1436939869080256512)]} 0 2
[junit4:junit4] 1> 84794 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1436939869095985152)]} 0 10
[junit4:junit4] 1> 84823 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1436939869130588160)]} 0 7
[junit4:junit4] 1> 84855 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1436939869162045440)]} 0 3
[junit4:junit4] 1> 84879 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1436939869188259840)]} 0 2
[junit4:junit4] 1> 84889 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1436939869199794176)]} 0 2
[junit4:junit4] 1> 84909 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1436939869220765696)]} 0 1
[junit4:junit4] 1> 84921 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1436939869233348608)]} 0 2
[junit4:junit4] 1> 84935 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1436939869246980096)]} 0 4
[junit4:junit4] 1> 84944 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 85033 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_l,generation=21}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_m,generation=22}
[junit4:junit4] 1> 85035 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? newest commit generation = 22
[junit4:junit4] 1> 85041 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@43f97215 main
[junit4:junit4] 1> 85042 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@43f97215 main{StandardDirectoryReader(segments_m:51:nrt _e(5.0):C10)}
[junit4:junit4] 1> 85043 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 85049 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@43f97215 main{StandardDirectoryReader(segments_m:51:nrt _e(5.0):C10)}
[junit4:junit4] 1> 85053 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 85054 [qtp462151375-52] 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 110
[junit4:junit4] 1> 85070 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=inStock:true&wt=javabin&version=2} hits=5 status=0 QTime=2
[junit4:junit4] 1> 85084 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=inStock:false&wt=javabin&version=2} hits=5 status=0 QTime=1
[junit4:junit4] 1> 85104 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={f.val_pi.stats.facet=inStock&stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=5
[junit4:junit4] 1> 85110 [TEST-SolrExampleJettyTest.testStatistics-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testStatistics
[junit4:junit4] 1> 85145 [TEST-SolrExampleJettyTest.testCommitWithinOnDelete-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testCommitWithinOnDelete
[junit4:junit4] 1> 85188 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@b042d00 realtime
[junit4:junit4] 1> 85192 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939869501784064)} 0 17
[junit4:junit4] 1> 85201 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 85204 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_m,generation=22}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_n,generation=23}
[junit4:junit4] 1> 85204 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? newest commit generation = 23
[junit4:junit4] 1> 85206 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@56d8db57 main
[junit4:junit4] 1> 85208 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@56d8db57 main{StandardDirectoryReader(segments_m:53:nrt)}
[junit4:junit4] 1> 85210 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 85214 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@56d8db57 main{StandardDirectoryReader(segments_m:53:nrt)}
[junit4:junit4] 1> 85209 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 85217 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 16
[junit4:junit4] 1> 85231 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 85250 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1436939869574135808)]} 0 5
[junit4:junit4] 1> 85265 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 85271 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_n,generation=23}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_o,generation=24}
[junit4:junit4] 1> 85273 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 24
[junit4:junit4] 1> 85276 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2b4adde7 main
[junit4:junit4] 1> 85284 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2b4adde7 main{StandardDirectoryReader(segments_o:55:nrt _f(5.0):C1)}
[junit4:junit4] 1> 85286 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 85293 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2b4adde7 main{StandardDirectoryReader(segments_o:55:nrt _f(5.0):C1)}
[junit4:junit4] 1> 85327 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 85329 [qtp462151375-54] 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 64
[junit4:junit4] 1> 85342 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=2
[junit4:junit4] 1> 85359 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1436939869691576320)]} 0 3
[junit4:junit4] 1> 85371 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 85631 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 85891 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 86151 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 86360 [commitScheduler-15-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> 86362 [commitScheduler-15-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@606492bb main
[junit4:junit4] 1> 86376 [commitScheduler-15-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 86385 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@606492bb main{StandardDirectoryReader(segments_o:57:nrt)}
[junit4:junit4] 1> 86386 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 86391 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@606492bb main{StandardDirectoryReader(segments_o:57:nrt)}
[junit4:junit4] 1> 86429 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 86438 [TEST-SolrExampleJettyTest.testCommitWithinOnDelete-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testCommitWithinOnDelete
[junit4:junit4] 1> 86474 [TEST-SolrExampleJettyTest.testContentStreamRequest-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testContentStreamRequest
[junit4:junit4] 1> 86482 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939870871224320)} 0 0
[junit4:junit4] 1> 86492 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 86494 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_o,generation=24}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_p,generation=25}
[junit4:junit4] 1> 86495 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 25
[junit4:junit4] 1> 86496 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@60c1306b main
[junit4:junit4] 1> 86497 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@60c1306b main{StandardDirectoryReader(segments_o:57:nrt)}
[junit4:junit4] 1> 86498 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 86498 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 86503 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@60c1306b main{StandardDirectoryReader(segments_o:57:nrt)}
[junit4:junit4] 1> 86505 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4:junit4] 1> 86513 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 86583 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 86613 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_p,generation=25}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_q,generation=26}
[junit4:junit4] 1> 86615 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 26
[junit4:junit4] 1> 86621 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5e8c3fe4 main
[junit4:junit4] 1> 86624 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 86639 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5e8c3fe4 main{StandardDirectoryReader(segments_q:59:nrt _g(5.0):C10)}
[junit4:junit4] 1> 86640 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 86647 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5e8c3fe4 main{StandardDirectoryReader(segments_q:59:nrt _g(5.0):C10)}
[junit4:junit4] 1> 86649 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0553573403 (1436939870919458816), 0553579908 (1436939870924701696), 055357342X (1436939870928896000), 0553293354 (1436939870932041728), 0812521390 (1436939870936236032), 0812550706 (1436939870940430336), 0441385532 (1436939870946721792), 0380014300 (1436939870961401856), 0805080481 (1436939870966644736), 080508049X (1436939870972936192)],commit=} 0 122
[junit4:junit4] 1> 86665 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=4
[junit4:junit4] 1> 86685 [TEST-SolrExampleJettyTest.testContentStreamRequest-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testContentStreamRequest
[junit4:junit4] 1> 86723 [TEST-SolrExampleJettyTest.testBadSetup-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testBadSetup
[junit4:junit4] 1> 86725 [TEST-SolrExampleJettyTest.testBadSetup-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testBadSetup
[junit4:junit4] 1> 86759 [TEST-SolrExampleJettyTest.testAddRetrieve-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAddRetrieve
[junit4:junit4] 1> 86774 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4caabc8c realtime
[junit4:junit4] 1> 86778 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939871169019904)} 0 12
[junit4:junit4] 1> 86796 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1436939871196282880), id2 (1436939871198380032)]} 0 5
[junit4:junit4] 1> 86804 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 86809 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_q,generation=26}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_r,generation=27}
[junit4:junit4] 1> 86810 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 27
[junit4:junit4] 1> 86813 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@245053e3 main
[junit4:junit4] 1> 86814 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@245053e3 main{StandardDirectoryReader(segments_r:63:nrt _h(5.0):C2)}
[junit4:junit4] 1> 86815 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 86818 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@245053e3 main{StandardDirectoryReader(segments_r:63:nrt _h(5.0):C2)}
[junit4:junit4] 1> 86820 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 86821 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 17
[junit4:junit4] 1> 86833 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4:junit4] 1> 86858 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1436939871248711680), id2 (1436939871257100288)]} 0 15
[junit4:junit4] 1> 86883 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 86892 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_r,generation=27}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_s,generation=28}
[junit4:junit4] 1> 86893 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 28
[junit4:junit4] 1> 86898 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@341dc81d main
[junit4:junit4] 1> 86901 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@341dc81d main{StandardDirectoryReader(segments_s:67:nrt _i(5.0):C2)}
[junit4:junit4] 1> 86904 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 86908 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@341dc81d main{StandardDirectoryReader(segments_s:67:nrt _i(5.0):C2)}
[junit4:junit4] 1> 86915 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 86920 [qtp462151375-53] 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 37
[junit4:junit4] 1> 86955 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=2
[junit4:junit4] 1> 86968 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h?llo&wt=javabin&version=2} hits=1 status=0 QTime=2
[junit4:junit4] 1> 86983 [TEST-SolrExampleJettyTest.testAddRetrieve-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAddRetrieve
[junit4:junit4] 1> 87038 [TEST-SolrExampleJettyTest.testExampleConfig-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testExampleConfig
[junit4:junit4] 1> 87047 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@367b9eb realtime
[junit4:junit4] 1> 87048 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939871460524032)} 0 4
[junit4:junit4] 1> 87069 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111 (1436939871481495552)]} 0 5
[junit4:junit4] 1> 87100 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 87111 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_s,generation=28}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_t,generation=29}
[junit4:junit4] 1> 87112 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 29
[junit4:junit4] 1> 87117 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5a7e7376 main
[junit4:junit4] 1> 87167 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 87175 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5a7e7376 main{StandardDirectoryReader(segments_t:71:nrt _j(5.0):C1)}
[junit4:junit4] 1> 87177 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 87184 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5a7e7376 main{StandardDirectoryReader(segments_t:71:nrt _j(5.0):C1)}
[junit4:junit4] 1> 87187 [qtp462151375-53] 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 87
[junit4:junit4] 1> 87199 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 87218 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_t,generation=29}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_u,generation=30}
[junit4:junit4] 1> 87220 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 30
[junit4:junit4] 1> 87221 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4e17c516 main
[junit4:junit4] 1> 87222 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 87223 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4e17c516 main{StandardDirectoryReader(segments_t:71:nrt _j(5.0):C1)}
[junit4:junit4] 1> 87224 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 87230 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4e17c516 main{StandardDirectoryReader(segments_t:71:nrt _j(5.0):C1)}
[junit4:junit4] 1> 87233 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 34
[junit4:junit4] 1> 87249 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 87346 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1436939871692259328), 3 (1436939871715328000), 4 (1436939871723716608), 5 (1436939871726862336)]} 0 82
[junit4:junit4] 1> 87368 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 87379 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_u,generation=30}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_v,generation=31}
[junit4:junit4] 1> 87380 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 31
[junit4:junit4] 1> 87384 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4aca9e8c main
[junit4:junit4] 1> 87387 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4aca9e8c main{StandardDirectoryReader(segments_v:73:nrt _j(5.0):C1 _k(5.0):C4)}
[junit4:junit4] 1> 87387 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 87392 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4aca9e8c main{StandardDirectoryReader(segments_v:73:nrt _j(5.0):C1 _k(5.0):C4)}
[junit4:junit4] 1> 87387 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 87425 [qtp462151375-53] 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 57
[junit4:junit4] 1> 87437 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 87515 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_v,generation=31}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_w,generation=32}
[junit4:junit4] 1> 87517 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 32
[junit4:junit4] 1> 87586 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@ae84998 main
[junit4:junit4] 1> 87587 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 87588 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@ae84998 main{StandardDirectoryReader(segments_w:75:nrt _l(5.0):C5)}
[junit4:junit4] 1> 87589 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 87594 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@ae84998 main{StandardDirectoryReader(segments_w:75:nrt _l(5.0):C5)}
[junit4:junit4] 1> 87600 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 163
[junit4:junit4] 1> 87622 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=javabin&version=2} hits=5 status=0 QTime=4
[junit4:junit4] 1> 87666 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=javabin&fq=inStock:true&version=2} hits=2 status=0 QTime=3
[junit4:junit4] 1> 87704 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&q=foo&wt=javabin&fq={!field+f%3DinStock}true&fq={!term+f%3Dname}hoss&version=2} hits=0 status=0 QTime=2
[junit4:junit4] 1> 87712 [TEST-SolrExampleJettyTest.testExampleConfig-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testExampleConfig
[junit4:junit4] 1> 87787 [TEST-SolrExampleJettyTest.testUpdateField-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateField
[junit4:junit4] 1> 87817 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7ac172dd realtime
[junit4:junit4] 1> 87819 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436939872258490368)} 0 15
[junit4:junit4] 1> 87834 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 87841 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_w,generation=32}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_x,generation=33}
[junit4:junit4] 1> 87842 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 33
[junit4:junit4] 1> 87885 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@35ad7d85 main
[junit4:junit4] 1> 87888 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 87889 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@35ad7d85 main{StandardDirectoryReader(segments_w:77:nrt)}
[junit4:junit4] 1> 87890 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 87900 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@35ad7d85 main{StandardDirectoryReader(segments_w:77:nrt)}
[junit4:junit4] 1> 87999 [qtp462151375-54] 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 165
[junit4:junit4] 1> 88034 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1436939872484982784)]} 0 13
[junit4:junit4] 1> 88058 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 88234 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_x,generation=33}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_y,generation=34}
[junit4:junit4] 1> 88235 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? newest commit generation = 34
[junit4:junit4] 1> 88238 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4ae22b2 main
[junit4:junit4] 1> 88241 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 88249 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4ae22b2 main{StandardDirectoryReader(segments_y:79:nrt _m(5.0):C1)}
[junit4:junit4] 1> 88250 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 88256 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4ae22b2 main{StandardDirectoryReader(segments_y:79:nrt _m(5.0):C1)}
[junit4:junit4] 1> 88271 [qtp462151375-57] 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 212
[junit4:junit4] 1> 88289 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 88301 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 1
[junit4:junit4] 1> 288604 [TEST-SolrExampleJettyTest.testUpdateField-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateField
[junit4:junit4] 1> 288607 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1436940082810454016)]} 0 5
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleJettyTest -Dtests.method=testUpdateField -Dtests.seed=54CE8E97A3525C11 -Dtests.slow=true -Dtests.locale=da_DK -Dtests.timezone=Asia/Bahrain -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR 201s | SolrExampleJettyTest.testUpdateField <<<
[junit4:junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:51417/solr/collection1
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([54CE8E97A3525C11:1BD24C3637A4CD7]:0)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4:junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testUpdateField(SolrExampleTests.java:1370)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
[junit4:junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
[junit4:junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
[junit4:junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
[junit4:junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
[junit4:junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
[junit4:junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
[junit4:junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
[junit4:junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
[junit4:junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
[junit4:junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
[junit4:junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4:junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4:junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
[junit4:junit4] > ... 45 more
[junit4:junit4] 1> 288992 [TEST-SolrExampleJettyTest.testPivotFacets-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPivotFacets
[junit4:junit4] 1> 289125 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7df62951 realtime
[junit4:junit4] 1> 289127 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436940083273924608)} 0 83
[junit4:junit4] 1> 289144 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 289147 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_y,generation=34}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_z,generation=35}
[junit4:junit4] 1> 289149 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 35
[junit4:junit4] 1> 289152 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1e2694e7 main
[junit4:junit4] 1> 289157 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 289158 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1e2694e7 main{StandardDirectoryReader(segments_y:83:nrt)}
[junit4:junit4] 1> 289159 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 289171 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1e2694e7 main{StandardDirectoryReader(segments_y:83:nrt)}
[junit4:junit4] 1> 289235 [qtp462151375-53] 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 91
[junit4:junit4] 1> 289257 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 289447 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1436940083553894400), 2 (1436940083629391872), 3 (1436940083632537600), 4 (1436940083634634752), 5 (1436940083640926208), 6 (1436940083645120512), 7 (1436940083647217664), 8 (1436940083655606272), 9 (1436940083684966400), 10 (1436940083687063552), ... (12 adds)]} 0 135
[junit4:junit4] 1> 289469 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 289485 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_z,generation=35}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_10,generation=36}
[junit4:junit4] 1> 289488 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 36
[junit4:junit4] 1> 289495 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2f601cda main
[junit4:junit4] 1> 289571 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2f601cda main{StandardDirectoryReader(segments_10:85:nrt _o(5.0):C12)}
[junit4:junit4] 1> 289573 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 289572 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 289579 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2f601cda main{StandardDirectoryReader(segments_10:85:nrt _o(5.0):C12)}
[junit4:junit4] 1> 289587 [qtp462151375-55] 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 118
[junit4:junit4] 1> 289618 [qtp462151375-52] 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> 289620 [qtp462151375-52] 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> 289625 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet.missing=false&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=9
[junit4:junit4] 1> 289646 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=4
[junit4:junit4] 1> 289657 [TEST-SolrExampleJettyTest.testPivotFacets-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPivotFacets
[junit4:junit4] 1> 289786 [TEST-SolrExampleJettyTest.testStreamingRequest-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testStreamingRequest
[junit4:junit4] 1> 289801 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@47da4d19 realtime
[junit4:junit4] 1> 289803 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436940084061405184)} 0 7
[junit4:junit4] 1> 289827 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 289833 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_10,generation=36}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_11,generation=37}
[junit4:junit4] 1> 289835 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 37
[junit4:junit4] 1> 289840 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@530f5e8e main
[junit4:junit4] 1> 289843 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 289845 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@530f5e8e main{StandardDirectoryReader(segments_10:87:nrt)}
[junit4:junit4] 1> 289846 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 289853 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@530f5e8e main{StandardDirectoryReader(segments_10:87:nrt)}
[junit4:junit4] 1> 289860 [qtp462151375-54] 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 34
[junit4:junit4] 1> 289882 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 289990 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 290057 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_11,generation=37}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_12,generation=38}
[junit4:junit4] 1> 290063 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 38
[junit4:junit4] 1> 290073 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@35265894 main
[junit4:junit4] 1> 290075 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 290075 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@35265894 main{StandardDirectoryReader(segments_12:89:nrt _p(5.0):C10)}
[junit4:junit4] 1> 290079 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 290086 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@35265894 main{StandardDirectoryReader(segments_12:89:nrt _p(5.0):C10)}
[junit4:junit4] 1> 290089 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0 (1436940084201914368), 1 (1436940084232323072), 2 (1436940084239663104), 3 (1436940084245954560), 4 (1436940084248051712), 5 (1436940084249100288), 6 (1436940084251197440), 7 (1436940084256440320), 8 (1436940084261683200), 9 (1436940084263780352)],commit=} 0 159
[junit4:junit4] 1> 290105 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=3
[junit4:junit4] 1> 290127 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4:junit4] 1> 290160 [TEST-SolrExampleJettyTest.testStreamingRequest-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testStreamingRequest
[junit4:junit4] 1> 290232 [TEST-SolrExampleJettyTest.testUnicode-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUnicode
[junit4:junit4] 1> 290243 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5df6cd53 realtime
[junit4:junit4] 1> 290245 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436940084528021504)} 0 5
[junit4:junit4] 1> 290263 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1436940084544798720), 1 (1436940084546895872), 2 (1436940084547944448), 3 (1436940084550041600)]} 0 6
[junit4:junit4] 1> 290272 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 290289 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_12,generation=38}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_13,generation=39}
[junit4:junit4] 1> 290295 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 39
[junit4:junit4] 1> 290300 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@10217107 main
[junit4:junit4] 1> 290304 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@10217107 main{StandardDirectoryReader(segments_13:93:nrt _q(5.0):C4)}
[junit4:junit4] 1> 290306 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 290312 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@10217107 main{StandardDirectoryReader(segments_13:93:nrt _q(5.0):C4)}
[junit4:junit4] 1> 290317 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 290319 [qtp462151375-54] 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 48
[junit4:junit4] 1> 290367 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=4} hits=4 status=0 QTime=1
[junit4:junit4] 1> 290402 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3e21d72f realtime
[junit4:junit4] 1> 290404 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1436940084681113600)} 0 17
[junit4:junit4] 1> 290430 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1436940084719910912), 1 (1436940084723056640), 2 (1436940084724105216)]} 0 7
[junit4:junit4] 1> 290455 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 290527 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_13,generation=39}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_14,generation=40}
[junit4:junit4] 1> 290529 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 40
[junit4:junit4] 1> 290534 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@60cd886a main
[junit4:junit4] 1> 290549 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 290552 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@60cd886a main{StandardDirectoryReader(segments_14:97:nrt _r(5.0):C3)}
[junit4:junit4] 1> 290553 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 290561 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@60cd886a main{StandardDirectoryReader(segments_14:97:nrt _r(5.0):C3)}
[junit4:junit4] 1> 290591 [qtp462151375-53] 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 136
[junit4:junit4] 1> 290626 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=3} hits=3 status=0 QTime=1
[junit4:junit4] 1> 290663 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@32cae8bb realtime
[junit4:junit4] 1> 290666 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1436940084965277696)} 0 8
[junit4:junit4] 1> 290700 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1436940084992540672), 1 (1436940084994637824), 2 (1436940084996734976), 3 (1436940084997783552), 4 (1436940084999880704), 5 (1436940085000929280), 6 (1436940085003026432), 7 (1436940085004075008), 8 (1436940085006172160), 9 (1436940085008269312)]} 0 17
[junit4:junit4] 1> 290717 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 290725 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_14,generation=40}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_15,generation=41}
[junit4:junit4] 1> 290734 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? newest commit generation = 41
[junit4:junit4] 1> 290738 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3943e3c4 main
[junit4:junit4] 1> 290740 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3943e3c4 main{StandardDirectoryReader(segments_15:101:nrt _s(5.0):C10)}
[junit4:junit4] 1> 290741 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 290740 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 290744 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3943e3c4 main{StandardDirectoryReader(segments_15:101:nrt _s(5.0):C10)}
[junit4:junit4] 1> 290746 [qtp462151375-52] 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 30
[junit4:junit4] 1> 290763 [qtp462151375-53] 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=1
[junit4:junit4] 1> 290804 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@23f4d85b realtime
[junit4:junit4] 1> 290807 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1436940085114175488)} 0 7
[junit4:junit4] 1> 290851 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1436940085156118528), 1 (1436940085158215680), 2 (1436940085159264256), 3 (1436940085160312832), 4 (1436940085161361408), 5 (1436940085163458560), 6 (1436940085165555712)]} 0 11
[junit4:junit4] 1> 290881 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 290895 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_15,generation=41}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_16,generation=42}
[junit4:junit4] 1> 290897 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 42
[junit4:junit4] 1> 290902 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5c3e28e1 main
[junit4:junit4] 1> 290917 [qtp462151375-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 290918 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5c3e28e1 main{StandardDirectoryReader(segments_16:105:nrt _t(5.0):C7)}
[junit4:junit4] 1> 290920 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 290928 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5c3e28e1 main{StandardDirectoryReader(segments_16:105:nrt _t(5.0):C7)}
[junit4:junit4] 1> 290989 [qtp462151375-53] 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 108
[junit4:junit4] 1> 291004 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=7} hits=7 status=0 QTime=1
[junit4:junit4] 1> 291011 [TEST-SolrExampleJettyTest.testUnicode-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUnicode
[junit4:junit4] 1> 291046 [TEST-SolrExampleJettyTest.testUpdateRequestWithParameters-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateRequestWithParameters
[junit4:junit4] 1> 291048 [TEST-SolrExampleJettyTest.testUpdateRequestWithParameters-seed#[54CE8E97A3525C11]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 291199 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7938d3fb realtime
[junit4:junit4] 1> 291202 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436940085528363008)} 0 7
[junit4:junit4] 1> 291237 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 291241 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_16,generation=42}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_17,generation=43}
[junit4:junit4] 1> 291247 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 43
[junit4:junit4] 1> 291256 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1f564653 main
[junit4:junit4] 1> 291257 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1f564653 main{StandardDirectoryReader(segments_16:107:nrt)}
[junit4:junit4] 1> 291257 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 291257 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 291265 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1f564653 main{StandardDirectoryReader(segments_16:107:nrt)}
[junit4:junit4] 1> 291268 [qtp462151375-54] 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 30
[junit4:junit4] 1> 291283 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1436940085618540544)]} 0 3
[junit4:junit4] 1> 291293 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1436940085630074880)]} 0 2
[junit4:junit4] 1> 291302 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 291306 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_17,generation=43}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_18,generation=44}
[junit4:junit4] 1> 291307 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? newest commit generation = 44
[junit4:junit4] 1> 291309 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1848173a main
[junit4:junit4] 1> 291312 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 291313 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1848173a main{StandardDirectoryReader(segments_18:109:nrt _u(5.0):C2)}
[junit4:junit4] 1> 291314 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 291317 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1848173a main{StandardDirectoryReader(segments_18:109:nrt _u(5.0):C2)}
[junit4:junit4] 1> 291319 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4] 1> 291339 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4:junit4] 1> 291343 [TEST-SolrExampleJettyTest.testUpdateRequestWithParameters-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateRequestWithParameters
[junit4:junit4] 1> 291379 [TEST-SolrExampleJettyTest.testPivotFacetsMissing-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPivotFacetsMissing
[junit4:junit4] 1> 291390 [qtp462151375-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1a81ed21 realtime
[junit4:junit4] 1> 291392 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436940085728641024)} 0 6
[junit4:junit4] 1> 291415 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 291417 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_18,generation=44}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_19,generation=45}
[junit4:junit4] 1> 291420 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 45
[junit4:junit4] 1> 291422 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1eebd11b main
[junit4:junit4] 1> 291425 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 291427 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1eebd11b main{StandardDirectoryReader(segments_18:111:nrt)}
[junit4:junit4] 1> 291428 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 291434 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1eebd11b main{StandardDirectoryReader(segments_18:111:nrt)}
[junit4:junit4] 1> 291436 [qtp462151375-54] 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 21
[junit4:junit4] 1> 291444 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 291498 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1436940085816721408), 2 (1436940085824061440), 3 (1436940085826158592), 4 (1436940085828255744), 5 (1436940085831401472), 6 (1436940085833498624), 7 (1436940085835595776), 8 (1436940085837692928), 9 (1436940085839790080), 10 (1436940085841887232), ... (12 adds)]} 0 32
[junit4:junit4] 1> 291511 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 291557 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_19,generation=45}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1a,generation=46}
[junit4:junit4] 1> 291558 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? newest commit generation = 46
[junit4:junit4] 1> 291567 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@122aa8ef main
[junit4:junit4] 1> 291569 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@122aa8ef main{StandardDirectoryReader(segments_1a:113:nrt _v(5.0):C12)}
[junit4:junit4] 1> 291570 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 291570 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 291575 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@122aa8ef main{StandardDirectoryReader(segments_1a:113:nrt _v(5.0):C12)}
[junit4:junit4] 1> 291577 [qtp462151375-57] 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 67
[junit4:junit4] 1> 291653 [qtp462151375-53] 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> 291655 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4] 1> 291662 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet.missing=true&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=11
[junit4:junit4] 1> 291682 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=8
[junit4:junit4] 1> 291685 [TEST-SolrExampleJettyTest.testPivotFacetsMissing-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPivotFacetsMissing
[junit4:junit4] 1> 291731 [TEST-SolrExampleJettyTest.testFaceting-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testFaceting
[junit4:junit4] 1> 291745 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6b558bb5 realtime
[junit4:junit4] 1> 291746 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436940086101934080)} 0 4
[junit4:junit4] 1> 291772 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 291774 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1a,generation=46}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1b,generation=47}
[junit4:junit4] 1> 291774 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? newest commit generation = 47
[junit4:junit4] 1> 291776 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@18c8fc2b main
[junit4:junit4] 1> 291778 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@18c8fc2b main{StandardDirectoryReader(segments_1a:115:nrt)}
[junit4:junit4] 1> 291779 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 291778 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 291785 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@18c8fc2b main{StandardDirectoryReader(segments_1a:115:nrt)}
[junit4:junit4] 1> 291788 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 16
[junit4:junit4] 1> 291803 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 291862 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1436940086180577280), 2 (1436940086200500224), 3 (1436940086202597376), 4 (1436940086204694528), 5 (1436940086214131712), 6 (1436940086217277440), 7 (1436940086220423168), 8 (1436940086222520320), 9 (1436940086223568896), 10 (1436940086225666048)]} 0 45
[junit4:junit4] 1> 291877 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 291886 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1b,generation=47}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1c,generation=48}
[junit4:junit4] 1> 291888 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 48
[junit4:junit4] 1> 291892 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@14c64f65 main
[junit4:junit4] 1> 291895 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 291896 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@14c64f65 main{StandardDirectoryReader(segments_1c:117:nrt _w(5.0):C10)}
[junit4:junit4] 1> 291898 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 291903 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@14c64f65 main{StandardDirectoryReader(segments_1c:117:nrt _w(5.0):C10)}
[junit4:junit4] 1> 291907 [qtp462151375-54] 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 30
[junit4:junit4] 1> 291919 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=1,phase1=1,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4] 1> 291920 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=2
[junit4:junit4] 1> 291937 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4:junit4] 1> 291956 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=4&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=3
[junit4:junit4] 1> 291981 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=-1&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=2
[junit4:junit4] 1> 292005 [TEST-SolrExampleJettyTest.testFaceting-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testFaceting
[junit4:junit4] 1> 292075 [TEST-SolrExampleJettyTest.testLukeHandler-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testLukeHandler
[junit4:junit4] 1> 292095 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@ff80080 realtime
[junit4:junit4] 1> 292097 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436940086466838528)} 0 8
[junit4:junit4] 1> 292121 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0 (1436940086487810048)]} 0 12
[junit4:junit4] 1> 292151 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1 (1436940086528704512)]} 0 3
[junit4:junit4] 1> 292185 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2 (1436940086565404672)]} 0 1
[junit4:junit4] 1> 292199 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3 (1436940086579036160)]} 0 1
[junit4:junit4] 1> 292221 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID4 (1436940086603153408)]} 0 1
[junit4:junit4] 1> 292236 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 292244 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1c,generation=48}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1d,generation=49}
[junit4:junit4] 1> 292248 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 49
[junit4:junit4] 1> 292265 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@36850fb7 main
[junit4:junit4] 1> 292345 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@36850fb7 main{StandardDirectoryReader(segments_1d:121:nrt _x(5.0):C5)}
[junit4:junit4] 1> 292346 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 292362 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 292369 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@36850fb7 main{StandardDirectoryReader(segments_1d:121:nrt _x(5.0):C5)}
[junit4:junit4] 1> 292435 [qtp462151375-55] 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 199
[junit4:junit4] 1> 292457 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=1
[junit4:junit4] 1> 292477 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=1
[junit4:junit4] 1> 292496 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2} status=0 QTime=9
[junit4:junit4] 1> 292548 [TEST-SolrExampleJettyTest.testLukeHandler-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testLukeHandler
[junit4:junit4] 1> 292692 [TEST-SolrExampleJettyTest.testUpdateMultiValuedField-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateMultiValuedField
[junit4:junit4] 1> 292717 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1436940087108567040)]} 0 15
[junit4:junit4] 1> 292739 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 292794 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1d,generation=49}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1e,generation=50}
[junit4:junit4] 1> 292795 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? newest commit generation = 50
[junit4:junit4] 1> 292836 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@be8f8e3 main
[junit4:junit4] 1> 292839 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@be8f8e3 main{StandardDirectoryReader(segments_1e:123:nrt _x(5.0):C5 _y(5.0):C1)}
[junit4:junit4] 1> 292840 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 292840 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 292844 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@be8f8e3 main{StandardDirectoryReader(segments_1e:123:nrt _x(5.0):C5 _y(5.0):C1)}
[junit4:junit4] 1> 292849 [qtp462151375-52] 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 110
[junit4:junit4] 1> 292874 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 292899 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1436940087299407872)]} 0 16
[junit4:junit4] 1> 292931 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 292938 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1e,generation=50}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1f,generation=51}
[junit4:junit4] 1> 292939 [qtp462151375-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 51
[junit4:junit4] 1> 292943 [qtp462151375-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@619587e5 main
[junit4:junit4] 1> 292945 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@619587e5 main{StandardDirectoryReader(segments_1f:127:nrt _x(5.0):C5 _z(5.0):C1)}
[junit4:junit4] 1> 292946 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 292951 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@619587e5 main{StandardDirectoryReader(segments_1f:127:nrt _x(5.0):C5 _z(5.0):C1)}
[junit4:junit4] 1> 292953 [qtp462151375-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 292955 [qtp462151375-55] 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 25
[junit4:junit4] 1> 292985 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 293013 [qtp462151375-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1436940087419994112)]} 0 15
[junit4:junit4] 1> 293025 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 293080 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1f,generation=51}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1g,generation=52}
[junit4:junit4] 1> 293098 [qtp462151375-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 52
[junit4:junit4] 1> 293162 [qtp462151375-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@22f19b06 main
[junit4:junit4] 1> 293172 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@22f19b06 main{StandardDirectoryReader(segments_1g:131:nrt _x(5.0):C5 _10(5.0):C1)}
[junit4:junit4] 1> 293172 [qtp462151375-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 293173 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 293177 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@22f19b06 main{StandardDirectoryReader(segments_1g:131:nrt _x(5.0):C5 _10(5.0):C1)}
[junit4:junit4] 1> 293181 [qtp462151375-54] 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 156
[junit4:junit4] 1> 293195 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 293203 [TEST-SolrExampleJettyTest.testUpdateMultiValuedField-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateMultiValuedField
[junit4:junit4] 1> 293267 [TEST-SolrExampleJettyTest.testAddDelete-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAddDelete
[junit4:junit4] 1> 293277 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4494aed2 realtime
[junit4:junit4] 1> 293279 [qtp462151375-57] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1436940087708352512)} 0 6
[junit4:junit4] 1> 293286 [qtp462151375-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1436940087719886848)]} 0 1
[junit4:junit4] 1> 293294 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 293298 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1g,generation=52}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1h,generation=53}
[junit4:junit4] 1> 293299 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? newest commit generation = 53
[junit4:junit4] 1> 293302 [qtp462151375-52] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2a65555 main
[junit4:junit4] 1> 293305 [qtp462151375-52] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 293306 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2a65555 main{StandardDirectoryReader(segments_1h:135:nrt _11(5.0):C1)}
[junit4:junit4] 1> 293307 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 293311 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2a65555 main{StandardDirectoryReader(segments_1h:135:nrt _11(5.0):C1)}
[junit4:junit4] 1> 293313 [qtp462151375-52] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 19
[junit4:junit4] 1> 293330 [qtp462151375-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 293346 [qtp462151375-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1436940087781752832)]} 0 2
[junit4:junit4] 1> 293354 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 293359 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1h,generation=53}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1i,generation=54}
[junit4:junit4] 1> 293360 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? newest commit generation = 54
[junit4:junit4] 1> 293
[...truncated too long message...]
[qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 293781 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1k,generation=56}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1l,generation=57}
[junit4:junit4] 1> 293782 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? newest commit generation = 57
[junit4:junit4] 1> 293806 [qtp462151375-57] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4648e733 main
[junit4:junit4] 1> 293810 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4648e733 main{StandardDirectoryReader(segments_1l:143:nrt _13(5.0):C3)}
[junit4:junit4] 1> 293810 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 293814 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4648e733 main{StandardDirectoryReader(segments_1l:143:nrt _13(5.0):C3)}
[junit4:junit4] 1> 293810 [qtp462151375-57] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 293829 [qtp462151375-57] 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 52
[junit4:junit4] 1> 293840 [qtp462151375-52] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=1
[junit4:junit4] 1> 293856 [qtp462151375-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1436940088313380864), 1 & 222 (-1436940088315478016), 2 & 222 (-1436940088315478017)]} 0 5
[junit4:junit4] 1> 293866 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 293890 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1l,generation=57}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15a27b54 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3afbe236),segFN=segments_1m,generation=58}
[junit4:junit4] 1> 293891 [qtp462151375-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 58
[junit4:junit4] 1> 293893 [qtp462151375-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1817c4e6 main
[junit4:junit4] 1> 293895 [qtp462151375-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 293896 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1817c4e6 main{StandardDirectoryReader(segments_1m:145:nrt)}
[junit4:junit4] 1> 293897 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 293902 [searcherExecutor-12-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1817c4e6 main{StandardDirectoryReader(segments_1m:145:nrt)}
[junit4:junit4] 1> 293953 [qtp462151375-56] 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 87
[junit4:junit4] 1> 293964 [qtp462151375-57] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 293967 [TEST-SolrExampleJettyTest.testAddDelete-seed#[54CE8E97A3525C11]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAddDelete
[junit4:junit4] 1> 294008 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.CoreContainer ? Shutting down CoreContainer instance=1413728603
[junit4:junit4] 1> 294010 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCore ? [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f9bca3f
[junit4:junit4] 1> 294135 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.update.UpdateHandler ? closing DirectUpdateHandler2{commits=58,autocommit maxTime=15000ms,autocommits=0,soft autocommits=3,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=147,cumulative_deletesById=5,cumulative_deletesByQuery=28,cumulative_errors=1}
[junit4:junit4] 1> 294145 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.update.SolrCoreState ? Closing SolrCoreState
[junit4:junit4] 1> 294147 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.update.DefaultSolrCoreState ? SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 1> 294147 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.update.DefaultSolrCoreState ? closing IndexWriter with IndexWriterCloser
[junit4:junit4] 1> 294157 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.SolrCore ? [collection1] Closing main searcher on request.
[junit4:junit4] 1> 294160 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 1> 294160 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901 [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901;done=false>>]
[junit4:junit4] 1> 294161 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901
[junit4:junit4] 1> 294174 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901/index;done=false>>]
[junit4:junit4] 1> 294176 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleJettyTest-1370372635901/index
[junit4:junit4] 1> 294177 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler ? stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 1> 294232 [SUITE-SolrExampleJettyTest-seed#[54CE8E97A3525C11]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=da_DK, timezone=Asia/Bahrain
[junit4:junit4] 2> NOTE: Mac OS X 10.8.3 x86_64/Oracle Corporation 1.7.0_21 (64-bit)/cpus=2,threads=1,free=67317464,total=96468992
[junit4:junit4] 2> NOTE: All tests run in this JVM: [SolrQueryTest, SolrExampleStreamingBinaryTest, IteratorChainTest, AnlysisResponseBaseTest, SolrExampleJettyTest]
[junit4:junit4] Completed in 221.19s, 27 tests, 1 error <<< FAILURES!
[...truncated 132 lines...]
BUILD FAILED
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/build.xml:380: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/build.xml:360: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:194: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:437: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1240: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:884: There were test failures: 46 suites, 272 tests, 1 error
Total time: 136 minutes 35 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure