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/07/12 01:05:44 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.8.0-ea-b96) - Build #
6444 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/6444/
Java: 64bit/jdk1.8.0-ea-b96 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.client.solrj.SolrExampleBinaryTest.testStatistics
Error Message:
IOException occured when talking to server at: https://127.0.0.1:49538/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:49538/solr/collection1
at __randomizedtesting.SeedInfo.seed([E47787219CA7B033:13DEB445308A6ADB]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:436)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:816)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
... 45 more
Build Log:
[...truncated 10841 lines...]
[junit4] Suite: org.apache.solr.client.solrj.SolrExampleBinaryTest
[junit4] 1> 14402 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.SolrJettyTestBase – Randomized ssl (true) and clientAuth (false)
[junit4] 1> 14403 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ####initCore
[junit4] 1> 14404 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ####initCore end
[junit4] 1> 14535 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.eclipse.jetty.server.Server – jetty-8.1.10.v20130312
[junit4] 1> 14809 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.eclipse.jetty.util.ssl.SslContextFactory – Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 1> 14841 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.eclipse.jetty.server.AbstractConnector – Started SslSelectChannelConnector@127.0.0.1:49538
[junit4] 1> 14873 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init()
[junit4] 1> 14874 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.SolrResourceLoader – JNDI not configured for solr (NoInitialContextEx)
[junit4] 1> 14874 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.SolrResourceLoader – using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4] 1> 14875 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4] 1> 14891 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.ConfigSolr – Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/solr.xml
[junit4] 1> 14909 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.ConfigSolrXml – Config-defined core root directory:
[junit4] 1> 14911 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CoreContainer – New CoreContainer 1476666466
[junit4] 1> 14912 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CoreContainer – Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/]
[junit4] 1> 14924 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting socketTimeout to: 0
[junit4] 1> 14925 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting urlScheme to: http://
[junit4] 1> 14925 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting connTimeout to: 0
[junit4] 1> 14926 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maxConnectionsPerHost to: 20
[junit4] 1> 14926 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting corePoolSize to: 0
[junit4] 1> 14927 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maximumPoolSize to: 2147483647
[junit4] 1> 14928 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maxThreadIdleTime to: 5
[junit4] 1> 14928 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting sizeOfQueue to: -1
[junit4] 1> 14929 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting fairnessPolicy to: false
[junit4] 1> 14930 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-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] 1> 14934 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.logging.LogWatcher – Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 1> 14937 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CoresLocator – Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4] 1> 14938 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CoresLocator – Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/
[junit4] 1> 14944 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CoresLocator – Found 1 core definitions
[junit4] 1> 14945 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.CoreContainer – Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1
[junit4] 1> 14946 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/'
[junit4] 1> 14999 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrConfig – Adding specified lib dirs to ClassLoader
[junit4] 1> 15000 [coreLoadExecutor-57-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4] 1> 15000 [coreLoadExecutor-57-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 1> 15001 [coreLoadExecutor-57-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4] 1> 15001 [coreLoadExecutor-57-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 1> 15002 [coreLoadExecutor-57-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4] 1> 15002 [coreLoadExecutor-57-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 1> 15003 [coreLoadExecutor-57-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4] 1> 15003 [coreLoadExecutor-57-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 1> 15040 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.update.SolrIndexConfig – IndexWriter infoStream solr logging is enabled
[junit4] 1> 15042 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrConfig – Using Lucene MatchVersion: LUCENE_43
[junit4] 1> 15089 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.Config – Loaded SolrConfig: solrconfig.xml
[junit4] 1> 15091 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.schema.IndexSchema – Reading Solr Schema from schema.xml
[junit4] 1> 15163 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.schema.IndexSchema – [collection1] Schema name=example
[junit4] 1> 15458 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.schema.IndexSchema – unique key field: id
[junit4] 1> 15482 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider – Reloading exchange rates from file currency.xml
[junit4] 1> 15491 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider – Reloading exchange rates from file currency.xml
[junit4] 1> 15532 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – org.apache.solr.core.MockDirectoryFactory
[junit4] 1> 15532 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020/
[junit4] 1> 15533 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.JmxMonitoredMap – JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a0aa43f
[junit4] 1> 15536 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 1> 15536 [coreLoadExecutor-57-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] 1> 15537 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory – return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020
[junit4] 1> 15538 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020/index/
[junit4] 1> 15538 [coreLoadExecutor-57-thread-1] WARN org.apache.solr.core.SolrCore – [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020/index' doesn't exist. Creating new index...
[junit4] 1> 15538 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory – return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020/index
[junit4] 1> 15541 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1,generation=1}
[junit4] 1> 15542 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – newest commit generation = 1
[junit4] 1> 15542 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – created json: solr.JSONResponseWriter
[junit4] 1> 15543 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 1> 15543 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – created velocity: solr.VelocityResponseWriter
[junit4] 1> 15543 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – created xslt: solr.XSLTResponseWriter
[junit4] 1> 15544 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.response.XSLTResponseWriter – xsltCacheLifetimeSeconds=5
[junit4] 1> 15552 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /select: solr.SearchHandler
[junit4] 1> 15553 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /query: solr.SearchHandler
[junit4] 1> 15554 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /get: solr.RealTimeGetHandler
[junit4] 1> 15556 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /browse: solr.SearchHandler
[junit4] 1> 15557 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update: solr.UpdateRequestHandler
[junit4] 1> 15558 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/json: solr.JsonUpdateRequestHandler
[junit4] 1> 15559 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/csv: solr.CSVRequestHandler
[junit4] 1> 15560 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 1> 15560 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 1> 15561 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 1> 15561 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 1> 15562 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 1> 15562 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 1> 15563 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /admin/: solr.admin.AdminHandlers
[junit4] 1> 15565 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /admin/ping: solr.PingRequestHandler
[junit4] 1> 15567 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /debug/dump: solr.DumpRequestHandler
[junit4] 1> 15568 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /replication: solr.ReplicationHandler
[junit4] 1> 15568 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 15569 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /spell: solr.SearchHandler
[junit4] 1> 15569 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 15569 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /tvrh: solr.SearchHandler
[junit4] 1> 15570 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 15570 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /terms: solr.SearchHandler
[junit4] 1> 15571 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 15571 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.RequestHandlers – created /elevate: solr.SearchHandler
[junit4] 1> 15573 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4] 1> 15575 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4] 1> 15577 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4] 1> 15580 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2f3af889 main
[junit4] 1> 15581 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – Hard AutoCommit: if uncommited for 15000ms;
[junit4] 1> 15582 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.SolrCore – Soft AutoCommit: disabled
[junit4] 1> 15584 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Initializing spell checkers
[junit4] 1> 15585 [coreLoadExecutor-57-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] 1> 15585 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – No queryConverter defined, using default converter
[junit4] 1> 15586 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.handler.component.QueryElevationComponent – Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4] 1> 15610 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.handler.ReplicationHandler – Commits will be reserved for 10000
[junit4] 1> 15611 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2f3af889 main{StandardDirectoryReader(segments_1:1)}
[junit4] 1> 15612 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] webapp=null path=null params={event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1
[junit4] 1> 15613 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 15613 [searcherExecutor-58-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Loading spell index for spellchecker: default
[junit4] 1> 15613 [searcherExecutor-58-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Loading spell index for spellchecker: wordbreak
[junit4] 1> 15618 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2f3af889 main{StandardDirectoryReader(segments_1:1)}
[junit4] 1> 15627 [coreLoadExecutor-57-thread-1] INFO org.apache.solr.core.CoreContainer – registering core: collection1
[junit4] 1> 15628 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1
[junit4] 1> 15628 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init() done
[junit4] 1> 15629 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.SolrJettyTestBase – Jetty Assigned Port#49538
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/./solrtest-SolrExampleBinaryTest-1373583722020
[junit4] 1> 15638 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testMultiContentStreamRequest
[junit4] 1> 15660 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[E47787219CA7B033]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 1> 16084 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onInit: commits: num=1
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1,generation=1}
[junit4] 1> 16086 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 1
[junit4] 1> 16089 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2b42176c realtime
[junit4] 1> 16090 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440306926660354048)} 0 12
[junit4] 1> 16172 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 16173 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – No uncommitted changes. Skipping IW.commit.
[junit4] 1> 16174 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3140e8b2 main
[junit4] 1> 16175 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 16175 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3140e8b2 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 1> 16177 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 16194 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3140e8b2 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 1> 16197 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 25
[junit4] 1> 16204 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 16262 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 16292 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1,generation=1}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_2,generation=2}
[junit4] 1> 16294 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 2
[junit4] 1> 16299 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@18c9fce5 main
[junit4] 1> 16300 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 16300 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@18c9fce5 main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):C5)}
[junit4] 1> 16301 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 16310 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@18c9fce5 main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):C5)}
[junit4] 1> 16312 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&echoParams=ALL&commit=true&a=ሴ&wt=javabin&version=2&softCommit=false} {add=[SP2514N (1440306926819737600), 6H500F0 (1440306926829174784), TWINX2048-3200PRO (1440306926834417664), VS1GB400C3 (1440306926840709120), VDBDB1A16 (1440306926844903424)],commit=} 0 85
[junit4] 1> 16318 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=1
[junit4] 1> 16326 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testMultiContentStreamRequest
[junit4] 1> 16340 [TEST-SolrExampleBinaryTest.testCommitWithinOnAdd-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testCommitWithinOnAdd
[junit4] 1> 16345 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2333e173 realtime
[junit4] 1> 16346 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440306926935080960)} 0 2
[junit4] 1> 16355 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 16356 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_2,generation=2}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_3,generation=3}
[junit4] 1> 16357 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 3
[junit4] 1> 16359 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3c18d95f main
[junit4] 1> 16360 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3c18d95f main{StandardDirectoryReader(segments_2:5:nrt)}
[junit4] 1> 16360 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 16360 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 16370 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3c18d95f main{StandardDirectoryReader(segments_2:5:nrt)}
[junit4] 1> 16373 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4] 1> 16379 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 16389 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1440306926978072576)]} 0 4
[junit4] 1> 16394 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 16889 [commitScheduler-61-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 1> 16897 [commitScheduler-61-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@28354ae9 main
[junit4] 1> 16898 [commitScheduler-61-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 16898 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@28354ae9 main{StandardDirectoryReader(segments_3:7:nrt _1(4.5):C1)}
[junit4] 1> 16899 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 16906 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@28354ae9 main{StandardDirectoryReader(segments_3:7:nrt _1(4.5):C1)}
[junit4] 1> 17400 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 17407 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id4 (1440306928047620096)]} 0 2
[junit4] 1> 17907 [commitScheduler-61-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 1> 17914 [commitScheduler-61-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@49ab5495 main
[junit4] 1> 17915 [commitScheduler-61-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17915 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@49ab5495 main{StandardDirectoryReader(segments_3:9:nrt _1(4.5):C1 _2(4.5):C1)}
[junit4] 1> 17915 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17925 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@49ab5495 main{StandardDirectoryReader(segments_3:9:nrt _1(4.5):C1 _2(4.5):C1)}
[junit4] 1> 18413 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id4&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 18415 [TEST-SolrExampleBinaryTest.testCommitWithinOnAdd-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testCommitWithinOnAdd
[junit4] 1> 18425 [TEST-SolrExampleBinaryTest.testChineseDefaults-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testChineseDefaults
[junit4] 1> 18430 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@71931d80 realtime
[junit4] 1> 18431 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440306929120313344)} 0 3
[junit4] 1> 18441 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 18444 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_3,generation=3}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_4,generation=4}
[junit4] 1> 18445 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 4
[junit4] 1> 18446 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@14da0c7e main
[junit4] 1> 18447 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 18447 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@14da0c7e main{StandardDirectoryReader(segments_3:11:nrt)}
[junit4] 1> 18448 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 18454 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@14da0c7e main{StandardDirectoryReader(segments_3:11:nrt)}
[junit4] 1> 18456 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4] 1> 18462 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 18469 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 18474 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_4,generation=4}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_5,generation=5}
[junit4] 1> 18474 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 5
[junit4] 1> 18477 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5542de54 main
[junit4] 1> 18478 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 18478 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5542de54 main{StandardDirectoryReader(segments_5:13:nrt _3(4.5):C1)}
[junit4] 1> 18479 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 18482 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5542de54 main{StandardDirectoryReader(segments_5:13:nrt _3(4.5):C1)}
[junit4] 1> 18483 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[42 (1440306929161207808)],commit=} 0 16
[junit4] 1> 18490 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=北京大学&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 18491 [TEST-SolrExampleBinaryTest.testChineseDefaults-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testChineseDefaults
[junit4] 1> 18506 [TEST-SolrExampleBinaryTest.testStatistics-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testStatistics
[junit4] 1> 18512 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6b1002be realtime
[junit4] 1> 18513 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440306929206296576)} 0 3
[junit4] 1> 18523 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 18524 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_5,generation=5}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_6,generation=6}
[junit4] 1> 18524 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 6
[junit4] 1> 18525 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5c372610 main
[junit4] 1> 18526 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5c372610 main{StandardDirectoryReader(segments_5:15:nrt)}
[junit4] 1> 18526 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 18526 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 18532 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5c372610 main{StandardDirectoryReader(segments_5:15:nrt)}
[junit4] 1> 18533 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 18540 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2
[junit4] 1> 18548 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1440306929244045312)]} 0 2
[junit4] 1> 18556 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1440306929252433920)]} 0 2
[junit4] 1> 18564 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1440306929260822528)]} 0 2
[junit4] 1> 18570 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1440306929268162560)]} 0 1
[junit4] 1> 18579 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1440306929275502592)]} 0 3
[junit4] 1> 33548 [commitScheduler-60-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 33553 [commitScheduler-60-thread-1] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_6,generation=6}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_7,generation=7}
[junit4] 1> 33554 [commitScheduler-60-thread-1] INFO org.apache.solr.core.SolrCore – newest commit generation = 7
[junit4] 1> 33555 [commitScheduler-60-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@44ea5405 realtime
[junit4] 1> 33556 [commitScheduler-60-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 218852 [TEST-SolrExampleBinaryTest.testStatistics-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testStatistics
[junit4] 1> 218853 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1440307139280109568)]} 0 2
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleBinaryTest -Dtests.method=testStatistics -Dtests.seed=E47787219CA7B033 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_AR -Dtests.timezone=America/Tijuana -Dtests.file.encoding=UTF-8
[junit4] ERROR 200s J1 | SolrExampleBinaryTest.testStatistics <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:49538/solr/collection1
[junit4] > at __randomizedtesting.SeedInfo.seed([E47787219CA7B033:13DEB445308A6ADB]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:436)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:816)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
[junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
[junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
[junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
[junit4] > ... 45 more
[junit4] 1> 218895 [TEST-SolrExampleBinaryTest.testQueryWithParams-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testQueryWithParams
[junit4] 1> 218913 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=query&debug=true&wt=javabin&version=2} hits=0 status=0 QTime=3
[junit4] 1> 218915 [TEST-SolrExampleBinaryTest.testQueryWithParams-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testQueryWithParams
[junit4] 1> 218926 [TEST-SolrExampleBinaryTest.testContentStreamRequest-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testContentStreamRequest
[junit4] 1> 218938 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5c4dd7bb realtime
[junit4] 1> 218939 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307139361898496)} 0 9
[junit4] 1> 218946 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 218947 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_7,generation=7}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_8,generation=8}
[junit4] 1> 218947 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 8
[junit4] 1> 218948 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@58799e8e main
[junit4] 1> 218949 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@58799e8e main{StandardDirectoryReader(segments_7:21:nrt)}
[junit4] 1> 218949 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 218949 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 218954 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@58799e8e main{StandardDirectoryReader(segments_7:21:nrt)}
[junit4] 1> 218956 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 218961 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 219007 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219019 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_8,generation=8}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_9,generation=9}
[junit4] 1> 219019 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 9
[junit4] 1> 219021 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@698c2481 main
[junit4] 1> 219022 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219022 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@698c2481 main{StandardDirectoryReader(segments_9:23:nrt _6(4.5):C10)}
[junit4] 1> 219023 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219029 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@698c2481 main{StandardDirectoryReader(segments_9:23:nrt _6(4.5):C10)}
[junit4] 1> 219031 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0553573403 (1440307139422715904), 0553579908 (1440307139425861632), 055357342X (1440307139427958784), 0553293354 (1440307139430055936), 0812521390 (1440307139432153088), 0812550706 (1440307139433201664), 0441385532 (1440307139435298816), 0380014300 (1440307139437395968), 0805080481 (1440307139438444544), 080508049X (1440307139440541696)],commit=} 0 62
[junit4] 1> 219036 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 1> 219041 [TEST-SolrExampleBinaryTest.testContentStreamRequest-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testContentStreamRequest
[junit4] 1> 219053 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testStreamingRequest
[junit4] 1> 219059 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5afbc3f1 realtime
[junit4] 1> 219060 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307139495067648)} 0 3
[junit4] 1> 219067 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219068 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_9,generation=9}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_a,generation=10}
[junit4] 1> 219068 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 10
[junit4] 1> 219069 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6af035e2 main
[junit4] 1> 219070 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6af035e2 main{StandardDirectoryReader(segments_9:25:nrt)}
[junit4] 1> 219071 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219070 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219076 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6af035e2 main{StandardDirectoryReader(segments_9:25:nrt)}
[junit4] 1> 219078 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 219082 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 219093 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219097 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_a,generation=10}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_b,generation=11}
[junit4] 1> 219097 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 11
[junit4] 1> 219099 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@49a20b91 main
[junit4] 1> 219100 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@49a20b91 main{StandardDirectoryReader(segments_b:27:nrt _7(4.5):C10)}
[junit4] 1> 219100 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219100 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219106 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@49a20b91 main{StandardDirectoryReader(segments_b:27:nrt _7(4.5):C10)}
[junit4] 1> 219107 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0 (1440307139526524928), 1 (1440307139527573504), 2 (1440307139527573505), 3 (1440307139528622080), 4 (1440307139528622081), 5 (1440307139529670656), 6 (1440307139529670657), 7 (1440307139530719232), 8 (1440307139531767808), 9 (1440307139531767809)],commit=} 0 21
[junit4] 1> 219113 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=2
[junit4] 1> 219119 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 1> 219121 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testStreamingRequest
[junit4] 1> 219131 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testExampleConfig
[junit4] 1> 219136 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5ffa4822 realtime
[junit4] 1> 219137 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307139575808000)} 0 3
[junit4] 1> 219142 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111 (1440307139583148032)]} 0 1
[junit4] 1> 219150 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219153 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_b,generation=11}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_c,generation=12}
[junit4] 1> 219154 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 12
[junit4] 1> 219156 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@369ef1c3 main
[junit4] 1> 219157 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@369ef1c3 main{StandardDirectoryReader(segments_c:31:nrt _8(4.5):C1)}
[junit4] 1> 219157 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219157 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219161 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@369ef1c3 main{StandardDirectoryReader(segments_c:31:nrt _8(4.5):C1)}
[junit4] 1> 219162 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 219170 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219187 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_c,generation=12}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_d,generation=13}
[junit4] 1> 219187 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 13
[junit4] 1> 219191 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@29ecc25f main
[junit4] 1> 219192 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219192 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@29ecc25f main{StandardDirectoryReader(segments_d:33:nrt _9(4.5):C1)}
[junit4] 1> 219193 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219197 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@29ecc25f main{StandardDirectoryReader(segments_d:33:nrt _9(4.5):C1)}
[junit4] 1> 219198 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 28
[junit4] 1> 219203 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 219213 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1440307139653402624), 3 (1440307139655499776), 4 (1440307139656548352), 5 (1440307139657596928)]} 0 5
[junit4] 1> 219220 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219226 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_d,generation=13}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_e,generation=14}
[junit4] 1> 219226 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 14
[junit4] 1> 219228 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5f2bdcfd main
[junit4] 1> 219229 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5f2bdcfd main{StandardDirectoryReader(segments_e:35:nrt _9(4.5):C1 _a(4.5):C4)}
[junit4] 1> 219230 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219230 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219235 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5f2bdcfd main{StandardDirectoryReader(segments_e:35:nrt _9(4.5):C1 _a(4.5):C4)}
[junit4] 1> 219237 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 17
[junit4] 1> 219246 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219253 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_e,generation=14}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_f,generation=15}
[junit4] 1> 219254 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 15
[junit4] 1> 219258 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@20b169b0 main
[junit4] 1> 219259 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219259 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@20b169b0 main{StandardDirectoryReader(segments_f:37:nrt _b(4.5):C5)}
[junit4] 1> 219260 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219265 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@20b169b0 main{StandardDirectoryReader(segments_f:37:nrt _b(4.5):C5)}
[junit4] 1> 219266 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 21
[junit4] 1> 219321 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=javabin&version=2} hits=5 status=0 QTime=50
[junit4] 1> 219339 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=javabin&fq=inStock:true&version=2} hits=2 status=0 QTime=12
[junit4] 1> 219347 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&q=foo&wt=javabin&fq={!field+f%3DinStock}true&fq={!term+f%3Dname}hoss&version=2} hits=0 status=0 QTime=2
[junit4] 1> 219349 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[E47787219CA7B033]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 1> 219424 [qtp2021589839-62] INFO org.apache.solr.servlet.SolrDispatchFilter – [admin] webapp=null path=/admin/info/system params={wt=javabin&qt=/admin/info/system&version=2} status=0 QTime=5
[junit4] 1> 219430 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testExampleConfig
[junit4] 1> 219446 [TEST-SolrExampleBinaryTest.testUnicode-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUnicode
[junit4] 1> 219451 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1b7baefe realtime
[junit4] 1> 219452 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1440307139907158016)} 0 2
[junit4] 1> 219475 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1440307139922886656), 1 (1440307139923935232), 2 (1440307139924983808), 3 (1440307139924983809), 4 (1440307139926032384), 5 (1440307139927080960), 6 (1440307139927080961), 7 (1440307139928129536), 8 (1440307139928129537), 9 (1440307139929178112), ... (16 adds)]} 0 10
[junit4] 1> 219483 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219489 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_f,generation=15}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_g,generation=16}
[junit4] 1> 219489 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 16
[junit4] 1> 219491 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7c825f5d main
[junit4] 1> 219492 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@7c825f5d main{StandardDirectoryReader(segments_g:41:nrt _c(4.5):C16)}
[junit4] 1> 219492 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219492 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219497 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@7c825f5d main{StandardDirectoryReader(segments_g:41:nrt _c(4.5):C16)}
[junit4] 1> 219498 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 15
[junit4] 1> 219503 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=16} hits=16 status=0 QTime=1
[junit4] 1> 219517 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@98030bb realtime
[junit4] 1> 219518 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307139975315456)} 0 3
[junit4] 1> 219526 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440307139983704064), 1 (1440307139984752640), 2 (1440307139985801216), 3 (1440307139986849792)]} 0 3
[junit4] 1> 219534 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219538 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_g,generation=16}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_h,generation=17}
[junit4] 1> 219539 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 17
[junit4] 1> 219541 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2675475e main
[junit4] 1> 219542 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2675475e main{StandardDirectoryReader(segments_h:45:nrt _d(4.5):C4)}
[junit4] 1> 219543 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219544 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219555 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2675475e main{StandardDirectoryReader(segments_h:45:nrt _d(4.5):C4)}
[junit4] 1> 219558 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 24
[junit4] 1> 219563 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=4} hits=4 status=0 QTime=0
[junit4] 1> 219570 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@49590930 realtime
[junit4] 1> 219572 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1440307140031938560)} 0 3
[junit4] 1> 219581 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1440307140041375744), 1 (1440307140041375745), 2 (1440307140042424320), 3 (1440307140042424321), 4 (1440307140043472896), 5 (1440307140043472897), 6 (1440307140044521472)]} 0 4
[junit4] 1> 219591 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219595 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_h,generation=17}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_i,generation=18}
[junit4] 1> 219597 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 18
[junit4] 1> 219599 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@729d5457 main
[junit4] 1> 219600 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@729d5457 main{StandardDirectoryReader(segments_i:49:nrt _e(4.5):C7)}
[junit4] 1> 219602 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219601 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219606 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@729d5457 main{StandardDirectoryReader(segments_i:49:nrt _e(4.5):C7)}
[junit4] 1> 219607 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 17
[junit4] 1> 219612 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=7} hits=7 status=0 QTime=0
[junit4] 1> 219621 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2b08fc42 realtime
[junit4] 1> 219622 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1440307140084367360)} 0 3
[junit4] 1> 219639 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1440307140095901696), 1 (1440307140095901697), 2 (1440307140096950272), 3 (1440307140096950273), 4 (1440307140097998848), 5 (1440307140097998849), 6 (1440307140099047424), 7 (1440307140099047425), 8 (1440307140099047426), 9 (1440307140100096000), ... (19 adds)]} 0 10
[junit4] 1> 219647 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219654 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_i,generation=18}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_j,generation=19}
[junit4] 1> 219655 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 19
[junit4] 1> 219657 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@54b7b24f main
[junit4] 1> 219658 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@54b7b24f main{StandardDirectoryReader(segments_j:53:nrt _f(4.5):C19)}
[junit4] 1> 219659 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219659 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219664 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@54b7b24f main{StandardDirectoryReader(segments_j:53:nrt _f(4.5):C19)}
[junit4] 1> 219665 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 18
[junit4] 1> 219671 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=19} hits=19 status=0 QTime=1
[junit4] 1> 219686 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@225f9a3c realtime
[junit4] 1> 219687 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307140152524800)} 0 3
[junit4] 1> 219698 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440307140160913408), 1 (1440307140163010560), 2 (1440307140163010561), 3 (1440307140164059136), 4 (1440307140165107712), 5 (1440307140165107713), 6 (1440307140166156288)]} 0 6
[junit4] 1> 219706 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219711 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_j,generation=19}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_k,generation=20}
[junit4] 1> 219711 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 20
[junit4] 1> 219713 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@45660d4e main
[junit4] 1> 219714 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@45660d4e main{StandardDirectoryReader(segments_k:57:nrt _g(4.5):C7)}
[junit4] 1> 219714 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219715 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219719 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@45660d4e main{StandardDirectoryReader(segments_k:57:nrt _g(4.5):C7)}
[junit4] 1> 219720 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 219725 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=7} hits=7 status=0 QTime=0
[junit4] 1> 219733 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@69d225b1 realtime
[junit4] 1> 219734 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307140201807872)} 0 3
[junit4] 1> 219742 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440307140210196480), 1 (1440307140211245056), 2 (1440307140212293632), 3 (1440307140212293633), 4 (1440307140213342208)]} 0 3
[junit4] 1> 219751 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219756 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_k,generation=20}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_l,generation=21}
[junit4] 1> 219757 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 21
[junit4] 1> 219759 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@747bd42f main
[junit4] 1> 219760 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@747bd42f main{StandardDirectoryReader(segments_l:61:nrt _h(4.5):C5)}
[junit4] 1> 219760 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219761 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219765 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@747bd42f main{StandardDirectoryReader(segments_l:61:nrt _h(4.5):C5)}
[junit4] 1> 219766 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4] 1> 219771 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=5} hits=5 status=0 QTime=0
[junit4] 1> 219781 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2bde129 realtime
[junit4] 1> 219782 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307140253188096)} 0 3
[junit4] 1> 219792 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440307140262625280), 1 (1440307140263673856), 2 (1440307140264722432)]} 0 3
[junit4] 1> 219800 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219804 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_l,generation=21}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_m,generation=22}
[junit4] 1> 219804 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 22
[junit4] 1> 219806 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5de02b18 main
[junit4] 1> 219807 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5de02b18 main{StandardDirectoryReader(segments_m:65:nrt _i(4.5):C3)}
[junit4] 1> 219808 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219808 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219812 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5de02b18 main{StandardDirectoryReader(segments_m:65:nrt _i(4.5):C3)}
[junit4] 1> 219814 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 219819 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=3} hits=3 status=0 QTime=0
[junit4] 1> 219827 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@18f9a066 realtime
[junit4] 1> 219828 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307140300374016)} 0 3
[junit4] 1> 219854 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440307140311908352), 1 (1440307140312956928), 2 (1440307140314005504), 3 (1440307140315054080), 4 (1440307140316102656), 5 (1440307140316102657), 6 (1440307140317151232), 7 (1440307140318199808), 8 (1440307140319248384), 9 (1440307140319248385), ... (23 adds)]} 0 18
[junit4] 1> 219863 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219870 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_m,generation=22}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_n,generation=23}
[junit4] 1> 219871 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 23
[junit4] 1> 219873 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@54e87480 main
[junit4] 1> 219875 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@54e87480 main{StandardDirectoryReader(segments_n:69:nrt _j(4.5):C23)}
[junit4] 1> 219876 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219876 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219882 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@54e87480 main{StandardDirectoryReader(segments_n:69:nrt _j(4.5):C23)}
[junit4] 1> 219883 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 20
[junit4] 1> 219889 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=23} hits=23 status=0 QTime=1
[junit4] 1> 219904 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6763a954 realtime
[junit4] 1> 219905 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1440307140380065792)} 0 4
[junit4] 1> 219929 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1440307140394745856), 1 (1440307140399988736), 2 (1440307140399988737), 3 (1440307140401037312), 4 (1440307140402085888), 5 (1440307140403134464), 6 (1440307140404183040), 7 (1440307140404183041), 8 (1440307140405231616), 9 (1440307140406280192), ... (13 adds)]} 0 15
[junit4] 1> 219939 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219946 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_n,generation=23}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_o,generation=24}
[junit4] 1> 219946 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 24
[junit4] 1> 219948 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4604c6db main
[junit4] 1> 219949 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@4604c6db main{StandardDirectoryReader(segments_o:73:nrt _k(4.5):C13)}
[junit4] 1> 219949 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 219950 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 219956 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@4604c6db main{StandardDirectoryReader(segments_o:73:nrt _k(4.5):C13)}
[junit4] 1> 219957 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 18
[junit4] 1> 219964 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=13} hits=13 status=0 QTime=1
[junit4] 1> 219977 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@454a0041 realtime
[junit4] 1> 219978 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307140457660416)} 0 3
[junit4] 1> 219987 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440307140466049024), 1 (1440307140468146176), 2 (1440307140468146177), 3 (1440307140469194752)]} 0 4
[junit4] 1> 219994 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 219998 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_o,generation=24}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_p,generation=25}
[junit4] 1> 219998 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 25
[junit4] 1> 220000 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@674e3493 main
[junit4] 1> 220001 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@674e3493 main{StandardDirectoryReader(segments_p:77:nrt _l(4.5):C4)}
[junit4] 1> 220001 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220001 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220005 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@674e3493 main{StandardDirectoryReader(segments_p:77:nrt _l(4.5):C4)}
[junit4] 1> 220006 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 220010 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=4} hits=4 status=0 QTime=0
[junit4] 1> 220016 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3fabc467 realtime
[junit4] 1> 220017 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1440307140499603456)} 0 2
[junit4] 1> 220030 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1440307140507992064), 1 (1440307140509040640), 2 (1440307140509040641), 3 (1440307140510089216), 4 (1440307140510089217), 5 (1440307140511137792), 6 (1440307140511137793), 7 (1440307140512186368), 8 (1440307140512186369), 9 (1440307140513234944), ... (13 adds)]} 0 7
[junit4] 1> 220036 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220041 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_p,generation=25}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_q,generation=26}
[junit4] 1> 220041 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 26
[junit4] 1> 220043 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@678ab721 main
[junit4] 1> 220044 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@678ab721 main{StandardDirectoryReader(segments_q:81:nrt _m(4.5):C13)}
[junit4] 1> 220044 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220044 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220048 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@678ab721 main{StandardDirectoryReader(segments_q:81:nrt _m(4.5):C13)}
[junit4] 1> 220049 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 13
[junit4] 1> 220054 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=13} hits=13 status=0 QTime=0
[junit4] 1> 220064 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@555a685 realtime
[junit4] 1> 220065 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1440307140548886528)} 0 3
[junit4] 1> 220079 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1440307140558323712), 1 (1440307140559372288), 2 (1440307140560420864), 3 (1440307140561469440), 4 (1440307140561469441), 5 (1440307140562518016), 6 (1440307140562518017), 7 (1440307140563566592), 8 (1440307140564615168), 9 (1440307140564615169), ... (13 adds)]} 0 8
[junit4] 1> 220087 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220093 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_q,generation=26}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_r,generation=27}
[junit4] 1> 220093 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 27
[junit4] 1> 220095 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@44d7e52d main
[junit4] 1> 220097 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@44d7e52d main{StandardDirectoryReader(segments_r:85:nrt _n(4.5):C13)}
[junit4] 1> 220097 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220097 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220101 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@44d7e52d main{StandardDirectoryReader(segments_r:85:nrt _n(4.5):C13)}
[junit4] 1> 220102 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 15
[junit4] 1> 220107 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=13} hits=13 status=0 QTime=0
[junit4] 1> 220117 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5f59011 realtime
[junit4] 1> 220118 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307140605509632)} 0 2
[junit4] 1> 220130 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440307140613898240), 1 (1440307140614946816), 2 (1440307140614946817), 3 (1440307140614946818), 4 (1440307140615995392), 5 (1440307140615995393), 6 (1440307140617043968), 7 (1440307140617043969), 8 (1440307140618092544), 9 (1440307140618092545), ... (14 adds)]} 0 7
[junit4] 1> 220137 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220142 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_r,generation=27}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_s,generation=28}
[junit4] 1> 220142 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 28
[junit4] 1> 220144 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@14717de5 main
[junit4] 1> 220144 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@14717de5 main{StandardDirectoryReader(segments_s:89:nrt _o(4.5):C14)}
[junit4] 1> 220145 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220145 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220150 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@14717de5 main{StandardDirectoryReader(segments_s:89:nrt _o(4.5):C14)}
[junit4] 1> 220151 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 220156 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=14} hits=14 status=0 QTime=0
[junit4] 1> 220160 [TEST-SolrExampleBinaryTest.testUnicode-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUnicode
[junit4] 1> 220170 [TEST-SolrExampleBinaryTest.testRealtimeGet-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testRealtimeGet
[junit4] 1> 220180 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@71561188 realtime
[junit4] 1> 220181 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307140666327040)} 0 7
[junit4] 1> 220186 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[DOCID (1440307140677861376)]} 0 1
[junit4] 1> 220192 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220195 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_s,generation=28}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_t,generation=29}
[junit4] 1> 220195 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 29
[junit4] 1> 220197 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@75cd333c main
[junit4] 1> 220198 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@75cd333c main{StandardDirectoryReader(segments_t:93:nrt _p(4.5):C1)}
[junit4] 1> 220198 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220198 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220203 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@75cd333c main{StandardDirectoryReader(segments_t:93:nrt _p(4.5):C1)}
[junit4] 1> 220204 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 220209 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=javabin&qt=/get&version=2} status=0 QTime=1
[junit4] 1> 220214 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=xml&qt=/get&version=2.2} status=0 QTime=1
[junit4] 1> 220216 [TEST-SolrExampleBinaryTest.testRealtimeGet-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testRealtimeGet
[junit4] 1> 220226 [TEST-SolrExampleBinaryTest.testFaceting-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testFaceting
[junit4] 1> 220230 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@696cf8ce realtime
[junit4] 1> 220231 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307140723998720)} 0 2
[junit4] 1> 220238 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220239 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_t,generation=29}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_u,generation=30}
[junit4] 1> 220239 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 30
[junit4] 1> 220240 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@b895d50 main
[junit4] 1> 220241 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220241 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@b895d50 main{StandardDirectoryReader(segments_t:95:nrt)}
[junit4] 1> 220242 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220245 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@b895d50 main{StandardDirectoryReader(segments_t:95:nrt)}
[junit4] 1> 220246 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 220251 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 220260 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1440307140751261696), 2 (1440307140752310272), 3 (1440307140752310273), 4 (1440307140753358848), 5 (1440307140753358849), 6 (1440307140754407424), 7 (1440307140754407425), 8 (1440307140755456000), 9 (1440307140755456001), 10 (1440307140756504576)]} 0 5
[junit4] 1> 220267 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220271 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_u,generation=30}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_v,generation=31}
[junit4] 1> 220271 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 31
[junit4] 1> 220273 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5c0597e2 main
[junit4] 1> 220274 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5c0597e2 main{StandardDirectoryReader(segments_v:97:nrt _q(4.5):C10)}
[junit4] 1> 220274 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220274 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220278 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5c0597e2 main{StandardDirectoryReader(segments_v:97:nrt _q(4.5):C10)}
[junit4] 1> 220279 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 220290 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=1,phase1=1,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4] 1> 220293 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=10
[junit4] 1> 220298 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4] 1> 220304 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=4&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4] 1> 220309 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=-1&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4] 1> 220311 [TEST-SolrExampleBinaryTest.testFaceting-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testFaceting
[junit4] 1> 220323 [TEST-SolrExampleBinaryTest.testAugmentFields-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testAugmentFields
[junit4] 1> 220327 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@75b26f61 realtime
[junit4] 1> 220328 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307140825710592)} 0 2
[junit4] 1> 220334 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1440307140832002048)]} 0 2
[junit4] 1> 220340 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220344 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_v,generation=31}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_w,generation=32}
[junit4] 1> 220345 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 32
[junit4] 1> 220347 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@66ae1399 main
[junit4] 1> 220348 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220348 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@66ae1399 main{StandardDirectoryReader(segments_w:101:nrt _r(4.5):C1)}
[junit4] 1> 220348 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220354 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@66ae1399 main{StandardDirectoryReader(segments_w:101:nrt _r(4.5):C1)}
[junit4] 1> 220355 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4] 1> 220361 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222 (1440307140860313600)]} 0 2
[junit4] 1> 220367 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220371 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_w,generation=32}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_x,generation=33}
[junit4] 1> 220372 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 33
[junit4] 1> 220374 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4197a7e5 main
[junit4] 1> 220375 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@4197a7e5 main{StandardDirectoryReader(segments_x:103:nrt _r(4.5):C1 _s(4.5):C1)}
[junit4] 1> 220375 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220375 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220380 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@4197a7e5 main{StandardDirectoryReader(segments_x:103:nrt _r(4.5):C1 _s(4.5):C1)}
[junit4] 1> 220381 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 220387 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&fl=id,price,[docid],[explain+style%3Dnl],score,aaa:[value+v%3Daaa],ten:[value+v%3D10+t%3Dint]&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=2
[junit4] 1> 220389 [TEST-SolrExampleBinaryTest.testAugmentFields-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAugmentFields
[junit4] 1> 220400 [TEST-SolrExampleBinaryTest.testUpdateField-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateField
[junit4] 1> 220404 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7119b9b0 realtime
[junit4] 1> 220405 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307140906450944)} 0 3
[junit4] 1> 220410 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220411 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_x,generation=33}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_y,generation=34}
[junit4] 1> 220412 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 34
[junit4] 1> 220413 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6df6637b main
[junit4] 1> 220413 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6df6637b main{StandardDirectoryReader(segments_x:105:nrt)}
[junit4] 1> 220414 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220414 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220419 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6df6637b main{StandardDirectoryReader(segments_x:105:nrt)}
[junit4] 1> 220421 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 220426 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1440307140929519616)]} 0 2
[junit4] 1> 220433 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220436 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_y,generation=34}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_z,generation=35}
[junit4] 1> 220437 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 35
[junit4] 1> 220438 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@18747957 main
[junit4] 1> 220439 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@18747957 main{StandardDirectoryReader(segments_z:107:nrt _t(4.5):C1)}
[junit4] 1> 220439 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220439 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220444 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@18747957 main{StandardDirectoryReader(segments_z:107:nrt _t(4.5):C1)}
[junit4] 1> 220445 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 220450 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 220455 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 1
[junit4] 1> 220462 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1440307140966219776)]} 0 2
[junit4] 1> 220467 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220471 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_z,generation=35}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_10,generation=36}
[junit4] 1> 220471 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 36
[junit4] 1> 220473 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5128af47 main
[junit4] 1> 220474 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5128af47 main{StandardDirectoryReader(segments_10:111:nrt _u(4.5):C1)}
[junit4] 1> 220475 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220474 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220479 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5128af47 main{StandardDirectoryReader(segments_10:111:nrt _u(4.5):C1)}
[junit4] 1> 220480 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4] 1> 220485 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 220490 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1440307140996628480)]} 0 1
[junit4] 1> 220497 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220501 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_10,generation=36}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_11,generation=37}
[junit4] 1> 220501 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 37
[junit4] 1> 220503 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6666cc92 main
[junit4] 1> 220504 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6666cc92 main{StandardDirectoryReader(segments_11:115:nrt _v(4.5):C1)}
[junit4] 1> 220504 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220504 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220509 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6666cc92 main{StandardDirectoryReader(segments_11:115:nrt _v(4.5):C1)}
[junit4] 1> 220510 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4] 1> 220514 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 220515 [TEST-SolrExampleBinaryTest.testUpdateField-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateField
[junit4] 1> 220524 [TEST-SolrExampleBinaryTest.testAddRetrieve-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testAddRetrieve
[junit4] 1> 220528 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7823a6ef realtime
[junit4] 1> 220529 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307141036474368)} 0 2
[junit4] 1> 220536 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1440307141041717248), id2 (1440307141043814400)]} 0 4
[junit4] 1> 220542 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220546 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_11,generation=37}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_12,generation=38}
[junit4] 1> 220547 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 38
[junit4] 1> 220548 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@45b6f325 main
[junit4] 1> 220549 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@45b6f325 main{StandardDirectoryReader(segments_12:119:nrt _w(4.5):C2)}
[junit4] 1> 220549 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220550 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220554 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@45b6f325 main{StandardDirectoryReader(segments_12:119:nrt _w(4.5):C2)}
[junit4] 1> 220556 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 220560 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 1> 220567 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1440307141075271680), id2 (1440307141077368832)]} 0 3
[junit4] 1> 220573 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220577 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_12,generation=38}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_13,generation=39}
[junit4] 1> 220577 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 39
[junit4] 1> 220579 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@58ae5ca1 main
[junit4] 1> 220580 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@58ae5ca1 main{StandardDirectoryReader(segments_13:123:nrt _x(4.5):C2)}
[junit4] 1> 220581 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220581 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220586 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@58ae5ca1 main{StandardDirectoryReader(segments_13:123:nrt _x(4.5):C2)}
[junit4] 1> 220588 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4] 1> 220592 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 1> 220597 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h𩬅llo&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 220598 [TEST-SolrExampleBinaryTest.testAddRetrieve-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAddRetrieve
[junit4] 1> 220613 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testCommitWithinOnDelete
[junit4] 1> 220618 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7dc44820 realtime
[junit4] 1> 220619 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307141130846208)} 0 3
[junit4] 1> 220624 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220625 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_13,generation=39}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_14,generation=40}
[junit4] 1> 220626 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 40
[junit4] 1> 220626 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3a5eabc8 main
[junit4] 1> 220627 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3a5eabc8 main{StandardDirectoryReader(segments_13:125:nrt)}
[junit4] 1> 220627 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220628 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220633 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3a5eabc8 main{StandardDirectoryReader(segments_13:125:nrt)}
[junit4] 1> 220635 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 220639 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 220645 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1440307141158109184)]} 0 3
[junit4] 1> 220651 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 220654 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_14,generation=40}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_15,generation=41}
[junit4] 1> 220655 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 41
[junit4] 1> 220656 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@327e5a68 main
[junit4] 1> 220657 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@327e5a68 main{StandardDirectoryReader(segments_15:127:nrt _y(4.5):C1)}
[junit4] 1> 220658 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 220657 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 220662 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@327e5a68 main{StandardDirectoryReader(segments_15:127:nrt _y(4.5):C1)}
[junit4] 1> 220664 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 220668 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 220673 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1440307141188517888)]} 0 1
[junit4] 1> 220676 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 220931 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 221185 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 221440 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 221673 [commitScheduler-61-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 1> 221674 [commitScheduler-61-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6ab2930 main
[junit4] 1> 221675 [commitScheduler-61-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 221675 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6ab2930 main{StandardDirectoryReader(segments_15:129:nrt)}
[junit4] 1> 221675 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 221678 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6ab2930 main{StandardDirectoryReader(segments_15:129:nrt)}
[junit4] 1> 221694 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 221696 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testCommitWithinOnDelete
[junit4] 1> 221705 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateRequestWithParameters
[junit4] 1> 221706 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[E47787219CA7B033]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 1> 221780 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307142349291520)} 0 1
[junit4] 1> 221786 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 221787 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_15,generation=41}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_16,generation=42}
[junit4] 1> 221788 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 42
[junit4] 1> 221789 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@565a1158 main
[junit4] 1> 221790 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@565a1158 main{StandardDirectoryReader(segments_15:129:nrt)}
[junit4] 1> 221790 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 221790 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 221792 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@565a1158 main{StandardDirectoryReader(segments_15:129:nrt)}
[junit4] 1> 221793 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 1> 221798 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1440307142368165888)]} 0 1
[junit4] 1> 221803 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1440307142373408768)]} 0 1
[junit4] 1> 221809 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 221811 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_16,generation=42}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_17,generation=43}
[junit4] 1> 221811 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 43
[junit4] 1> 221813 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7acf9b5e main
[junit4] 1> 221813 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@7acf9b5e main{StandardDirectoryReader(segments_17:131:nrt _z(4.5):C2)}
[junit4] 1> 221813 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 221814 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 221816 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@7acf9b5e main{StandardDirectoryReader(segments_17:131:nrt _z(4.5):C2)}
[junit4] 1> 221817 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 221821 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 1> 221823 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateRequestWithParameters
[junit4] 1> 221831 [TEST-SolrExampleBinaryTest.testPingHandler-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPingHandler
[junit4] 1> 221836 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@554a0410 realtime
[junit4] 1> 221836 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307142406963200)} 0 2
[junit4] 1> 221842 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 221843 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_17,generation=43}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_18,generation=44}
[junit4] 1> 221843 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 44
[junit4] 1> 221844 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6365df92 main
[junit4] 1> 221845 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6365df92 main{StandardDirectoryReader(segments_17:133:nrt)}
[junit4] 1> 221845 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 221845 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 221848 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6365df92 main{StandardDirectoryReader(segments_17:133:nrt)}
[junit4] 1> 221849 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 1> 221852 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 221860 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=3
[junit4] 1> 221860 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=3
[junit4] 1> 221861 [TEST-SolrExampleBinaryTest.testPingHandler-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPingHandler
[junit4] 1> 221870 [TEST-SolrExampleBinaryTest.testSetNullUpdates-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testSetNullUpdates
[junit4] 1> 221875 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1440307142447857664)]} 0 2
[junit4] 1> 221880 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 221883 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_18,generation=44}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_19,generation=45}
[junit4] 1> 221883 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 45
[junit4] 1> 221885 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@a83a358 main
[junit4] 1> 221886 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@a83a358 main{StandardDirectoryReader(segments_19:135:nrt _10(4.5):C1)}
[junit4] 1> 221886 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 221886 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 221889 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@a83a358 main{StandardDirectoryReader(segments_19:135:nrt _10(4.5):C1)}
[junit4] 1> 221890 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 221895 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1440307142469877760)]} 0 2
[junit4] 1> 221901 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 221904 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_19,generation=45}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1a,generation=46}
[junit4] 1> 221905 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 46
[junit4] 1> 221906 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2a1879ed main
[junit4] 1> 221907 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2a1879ed main{StandardDirectoryReader(segments_1a:139:nrt _11(4.5):C1)}
[junit4] 1> 221907 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 221907 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 221910 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2a1879ed main{StandardDirectoryReader(segments_1a:139:nrt _11(4.5):C1)}
[junit4] 1> 221911 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 221915 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 221917 [TEST-SolrExampleBinaryTest.testSetNullUpdates-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testSetNullUpdates
[junit4] 1> 221925 [TEST-SolrExampleBinaryTest.testPivotFacets-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPivotFacets
[junit4] 1> 221929 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@17b703 realtime
[junit4] 1> 221930 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307142505529344)} 0 2
[junit4] 1> 221935 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 221936 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1a,generation=46}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1b,generation=47}
[junit4] 1> 221936 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 47
[junit4] 1> 221937 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@452b50ab main
[junit4] 1> 221938 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@452b50ab main{StandardDirectoryReader(segments_1a:141:nrt)}
[junit4] 1> 221938 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 221938 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 221942 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@452b50ab main{StandardDirectoryReader(segments_1a:141:nrt)}
[junit4] 1> 221943 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 221947 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 221961 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1440307142529646592), 2 (1440307142531743744), 3 (1440307142532792320), 4 (1440307142532792321), 5 (1440307142533840896), 6 (1440307142534889472), 7 (1440307142535938048), 8 (1440307142535938049), 9 (1440307142536986624), 10 (1440307142538035200), ... (12 adds)]} 0 10
[junit4] 1> 221966 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 221970 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1b,generation=47}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1c,generation=48}
[junit4] 1> 221971 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 48
[junit4] 1> 221973 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@60eb50ab main
[junit4] 1> 221973 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@60eb50ab main{StandardDirectoryReader(segments_1c:143:nrt _12(4.5):C12)}
[junit4] 1> 221973 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 221974 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 221977 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@60eb50ab main{StandardDirectoryReader(segments_1c:143:nrt _12(4.5):C12)}
[junit4] 1> 221978 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 221984 [qtp2021589839-59] 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] 1> 221985 [qtp2021589839-59] 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] 1> 221987 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet.missing=false&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=5
[junit4] 1> 221996 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=2
[junit4] 1> 221997 [TEST-SolrExampleBinaryTest.testPivotFacets-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPivotFacets
[junit4] 1> 222007 [TEST-SolrExampleBinaryTest.testLukeHandler-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testLukeHandler
[junit4] 1> 222011 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5261444c realtime
[junit4] 1> 222012 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307142591512576)} 0 2
[junit4] 1> 222017 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0 (1440307142597804032)]} 0 1
[junit4] 1> 222022 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1 (1440307142603046912)]} 0 1
[junit4] 1> 222026 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2 (1440307142608289792)]} 0 0
[junit4] 1> 222031 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3 (1440307142612484096)]} 0 1
[junit4] 1> 222036 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID4 (1440307142617726976)]} 0 1
[junit4] 1> 222042 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222044 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1c,generation=48}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1d,generation=49}
[junit4] 1> 222045 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 49
[junit4] 1> 222046 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@a946305 main
[junit4] 1> 222047 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@a946305 main{StandardDirectoryReader(segments_1d:147:nrt _13(4.5):C5)}
[junit4] 1> 222047 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222048 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222052 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@a946305 main{StandardDirectoryReader(segments_1d:147:nrt _13(4.5):C5)}
[junit4] 1> 222053 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 222057 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0
[junit4] 1> 222066 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=3
[junit4] 1> 222073 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2} status=0 QTime=2
[junit4] 1> 222091 [TEST-SolrExampleBinaryTest.testLukeHandler-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testLukeHandler
[junit4] 1> 222104 [TEST-SolrExampleBinaryTest.testUpdateMultiValuedField-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateMultiValuedField
[junit4] 1> 222115 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1440307142694273024)]} 0 8
[junit4] 1> 222123 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222125 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1d,generation=49}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1e,generation=50}
[junit4] 1> 222126 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 50
[junit4] 1> 222127 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@44d82317 main
[junit4] 1> 222128 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@44d82317 main{StandardDirectoryReader(segments_1e:149:nrt _13(4.5):C5 _14(4.5):C1)}
[junit4] 1> 222128 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222132 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222132 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@44d82317 main{StandardDirectoryReader(segments_1e:149:nrt _13(4.5):C5 _14(4.5):C1)}
[junit4] 1> 222133 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 222137 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 222143 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1440307142729924608)]} 0 2
[junit4] 1> 222148 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222152 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1e,generation=50}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1f,generation=51}
[junit4] 1> 222152 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 51
[junit4] 1> 222153 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@50705466 main
[junit4] 1> 222154 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@50705466 main{StandardDirectoryReader(segments_1f:153:nrt _13(4.5):C5 _15(4.5):C1)}
[junit4] 1> 222154 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222154 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222158 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@50705466 main{StandardDirectoryReader(segments_1f:153:nrt _13(4.5):C5 _15(4.5):C1)}
[junit4] 1> 222159 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 222164 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 222169 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1440307142757187584)]} 0 2
[junit4] 1> 222175 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222178 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1f,generation=51}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1g,generation=52}
[junit4] 1> 222178 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 52
[junit4] 1> 222180 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@39efe7e6 main
[junit4] 1> 222180 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@39efe7e6 main{StandardDirectoryReader(segments_1g:157:nrt _13(4.5):C5 _16(4.5):C1)}
[junit4] 1> 222181 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222180 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222185 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@39efe7e6 main{StandardDirectoryReader(segments_1g:157:nrt _13(4.5):C5 _16(4.5):C1)}
[junit4] 1> 222186 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 222190 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 222191 [TEST-SolrExampleBinaryTest.testUpdateMultiValuedField-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateMultiValuedField
[junit4] 1> 222199 [TEST-SolrExampleBinaryTest.testSetNullUpdateOrder-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testSetNullUpdateOrder
[junit4] 1> 222203 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1440307142792839168)]} 0 1
[junit4] 1> 222209 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222213 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1g,generation=52}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1h,generation=53}
[junit4] 1> 222213 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 53
[junit4] 1> 222215 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6ff94952 main
[junit4] 1> 222216 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6ff94952 main{StandardDirectoryReader(segments_1h:159:nrt _13(4.5):C5 _16(4.5):C1 _17(4.5):C1)}
[junit4] 1> 222216 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222216 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222220 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6ff94952 main{StandardDirectoryReader(segments_1h:159:nrt _13(4.5):C5 _16(4.5):C1 _17(4.5):C1)}
[junit4] 1> 222221 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 222226 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1440307142816956416)]} 0 1
[junit4] 1> 222232 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222235 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1h,generation=53}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1i,generation=54}
[junit4] 1> 222235 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 54
[junit4] 1> 222237 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5e613e65 main
[junit4] 1> 222238 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5e613e65 main{StandardDirectoryReader(segments_1i:163:nrt _13(4.5):C5 _16(4.5):C1 _18(4.5):C1)}
[junit4] 1> 222238 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222238 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222242 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5e613e65 main{StandardDirectoryReader(segments_1i:163:nrt _13(4.5):C5 _16(4.5):C1 _18(4.5):C1)}
[junit4] 1> 222243 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 222249 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 222251 [TEST-SolrExampleBinaryTest.testSetNullUpdateOrder-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testSetNullUpdateOrder
[junit4] 1> 222264 [TEST-SolrExampleBinaryTest.testPivotFacetsMissing-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPivotFacetsMissing
[junit4] 1> 222270 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@58cbf4e1 realtime
[junit4] 1> 222271 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307142862045184)} 0 3
[junit4] 1> 222278 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222279 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1i,generation=54}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1j,generation=55}
[junit4] 1> 222280 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 55
[junit4] 1> 222281 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2ddaa71f main
[junit4] 1> 222282 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2ddaa71f main{StandardDirectoryReader(segments_1i:165:nrt)}
[junit4] 1> 222282 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222282 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222286 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2ddaa71f main{StandardDirectoryReader(segments_1i:165:nrt)}
[junit4] 1> 222288 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 222293 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 222309 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1440307142892453888), 2 (1440307142894551040), 3 (1440307142895599616), 4 (1440307142896648192), 5 (1440307142897696768), 6 (1440307142898745344), 7 (1440307142899793920), 8 (1440307142900842496), 9 (1440307142901891072), 10 (1440307142902939648), ... (12 adds)]} 0 12
[junit4] 1> 222316 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222321 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1j,generation=55}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1k,generation=56}
[junit4] 1> 222322 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 56
[junit4] 1> 222323 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@42d13f44 main
[junit4] 1> 222324 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@42d13f44 main{StandardDirectoryReader(segments_1k:167:nrt _19(4.5):C12)}
[junit4] 1> 222324 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222325 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222328 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@42d13f44 main{StandardDirectoryReader(segments_1k:167:nrt _19(4.5):C12)}
[junit4] 1> 222329 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4] 1> 222334 [qtp2021589839-59] 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] 1> 222337 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 1> 222339 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet.missing=true&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=5
[junit4] 1> 222345 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=1
[junit4] 1> 222347 [TEST-SolrExampleBinaryTest.testPivotFacetsMissing-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPivotFacetsMissing
[junit4] 1> 222360 [TEST-SolrExampleBinaryTest.testErrorHandling-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testErrorHandling
[junit4] 1> 222378 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – Ignoring exception matching ignore_exception
[junit4] 1> 222379 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/analysis/field params={analysis.fieldtype=int&wt=javabin&qt=/analysis/field&analysis.fieldvalue=ignore_exception&version=2} status=400 QTime=16
[junit4] 1> 222387 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 4
[junit4] 1> 222388 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – Ignoring exception matching ignore_exception
[junit4] 1> 222392 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 0
[junit4] 1> 222393 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – Ignoring exception matching uniqueKey
[junit4] 1> 222394 [TEST-SolrExampleBinaryTest.testErrorHandling-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testErrorHandling
[junit4] 1> 222406 [TEST-SolrExampleBinaryTest.testAddDelete-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testAddDelete
[junit4] 1> 222411 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@74269b2a realtime
[junit4] 1> 222411 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307143009894400)} 0 2
[junit4] 1> 222416 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1440307143016185856)]} 0 1
[junit4] 1> 222421 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222423 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1k,generation=56}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1l,generation=57}
[junit4] 1> 222424 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 57
[junit4] 1> 222425 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6211e455 main
[junit4] 1> 222426 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6211e455 main{StandardDirectoryReader(segments_1l:171:nrt _1a(4.5):C1)}
[junit4] 1> 222426 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222426 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222429 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6211e455 main{StandardDirectoryReader(segments_1l:171:nrt _1a(4.5):C1)}
[junit4] 1> 222430 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 222433 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 222438 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1440307143039254528)]} 0 1
[junit4] 1> 222443 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222443 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – No uncommitted changes. Skipping IW.commit.
[junit4] 1> 222444 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5e2723ab main
[junit4] 1> 222445 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5e2723ab main{StandardDirectoryReader(segments_1l:173:nrt)}
[junit4] 1> 222445 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222445 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222449 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5e2723ab main{StandardDirectoryReader(segments_1l:173:nrt)}
[junit4] 1> 222450 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 1> 222454 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 222458 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1440307143060226048)]} 0 1
[junit4] 1> 222463 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222465 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1l,generation=57}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1m,generation=58}
[junit4] 1> 222466 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – newest commit generation = 58
[junit4] 1> 222467 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2e232358 main
[junit4] 1> 222467 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2e232358 main{StandardDirectoryReader(segments_1m:175:nrt _1b(4.5):C1)}
[junit4] 1> 222468 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222468 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222471 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2e232358 main{StandardDirectoryReader(segments_1m:175:nrt _1b(4.5):C1)}
[junit4] 1> 222472 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 222476 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 222481 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2f995199 realtime
[junit4] 1> 222482 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=id:"0\ \&\ 222" (-1440307143084343296)} 0 2
[junit4] 1> 222488 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222488 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1m,generation=58}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1n,generation=59}
[junit4] 1> 222489 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 59
[junit4] 1> 222489 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3fba064a main
[junit4] 1> 222490 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3fba064a main{StandardDirectoryReader(segments_1m:177:nrt)}
[junit4] 1> 222490 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222490 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222494 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3fba064a main{StandardDirectoryReader(segments_1m:177:nrt)}
[junit4] 1> 222495 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 222499 [qtp2021589839-62] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 222503 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1440307143107411968)]} 0 1
[junit4] 1> 222507 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 & 222 (1440307143112654848)]} 0 0
[junit4] 1> 222511 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 & 222 (1440307143116849152)]} 0 0
[junit4] 1> 222516 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222518 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1n,generation=59}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1o,generation=60}
[junit4] 1> 222519 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – newest commit generation = 60
[junit4] 1> 222520 [qtp2021589839-60] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5c03c7a1 main
[junit4] 1> 222521 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5c03c7a1 main{StandardDirectoryReader(segments_1o:179:nrt _1c(4.5):C3)}
[junit4] 1> 222521 [qtp2021589839-60] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222521 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222525 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5c03c7a1 main{StandardDirectoryReader(segments_1o:179:nrt _1c(4.5):C3)}
[junit4] 1> 222526 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 222530 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0
[junit4] 1> 222540 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1440307143140966400), 1 & 222 (-1440307143147257856), 2 & 222 (-1440307143147257857)]} 0 7
[junit4] 1> 222546 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222546 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – No uncommitted changes. Skipping IW.commit.
[junit4] 1> 222547 [qtp2021589839-62] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4580a9da main
[junit4] 1> 222548 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@4580a9da main{StandardDirectoryReader(segments_1o:181:nrt)}
[junit4] 1> 222548 [qtp2021589839-62] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222548 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222551 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@4580a9da main{StandardDirectoryReader(segments_1o:181:nrt)}
[junit4] 1> 222552 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 1> 222556 [qtp2021589839-60] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 222557 [TEST-SolrExampleBinaryTest.testAddDelete-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAddDelete
[junit4] 1> 222566 [TEST-SolrExampleBinaryTest.testGetEmptyResults-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testGetEmptyResults
[junit4] 1> 222570 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440307143177666560)} 0 1
[junit4] 1> 222576 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222577 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1o,generation=60}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1p,generation=61}
[junit4] 1> 222578 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – newest commit generation = 61
[junit4] 1> 222579 [qtp2021589839-59] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4d0c7fb0 main
[junit4] 1> 222579 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@4d0c7fb0 main{StandardDirectoryReader(segments_1o:181:nrt)}
[junit4] 1> 222580 [qtp2021589839-59] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222580 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222582 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@4d0c7fb0 main{StandardDirectoryReader(segments_1o:181:nrt)}
[junit4] 1> 222583 [qtp2021589839-59] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 1> 222589 [qtp2021589839-62] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1440307143196540928)]} 0 2
[junit4] 1> 222594 [qtp2021589839-60] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id2 (1440307143202832384)]} 0 1
[junit4] 1> 222600 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 222604 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1p,generation=61}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b0dbcc0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@47ae7c9e),segFN=segments_1q,generation=62}
[junit4] 1> 222605 [qtp2021589839-61] INFO org.apache.solr.core.SolrCore – newest commit generation = 62
[junit4] 1> 222608 [qtp2021589839-61] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@788a321a main
[junit4] 1> 222609 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@788a321a main{StandardDirectoryReader(segments_1q:183:nrt _1d(4.5):C2)}
[junit4] 1> 222609 [qtp2021589839-61] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 222609 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 222612 [searcherExecutor-58-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@788a321a main{StandardDirectoryReader(segments_1q:183:nrt _1d(4.5):C2)}
[junit4] 1> 222612 [qtp2021589839-61] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4] 1> 222617 [qtp2021589839-59] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=foofoofoo&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 1> 222618 [TEST-SolrExampleBinaryTest.testGetEmptyResults-seed#[E47787219CA7B033]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testGetEmptyResults
[junit4] 1> 222680 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CoreContainer – Shutting down CoreContainer instance=1476666466
[junit4] 1> 222681 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.SolrCore – [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6d946ef8
[junit4] 1> 222719 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.update.UpdateHandler – closing DirectUpdateHandler2{commits=65,autocommit maxTime=15000ms,autocommits=1,soft autocommits=3,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=246,cumulative_deletesById=5,cumulative_deletesByQuery=35,cumulative_errors=1}
[junit4] 1> 222720 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.update.SolrCoreState – Closing SolrCoreState
[junit4] 1> 222720 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.update.DefaultSolrCoreState – SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 1> 222721 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.update.DefaultSolrCoreState – closing IndexWriter with IndexWriterCloser
[junit4] 1> 222722 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.SolrCore – [collection1] Closing main searcher on request.
[junit4] 1> 222723 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 1> 222723 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020;done=false>>]
[junit4] 1> 222724 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020
[junit4] 1> 222724 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020/index;done=false>>]
[junit4] 1> 222724 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373583722020/index
[junit4] 1> 222725 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler – stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 1> 222779 [SUITE-SolrExampleBinaryTest-seed#[E47787219CA7B033]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ###deleteCore
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=232), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=232)), sim=DefaultSimilarity, locale=es_AR, timezone=America/Tijuana
[junit4] 2> NOTE: Linux 3.2.0-49-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=76157816,total=126484480
[junit4] 2> NOTE: All tests run in this JVM: [TestSpellCheckResponse, TestFastInputStream, QueryResponseTest, SolrQueryTest, MultiCoreEmbeddedTest, FacetFieldTest, MergeIndexesEmbeddedTest, SolrExampleBinaryTest]
[junit4] Completed on J1 in 208.42s, 26 tests, 1 error <<< FAILURES!
[...truncated 19 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:395: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:375: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:194: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1250: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:893: There were test failures: 46 suites, 272 tests, 1 error
Total time: 40 minutes 29 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b96 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure