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/09 02:08:50 UTC
[JENKINS] Lucene-Solr-trunk-Linux (64bit/ibm-j9-jdk7) - Build #
6047 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/6047/
Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
1 tests failed.
REGRESSION: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest.testCommitWithinOnDelete
Error Message:
IOException occured when talking to server at: https://127.0.0.1:57840/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:57840/solr/collection1
at __randomizedtesting.SeedInfo.seed([DF2AF81E5AEA4507:B33E0FAF8069AF3C]: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.commit(SolrServer.java:168)
at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
at org.apache.solr.client.solrj.SolrExampleTests.testCommitWithinOnDelete(SolrExampleTests.java:470)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
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:780)
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 10590 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest
[junit4:junit4] 1> 54851 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.SolrJettyTestBase – Randomized ssl (true) and clientAuth (false)
[junit4:junit4] 1> 54852 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ####initCore
[junit4:junit4] 1> 54853 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ####initCore end
[junit4:junit4] 1> 54854 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.eclipse.jetty.server.Server – jetty-8.1.10.v20130312
[junit4:junit4] 1> 54857 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.eclipse.jetty.util.ssl.SslContextFactory – Enabled Protocols [TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4:junit4] 1> 54861 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.eclipse.jetty.server.AbstractConnector – Started SslSelectChannelConnector@127.0.0.1:57840
[junit4:junit4] 1> 54863 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init()
[junit4:junit4] 1> 54863 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrResourceLoader – JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 1> 54864 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrResourceLoader – using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
[junit4:junit4] 1> 54865 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.CoreContainer – looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/solr.xml
[junit4:junit4] 1> 54865 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.CoreContainer – New CoreContainer -1789457270
[junit4:junit4] 1> 54866 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.CoreContainer – Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/'
[junit4:junit4] 1> 54867 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/'
[junit4:junit4] 1> 54944 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
[junit4:junit4] 1> 54945 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Discovered properties file /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/core.properties, adding to cores
[junit4:junit4] 1> 54946 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn
[junit4:junit4] 1> 54947 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/prop-base
[junit4:junit4] 1> 54948 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/tmp
[junit4:junit4] 1> 54949 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/tmp/prop-base
[junit4:junit4] 1> 54949 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/tmp/text-base
[junit4:junit4] 1> 54950 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/tmp/props
[junit4:junit4] 1> 54951 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/text-base
[junit4:junit4] 1> 54952 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/props
[junit4:junit4] 1> 54954 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting socketTimeout to: 0
[junit4:junit4] 1> 54955 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting urlScheme to: http://
[junit4:junit4] 1> 54955 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting connTimeout to: 0
[junit4:junit4] 1> 54956 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maxConnectionsPerHost to: 20
[junit4:junit4] 1> 54957 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting corePoolSize to: 0
[junit4:junit4] 1> 54957 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maximumPoolSize to: 2147483647
[junit4:junit4] 1> 54958 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maxThreadIdleTime to: 5
[junit4:junit4] 1> 54959 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting sizeOfQueue to: -1
[junit4:junit4] 1> 54959 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting fairnessPolicy to: false
[junit4:junit4] 1> 54960 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-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> 54963 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.logging.LogWatcher – Registering Log Listener
[junit4:junit4] 1> 54966 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.CoreContainer – Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1
[junit4:junit4] 1> 54967 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/'
[junit4:junit4] 1> 54994 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrConfig – Adding specified lib dirs to ClassLoader
[junit4:junit4] 1> 54995 [coreLoadExecutor-192-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4:junit4] 1> 54996 [coreLoadExecutor-192-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 54996 [coreLoadExecutor-192-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4:junit4] 1> 54997 [coreLoadExecutor-192-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 54997 [coreLoadExecutor-192-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4:junit4] 1> 54998 [coreLoadExecutor-192-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 54998 [coreLoadExecutor-192-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4:junit4] 1> 54999 [coreLoadExecutor-192-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 55038 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrConfig – Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 1> 55111 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.Config – Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 1> 55112 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.schema.IndexSchema – Reading Solr Schema from schema.xml
[junit4:junit4] 1> 55133 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.schema.IndexSchema – [collection1] Schema name=example
[junit4:junit4] 1> 55907 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.schema.IndexSchema – unique key field: id
[junit4:junit4] 1> 55973 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider – Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 55991 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider – Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 56083 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 56084 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041/
[junit4:junit4] 1> 56085 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.JmxMonitoredMap – No JMX servers found, not exposing Solr information with JMX.
[junit4:junit4] 1> 56085 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4] 1> 56086 [coreLoadExecutor-192-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> 56086 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 56087 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory – return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041
[junit4:junit4] 1> 56088 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041/index/
[junit4:junit4] 1> 56089 [coreLoadExecutor-192-thread-1] WARN org.apache.solr.core.SolrCore – [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041/index' doesn't exist. Creating new index...
[junit4:junit4] 1> 56091 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory – return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041/index
[junit4:junit4] 1> 56101 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1,generation=1}
[junit4:junit4] 1> 56101 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – newest commit generation = 1
[junit4:junit4] 1> 56102 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – created json: solr.JSONResponseWriter
[junit4:junit4] 1> 56103 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4] 1> 56103 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – created velocity: solr.VelocityResponseWriter
[junit4:junit4] 1> 56104 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – created xslt: solr.XSLTResponseWriter
[junit4:junit4] 1> 56104 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.response.XSLTResponseWriter – xsltCacheLifetimeSeconds=5
[junit4:junit4] 1> 56106 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /select: solr.SearchHandler
[junit4:junit4] 1> 56106 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /query: solr.SearchHandler
[junit4:junit4] 1> 56107 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /get: solr.RealTimeGetHandler
[junit4:junit4] 1> 56108 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /browse: solr.SearchHandler
[junit4:junit4] 1> 56108 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update: solr.UpdateRequestHandler
[junit4:junit4] 1> 56109 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4] 1> 56109 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/csv: solr.CSVRequestHandler
[junit4:junit4] 1> 56110 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 56110 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 56111 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 56111 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 56112 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 56113 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 56114 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /admin/: solr.admin.AdminHandlers
[junit4:junit4] 1> 56116 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /admin/ping: solr.PingRequestHandler
[junit4:junit4] 1> 56118 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 1> 56120 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /replication: solr.ReplicationHandler
[junit4:junit4] 1> 56122 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 56122 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /spell: solr.SearchHandler
[junit4:junit4] 1> 56122 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 56123 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /tvrh: solr.SearchHandler
[junit4:junit4] 1> 56124 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 56124 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /terms: solr.SearchHandler
[junit4:junit4] 1> 56125 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 56126 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.RequestHandlers – created /elevate: solr.SearchHandler
[junit4:junit4] 1> 56137 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 56141 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 56143 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 56151 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@8b04ae2f main
[junit4:junit4] 1> 56155 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.update.UpdateHandler – Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041/tlog
[junit4:junit4] 1> 56159 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – Hard AutoCommit: if uncommited for 15000ms;
[junit4:junit4] 1> 56159 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.SolrCore – Soft AutoCommit: disabled
[junit4:junit4] 1> 56160 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Initializing spell checkers
[junit4:junit4] 1> 56162 [coreLoadExecutor-192-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> 56164 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – No queryConverter defined, using default converter
[junit4:junit4] 1> 56167 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.handler.component.QueryElevationComponent – Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4:junit4] 1> 56178 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.handler.ReplicationHandler – Commits will be reserved for 10000
[junit4:junit4] 1> 56179 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@8b04ae2f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 56179 [coreLoadExecutor-192-thread-1] INFO org.apache.solr.core.CoreContainer – registering core: collection1
[junit4:junit4] 1> 56180 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] webapp=null path=null params={q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false&event=firstSearcher} hits=0 status=0 QTime=1
[junit4:junit4] 1> 56180 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1
[junit4:junit4] 1> 56181 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init() done
[junit4:junit4] 1> 56181 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 56182 [searcherExecutor-193-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Loading spell index for spellchecker: default
[junit4:junit4] 1> 56182 [searcherExecutor-193-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Loading spell index for spellchecker: wordbreak
[junit4:junit4] 1> 56183 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@8b04ae2f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 56181 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.SolrJettyTestBase – Jetty Assigned Port#57840
[junit4:junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/./solrtest-SolrExampleJettyTest-1370736319041
[junit4:junit4] 1> 56194 [TEST-SolrExampleJettyTest.testQueryWithParams-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testQueryWithParams
[junit4:junit4] 1> 56195 [TEST-SolrExampleJettyTest.testQueryWithParams-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 56897 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=query&debug=true&version=2&wt=javabin} hits=0 status=0 QTime=4
[junit4:junit4] 1> 56899 [TEST-SolrExampleJettyTest.testQueryWithParams-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testQueryWithParams
[junit4:junit4] 1> 56924 [TEST-SolrExampleJettyTest.testCommitWithinOnDelete-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testCommitWithinOnDelete
[junit4:junit4] 1> 56930 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1,generation=1}
[junit4:junit4] 1> 56932 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 1
[junit4:junit4] 1> 56934 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@605de7aa realtime
[junit4:junit4] 1> 56935 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321208652627968)} 0 7
[junit4:junit4] 1> 71935 [commitScheduler-195-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 71936 [commitScheduler-195-thread-1] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1,generation=1}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_2,generation=2}
[junit4:junit4] 1> 71936 [commitScheduler-195-thread-1] INFO org.apache.solr.core.SolrCore – newest commit generation = 2
[junit4:junit4] 1> 71937 [commitScheduler-195-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257180 [TEST-SolrExampleJettyTest.testCommitWithinOnDelete-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testCommitWithinOnDelete
[junit4:junit4] 1> 257180 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257182 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_2,generation=2}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_3,generation=3}
[junit4:junit4] 1> 257183 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 3
[junit4:junit4] 1> 257183 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@78b879ba main
[junit4:junit4] 1> 257184 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257184 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@78b879ba main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 257186 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257186 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@78b879ba main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 257187 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleJettyTest -Dtests.method=testCommitWithinOnDelete -Dtests.seed=DF2AF81E5AEA4507 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sv_SE_PREEURO -Dtests.timezone=Europe/Zurich -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR 200s J1 | SolrExampleJettyTest.testCommitWithinOnDelete <<<
[junit4:junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:57840/solr/collection1
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([DF2AF81E5AEA4507:B33E0FAF8069AF3C]: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.commit(SolrServer.java:168)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testCommitWithinOnDelete(SolrExampleTests.java:470)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:780)
[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> 257217 [TEST-SolrExampleJettyTest.testPivotFacets-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPivotFacets
[junit4:junit4] 1> 257226 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321418678206464)} 0 2
[junit4:junit4] 1> 257229 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257230 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_3,generation=3}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_4,generation=4}
[junit4:junit4] 1> 257231 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 4
[junit4:junit4] 1> 257232 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@937aad8d main
[junit4:junit4] 1> 257233 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257233 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@937aad8d main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 257234 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257234 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@937aad8d main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 257235 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 6
[junit4:junit4] 1> 257237 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 257244 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 (1437321418694983680), 2 (1437321418696032256), 3 (1437321418696032257), 4 (1437321418696032258), 5 (1437321418696032259), 6 (1437321418697080832), 7 (1437321418697080833), 8 (1437321418697080834), 9 (1437321418697080835), 10 (1437321418697080836), ... (12 adds)]} 0 3
[junit4:junit4] 1> 257246 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257250 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_4,generation=4}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_5,generation=5}
[junit4:junit4] 1> 257250 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 5
[junit4:junit4] 1> 257252 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@8cd9a617 main
[junit4:junit4] 1> 257253 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257253 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@8cd9a617 main{StandardDirectoryReader(segments_5:3:nrt _0(5.0):C12)}
[junit4:junit4] 1> 257254 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257254 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@8cd9a617 main{StandardDirectoryReader(segments_5:3:nrt _0(5.0):C12)}
[junit4:junit4] 1> 257255 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 9
[junit4:junit4] 1> 257273 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4] 1> 257276 [qtp1889012424-296] 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> 257279 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&facet.mincount=0&facet=true&version=2&rows=0&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&wt=javabin&facet.missing=false} hits=12 status=0 QTime=22
[junit4:junit4] 1> 257288 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&facet.mincount=0&facet=true&version=2&rows=0&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&wt=javabin} hits=11 status=0 QTime=6
[junit4:junit4] 1> 257289 [TEST-SolrExampleJettyTest.testPivotFacets-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPivotFacets
[junit4:junit4] 1> 257299 [TEST-SolrExampleJettyTest.testFaceting-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testFaceting
[junit4:junit4] 1> 257303 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@95c2ffaf realtime
[junit4:junit4] 1> 257304 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321418757898240)} 0 3
[junit4:junit4] 1> 257307 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257308 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_5,generation=5}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_6,generation=6}
[junit4:junit4] 1> 257309 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 6
[junit4:junit4] 1> 257310 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@ebde9d5 main
[junit4:junit4] 1> 257311 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257311 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@ebde9d5 main{StandardDirectoryReader(segments_5:5:nrt)}
[junit4:junit4] 1> 257312 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257312 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@ebde9d5 main{StandardDirectoryReader(segments_5:5:nrt)}
[junit4:junit4] 1> 257313 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 6
[junit4:junit4] 1> 257316 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 257322 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 (1437321418776772608), 2 (1437321418777821184), 3 (1437321418777821185), 4 (1437321418778869760), 5 (1437321418778869761), 6 (1437321418778869762), 7 (1437321418778869763), 8 (1437321418778869764), 9 (1437321418778869765), 10 (1437321418778869766)]} 0 3
[junit4:junit4] 1> 257324 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257328 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_6,generation=6}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_7,generation=7}
[junit4:junit4] 1> 257329 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 7
[junit4:junit4] 1> 257331 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@dda76c0a main
[junit4:junit4] 1> 257332 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257332 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@dda76c0a main{StandardDirectoryReader(segments_7:7:nrt _1(5.0):C10)}
[junit4:junit4] 1> 257333 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257334 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@dda76c0a main{StandardDirectoryReader(segments_7:7:nrt _1(5.0):C10)}
[junit4:junit4] 1> 257334 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 10
[junit4:junit4] 1> 257337 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=0,phase1=0,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4] 1> 257339 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&facet.field=features&facet.mincount=0&facet=true&version=2&rows=0&wt=javabin} hits=10 status=0 QTime=2
[junit4:junit4] 1> 257342 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&facet.field=features&facet.mincount=0&facet=true&version=2&rows=0&fq=features:two&wt=javabin} hits=5 status=0 QTime=1
[junit4:junit4] 1> 257345 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&facet.field=features&facet.mincount=4&facet=true&version=2&rows=0&fq=features:two&wt=javabin} hits=5 status=0 QTime=1
[junit4:junit4] 1> 257348 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&facet.field=features&facet.mincount=-1&facet=true&version=2&rows=0&fq=features:two&wt=javabin} hits=5 status=0 QTime=1
[junit4:junit4] 1> 257349 [TEST-SolrExampleJettyTest.testFaceting-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testFaceting
[junit4:junit4] 1> 257356 [TEST-SolrExampleJettyTest.testMultiContentStreamRequest-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testMultiContentStreamRequest
[junit4:junit4] 1> 257360 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1d926f75 realtime
[junit4:junit4] 1> 257360 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321418817667072)} 0 2
[junit4:junit4] 1> 257363 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257364 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_7,generation=7}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_8,generation=8}
[junit4:junit4] 1> 257365 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 8
[junit4:junit4] 1> 257365 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2f2e1b57 main
[junit4:junit4] 1> 257366 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257366 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2f2e1b57 main{StandardDirectoryReader(segments_7:9:nrt)}
[junit4:junit4] 1> 257367 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257367 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2f2e1b57 main{StandardDirectoryReader(segments_7:9:nrt)}
[junit4:junit4] 1> 257368 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 5
[junit4:junit4] 1> 257370 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 257439 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257445 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_8,generation=8}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_9,generation=9}
[junit4:junit4] 1> 257446 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 9
[junit4:junit4] 1> 257448 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@ada93e27 main
[junit4:junit4] 1> 257450 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257450 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@ada93e27 main{StandardDirectoryReader(segments_9:11:nrt _2(5.0):C5)}
[junit4:junit4] 1> 257451 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257451 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@ada93e27 main{StandardDirectoryReader(segments_9:11:nrt _2(5.0):C5)}
[junit4:junit4] 1> 257451 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&a=ሴ&commit=true&version=2&waitSearcher=true&echoParams=ALL&wt=javabin} {add=[SP2514N (1437321418891067392), 6H500F0 (1437321418896310272), TWINX2048-3200PRO (1437321418897358848), VS1GB400C3 (1437321418900504576), VDBDB1A16 (1437321418901553152)],commit=} 0 25
[junit4:junit4] 1> 257454 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=5 status=0 QTime=0
[junit4:junit4] 1> 257457 [TEST-SolrExampleJettyTest.testMultiContentStreamRequest-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testMultiContentStreamRequest
[junit4:junit4] 1> 257467 [TEST-SolrExampleJettyTest.testGetEmptyResults-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testGetEmptyResults
[junit4:junit4] 1> 257470 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@987db6e realtime
[junit4:junit4] 1> 257471 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321418934059008)} 0 2
[junit4:junit4] 1> 257474 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257475 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_9,generation=9}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_a,generation=10}
[junit4:junit4] 1> 257476 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 10
[junit4:junit4] 1> 257478 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@9e133f8e main
[junit4:junit4] 1> 257479 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257479 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@9e133f8e main{StandardDirectoryReader(segments_9:13:nrt)}
[junit4:junit4] 1> 257480 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257480 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@9e133f8e main{StandardDirectoryReader(segments_9:13:nrt)}
[junit4:junit4] 1> 257481 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 257485 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id1 (1437321418949787648)]} 0 1
[junit4:junit4] 1> 257488 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id2 (1437321418953981952)]} 0 0
[junit4:junit4] 1> 257491 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257495 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_a,generation=10}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_b,generation=11}
[junit4:junit4] 1> 257496 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 11
[junit4:junit4] 1> 257498 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@76ddd887 main
[junit4:junit4] 1> 257499 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257499 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@76ddd887 main{StandardDirectoryReader(segments_b:15:nrt _3(5.0):C2)}
[junit4:junit4] 1> 257500 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257501 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@76ddd887 main{StandardDirectoryReader(segments_b:15:nrt _3(5.0):C2)}
[junit4:junit4] 1> 257501 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 10
[junit4:junit4] 1> 257824 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&fl=foofoofoo&version=2&wt=javabin} hits=2 status=0 QTime=320
[junit4:junit4] 1> 257825 [TEST-SolrExampleJettyTest.testGetEmptyResults-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testGetEmptyResults
[junit4:junit4] 1> 257833 [TEST-SolrExampleJettyTest.testPingHandler-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPingHandler
[junit4:junit4] 1> 257836 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@83329ef1 realtime
[junit4:junit4] 1> 257837 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321419317837824)} 0 2
[junit4:junit4] 1> 257840 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257842 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_b,generation=11}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_c,generation=12}
[junit4:junit4] 1> 257842 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 12
[junit4:junit4] 1> 257844 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@17fb78ef main
[junit4:junit4] 1> 257845 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257845 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@17fb78ef main{StandardDirectoryReader(segments_b:17:nrt)}
[junit4:junit4] 1> 257846 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257846 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@17fb78ef main{StandardDirectoryReader(segments_b:17:nrt)}
[junit4:junit4] 1> 257847 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 257850 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 257854 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/ping params={version=2&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 1> 257855 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/ping params={version=2&wt=javabin} status=0 QTime=2
[junit4:junit4] 1> 257856 [TEST-SolrExampleJettyTest.testPingHandler-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPingHandler
[junit4:junit4] 1> 257862 [TEST-SolrExampleJettyTest.testSetNullUpdateOrder-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testSetNullUpdateOrder
[junit4:junit4] 1> 257866 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdateOrder (1437321419349295104)]} 0 1
[junit4:junit4] 1> 257869 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257872 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_c,generation=12}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_d,generation=13}
[junit4:junit4] 1> 257873 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 13
[junit4:junit4] 1> 257875 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@54f7dd9b main
[junit4:junit4] 1> 257876 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@54f7dd9b main{StandardDirectoryReader(segments_d:19:nrt _4(5.0):C1)}
[junit4:junit4] 1> 257876 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257876 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257876 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@54f7dd9b main{StandardDirectoryReader(segments_d:19:nrt _4(5.0):C1)}
[junit4:junit4] 1> 257878 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 9
[junit4:junit4] 1> 257891 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdateOrder (1437321419375509504)]} 0 3
[junit4:junit4] 1> 257894 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257896 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_d,generation=13}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_e,generation=14}
[junit4:junit4] 1> 257897 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 14
[junit4:junit4] 1> 257898 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@a8d75c68 main
[junit4:junit4] 1> 257899 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@a8d75c68 main{StandardDirectoryReader(segments_e:23:nrt _5(5.0):C1)}
[junit4:junit4] 1> 257899 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257899 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257900 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@a8d75c68 main{StandardDirectoryReader(segments_e:23:nrt _5(5.0):C1)}
[junit4:junit4] 1> 257901 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 257903 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&version=2&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 257905 [TEST-SolrExampleJettyTest.testSetNullUpdateOrder-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testSetNullUpdateOrder
[junit4:junit4] 1> 257911 [TEST-SolrExampleJettyTest.testAddDelete-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testAddDelete
[junit4:junit4] 1> 257915 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@56877161 realtime
[junit4:junit4] 1> 257916 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321419400675328)} 0 3
[junit4:junit4] 1> 257919 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 & 222 (1437321419405918208)]} 0 0
[junit4:junit4] 1> 257922 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257925 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_e,generation=14}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_f,generation=15}
[junit4:junit4] 1> 257925 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 15
[junit4:junit4] 1> 257927 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2216ba38 main
[junit4:junit4] 1> 257929 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2216ba38 main{StandardDirectoryReader(segments_f:27:nrt _6(5.0):C1)}
[junit4:junit4] 1> 257929 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257929 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257930 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2216ba38 main{StandardDirectoryReader(segments_f:27:nrt _6(5.0):C1)}
[junit4:junit4] 1> 257930 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 8
[junit4:junit4] 1> 257933 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 257937 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {delete=[0 & 222 (-1437321419424792576)]} 0 1
[junit4:junit4] 1> 257940 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257941 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_f,generation=15}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_g,generation=16}
[junit4:junit4] 1> 257942 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 16
[junit4:junit4] 1> 257943 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@184dca3e main
[junit4:junit4] 1> 257943 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@184dca3e main{StandardDirectoryReader(segments_g:29:nrt)}
[junit4:junit4] 1> 257943 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257944 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257945 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@184dca3e main{StandardDirectoryReader(segments_g:29:nrt)}
[junit4:junit4] 1> 257945 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 5
[junit4:junit4] 1> 257947 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 257952 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 & 222 (1437321419439472640)]} 0 1
[junit4:junit4] 1> 257955 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257958 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_g,generation=16}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_h,generation=17}
[junit4:junit4] 1> 257959 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 17
[junit4:junit4] 1> 257960 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@be2190e9 main
[junit4:junit4] 1> 257962 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@be2190e9 main{StandardDirectoryReader(segments_h:31:nrt _7(5.0):C1)}
[junit4:junit4] 1> 257962 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257962 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257962 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@be2190e9 main{StandardDirectoryReader(segments_h:31:nrt _7(5.0):C1)}
[junit4:junit4] 1> 257963 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 8
[junit4:junit4] 1> 257966 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 257970 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7b12111c realtime
[junit4:junit4] 1> 257971 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=id:"0\ \&\ 222" (-1437321419458347008)} 0 2
[junit4:junit4] 1> 257973 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257975 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_h,generation=17}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_i,generation=18}
[junit4:junit4] 1> 257976 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 18
[junit4:junit4] 1> 257977 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7a15c99d main
[junit4:junit4] 1> 257978 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@7a15c99d main{StandardDirectoryReader(segments_h:33:nrt)}
[junit4:junit4] 1> 257978 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257978 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257978 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@7a15c99d main{StandardDirectoryReader(segments_h:33:nrt)}
[junit4:junit4] 1> 257980 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 257983 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 257986 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 & 222 (1437321419476172800)]} 0 1
[junit4:junit4] 1> 257989 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 & 222 (1437321419479318528)]} 0 0
[junit4:junit4] 1> 257992 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[2 & 222 (1437321419482464256)]} 0 0
[junit4:junit4] 1> 257995 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257998 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_i,generation=18}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_j,generation=19}
[junit4:junit4] 1> 257999 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 19
[junit4:junit4] 1> 258001 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@27ccc35a main
[junit4:junit4] 1> 258002 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@27ccc35a main{StandardDirectoryReader(segments_j:35:nrt _8(5.0):C3)}
[junit4:junit4] 1> 258002 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 258002 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 258002 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@27ccc35a main{StandardDirectoryReader(segments_j:35:nrt _8(5.0):C3)}
[junit4:junit4] 1> 258004 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 9
[junit4:junit4] 1> 258006 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=3 status=0 QTime=0
[junit4:junit4] 1> 258010 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {delete=[0 & 222 (-1437321419500290048), 1 & 222 (-1437321419501338624), 2 & 222 (-1437321419501338625)]} 0 1
[junit4:junit4] 1> 258013 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258014 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_j,generation=19}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_k,generation=20}
[junit4:junit4] 1> 258015 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 20
[junit4:junit4] 1> 258017 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@9a874e4a main
[junit4:junit4] 1> 258028 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@9a874e4a main{StandardDirectoryReader(segments_k:37:nrt)}
[junit4:junit4] 1> 258029 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 258029 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 258029 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@9a874e4a main{StandardDirectoryReader(segments_k:37:nrt)}
[junit4:junit4] 1> 258030 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 17
[junit4:junit4] 1> 258033 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 258035 [TEST-SolrExampleJettyTest.testAddDelete-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAddDelete
[junit4:junit4] 1> 258043 [TEST-SolrExampleJettyTest.testContentStreamRequest-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testContentStreamRequest
[junit4:junit4] 1> 258046 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321419539087360)} 0 1
[junit4:junit4] 1> 258049 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258050 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_k,generation=20}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_l,generation=21}
[junit4:junit4] 1> 258051 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 21
[junit4:junit4] 1> 258053 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7cf43a38 main
[junit4:junit4] 1> 258054 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@7cf43a38 main{StandardDirectoryReader(segments_k:37:nrt)}
[junit4:junit4] 1> 258054 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 258054 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 258054 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@7cf43a38 main{StandardDirectoryReader(segments_k:37:nrt)}
[junit4:junit4] 1> 258055 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 6
[junit4:junit4] 1> 258058 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 258079 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258084 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_l,generation=21}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_m,generation=22}
[junit4:junit4] 1> 258085 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 22
[junit4:junit4] 1> 258088 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@cd70d6e5 main
[junit4:junit4] 1> 258089 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@cd70d6e5 main{StandardDirectoryReader(segments_m:39:nrt _9(5.0):C10)}
[junit4:junit4] 1> 258089 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 258089 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 258090 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@cd70d6e5 main{StandardDirectoryReader(segments_m:39:nrt _9(5.0):C10)}
[junit4:junit4] 1> 258091 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {add=[0553573403 (1437321419564253184), 0553579908 (1437321419567398912), 055357342X (1437321419567398913), 0553293354 (1437321419568447488), 0812521390 (1437321419569496064), 0812550706 (1437321419570544640), 0441385532 (1437321419570544641), 0380014300 (1437321419571593216), 0805080481 (1437321419572641792), 080508049X (1437321419572641793)],commit=} 0 28
[junit4:junit4] 1> 258094 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=10 status=0 QTime=0
[junit4:junit4] 1> 258096 [TEST-SolrExampleJettyTest.testContentStreamRequest-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testContentStreamRequest
[junit4:junit4] 1> 258104 [TEST-SolrExampleJettyTest.testStreamingRequest-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testStreamingRequest
[junit4:junit4] 1> 258108 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6664744 realtime
[junit4:junit4] 1> 258109 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321419603050496)} 0 2
[junit4:junit4] 1> 258112 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258113 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_m,generation=22}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_n,generation=23}
[junit4:junit4] 1> 258114 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 23
[junit4:junit4] 1> 258115 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@76557c50 main
[junit4:junit4] 1> 258116 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@76557c50 main{StandardDirectoryReader(segments_m:41:nrt)}
[junit4:junit4] 1> 258116 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 258116 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 258116 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@76557c50 main{StandardDirectoryReader(segments_m:41:nrt)}
[junit4:junit4] 1> 258117 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 5
[junit4:junit4] 1> 258120 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 258126 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258129 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_n,generation=23}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_o,generation=24}
[junit4:junit4] 1> 258130 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 24
[junit4:junit4] 1> 258132 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@9334f32f main
[junit4:junit4] 1> 258133 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@9334f32f main{StandardDirectoryReader(segments_o:43:nrt _a(5.0):C10)}
[junit4:junit4] 1> 258134 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 258134 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 258134 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@9334f32f main{StandardDirectoryReader(segments_o:43:nrt _a(5.0):C10)}
[junit4:junit4] 1> 258135 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {add=[0 (1437321419619827712), 1 (1437321419620876288), 2 (1437321419621924864), 3 (1437321419621924865), 4 (1437321419621924866), 5 (1437321419621924867), 6 (1437321419621924868), 7 (1437321419621924869), 8 (1437321419621924870), 9 (1437321419622973440)],commit=} 0 12
[junit4:junit4] 1> 258139 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&fl=id,score,_docid_&version=2&wt=javabin} hits=10 status=0 QTime=1
[junit4:junit4] 1> 259470 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&fl=id,score,_docid_&version=2&wt=javabin} hits=10 status=0 QTime=0
[junit4:junit4] 1> 259471 [TEST-SolrExampleJettyTest.testStreamingRequest-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testStreamingRequest
[junit4:junit4] 1> 259480 [TEST-SolrExampleJettyTest.testStatistics-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testStatistics
[junit4:junit4] 1> 259484 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@ddc861d0 realtime
[junit4:junit4] 1> 259485 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321421045891072)} 0 3
[junit4:junit4] 1> 259488 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 259489 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_o,generation=24}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_p,generation=25}
[junit4:junit4] 1> 259490 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 25
[junit4:junit4] 1> 259491 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@e9b1ef9 main
[junit4:junit4] 1> 259491 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@e9b1ef9 main{StandardDirectoryReader(segments_o:45:nrt)}
[junit4:junit4] 1> 259492 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 259492 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 259493 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@e9b1ef9 main{StandardDirectoryReader(segments_o:45:nrt)}
[junit4:junit4] 1> 259493 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 5
[junit4:junit4] 1> 259496 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 259500 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc0 (1437321421062668288)]} 0 2
[junit4:junit4] 1> 259503 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc1 (1437321421066862592)]} 0 1
[junit4:junit4] 1> 259506 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc2 (1437321421070008320)]} 0 0
[junit4:junit4] 1> 259509 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc3 (1437321421073154048)]} 0 1
[junit4:junit4] 1> 259511 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc4 (1437321421075251200)]} 0 0
[junit4:junit4] 1> 259514 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc5 (1437321421078396928)]} 0 0
[junit4:junit4] 1> 259517 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc6 (1437321421081542656)]} 0 0
[junit4:junit4] 1> 259520 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc7 (1437321421084688384)]} 0 0
[junit4:junit4] 1> 259523 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc8 (1437321421087834112)]} 0 0
[junit4:junit4] 1> 259526 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc9 (1437321421089931264)]} 0 1
[junit4:junit4] 1> 259528 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 259532 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_p,generation=25}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_q,generation=26}
[junit4:junit4] 1> 259532 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 26
[junit4:junit4] 1> 259534 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@913feeb2 main
[junit4:junit4] 1> 259535 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@913feeb2 main{StandardDirectoryReader(segments_q:47:nrt _b(5.0):C10)}
[junit4:junit4] 1> 259535 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 259535 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 259536 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@913feeb2 main{StandardDirectoryReader(segments_q:47:nrt _b(5.0):C10)}
[junit4:junit4] 1> 259536 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 8
[junit4:junit4] 1> 259539 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=10 status=0 QTime=0
[junit4:junit4] 1> 259564 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&rows=0&stats.field=val_pi&wt=javabin&stats=true} hits=10 status=0 QTime=7
[junit4:junit4] 1> 259569 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@b6121cb6 realtime
[junit4:junit4] 1> 259570 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321421135020032)} 0 2
[junit4:junit4] 1> 259572 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 259573 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_q,generation=26}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_r,generation=27}
[junit4:junit4] 1> 259574 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 27
[junit4:junit4] 1> 259575 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@e3768232 main
[junit4:junit4] 1> 259576 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@e3768232 main{StandardDirectoryReader(segments_q:49:nrt)}
[junit4:junit4] 1> 259576 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 259576 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 259577 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@e3768232 main{StandardDirectoryReader(segments_q:49:nrt)}
[junit4:junit4] 1> 259577 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 5
[junit4:junit4] 1> 259580 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 259583 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc10 (1437321421149700096)]} 0 1
[junit4:junit4] 1> 259586 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc11 (1437321421153894400)]} 0 0
[junit4:junit4] 1> 259589 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc12 (1437321421155991552)]} 0 1
[junit4:junit4] 1> 259591 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc13 (1437321421159137280)]} 0 0
[junit4:junit4] 1> 259594 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc14 (1437321421162283008)]} 0 0
[junit4:junit4] 1> 259597 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 259600 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_r,generation=27}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_s,generation=28}
[junit4:junit4] 1> 259601 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 28
[junit4:junit4] 1> 259602 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2d94c64d main
[junit4:junit4] 1> 259603 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2d94c64d main{StandardDirectoryReader(segments_s:51:nrt _c(5.0):C5)}
[junit4:junit4] 1> 259603 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 259604 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 259605 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2d94c64d main{StandardDirectoryReader(segments_s:51:nrt _c(5.0):C5)}
[junit4:junit4] 1> 259605 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 8
[junit4:junit4] 1> 259608 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=5 status=0 QTime=0
[junit4:junit4] 1> 259611 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&rows=0&stats.field=val_pi&wt=javabin&stats=true} hits=5 status=0 QTime=0
[junit4:junit4] 1> 259615 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@52e2a257 realtime
[junit4:junit4] 1> 259616 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321421183254528)} 0 2
[junit4:junit4] 1> 259619 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 259619 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_s,generation=28}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_t,generation=29}
[junit4:junit4] 1> 259620 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 29
[junit4:junit4] 1> 259621 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@eecd37f6 main
[junit4:junit4] 1> 259622 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@eecd37f6 main{StandardDirectoryReader(segments_s:53:nrt)}
[junit4:junit4] 1> 259623 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 259623 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 259623 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@eecd37f6 main{StandardDirectoryReader(segments_s:53:nrt)}
[junit4:junit4] 1> 259624 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 5
[junit4:junit4] 1> 259627 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 259631 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc0 (1437321421200031744)]} 0 1
[junit4:junit4] 1> 259633 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc1 (1437321421203177472)]} 0 0
[junit4:junit4] 1> 259637 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc2 (1437321421206323200)]} 0 1
[junit4:junit4] 1> 259640 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc3 (1437321421209468928)]} 0 1
[junit4:junit4] 1> 259642 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc4 (1437321421212614656)]} 0 0
[junit4:junit4] 1> 259645 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc5 (1437321421215760384)]} 0 0
[junit4:junit4] 1> 259648 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc6 (1437321421218906112)]} 0 0
[junit4:junit4] 1> 259651 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc7 (1437321421222051840)]} 0 1
[junit4:junit4] 1> 259653 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc8 (1437321421224148992)]} 0 0
[junit4:junit4] 1> 259656 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc9 (1437321421227294720)]} 0 0
[junit4:junit4] 1> 259659 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 259662 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_t,generation=29}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_u,generation=30}
[junit4:junit4] 1> 259662 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 30
[junit4:junit4] 1> 259663 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@d0669973 main
[junit4:junit4] 1> 259664 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@d0669973 main{StandardDirectoryReader(segments_u:55:nrt _d(5.0):C10)}
[junit4:junit4] 1> 259664 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 259665 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 259666 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@d0669973 main{StandardDirectoryReader(segments_u:55:nrt _d(5.0):C10)}
[junit4:junit4] 1> 259666 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 259669 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=inStock:true&version=2&wt=javabin} hits=5 status=0 QTime=1
[junit4:junit4] 1> 259671 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=inStock:false&version=2&wt=javabin} hits=5 status=0 QTime=0
[junit4:junit4] 1> 259678 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&f.val_pi.stats.facet=inStock&rows=0&stats.field=val_pi&wt=javabin&stats=true} hits=10 status=0 QTime=4
[junit4:junit4] 1> 259679 [TEST-SolrExampleJettyTest.testStatistics-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testStatistics
[junit4:junit4] 1> 259693 [TEST-SolrExampleJettyTest.testAddRetrieve-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testAddRetrieve
[junit4:junit4] 1> 259697 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5670ec7a realtime
[junit4:junit4] 1> 259698 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321421269237760)} 0 2
[junit4:junit4] 1> 259702 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id1 (1437321421274480640), id2 (1437321421274480641)]} 0 1
[junit4:junit4] 1> 259705 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 259708 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_u,generation=30}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_v,generation=31}
[junit4:junit4] 1> 259709 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 31
[junit4:junit4] 1> 259710 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@62bdf0e8 main
[junit4:junit4] 1> 259711 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@62bdf0e8 main{StandardDirectoryReader(segments_v:59:nrt _e(5.0):C2)}
[junit4:junit4] 1> 259711 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 259712 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 259713 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@62bdf0e8 main{StandardDirectoryReader(segments_v:59:nrt _e(5.0):C2)}
[junit4:junit4] 1> 259713 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 8
[junit4:junit4] 1> 259794 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin&sort=price+asc} hits=2 status=0 QTime=78
[junit4:junit4] 1> 259798 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id1 (1437321421375143936), id2 (1437321421376192512)]} 0 1
[junit4:junit4] 1> 259801 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 259804 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_v,generation=31}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_w,generation=32}
[junit4:junit4] 1> 259805 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 32
[junit4:junit4] 1> 259806 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@a0739a1c main
[junit4:junit4] 1> 259807 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@a0739a1c main{StandardDirectoryReader(segments_w:63:nrt _f(5.0):C2)}
[junit4:junit4] 1> 259807 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 259807 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 259808 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@a0739a1c main{StandardDirectoryReader(segments_w:63:nrt _f(5.0):C2)}
[junit4:junit4] 1> 259809 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 8
[junit4:junit4] 1> 259812 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin&sort=price+asc} hits=2 status=0 QTime=1
[junit4:junit4] 1> 259815 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=name:h𩬅llo&version=2&wt=javabin&sort=price+asc} hits=1 status=0 QTime=1
[junit4:junit4] 1> 259816 [TEST-SolrExampleJettyTest.testAddRetrieve-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAddRetrieve
[junit4:junit4] 1> 259823 [TEST-SolrExampleJettyTest.testCommitWithinOnAdd-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testCommitWithinOnAdd
[junit4:junit4] 1> 259827 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@c3079edf realtime
[junit4:junit4] 1> 259827 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321421405552640)} 0 1
[junit4:junit4] 1> 259830 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 259831 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_w,generation=32}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_x,generation=33}
[junit4:junit4] 1> 259832 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 33
[junit4:junit4] 1> 259833 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@9e5c12c0 main
[junit4:junit4] 1> 259833 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@9e5c12c0 main{StandardDirectoryReader(segments_w:65:nrt)}
[junit4:junit4] 1> 259833 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 259834 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 259835 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@9e5c12c0 main{StandardDirectoryReader(segments_w:65:nrt)}
[junit4:junit4] 1> 259835 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 5
[junit4:junit4] 1> 259838 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 259842 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id3 (1437321421421281280)]} 0 2
[junit4:junit4] 1> 259844 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 260342 [commitScheduler-196-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> 260345 [commitScheduler-196-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1cbc7a66 main
[junit4:junit4] 1> 260345 [commitScheduler-196-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 260345 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1cbc7a66 main{StandardDirectoryReader(segments_x:67:nrt _g(5.0):C1)}
[junit4:junit4] 1> 260346 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 260346 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1cbc7a66 main{StandardDirectoryReader(segments_x:67:nrt _g(5.0):C1)}
[junit4:junit4] 1> 260847 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&version=2&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 260851 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id4 (1437321422480343040)]} 0 0
[junit4:junit4] 1> 261352 [commitScheduler-196-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> 261355 [commitScheduler-196-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@df95e400 main
[junit4:junit4] 1> 261355 [commitScheduler-196-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261355 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@df95e400 main{StandardDirectoryReader(segments_x:69:nrt _g(5.0):C1 _h(5.0):C1)}
[junit4:junit4] 1> 261356 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261356 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@df95e400 main{StandardDirectoryReader(segments_x:69:nrt _g(5.0):C1 _h(5.0):C1)}
[junit4:junit4] 1> 261855 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id4&version=2&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 261857 [TEST-SolrExampleJettyTest.testCommitWithinOnAdd-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testCommitWithinOnAdd
[junit4:junit4] 1> 261864 [TEST-SolrExampleJettyTest.testUnicode-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUnicode
[junit4:junit4] 1> 261868 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@721089c6 realtime
[junit4:junit4] 1> 261869 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321423544647680)} 0 3
[junit4:junit4] 1> 261876 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437321423551987712), 1 (1437321423553036288), 2 (1437321423553036289), 3 (1437321423553036290), 4 (1437321423553036291), 5 (1437321423553036292), 6 (1437321423553036293), 7 (1437321423554084864), 8 (1437321423554084865), 9 (1437321423554084866), ... (15 adds)]} 0 3
[junit4:junit4] 1> 261879 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 261883 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_x,generation=33}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_y,generation=34}
[junit4:junit4] 1> 261884 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 34
[junit4:junit4] 1> 261885 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@c81ebee5 main
[junit4:junit4] 1> 261887 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@c81ebee5 main{StandardDirectoryReader(segments_y:73:nrt _i(5.0):C15)}
[junit4:junit4] 1> 261887 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261887 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261887 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@c81ebee5 main{StandardDirectoryReader(segments_y:73:nrt _i(5.0):C15)}
[junit4:junit4] 1> 261889 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 10
[junit4:junit4] 1> 261892 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&rows=15&wt=javabin} hits=15 status=0 QTime=0
[junit4:junit4] 1> 261896 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@77b0b394 realtime
[junit4:junit4] 1> 261897 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321423575056384)} 0 2
[junit4:junit4] 1> 261901 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437321423580299264), 1 (1437321423580299265), 2 (1437321423580299266), 3 (1437321423580299267), 4 (1437321423580299268)]} 0 1
[junit4:junit4] 1> 261903 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 261905 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_y,generation=34}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_z,generation=35}
[junit4:junit4] 1> 261906 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 35
[junit4:junit4] 1> 261907 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@e1c45d main
[junit4:junit4] 1> 261908 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@e1c45d main{StandardDirectoryReader(segments_z:77:nrt _j(5.0):C5)}
[junit4:junit4] 1> 261908 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261909 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261910 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@e1c45d main{StandardDirectoryReader(segments_z:77:nrt _j(5.0):C5)}
[junit4:junit4] 1> 261910 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 261913 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&rows=5&wt=javabin} hits=5 status=0 QTime=0
[junit4:junit4] 1> 261924 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@cece53e0 realtime
[junit4:junit4] 1> 261925 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {deleteByQuery=*:* (-1437321423604416512)} 0 2
[junit4:junit4] 1> 261944 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {add=[0 (1437321423619096576), 1 (1437321423620145152), 2 (1437321423620145153), 3 (1437321423621193728), 4 (1437321423621193729), 5 (1437321423621193730), 6 (1437321423621193731), 7 (1437321423621193732), 8 (1437321423622242304), 9 (1437321423622242305), ... (30 adds)]} 0 7
[junit4:junit4] 1> 261947 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 261950 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_z,generation=35}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_10,generation=36}
[junit4:junit4] 1> 261951 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 36
[junit4:junit4] 1> 261952 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@cc57e551 main
[junit4:junit4] 1> 261953 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261954 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@cc57e551 main{StandardDirectoryReader(segments_10:81:nrt _k(5.0):C30)}
[junit4:junit4] 1> 261954 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261954 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@cc57e551 main{StandardDirectoryReader(segments_10:81:nrt _k(5.0):C30)}
[junit4:junit4] 1> 261955 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2.2&waitSearcher=true&wt=xml} {commit=} 0 8
[junit4:junit4] 1> 261958 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2.2&rows=30&wt=xml} hits=30 status=0 QTime=0
[junit4:junit4] 1> 261971 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@50725e98 realtime
[junit4:junit4] 1> 261973 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321423653699584)} 0 3
[junit4:junit4] 1> 261980 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437321423661039616), 1 (1437321423662088192), 2 (1437321423662088193), 3 (1437321423662088194), 4 (1437321423662088195), 5 (1437321423663136768), 6 (1437321423663136769), 7 (1437321423663136770), 8 (1437321423663136771), 9 (1437321423663136772), ... (12 adds)]} 0 3
[junit4:junit4] 1> 261983 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 261986 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_10,generation=36}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_11,generation=37}
[junit4:junit4] 1> 261986 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 37
[junit4:junit4] 1> 261987 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@64ae28ea main
[junit4:junit4] 1> 261988 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@64ae28ea main{StandardDirectoryReader(segments_11:85:nrt _l(5.0):C12)}
[junit4:junit4] 1> 261989 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261989 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261989 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@64ae28ea main{StandardDirectoryReader(segments_11:85:nrt _l(5.0):C12)}
[junit4:junit4] 1> 261990 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 261993 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&rows=12&wt=javabin} hits=12 status=0 QTime=0
[junit4:junit4] 1> 261999 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@14733a96 realtime
[junit4:junit4] 1> 262000 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {deleteByQuery=*:* (-1437321423683059712)} 0 3
[junit4:junit4] 1> 262008 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {add=[0 (1437321423690399744), 1 (1437321423690399745), 2 (1437321423691448320), 3 (1437321423691448321), 4 (1437321423691448322), 5 (1437321423691448323), 6 (1437321423691448324), 7 (1437321423691448325), 8 (1437321423691448326), 9 (1437321423691448327), ... (22 adds)]} 0 3
[junit4:junit4] 1> 262011 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262015 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_11,generation=37}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_12,generation=38}
[junit4:junit4] 1> 262016 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 38
[junit4:junit4] 1> 262018 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@48f16f16 main
[junit4:junit4] 1> 262019 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@48f16f16 main{StandardDirectoryReader(segments_12:89:nrt _m(5.0):C22)}
[junit4:junit4] 1> 262020 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262020 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@48f16f16 main{StandardDirectoryReader(segments_12:89:nrt _m(5.0):C22)}
[junit4:junit4] 1> 262020 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262021 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2.2&waitSearcher=true&wt=xml} {commit=} 0 10
[junit4:junit4] 1> 262025 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2.2&rows=22&wt=xml} hits=22 status=0 QTime=0
[junit4:junit4] 1> 262035 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@951f57df realtime
[junit4:junit4] 1> 262037 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321423720808448)} 0 3
[junit4:junit4] 1> 262044 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437321423728148480), 1 (1437321423729197056), 2 (1437321423729197057), 3 (1437321423729197058), 4 (1437321423729197059), 5 (1437321423729197060), 6 (1437321423729197061), 7 (1437321423729197062), 8 (1437321423729197063), 9 (1437321423729197064), ... (21 adds)]} 0 3
[junit4:junit4] 1> 262047 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262050 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_12,generation=38}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_13,generation=39}
[junit4:junit4] 1> 262051 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 39
[junit4:junit4] 1> 262054 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@a9c8989 main
[junit4:junit4] 1> 262055 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@a9c8989 main{StandardDirectoryReader(segments_13:93:nrt _n(5.0):C21)}
[junit4:junit4] 1> 262056 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262056 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262057 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@a9c8989 main{StandardDirectoryReader(segments_13:93:nrt _n(5.0):C21)}
[junit4:junit4] 1> 262058 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 11
[junit4:junit4] 1> 262061 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&rows=21&wt=javabin} hits=21 status=0 QTime=0
[junit4:junit4] 1> 262069 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4b9b46ad realtime
[junit4:junit4] 1> 262070 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321423755411456)} 0 3
[junit4:junit4] 1> 262078 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437321423763800064), 1 (1437321423764848640), 2 (1437321423764848641), 3 (1437321423764848642), 4 (1437321423764848643), 5 (1437321423764848644), 6 (1437321423764848645), 7 (1437321423765897216), 8 (1437321423765897217), 9 (1437321423765897218), ... (17 adds)]} 0 3
[junit4:junit4] 1> 262082 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262084 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_13,generation=39}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_14,generation=40}
[junit4:junit4] 1> 262085 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 40
[junit4:junit4] 1> 262086 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@9a6e5101 main
[junit4:junit4] 1> 262087 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@9a6e5101 main{StandardDirectoryReader(segments_14:97:nrt _o(5.0):C17)}
[junit4:junit4] 1> 262087 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262088 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262089 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@9a6e5101 main{StandardDirectoryReader(segments_14:97:nrt _o(5.0):C17)}
[junit4:junit4] 1> 262089 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 262092 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&rows=17&wt=javabin} hits=17 status=0 QTime=0
[junit4:junit4] 1> 262099 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@492480ee realtime
[junit4:junit4] 1> 262100 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {deleteByQuery=*:* (-1437321423786868736)} 0 3
[junit4:junit4] 1> 262106 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {add=[0 (1437321423794208768), 1 (1437321423794208769), 2 (1437321423795257344), 3 (1437321423795257345), 4 (1437321423795257346), 5 (1437321423795257347), 6 (1437321423795257348), 7 (1437321423795257349), 8 (1437321423795257350), 9 (1437321423795257351), ... (14 adds)]} 0 2
[junit4:junit4] 1> 262109 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262111 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_14,generation=40}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_15,generation=41}
[junit4:junit4] 1> 262112 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 41
[junit4:junit4] 1> 262114 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1b08c26d main
[junit4:junit4] 1> 262115 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1b08c26d main{StandardDirectoryReader(segments_15:101:nrt _p(5.0):C14)}
[junit4:junit4] 1> 262115 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262115 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262116 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1b08c26d main{StandardDirectoryReader(segments_15:101:nrt _p(5.0):C14)}
[junit4:junit4] 1> 262117 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2.2&waitSearcher=true&wt=xml} {commit=} 0 8
[junit4:junit4] 1> 262120 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2.2&rows=14&wt=xml} hits=14 status=0 QTime=0
[junit4:junit4] 1> 262127 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@dd93868a realtime
[junit4:junit4] 1> 262128 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321423817277440)} 0 2
[junit4:junit4] 1> 262132 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437321423823568896), 1 (1437321423823568897)]} 0 1
[junit4:junit4] 1> 262135 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262137 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_15,generation=41}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_16,generation=42}
[junit4:junit4] 1> 262138 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 42
[junit4:junit4] 1> 262139 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@730452ce main
[junit4:junit4] 1> 262140 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262140 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@730452ce main{StandardDirectoryReader(segments_16:105:nrt _q(5.0):C2)}
[junit4:junit4] 1> 262141 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262141 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@730452ce main{StandardDirectoryReader(segments_16:105:nrt _q(5.0):C2)}
[junit4:junit4] 1> 262142 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 262145 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&rows=2&wt=javabin} hits=2 status=0 QTime=0
[junit4:junit4] 1> 262146 [TEST-SolrExampleJettyTest.testUnicode-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUnicode
[junit4:junit4] 1> 262153 [TEST-SolrExampleJettyTest.testPivotFacetsMissing-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPivotFacetsMissing
[junit4:junit4] 1> 262157 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@b09f3084 realtime
[junit4:junit4] 1> 262158 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321423847686144)} 0 3
[junit4:junit4] 1> 262161 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262162 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_16,generation=42}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_17,generation=43}
[junit4:junit4] 1> 262162 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 43
[junit4:junit4] 1> 262163 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4170373b main
[junit4:junit4] 1> 262164 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262164 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@4170373b main{StandardDirectoryReader(segments_16:107:nrt)}
[junit4:junit4] 1> 262165 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262165 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@4170373b main{StandardDirectoryReader(segments_16:107:nrt)}
[junit4:junit4] 1> 262166 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 5
[junit4:junit4] 1> 262168 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 262175 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 (1437321423865511936), 2 (1437321423866560512), 3 (1437321423866560513), 4 (1437321423866560514), 5 (1437321423867609088), 6 (1437321423867609089), 7 (1437321423867609090), 8 (1437321423867609091), 9 (1437321423867609092), 10 (1437321423868657664), ... (12 adds)]} 0 3
[junit4:junit4] 1> 262179 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262182 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_17,generation=43}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_18,generation=44}
[junit4:junit4] 1> 262183 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 44
[junit4:junit4] 1> 262185 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@288b6015 main
[junit4:junit4] 1> 262186 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@288b6015 main{StandardDirectoryReader(segments_18:109:nrt _r(5.0):C12)}
[junit4:junit4] 1> 262187 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262187 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262187 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@288b6015 main{StandardDirectoryReader(segments_18:109:nrt _r(5.0):C12)}
[junit4:junit4] 1> 262188 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 9
[junit4:junit4] 1> 262191 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4] 1> 262194 [qtp1889012424-297] 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> 262196 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&facet.mincount=0&facet=true&version=2&rows=0&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&wt=javabin&facet.missing=true} hits=12 status=0 QTime=5
[junit4:junit4] 1> 262200 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&facet.mincount=0&facet=true&version=2&rows=0&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&wt=javabin} hits=11 status=0 QTime=1
[junit4:junit4] 1> 262201 [TEST-SolrExampleJettyTest.testPivotFacetsMissing-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPivotFacetsMissing
[junit4:junit4] 1> 262208 [TEST-SolrExampleJettyTest.testRealtimeGet-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testRealtimeGet
[junit4:junit4] 1> 262211 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@afccc104 realtime
[junit4:junit4] 1> 262213 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321423905357824)} 0 3
[junit4:junit4] 1> 262216 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[DOCID (1437321423911649280)]} 0 0
[junit4:junit4] 1> 262219 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262221 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_18,generation=44}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_19,generation=45}
[junit4:junit4] 1> 262222 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 45
[junit4:junit4] 1> 262223 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@e407104f main
[junit4:junit4] 1> 262224 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262224 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@e407104f main{StandardDirectoryReader(segments_19:113:nrt _s(5.0):C1)}
[junit4:junit4] 1> 262225 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262225 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@e407104f main{StandardDirectoryReader(segments_19:113:nrt _s(5.0):C1)}
[junit4:junit4] 1> 262226 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 262229 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/get params={qt=/get&fl=id,name,aaa:[value+v%3Daaa]&version=2&id=DOCID&wt=javabin} status=0 QTime=1
[junit4:junit4] 1> 262231 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/get params={qt=/get&fl=id,name,aaa:[value+v%3Daaa]&version=2.2&id=DOCID&wt=xml} status=0 QTime=0
[junit4:junit4] 1> 262233 [TEST-SolrExampleJettyTest.testRealtimeGet-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testRealtimeGet
[junit4:junit4] 1> 262240 [TEST-SolrExampleJettyTest.testUpdateRequestWithParameters-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateRequestWithParameters
[junit4:junit4] 1> 262240 [TEST-SolrExampleJettyTest.testUpdateRequestWithParameters-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 262333 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@b069bdf6 realtime
[junit4:junit4] 1> 262345 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321424032235520)} 0 14
[junit4:junit4] 1> 262349 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262350 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_19,generation=45}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1a,generation=46}
[junit4:junit4] 1> 262351 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 46
[junit4:junit4] 1> 262352 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@a41ff074 main
[junit4:junit4] 1> 262353 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262353 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@a41ff074 main{StandardDirectoryReader(segments_19:115:nrt)}
[junit4:junit4] 1> 262355 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262355 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@a41ff074 main{StandardDirectoryReader(segments_19:115:nrt)}
[junit4:junit4] 1> 262356 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 262361 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&overwrite=false&wt=javabin} {add=[id1 (1437321424062644224)]} 0 2
[junit4:junit4] 1> 262365 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&overwrite=false&wt=javabin} {add=[id1 (1437321424066838528)]} 0 1
[junit4:junit4] 1> 262369 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262371 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1a,generation=46}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1b,generation=47}
[junit4:junit4] 1> 262372 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 47
[junit4:junit4] 1> 262374 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@d2073356 main
[junit4:junit4] 1> 262375 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262375 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@d2073356 main{StandardDirectoryReader(segments_1b:117:nrt _t(5.0):C2)}
[junit4:junit4] 1> 262376 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262377 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@d2073356 main{StandardDirectoryReader(segments_1b:117:nrt _t(5.0):C2)}
[junit4:junit4] 1> 262377 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 9
[junit4:junit4] 1> 262381 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=2 status=0 QTime=0
[junit4:junit4] 1> 262382 [TEST-SolrExampleJettyTest.testUpdateRequestWithParameters-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateRequestWithParameters
[junit4:junit4] 1> 262393 [TEST-SolrExampleJettyTest.testChineseDefaults-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testChineseDefaults
[junit4:junit4] 1> 262397 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3e1efde2 realtime
[junit4:junit4] 1> 262399 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321424100392960)} 0 3
[junit4:junit4] 1> 262402 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262404 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1b,generation=47}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1c,generation=48}
[junit4:junit4] 1> 262405 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 48
[junit4:junit4] 1> 262406 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@606018d7 main
[junit4:junit4] 1> 262407 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@606018d7 main{StandardDirectoryReader(segments_1b:119:nrt)}
[junit4:junit4] 1> 262408 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262408 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262408 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@606018d7 main{StandardDirectoryReader(segments_1b:119:nrt)}
[junit4:junit4] 1> 262409 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 7
[junit4:junit4] 1> 262412 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 262416 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262418 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1c,generation=48}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1d,generation=49}
[junit4:junit4] 1> 262419 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 49
[junit4:junit4] 1> 262420 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6ceafaf2 main
[junit4:junit4] 1> 262422 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262423 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6ceafaf2 main{StandardDirectoryReader(segments_1d:121:nrt _u(5.0):C1)}
[junit4:junit4] 1> 262423 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262424 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6ceafaf2 main{StandardDirectoryReader(segments_1d:121:nrt _u(5.0):C1)}
[junit4:junit4] 1> 262424 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {add=[42 (1437321424120315904)],commit=} 0 9
[junit4:junit4] 1> 262428 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=北京大学&version=2&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 262430 [TEST-SolrExampleJettyTest.testChineseDefaults-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testChineseDefaults
[junit4:junit4] 1> 262440 [TEST-SolrExampleJettyTest.testLukeHandler-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testLukeHandler
[junit4:junit4] 1> 262444 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@48009b67 realtime
[junit4:junit4] 1> 262446 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321424149676032)} 0 3
[junit4:junit4] 1> 262450 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[ID0 (1437321424155967488)]} 0 1
[junit4:junit4] 1> 262454 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[ID1 (1437321424160161792)]} 0 1
[junit4:junit4] 1> 262457 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[ID2 (1437321424164356096)]} 0 0
[junit4:junit4] 1> 262461 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[ID3 (1437321424168550400)]} 0 0
[junit4:junit4] 1> 262465 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[ID4 (1437321424172744704)]} 0 0
[junit4:junit4] 1> 262468 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262471 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1d,generation=49}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1e,generation=50}
[junit4:junit4] 1> 262473 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 50
[junit4:junit4] 1> 262474 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1d231166 main
[junit4:junit4] 1> 262478 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1d231166 main{StandardDirectoryReader(segments_1e:125:nrt _v(5.0):C5)}
[junit4:junit4] 1> 262479 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262479 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1d231166 main{StandardDirectoryReader(segments_1e:125:nrt _v(5.0):C5)}
[junit4:junit4] 1> 262479 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262480 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 12
[junit4:junit4] 1> 262484 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin} hits=5 status=0 QTime=0
[junit4:junit4] 1> 262490 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/luke params={version=2&wt=javabin} status=0 QTime=2
[junit4:junit4] 1> 262498 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/luke params={version=2&wt=javabin&show=schema} status=0 QTime=2
[junit4:junit4] 1> 262522 [TEST-SolrExampleJettyTest.testLukeHandler-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testLukeHandler
[junit4:junit4] 1> 262534 [TEST-SolrExampleJettyTest.testBadSetup-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testBadSetup
[junit4:junit4] 1> 262535 [TEST-SolrExampleJettyTest.testBadSetup-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testBadSetup
[junit4:junit4] 1> 262544 [TEST-SolrExampleJettyTest.testUpdateField-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateField
[junit4:junit4] 1> 262549 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@9dac36db realtime
[junit4:junit4] 1> 262550 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437321424258727936)} 0 3
[junit4:junit4] 1> 262554 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262555 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1e,generation=50}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1f,generation=51}
[junit4:junit4] 1> 262556 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 51
[junit4:junit4] 1> 262558 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@eddf0aef main
[junit4:junit4] 1> 262559 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@eddf0aef main{StandardDirectoryReader(segments_1e:127:nrt)}
[junit4:junit4] 1> 262559 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262559 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262561 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@eddf0aef main{StandardDirectoryReader(segments_1e:127:nrt)}
[junit4:junit4] 1> 262561 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 8
[junit4:junit4] 1> 262566 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[unique (1437321424277602304)]} 0 1
[junit4:junit4] 1> 262570 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262573 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1f,generation=51}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1g,generation=52}
[junit4:junit4] 1> 262574 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 52
[junit4:junit4] 1> 262576 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7391bfe0 main
[junit4:junit4] 1> 262580 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@7391bfe0 main{StandardDirectoryReader(segments_1g:129:nrt _w(5.0):C1)}
[junit4:junit4] 1> 262580 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262582 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@7391bfe0 main{StandardDirectoryReader(segments_1g:129:nrt _w(5.0):C1)}
[junit4:junit4] 1> 262582 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262583 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 13
[junit4:junit4] 1> 262587 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&fl=id,price_f,name,_version_&version=2&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 262591 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 1
[junit4:junit4] 1> 262596 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[unique (1437321424309059584)]} 0 1
[junit4:junit4] 1> 262600 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262603 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1g,generation=52}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1h,generation=53}
[junit4:junit4] 1> 262605 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 53
[junit4:junit4] 1> 262607 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3088038f main
[junit4:junit4] 1> 262610 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3088038f main{StandardDirectoryReader(segments_1h:133:nrt _x(5.0):C1)}
[junit4:junit4] 1> 262610 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262612 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3088038f main{StandardDirectoryReader(segments_1h:133:nrt _x(5.0):C1)}
[junit4:junit4] 1> 262613 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262614 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 14
[junit4:junit4] 1> 262619 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&fl=id,price_f,name,_version_&version=2&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 262623 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[unique (1437321424337371136)]} 0 1
[junit4:junit4] 1> 262627 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262631 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1h,generation=53}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1i,generation=54}
[junit4:junit4] 1> 262632 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – newest commit generation = 54
[junit4:junit4] 1> 262634 [qtp1889012424-297] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@d6d8b308 main
[junit4:junit4] 1> 262635 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@d6d8b308 main{StandardDirectoryReader(segments_1i:137:nrt _y(5.0):C1)}
[junit4:junit4] 1> 262636 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262636 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262636 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@d6d8b308 main{StandardDirectoryReader(segments_1i:137:nrt _y(5.0):C1)}
[junit4:junit4] 1> 262638 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 11
[junit4:junit4] 1> 262641 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&fl=id,price_f,name,_version_&version=2&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 262643 [TEST-SolrExampleJettyTest.testUpdateField-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateField
[junit4:junit4] 1> 262654 [TEST-SolrExampleJettyTest.testSetNullUpdates-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testSetNullUpdates
[junit4:junit4] 1> 262658 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdates (1437321424374071296)]} 0 1
[junit4:junit4] 1> 262662 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262665 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1i,generation=54}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1j,generation=55}
[junit4:junit4] 1> 262666 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – newest commit generation = 55
[junit4:junit4] 1> 262668 [qtp1889012424-296] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@9e801d6c main
[junit4:junit4] 1> 262670 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@9e801d6c main{StandardDirectoryReader(segments_1j:139:nrt _y(5.0):C1 _z(5.0):C1)}
[junit4:junit4] 1> 262670 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262670 [qtp1889012424-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262671 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@9e801d6c main{StandardDirectoryReader(segments_1j:139:nrt _y(5.0):C1 _z(5.0):C1)}
[junit4:junit4] 1> 262672 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 10
[junit4:junit4] 1> 262677 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdates (1437321424393994240)]} 0 2
[junit4:junit4] 1> 262680 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262684 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1j,generation=55}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1k,generation=56}
[junit4:junit4] 1> 262685 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – newest commit generation = 56
[junit4:junit4] 1> 262687 [qtp1889012424-298] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@479024e main
[junit4:junit4] 1> 262691 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@479024e main{StandardDirectoryReader(segments_1k:143:nrt _y(5.0):C1 _10(5.0):C1)}
[junit4:junit4] 1> 262691 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262692 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@479024e main{StandardDirectoryReader(segments_1k:143:nrt _y(5.0):C1 _10(5.0):C1)}
[junit4:junit4] 1> 262693 [qtp1889012424-298] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262696 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 16
[junit4:junit4] 1> 262700 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&version=2&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 262702 [TEST-SolrExampleJettyTest.testSetNullUpdates-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testSetNullUpdates
[junit4:junit4] 1> 262712 [TEST-SolrExampleJettyTest.testUpdateMultiValuedField-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateMultiValuedField
[junit4:junit4] 1> 262716 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[123 (1437321424434888704)]} 0 1
[junit4:junit4] 1> 262719 [qtp1889012424-297] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262724 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]
[...truncated too long message...]
4-296] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262924 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@76fd67a1 main{StandardDirectoryReader(segments_1s:165:nrt _17(5.0):C1)}
[junit4:junit4] 1> 262925 [qtp1889012424-296] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 8
[junit4:junit4] 1> 262930 [qtp1889012424-297] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[222 (1437321424658235392)]} 0 2
[junit4:junit4] 1> 262933 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262941 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1s,generation=64}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cf26f81f lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce844f),segFN=segments_1t,generation=65}
[junit4:junit4] 1> 262942 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – newest commit generation = 65
[junit4:junit4] 1> 262943 [qtp1889012424-292] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@68fde331 main
[junit4:junit4] 1> 262944 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@68fde331 main{StandardDirectoryReader(segments_1t:167:nrt _17(5.0):C1 _18(5.0):C1)}
[junit4:junit4] 1> 262944 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262944 [qtp1889012424-292] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262945 [searcherExecutor-193-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@68fde331 main{StandardDirectoryReader(segments_1t:167:nrt _17(5.0):C1 _18(5.0):C1)}
[junit4:junit4] 1> 262946 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 14
[junit4:junit4] 1> 262950 [qtp1889012424-296] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&fl=id,price,[docid],[explain+style%3Dnl],score,aaa:[value+v%3Daaa],ten:[value+v%3D10+t%3Dint]&version=2&wt=javabin&sort=price+asc} hits=2 status=0 QTime=2
[junit4:junit4] 1> 262951 [TEST-SolrExampleJettyTest.testAugmentFields-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAugmentFields
[junit4:junit4] 1> 262961 [TEST-SolrExampleJettyTest.testErrorHandling-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testErrorHandling
[junit4:junit4] 1> 262976 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – Ignoring exception matching ignore_exception
[junit4:junit4] 1> 262977 [qtp1889012424-297] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/analysis/field params={analysis.fieldtype=int&qt=/analysis/field&version=2&analysis.fieldvalue=ignore_exception&wt=javabin} status=400 QTime=13
[junit4:junit4] 1> 262986 [qtp1889012424-298] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 5
[junit4:junit4] 1> 262988 [qtp1889012424-298] INFO org.apache.solr.core.SolrCore – Ignoring exception matching ignore_exception
[junit4:junit4] 1> 262992 [qtp1889012424-292] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 1
[junit4:junit4] 1> 262993 [qtp1889012424-292] INFO org.apache.solr.core.SolrCore – Ignoring exception matching uniqueKey
[junit4:junit4] 1> 262994 [TEST-SolrExampleJettyTest.testErrorHandling-seed#[DF2AF81E5AEA4507]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testErrorHandling
[junit4:junit4] 1> 263056 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.CoreContainer – Shutting down CoreContainer instance=-1789457270
[junit4:junit4] 1> 263057 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCore – [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@c89b685
[junit4:junit4] 1> 263057 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.update.UpdateHandler – closing DirectUpdateHandler2{commits=64,autocommit maxTime=15000ms,autocommits=1,soft autocommits=2,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=1,errors=1,cumulative_adds=261,cumulative_deletesById=4,cumulative_deletesByQuery=33,cumulative_errors=1}
[junit4:junit4] 1> 263058 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.update.SolrCoreState – Closing SolrCoreState
[junit4:junit4] 1> 263058 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.update.DefaultSolrCoreState – SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 1> 263059 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.update.DefaultSolrCoreState – closing IndexWriter with IndexWriterCloser
[junit4:junit4] 1> 263060 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.SolrCore – [collection1] Closing main searcher on request.
[junit4:junit4] 1> 263061 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 1> 263061 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041;done=false>>]
[junit4:junit4] 1> 263061 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041
[junit4:junit4] 1> 263062 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041/index;done=false>>]
[junit4:junit4] 1> 263062 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1370736319041/index
[junit4:junit4] 1> 263063 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler – stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 1> 263115 [SUITE-SolrExampleJettyTest-seed#[DF2AF81E5AEA4507]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=sv_SE_PREEURO, timezone=Europe/Zurich
[junit4:junit4] 2> NOTE: Linux 3.2.0-45-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=34853272,total=69861376
[junit4:junit4] 2> NOTE: All tests run in this JVM: [SolrDocumentTest, TestNamedListCodec, TestBatchUpdate, FacetFieldTest, MultiCoreEmbeddedTest, TestSolrProperties, TestLBHttpSolrServer, MultiCoreExampleJettyTest, MergeIndexesEmbeddedTest, NamedListTest, SolrExampleJettyTest]
[junit4:junit4] Completed on J1 in 208.33s, 27 tests, 1 error <<< FAILURES!
[...truncated 10 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:385: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:378: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:194: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:437: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1240: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:884: There were test failures: 46 suites, 272 tests, 1 error, 1 ignored
Total time: 34 minutes 49 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure