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/11/23 16:52:17 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_45) - Build #
3498 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3498/
Java: 64bit/jdk1.7.0_45 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
REGRESSION: org.apache.solr.cloud.DeleteInactiveReplicaTest.testDistribSearch
Error Message:
No live SolrServers available to handle this request:[http://127.0.0.1:64039, http://127.0.0.1:64027]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:64039, http://127.0.0.1:64027]
at __randomizedtesting.SeedInfo.seed([93C71E419DC7642B:12219059EA980417]:0)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:354)
at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:640)
at org.apache.solr.cloud.DeleteReplicaTest.removeAndWaitForReplicaGone(DeleteReplicaTest.java:138)
at org.apache.solr.cloud.DeleteInactiveReplicaTest.deleteInactiveReplicaTest(DeleteInactiveReplicaTest.java:120)
at org.apache.solr.cloud.DeleteInactiveReplicaTest.doTest(DeleteInactiveReplicaTest.java:43)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.GeneratedMethodAccessor60.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:744)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: deletereplica the collection time out:60s
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:491)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:197)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:285)
... 44 more
REGRESSION: org.apache.solr.cloud.ShardSplitTest.testDistribSearch
Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:64357/p_y/ov
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:64357/p_y/ov
at __randomizedtesting.SeedInfo.seed([93C71E419DC7642B:12219059EA980417]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:499)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:197)
at org.apache.solr.cloud.ShardSplitTest.splitShard(ShardSplitTest.java:537)
at org.apache.solr.cloud.ShardSplitTest.incompleteOrOverlappingCustomRangeTest(ShardSplitTest.java:147)
at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:112)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.GeneratedMethodAccessor60.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:744)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:152)
at java.net.SocketInputStream.read(SocketInputStream.java:122)
at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:166)
at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:90)
at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:281)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:92)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:61)
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:715)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:520)
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:393)
... 44 more
Build Log:
[...truncated 10974 lines...]
[junit4] Suite: org.apache.solr.cloud.DeleteInactiveReplicaTest
[junit4] 2> 2924830 T8373 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 2924834 T8373 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-DeleteInactiveReplicaTest-1385221059819
[junit4] 2> 2924838 T8373 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 2924839 T8374 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 2924950 T8373 oasc.ZkTestServer.run start zk server on port:64020
[junit4] 2> 2924952 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2924960 T8380 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@25918a1f name:ZooKeeperConnection Watcher:127.0.0.1:64020 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2924960 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2924961 T8373 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2924967 T8375 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x142859bf4fc0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 2924970 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2924973 T8382 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e39a236 name:ZooKeeperConnection Watcher:127.0.0.1:64020/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2924973 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2924974 T8373 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 2924979 T8373 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 2924985 T8373 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 2924989 T8373 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 2924995 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2924995 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2925003 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2925004 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 2925011 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2925012 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2925016 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2925016 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2925024 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2925024 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 2925031 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2925031 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 2925036 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2925036 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 2925043 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2925044 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2925049 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2925049 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2925056 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2925057 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2925063 T8373 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2925064 T8373 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2925072 T8375 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x142859bf4fc0001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 2925624 T8373 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2925629 T8373 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64027
[junit4] 2> 2925630 T8373 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2925630 T8373 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2925630 T8373 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058
[junit4] 2> 2925630 T8373 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\'
[junit4] 2> 2925665 T8373 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\solr.xml
[junit4] 2> 2925784 T8373 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 2925784 T8373 oasc.CoreContainer.<init> New CoreContainer 1891592466
[junit4] 2> 2925785 T8373 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\]
[junit4] 2> 2925786 T8373 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2925787 T8373 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2925787 T8373 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2925787 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2925788 T8373 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2925788 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2925788 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2925788 T8373 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2925789 T8373 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2925793 T8373 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2925794 T8373 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2925794 T8373 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2925794 T8373 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64020/solr
[junit4] 2> 2925794 T8373 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2925797 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2925801 T8393 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5de780f8 name:ZooKeeperConnection Watcher:127.0.0.1:64020 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2925803 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2925808 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2925811 T8395 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b4b3844 name:ZooKeeperConnection Watcher:127.0.0.1:64020/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2925812 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2925816 T8373 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 2925822 T8373 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 2925828 T8373 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2925831 T8373 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64027_
[junit4] 2> 2925832 T8373 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64027_
[junit4] 2> 2925836 T8373 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 2925845 T8373 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 2925849 T8373 oasc.Overseer.start Overseer (id=90781847377412099-127.0.0.1:64027_-n_0000000000) starting
[junit4] 2> 2925855 T8373 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 2925861 T8397 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 2925864 T8373 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 2925868 T8373 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 2925871 T8373 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2925877 T8396 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 2925882 T8373 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058
[junit4] 2> 2926132 T8373 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\conf\
[junit4] 2> 2926144 T8373 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2926145 T8373 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2926145 T8373 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2926151 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2926156 T8400 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3f44e4e9 name:ZooKeeperConnection Watcher:127.0.0.1:64020/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2926157 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2926158 T8373 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2926162 T8373 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2926686 T8373 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2926690 T8373 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64039
[junit4] 2> 2926691 T8373 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2926691 T8373 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2926691 T8373 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty1-1385221061144
[junit4] 2> 2926692 T8373 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty1-1385221061144\'
[junit4] 2> 2926722 T8373 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty1-1385221061144\solr.xml
[junit4] 2> 2926807 T8373 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 2926808 T8373 oasc.CoreContainer.<init> New CoreContainer 555187334
[junit4] 2> 2926808 T8373 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty1-1385221061144\]
[junit4] 2> 2926810 T8373 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2926810 T8373 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2926810 T8373 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2926811 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2926811 T8373 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2926811 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2926812 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2926812 T8373 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2926812 T8373 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2926818 T8373 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2926818 T8373 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2926818 T8373 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2926819 T8373 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64020/solr
[junit4] 2> 2926819 T8373 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2926821 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2926833 T8411 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@315daf3e name:ZooKeeperConnection Watcher:127.0.0.1:64020 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2926834 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2926841 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2926844 T8413 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13c70843 name:ZooKeeperConnection Watcher:127.0.0.1:64020/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2926845 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2926853 T8373 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2927974 T8373 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64039_
[junit4] 2> 2927978 T8373 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64039_
[junit4] 2> 2927986 T8400 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2927988 T8413 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2927988 T8395 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2928014 T8373 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty1-1385221061144
[junit4] 2> 2928283 T8373 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty1-1385221061144\conf\
[junit4] 2> 2928299 T8373 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2928300 T8373 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2928300 T8373 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2928822 T8373 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2928827 T8373 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64048
[junit4] 2> 2928827 T8373 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2928827 T8373 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2928827 T8373 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287
[junit4] 2> 2928827 T8373 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\'
[junit4] 2> 2928860 T8373 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\solr.xml
[junit4] 2> 2928958 T8373 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 2928959 T8373 oasc.CoreContainer.<init> New CoreContainer 1847607940
[junit4] 2> 2928959 T8373 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\]
[junit4] 2> 2928961 T8373 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2928961 T8373 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2928962 T8373 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2928962 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2928962 T8373 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2928962 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2928963 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2928963 T8373 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2928963 T8373 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2928969 T8373 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2928969 T8373 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2928969 T8373 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2928970 T8373 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64020/solr
[junit4] 2> 2928970 T8373 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2928972 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2928981 T8425 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@659b4a9c name:ZooKeeperConnection Watcher:127.0.0.1:64020 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2928982 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2928987 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2928991 T8427 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2bc872ab name:ZooKeeperConnection Watcher:127.0.0.1:64020/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2928992 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2928999 T8373 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2930120 T8373 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64048_
[junit4] 2> 2930122 T8373 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64048_
[junit4] 2> 2930129 T8427 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2930129 T8413 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2930129 T8395 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2930130 T8400 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2930139 T8373 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287
[junit4] 2> 2930382 T8373 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\conf\
[junit4] 2> 2930395 T8373 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2930396 T8373 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2930396 T8373 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2930928 T8373 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2930934 T8373 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64057
[junit4] 2> 2930935 T8373 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2930935 T8373 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2930936 T8373 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382
[junit4] 2> 2930936 T8373 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\'
[junit4] 2> 2930966 T8373 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\solr.xml
[junit4] 2> 2931050 T8373 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 2931051 T8373 oasc.CoreContainer.<init> New CoreContainer 517402658
[junit4] 2> 2931051 T8373 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\]
[junit4] 2> 2931053 T8373 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2931053 T8373 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2931053 T8373 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2931055 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2931055 T8373 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2931055 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2931055 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2931056 T8373 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2931056 T8373 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2931061 T8373 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2931061 T8373 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2931062 T8373 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2931062 T8373 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64020/solr
[junit4] 2> 2931062 T8373 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2931064 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2931073 T8439 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d23d9dc name:ZooKeeperConnection Watcher:127.0.0.1:64020 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2931074 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2931076 T8375 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x142859bf4fc0009, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 2931080 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2931083 T8441 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@709605d9 name:ZooKeeperConnection Watcher:127.0.0.1:64020/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2931084 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2931089 T8373 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2932211 T8373 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64057_
[junit4] 2> 2932214 T8373 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64057_
[junit4] 2> 2932219 T8400 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2932219 T8441 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2932220 T8427 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2932220 T8395 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2932220 T8413 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2932231 T8373 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382
[junit4] 2> 2932462 T8373 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\conf\
[junit4] 2> 2932472 T8373 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2932475 T8373 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2932475 T8373 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2933003 T8373 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2933007 T8373 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64066
[junit4] 2> 2933008 T8373 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2933008 T8373 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2933009 T8373 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty4-1385221067462
[junit4] 2> 2933009 T8373 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty4-1385221067462\'
[junit4] 2> 2933043 T8373 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty4-1385221067462\solr.xml
[junit4] 2> 2933148 T8373 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 2933149 T8373 oasc.CoreContainer.<init> New CoreContainer 1603841115
[junit4] 2> 2933149 T8373 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty4-1385221067462\]
[junit4] 2> 2933150 T8373 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2933151 T8373 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2933151 T8373 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2933151 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2933151 T8373 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2933152 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2933152 T8373 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2933152 T8373 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2933153 T8373 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2933157 T8373 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2933159 T8373 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2933159 T8373 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2933159 T8373 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64020/solr
[junit4] 2> 2933159 T8373 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2933162 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2933168 T8453 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@397febc5 name:ZooKeeperConnection Watcher:127.0.0.1:64020 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2933169 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2933175 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2933178 T8455 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e1b9947 name:ZooKeeperConnection Watcher:127.0.0.1:64020/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2933180 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2933196 T8373 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2934207 T8373 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64066_
[junit4] 2> 2934209 T8373 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64066_
[junit4] 2> 2934215 T8455 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2934215 T8413 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2934216 T8441 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2934216 T8427 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2934216 T8395 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2934217 T8400 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2934226 T8373 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty4-1385221067462
[junit4] 2> 2934436 T8373 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty4-1385221067462\conf\
[junit4] 2> 2934448 T8373 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2934450 T8373 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2934450 T8373 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2934456 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2934459 T8458 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e780837 name:ZooKeeperConnection Watcher:127.0.0.1:64020/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2934459 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2934461 T8373 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2934465 T8373 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2934469 T8460 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@35f755fa name:ZooKeeperConnection Watcher:127.0.0.1:64020/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2934469 T8373 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2934471 T8373 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2934476 T8434 oasha.CollectionsHandler.handleCreateAction Creating Collection : numShards=2&maxShardsPerNode=2&name=delDeadColl&replicationFactor=2&action=CREATE&wt=javabin&version=2
[junit4] 2> 2934478 T8395 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2934480 T8395 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2934480 T8397 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"delDeadColl",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"2"}
[junit4] 2> 2934480 T8397 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"delDeadColl",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"2"}
[junit4] 2> 2934485 T8395 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2934487 T8396 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2934488 T8396 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: delDeadColl
[junit4] 2> 2934488 T8396 oasc.Overseer$ClusterStateUpdater.createCollection Create collection delDeadColl with shards [shard1, shard2]
[junit4] 2> 2934492 T8395 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2934494 T8458 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934494 T8460 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934494 T8400 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934495 T8413 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934496 T8441 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934496 T8455 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934497 T8427 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934497 T8395 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934586 T8397 oasc.OverseerCollectionProcessor.createCollection going to create cores replicas shardNames [shard1, shard2] , repFactor : 2
[junit4] 2> 2934586 T8397 oasc.OverseerCollectionProcessor.createCollection Creating shard delDeadColl_shard1_replica1 as part of slice shard1 of collection delDeadColl on 127.0.0.1:64027_
[junit4] 2> 2934587 T8397 oasc.OverseerCollectionProcessor.createCollection Creating shard delDeadColl_shard1_replica2 as part of slice shard1 of collection delDeadColl on 127.0.0.1:64048_
[junit4] 2> 2934587 T8397 oasc.OverseerCollectionProcessor.createCollection Creating shard delDeadColl_shard2_replica1 as part of slice shard2 of collection delDeadColl on 127.0.0.1:64057_
[junit4] 2> 2934589 T8397 oasc.OverseerCollectionProcessor.createCollection Creating shard delDeadColl_shard2_replica2 as part of slice shard2 of collection delDeadColl on 127.0.0.1:64066_
[junit4] 2> 2934595 T8435 oasc.ZkController.publish publishing core=delDeadColl_shard2_replica1 state=down
[junit4] 2> 2934595 T8388 oasc.ZkController.publish publishing core=delDeadColl_shard1_replica1 state=down
[junit4] 2> 2934596 T8449 oasc.ZkController.publish publishing core=delDeadColl_shard2_replica2 state=down
[junit4] 2> 2934598 T8419 oasc.ZkController.publish publishing core=delDeadColl_shard1_replica2 state=down
[junit4] 2> 2934599 T8435 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2934599 T8388 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2934599 T8395 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2934599 T8449 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2934601 T8419 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2934603 T8396 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2934604 T8396 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64057",
[junit4] 2> "core":"delDeadColl_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64057_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"delDeadColl",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2934609 T8395 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2934610 T8396 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64027",
[junit4] 2> "core":"delDeadColl_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64027_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"delDeadColl",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2934614 T8395 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2934615 T8396 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64066",
[junit4] 2> "core":"delDeadColl_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64066_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"delDeadColl",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2934620 T8395 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2934621 T8396 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64048",
[junit4] 2> "core":"delDeadColl_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64048_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"delDeadColl",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2934624 T8395 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2934729 T8458 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934729 T8413 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934730 T8441 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934729 T8460 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934730 T8455 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934729 T8400 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934731 T8427 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2934732 T8395 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2935604 T8435 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delDeadColl_shard2_replica1
[junit4] 2> 2935604 T8388 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delDeadColl_shard1_replica1
[junit4] 2> 2935606 T8449 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delDeadColl_shard2_replica2
[junit4] 2> 2935606 T8388 oasc.CoreContainer.create Creating SolrCore 'delDeadColl_shard1_replica1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\delDeadColl_shard1_replica1
[junit4] 2> 2935606 T8388 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delDeadColl
[junit4] 2> 2935607 T8419 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delDeadColl_shard1_replica2
[junit4] 2> 2935606 T8449 oasc.CoreContainer.create Creating SolrCore 'delDeadColl_shard2_replica2' using instanceDir: .\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty4-1385221067462\delDeadColl_shard2_replica2
[junit4] 2> 2935608 T8449 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delDeadColl
[junit4] 2> 2935609 T8388 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:delDeadColl
[junit4] 2> 2935609 T8435 oasc.CoreContainer.create Creating SolrCore 'delDeadColl_shard2_replica1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\delDeadColl_shard2_replica1
[junit4] 2> 2935609 T8435 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delDeadColl
[junit4] 2> 2935609 T8388 oasc.ZkController.getConfName Looking for collection configName
[junit4] 2> 2935610 T8449 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:delDeadColl
[junit4] 2> 2935610 T8449 oasc.ZkController.getConfName Looking for collection configName
[junit4] 2> 2935613 T8435 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:delDeadColl
[junit4] 2> 2935613 T8435 oasc.ZkController.getConfName Looking for collection configName
[junit4] 2> 2935613 T8388 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4] 2> 2935613 T8388 oascc.SolrZkClient.makePath makePath: /collections/delDeadColl
[junit4] 2> 2935614 T8449 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4] 2> 2935614 T8449 oascc.SolrZkClient.makePath makePath: /collections/delDeadColl
[junit4] 2> 2935613 T8419 oasc.CoreContainer.create Creating SolrCore 'delDeadColl_shard1_replica2' using instanceDir: .\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\delDeadColl_shard1_replica2
[junit4] 2> 2935615 T8419 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delDeadColl
[junit4] 2> 2935616 T8435 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4] 2> 2935617 T8435 oascc.SolrZkClient.makePath makePath: /collections/delDeadColl
[junit4] 2> 2935617 T8419 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:delDeadColl
[junit4] 2> 2935619 T8419 oasc.ZkController.getConfName Looking for collection configName
[junit4] 2> 2935619 T8449 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delDeadColl
[junit4] 2> 2935619 T8388 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delDeadColl
[junit4] 2> 2935620 T8449 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty4-1385221067462\delDeadColl_shard2_replica2\'
[junit4] 2> 2935622 T8388 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\delDeadColl_shard1_replica1\'
[junit4] 2> 2935624 T8435 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delDeadColl
[junit4] 2> 2935624 T8419 oascc.SolrZkClient.makePath makePath: /collections/delDeadColl
[junit4] 2> 2935627 T8435 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\delDeadColl_shard2_replica1\'
[junit4] 2> 2935644 T8419 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delDeadColl
[junit4] 2> 2935656 T8419 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\delDeadColl_shard1_replica2\'
[junit4] 2> 2935746 T8388 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2935753 T8419 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2935811 T8388 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2935848 T8435 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2935863 T8449 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2935925 T8435 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2935935 T8449 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2935939 T8388 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2935941 T8419 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2935943 T8435 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2935959 T8449 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2935965 T8435 oass.IndexSchema.readSchema [delDeadColl_shard2_replica1] Schema name=test
[junit4] 2> 2935963 T8388 oass.IndexSchema.readSchema [delDeadColl_shard1_replica1] Schema name=test
[junit4] 2> 2935973 T8419 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2936038 T8419 oass.IndexSchema.readSchema [delDeadColl_shard1_replica2] Schema name=test
[junit4] 2> 2935975 T8449 oass.IndexSchema.readSchema [delDeadColl_shard2_replica2] Schema name=test
[junit4] 2> 2937114 T8435 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2937124 T8435 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2937125 T8388 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2937128 T8435 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2937133 T8388 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2937137 T8388 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2937407 T8449 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2937413 T8419 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2937419 T8449 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2937420 T8419 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2937424 T8419 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2937424 T8449 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2937447 T8388 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2937449 T8435 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2937455 T8419 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2937456 T8388 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2937459 T8435 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2937461 T8419 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2937466 T8388 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2937467 T8435 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2937470 T8388 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2937470 T8388 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2937470 T8388 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2937471 T8419 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2937472 T8435 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2937473 T8435 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2937473 T8435 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2937474 T8419 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2937474 T8388 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2937475 T8388 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2937474 T8419 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2937475 T8419 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2937475 T8388 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2937477 T8388 oasc.SolrCore.<init> [delDeadColl_shard1_replica1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\delDeadColl_shard1_replica1\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\delDeadColl_shard1_replica1\data\
[junit4] 2> 2937477 T8388 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@29ad33e9
[junit4] 2> 2937478 T8435 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2937479 T8419 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2937479 T8419 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2937479 T8449 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2937479 T8435 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2937479 T8388 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\delDeadColl_shard1_replica1\data\
[junit4] 2> 2937479 T8419 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2937481 T8419 oasc.SolrCore.<init> [delDeadColl_shard1_replica2] Opening new SolrCore at .\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\delDeadColl_shard1_replica2\, dataDir=.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\delDeadColl_shard1_replica2\data\
[junit4] 2> 2937481 T8419 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@29ad33e9
[junit4] 2> 2937482 T8419 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\delDeadColl_shard1_replica2\data\
[junit4] 2> 2937482 T8419 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\delDeadColl_shard1_replica2\data\index/
[junit4] 2> 2937482 T8419 oasc.SolrCore.initIndex WARN [delDeadColl_shard1_replica2] Solr index directory '.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\delDeadColl_shard1_replica2\data\index' doesn't exist. Creating new index...
[junit4] 2> 2937483 T8419 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty2-1385221063287\delDeadColl_shard1_replica2\data\index
[junit4] 2> 2937483 T8419 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2937484 T8419 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7f480edb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6cc96bad),segFN=segments_1,generation=1}
[junit4] 2> 2937484 T8419 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2937485 T8449 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2937481 T8388 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\delDeadColl_shard1_replica1\data\index/
[junit4] 2> 2937485 T8388 oasc.SolrCore.initIndex WARN [delDeadColl_shard1_replica1] Solr index directory '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\delDeadColl_shard1_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2937487 T8388 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-controljetty-1385221060058\delDeadColl_shard1_replica1\data\index
[junit4] 2> 2937487 T8388 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2937488 T8388 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4f4dea55 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@312bfa49),segFN=segments_1,generation=1}
[junit4] 2> 2937488 T8388 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2937480 T8435 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2937488 T8419 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2937489 T8419 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2937490 T8419 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2937490 T8419 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2937490 T8419 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2937490 T8419 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2937491 T8419 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2937491 T8419 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2937492 T8419 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2937492 T8388 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2937492 T8419 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2937492 T8435 oasc.SolrCore.<init> [delDeadColl_shard2_replica1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\delDeadColl_shard2_replica1\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\delDeadColl_shard2_replica1\data\
[junit4] 2> 2937493 T8419 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2937493 T8419 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2937492 T8388 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2937493 T8419 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2937494 T8419 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2937495 T8419 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2937495 T8419 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2937493 T8435 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@29ad33e9
[junit4] 2> 2937494 T8388 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2937498 T8388 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2937498 T8388 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2937498 T8388 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2937498 T8449 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2937499 T8388 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2937500 T8388 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2937500 T8388 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2937500 T8388 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2937501 T8388 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2937501 T8388 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2937501 T8388 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2937502 T8388 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2937502 T8388 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2937503 T8388 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2937505 T8435 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\delDeadColl_shard2_replica1\data\
[junit4] 2> 2937506 T8435 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\delDeadColl_shard2_replica1\data\index/
[junit4] 2> 2937506 T8435 oasc.SolrCore.initIndex WARN [delDeadColl_shard2_replica1] Solr index directory '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\delDeadColl_shard2_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2937507 T8435 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.DeleteInactiveReplicaTest-jetty3-1385221065382\delDeadColl_shard2_replica1\data\index
[junit4] 2> 2937507 T8435 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2937508 T8419 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2937508 T8435 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@5f748273 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@59bd32ca),segFN=segments_1,generation=1}
[junit4] 2> 2937508 T8435 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2937510 T8449 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2937511 T8449 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2937511 T8449 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2937511 T8435 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2937513 T8435 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2937513 T8435 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2937513 T8435 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2937514 T8435 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2937514 T8435 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2937514 T8435 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2937514 T8419 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2937515 T8419 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2937515 T8388 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2937516 T8419 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=19, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4506938815374656]
[junit4] 2> 2937515 T8435 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2937517 T8435 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2937517 T8435 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2937518 T8435 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2937518 T8435 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2937518 T8435 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2937519 T8435 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2937519 T8435 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2937520 T8435 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2937520 T8388 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2937520 T8388 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2937522 T8435 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2937523 T8449 oass.OpenExchangeRatesOrgProvider$Ope
[...truncated too long message...]
onseHeader(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:715)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:520)
[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:393)
[junit4] > ... 44 more
[junit4] 2> 3521713 T8719 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene46: {}, docValues:{}, sim=DefaultSimilarity, locale=es_SV, timezone=Africa/Ouagadougou
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_45 (64-bit)/cpus=2,threads=2,free=149472560,total=319717376
[junit4] 2> NOTE: All tests run in this JVM: [ExternalFileFieldSortTest, SampleTest, TestLMJelinekMercerSimilarityFactory, TestFiltering, HardAutoCommitTest, TestRecovery, TestStressRecovery, DeleteShardTest, UUIDFieldTest, BasicDistributedZkTest, TestQuerySenderListener, TestPhraseSuggestions, TestCollapseQParserPlugin, TestNoOpRegenerator, TestBinaryResponseWriter, TestDefaultSearchFieldResource, SpellCheckComponentTest, TestInfoStreamLogging, SimplePostToolTest, DocValuesMissingTest, ParsingFieldUpdateProcessorsTest, TestUpdate, SortByFunctionTest, FieldFacetTest, SuggestComponentTest, FunctionTest, BadComponentTest, SolrXmlInZkTest, TestSchemaVersionResource, TestCopyFieldCollectionResource, MigrateRouteKeyTest, QueryFacetTest, SynonymTokenizerTest, DocValuesTest, AbstractAnalyticsStatsTest, TestElisionMultitermQuery, PrimUtilsTest, TestCoreDiscovery, BlockCacheTest, TestMaxScoreQueryParser, CoreMergeIndexesAdminHandlerTest, TestSolrXmlPersistence, IndexBasedSpellCheckerTest, TestDFRSimilarityFactory, FileBasedSpellCheckerTest, DirectSolrSpellCheckerTest, TestCollationField, StandardRequestHandlerTest, ClusterStateUpdateTest, ChangedSchemaMergeTest, TestLRUCache, RangeFacetTest, TestJmxMonitoredMap, FileUtilsTest, CSVRequestHandlerTest, SuggesterTSTTest, TestUniqueKeyFieldResource, SuggesterWFSTTest, SolrRequestParserTest, TestLuceneMatchVersion, TestRemoteStreaming, DistributedDebugComponentTest, DocumentAnalysisRequestHandlerTest, JSONWriterTest, PluginInfoTest, TestHighFrequencyDictionaryFactory, TestDistributedSearch, XmlUpdateRequestHandlerTest, MoreLikeThisHandlerTest, TestReversedWildcardFilterFactory, TestFoldingMultitermQuery, TestFastWriter, TestDocBasedVersionConstraints, HighlighterConfigTest, TestXIncludeConfig, TestOmitPositions, HdfsChaosMonkeySafeLeaderTest, TestCloudManagedSchema, TestDynamicFieldResource, RAMDirectoryFactoryTest, TestIndexSearcher, TestClassNameShortening, TestWordDelimiterFilterFactory, ReturnFieldsTest, BasicFunctionalityTest, SimpleFacetsTest, DOMUtilTest, TestSolrCoreProperties, CurrencyFieldOpenExchangeTest, DeleteReplicaTest, ExpressionTest, URLClassifyProcessorTest, TestJmxIntegration, AliasIntegrationTest, TimeZoneUtilsTest, SolrPluginUtilsTest, TestTrie, NoFacetTest, BlockDirectoryTest, SpatialFilterTest, HdfsCollectionsAPIDistributedZkTest, TestModifyConfFiles, OpenExchangeRatesOrgProviderTest, PreAnalyzedFieldTest, TestWriterPerf, TestFieldCollectionResource, TestSerializedLuceneMatchVersion, AbstractAnalyticsFacetTest, OverseerTest, TestRecoveryHdfs, FastVectorHighlighterTest, HdfsLockFactoryTest, TestPerFieldSimilarity, SolrInfoMBeanTest, BasicDistributedZk2Test, TestCharFilters, TestMultiCoreConfBootstrap, TestDistribDocBasedVersion, TestFaceting, DisMaxRequestHandlerTest, ChaosMonkeySafeLeaderTest, PathHierarchyTokenizerFactoryTest, TestStressVersions, ZkSolrClientTest, MinimalSchemaTest, TestSolrXmlPersistor, TestAnalyzedSuggestions, StatelessScriptUpdateProcessorFactoryTest, ZkControllerTest, RecoveryZkTest, TestNonNRTOpen, TestRangeQuery, IndexSchemaRuntimeFieldTest, SolrTestCaseJ4Test, TestRTGBase, SystemInfoHandlerTest, SpellPossibilityIteratorTest, SolrIndexSplitterTest, TestSimpleQParserPlugin, TestBadConfig, PreAnalyzedUpdateProcessorTest, TestCSVLoader, SolrCoreCheckLockOnStartupTest, TestCloudManagedSchemaAddField, PolyFieldTest, SuggesterFSTTest, TestSolrDeletionPolicy2, FieldMutatingUpdateProcessorTest, NumericFieldsTest, TestComponentsName, WordBreakSolrSpellCheckerTest, RequiredFieldsTest, UnloadDistributedZkTest, TestFieldTypeResource, InfoHandlerTest, TestRealTimeGet, DebugComponentTest, PrimitiveFieldTypeTest, TestFunctionQuery, TestStressUserVersions, DirectUpdateHandlerTest, TestCSVResponseWriter, TestSolrDeletionPolicy1, BadCopyFieldTest, TestLFUCache, ClusterStateTest, TestSolrXMLSerializer, TestMergePolicyConfig, CacheHeaderTest, TestSchemaSimilarityResource, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, TestRandomFaceting, LeaderElectionTest, TestZkChroot, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, SliceStateUpdateTest, AutoCommitTest, TestDistributedGrouping, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestStressReorder, TestJoin, TestReload, DistributedTermsComponentTest, TestCoreContainer, TestSolr4Spatial, SolrCoreTest, StatsComponentTest, TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, PeerSyncTest, BadIndexSchemaTest, ConvertedLegacyTest, TestSort, TestLazyCores, OverseerCollectionProcessorTest, HighlighterTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, CoreAdminHandlerTest, SuggesterTest, TestStressLucene, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, DocValuesMultiTest, DistanceFunctionTest, XsltUpdateRequestHandlerTest, TestSurroundQueryParser, LukeRequestHandlerTest, TestQueryUtils, TestQueryTypes, TermsComponentTest, RequestHandlersTest, DocumentBuilderTest, TestValueSourceCache, TermVectorComponentTest, TestIndexingPerformance, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir, LoggingHandlerTest, RegexBoostProcessorTest, IndexSchemaTest, UpdateRequestProcessorFactoryTest, MBeansHandlerTest, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, PingRequestHandlerTest, SearchHandlerTest, CoreContainerCoreInitFailuresTest, SOLR749Test, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, MultiTermTest, TestDocSet, TestBinaryField, TestSearchPerf, TestConfig, OutputWriterTest, TestFuzzyAnalyzedSuggestions, TestPostingsSolrHighlighter, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestCodecSupport, EchoParamsTest, TestSweetSpotSimilarityFactory, TestNumberUtils, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, LegacyHTMLStripCharFilterTest, ResourceLoaderTest, TestFastOutputStream, ScriptEngineTest, TestFastLRUCache, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, TestSystemIdResolver, TestSuggestSpellingConverter, DateFieldTest, SpellingQueryConverterTest, TestSolrJ, TestUtils, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, CircularListTest, FieldFacetExtrasTest, AssignTest, CustomCollectionTest, DeleteInactiveReplicaTest, ShardSplitTest]
[junit4] Completed in 348.69s, 1 test, 1 error <<< FAILURES!
[...truncated 119 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:420: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:400: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:492: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1272: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:908: There were test failures: 353 suites, 1557 tests, 2 errors, 39 ignored (11 assumptions)
Total time: 88 minutes 51 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_45 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0-ea-b116) -
Build # 3499 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3499/
Java: 32bit/jdk1.8.0-ea-b116 -client -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION: org.apache.solr.analytics.NoFacetTest.stddevTest
Error Message:
Oops: (double raws) 4631318898052956160 - 4628496337733101339 < 4442235333156365461 Calculated diff 4625071700926640586 Let's see what the JVM thinks these bits are. FloatResult: 43.5 floatTest: 27.72225134241226 Diff 15.77774865758774
Stack Trace:
java.lang.AssertionError: Oops: (double raws) 4631318898052956160 - 4628496337733101339 < 4442235333156365461 Calculated diff 4625071700926640586 Let's see what the JVM thinks these bits are. FloatResult: 43.5 floatTest: 27.72225134241226 Diff 15.77774865758774
at __randomizedtesting.SeedInfo.seed([AB58247AB2E07978:AE9864CF82837D77]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.analytics.NoFacetTest.stddevTest(NoFacetTest.java:227)
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:483)
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:744)
Build Log:
[...truncated 10842 lines...]
[junit4] Suite: org.apache.solr.analytics.NoFacetTest
[junit4] 2> 1993267 T7473 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061
[junit4] 2> 1993269 T7473 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 1993271 T7473 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1993271 T7473 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1993319 T7473 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1993329 T7473 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1993332 T7473 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 1993338 T7473 oass.IndexSchema.readSchema [null] Schema name=schema-docValues
[junit4] 2> 1993353 T7473 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1993354 T7473 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1993355 T7473 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 1993356 T7473 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 1993381 T7473 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1993381 T7473 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 1993383 T7473 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 1993427 T7473 oasc.CoreContainer.<init> New CoreContainer 3810782
[junit4] 2> 1993430 T7473 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1993433 T7473 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1993433 T7473 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1993438 T7473 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1993438 T7473 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1993438 T7473 oasc.CoreContainer.load Host Name:
[junit4] 2> 1993445 T7474 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 1993446 T7474 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 1993447 T7474 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1993447 T7474 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1993488 T7474 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1993497 T7474 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1993498 T7474 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 1993505 T7474 oass.IndexSchema.readSchema [collection1] Schema name=schema-docValues
[junit4] 2> 1993519 T7474 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1993521 T7474 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1993522 T7474 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\
[junit4] 2> 1993524 T7474 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1993525 T7474 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\
[junit4] 2> 1993525 T7474 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index/
[junit4] 2> 1993526 T7474 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index' doesn't exist. Creating new index...
[junit4] 2> 1993527 T7474 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index
[junit4] 2> 1993528 T7474 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=11.67578125, floorSegmentMB=0.4619140625, forceMergeDeletesPctAllowed=2.221663527451109, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3309897509307359
[junit4] 2> 1993531 T7474 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_1,generation=1}
[junit4] 2> 1993532 T7474 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1993535 T7474 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1993536 T7474 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1993537 T7474 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1993544 T7474 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1993545 T7474 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1993545 T7474 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1993546 T7474 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=6.0595703125, floorSegmentMB=0.98828125, forceMergeDeletesPctAllowed=8.48780093132007, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8091214734897925
[junit4] 2> 1993548 T7474 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_1,generation=1}
[junit4] 2> 1993548 T7474 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1993548 T7474 oass.SolrIndexSearcher.<init> Opening Searcher@796684 main
[junit4] 2> 1993550 T7474 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1993552 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@796684 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1993554 T7473 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> ASYNC NEW_CORE C8568 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
[junit4] 2> 1993559 T7473 C8568 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1993560 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 4
[junit4] 2> 1993562 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10000]} 0 1
[junit4] 2> 1993566 T7473 C8568 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993577 T7473 C8568 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_2,generation=2}
[junit4] 2> 1993579 T7473 C8568 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1993581 T7473 C8568 oass.SolrIndexSearcher.<init> Opening Searcher@1b56e72 main
[junit4] 2> 1993582 T7473 C8568 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993582 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b56e72 main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):c1)}
[junit4] 2> 1993583 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 18
[junit4] 2> 1993586 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10001]} 0 2
[junit4] 2> 1993589 T7473 C8568 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993594 T7473 C8568 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_3,generation=3}
[junit4] 2> 1993594 T7473 C8568 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1993598 T7473 C8568 oass.SolrIndexSearcher.<init> Opening Searcher@101fb53 main
[junit4] 2> 1993598 T7473 C8568 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993598 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@101fb53 main{StandardDirectoryReader(segments_3:6:nrt _0(5.0):c1 _1(5.0):c1)}
[junit4] 2> 1993599 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
[junit4] 2> 1993602 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10002]} 0 1
[junit4] 2> ASYNC NEW_CORE C8569 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
[junit4] 2> 1993611 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993618 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_4,generation=4}
[junit4] 2> 1993618 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 1993621 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@38f65e main
[junit4] 2> 1993621 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993622 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@38f65e main{StandardDirectoryReader(segments_4:8:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1)}
[junit4] 2> 1993622 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 1993626 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10003]} 0 3
[junit4] 2> 1993628 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993632 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_5,generation=5}
[junit4] 2> 1993634 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 1993639 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@c4ba6d main
[junit4] 2> 1993639 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993640 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c4ba6d main{StandardDirectoryReader(segments_5:10:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1)}
[junit4] 2> 1993641 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
[junit4] 2> 1993649 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10004]} 0 2
[junit4] 2> 1993651 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993656 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_6,generation=6}
[junit4] 2> 1993658 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 1993660 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@6491aa main
[junit4] 2> 1993661 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993661 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6491aa main{StandardDirectoryReader(segments_6:12:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1)}
[junit4] 2> 1993662 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 1993666 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10005]} 0 1
[junit4] 2> 1993667 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993674 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_7,generation=7}
[junit4] 2> 1993674 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 1993676 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@1d3781a main
[junit4] 2> 1993677 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993677 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d3781a main{StandardDirectoryReader(segments_7:14:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1)}
[junit4] 2> 1993678 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 1993680 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10006]} 0 1
[junit4] 2> 1993684 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993690 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_8,generation=8}
[junit4] 2> 1993690 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 1993693 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@84beac main
[junit4] 2> 1993694 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993694 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@84beac main{StandardDirectoryReader(segments_8:16:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1)}
[junit4] 2> 1993695 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 1993701 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10007]} 0 5
[junit4] 2> 1993705 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993712 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_9,generation=9}
[junit4] 2> 1993715 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 1993718 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@1a7c243 main
[junit4] 2> 1993719 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993719 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a7c243 main{StandardDirectoryReader(segments_9:18:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1)}
[junit4] 2> 1993720 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 15
[junit4] 2> 1993724 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10008]} 0 4
[junit4] 2> 1993725 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993732 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_a,generation=10}
[junit4] 2> 1993733 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 1993736 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@187304f main
[junit4] 2> 1993738 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993738 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@187304f main{StandardDirectoryReader(segments_a:20:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1)}
[junit4] 2> 1993739 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 15
[junit4] 2> 1993743 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10009]} 0 4
[junit4] 2> 1993746 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993752 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_b,generation=11}
[junit4] 2> 1993755 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 1993757 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@1a325dd main
[junit4] 2> 1993758 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993760 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a325dd main{StandardDirectoryReader(segments_b:22:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1)}
[junit4] 2> 1993760 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 16
[junit4] 2> 1993763 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100010]} 0 2
[junit4] 2> 1993765 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993772 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_b,generation=11}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_c,generation=12}
[junit4] 2> 1993772 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
[junit4] 2> 1993774 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@7ee6f6 main
[junit4] 2> 1993776 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993777 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7ee6f6 main{StandardDirectoryReader(segments_c:24:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1)}
[junit4] 2> 1993779 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
[junit4] 2> 1993781 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100011]} 0 1
[junit4] 2> 1993784 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993790 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_c,generation=12}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_d,generation=13}
[junit4] 2> 1993790 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
[junit4] 2> 1993793 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@12c7cd1 main
[junit4] 2> 1993793 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993794 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12c7cd1 main{StandardDirectoryReader(segments_d:26:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1)}
[junit4] 2> 1993795 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 1993800 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100012]} 0 5
[junit4] 2> 1993802 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993809 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_d,generation=13}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_e,generation=14}
[junit4] 2> 1993811 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
[junit4] 2> 1993813 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@9b56be main
[junit4] 2> 1993814 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993814 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9b56be main{StandardDirectoryReader(segments_e:28:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1)}
[junit4] 2> 1993815 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
[junit4] 2> ASYNC NEW_CORE C8570 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
[junit4] 2> 1993823 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100013]} 0 1
[junit4] 2> 1993827 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993831 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_e,generation=14}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_f,generation=15}
[junit4] 2> 1993833 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
[junit4] 2> 1993834 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1b33b55 main
[junit4] 2> 1993836 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993837 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b33b55 main{StandardDirectoryReader(segments_f:30:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1)}
[junit4] 2> 1993837 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 1993839 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100014]} 0 0
[junit4] 2> 1993842 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993848 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_f,generation=15}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_g,generation=16}
[junit4] 2> 1993849 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
[junit4] 2> 1993852 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1651603 main
[junit4] 2> 1993853 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993853 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1651603 main{StandardDirectoryReader(segments_g:32:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1)}
[junit4] 2> 1993855 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
[junit4] 2> 1993858 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100015]} 0 1
[junit4] 2> 1993859 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993865 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_g,generation=16}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_h,generation=17}
[junit4] 2> 1993866 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 17
[junit4] 2> 1993868 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@b7058e main
[junit4] 2> 1993868 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993869 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b7058e main{StandardDirectoryReader(segments_h:34:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1)}
[junit4] 2> 1993870 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 1993874 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100016]} 0 2
[junit4] 2> 1993876 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993881 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_h,generation=17}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_i,generation=18}
[junit4] 2> 1993884 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 18
[junit4] 2> 1993885 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1b64ad1 main
[junit4] 2> 1993886 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993886 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b64ad1 main{StandardDirectoryReader(segments_i:36:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1)}
[junit4] 2> 1993888 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 1993890 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100017]} 0 1
[junit4] 2> 1993893 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993898 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_i,generation=18}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_j,generation=19}
[junit4] 2> 1993899 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 19
[junit4] 2> 1993900 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@2629e9 main
[junit4] 2> 1993903 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993903 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2629e9 main{StandardDirectoryReader(segments_j:38:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1)}
[junit4] 2> 1993904 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 1993906 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100018]} 0 1
[junit4] 2> 1993908 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993915 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_j,generation=19}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_k,generation=20}
[junit4] 2> 1993915 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 20
[junit4] 2> 1993917 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1ede14c main
[junit4] 2> 1993918 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993918 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ede14c main{StandardDirectoryReader(segments_k:40:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1)}
[junit4] 2> 1993919 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 1993923 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100019]} 0 1
[junit4] 2> 1993924 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993930 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_k,generation=20}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_l,generation=21}
[junit4] 2> 1993932 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 21
[junit4] 2> 1993934 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1bfb387 main
[junit4] 2> 1993935 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993935 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1bfb387 main{StandardDirectoryReader(segments_l:42:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1)}
[junit4] 2> 1993936 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 1993938 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100020]} 0 1
[junit4] 2> 1993941 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993947 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_l,generation=21}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_m,generation=22}
[junit4] 2> 1993947 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 22
[junit4] 2> 1993949 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1deb182 main
[junit4] 2> 1993949 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993952 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1deb182 main{StandardDirectoryReader(segments_m:44:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1)}
[junit4] 2> 1993952 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 1993955 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100021]} 0 1
[junit4] 2> 1993957 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993962 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_m,generation=22}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_n,generation=23}
[junit4] 2> 1993962 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 23
[junit4] 2> 1993965 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@13d8dda main
[junit4] 2> 1993965 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993966 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13d8dda main{StandardDirectoryReader(segments_n:46:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1)}
[junit4] 2> 1993967 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 1993968 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100022]} 0 1
[junit4] 2> 1993972 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993977 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_n,generation=23}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_o,generation=24}
[junit4] 2> 1993979 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 24
[junit4] 2> 1993980 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1c622d2 main
[junit4] 2> 1993982 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1993983 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c622d2 main{StandardDirectoryReader(segments_o:48:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1)}
[junit4] 2> 1993984 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4] 2> ASYNC NEW_CORE C8571 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
[junit4] 2> 1993995 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100023]} 0 10
[junit4] 2> 1993998 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994002 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_o,generation=24}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_p,generation=25}
[junit4] 2> 1994004 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 25
[junit4] 2> 1994005 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@c9033e main
[junit4] 2> 1994005 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994008 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c9033e main{StandardDirectoryReader(segments_p:50:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1)}
[junit4] 2> 1994009 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 1994011 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100024]} 0 1
[junit4] 2> 1994013 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994023 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_p,generation=25}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_q,generation=26}
[junit4] 2> 1994023 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 26
[junit4] 2> 1994028 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@14e312b main
[junit4] 2> 1994028 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994028 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14e312b main{StandardDirectoryReader(segments_q:52:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1)}
[junit4] 2> 1994029 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 17
[junit4] 2> 1994030 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100025]} 0 1
[junit4] 2> 1994033 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994038 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_q,generation=26}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_r,generation=27}
[junit4] 2> 1994039 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 27
[junit4] 2> 1994041 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@146b134 main
[junit4] 2> 1994041 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994042 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@146b134 main{StandardDirectoryReader(segments_r:54:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1)}
[junit4] 2> 1994043 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
[junit4] 2> 1994048 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100026]} 0 2
[junit4] 2> 1994050 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994059 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_r,generation=27}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_s,generation=28}
[junit4] 2> 1994060 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 28
[junit4] 2> 1994062 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@ddda20 main
[junit4] 2> 1994062 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994063 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ddda20 main{StandardDirectoryReader(segments_s:56:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1)}
[junit4] 2> 1994063 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4] 2> 1994067 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100027]} 0 2
[junit4] 2> 1994070 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994079 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_s,generation=28}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_t,generation=29}
[junit4] 2> 1994079 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 29
[junit4] 2> 1994079 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@15bb2ba main
[junit4] 2> 1994082 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994082 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15bb2ba main{StandardDirectoryReader(segments_t:58:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1)}
[junit4] 2> 1994084 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 16
[junit4] 2> 1994085 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100028]} 0 1
[junit4] 2> 1994088 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994094 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_t,generation=29}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_u,generation=30}
[junit4] 2> 1994095 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 30
[junit4] 2> 1994096 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@1737862 main
[junit4] 2> 1994098 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1737862 main{StandardDirectoryReader(segments_u:60:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1)}
[junit4] 2> 1994099 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994099 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 1994101 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100029]} 0 0
[junit4] 2> 1994105 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994113 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_u,generation=30}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_v,generation=31}
[junit4] 2> 1994114 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 31
[junit4] 2> 1994115 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@1380011 main
[junit4] 2> 1994116 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1380011 main{StandardDirectoryReader(segments_v:62:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1)}
[junit4] 2> 1994117 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994117 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4] 2> 1994120 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100030]} 0 2
[junit4] 2> 1994125 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994137 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_v,generation=31}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_w,generation=32}
[junit4] 2> 1994138 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 32
[junit4] 2> 1994143 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@51e92d main
[junit4] 2> 1994144 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994144 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@51e92d main{StandardDirectoryReader(segments_w:64:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1)}
[junit4] 2> 1994145 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 22
[junit4] 2> 1994151 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100031]} 0 3
[junit4] 2> 1994153 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994164 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_w,generation=32}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_x,generation=33}
[junit4] 2> 1994165 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 33
[junit4] 2> 1994168 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@d4fac3 main
[junit4] 2> 1994170 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994170 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d4fac3 main{StandardDirectoryReader(segments_x:66:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1)}
[junit4] 2> 1994171 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 18
[junit4] 2> 1994173 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100032]} 0 1
[junit4] 2> 1994176 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100033]} 0 1
[junit4] 2> 1994177 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994186 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_x,generation=33}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_y,generation=34}
[junit4] 2> 1994186 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 34
[junit4] 2> ASYNC NEW_CORE C8572 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
[junit4] 2> 1994196 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@90b6e9 main
[junit4] 2> 1994196 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994199 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@90b6e9 main{StandardDirectoryReader(segments_y:68:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2)}
[junit4] 2> 1994200 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 23
[junit4] 2> 1994202 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100034]} 0 2
[junit4] 2> 1994204 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994210 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_y,generation=34}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_z,generation=35}
[junit4] 2> 1994212 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 35
[junit4] 2> 1994212 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@3818db main
[junit4] 2> 1994213 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994215 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3818db main{StandardDirectoryReader(segments_z:70:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1)}
[junit4] 2> 1994215 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 1994220 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100035]} 0 2
[junit4] 2> 1994222 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994234 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_z,generation=35}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_10,generation=36}
[junit4] 2> 1994235 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 36
[junit4] 2> 1994238 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@161ee23 main
[junit4] 2> 1994238 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994239 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@161ee23 main{StandardDirectoryReader(segments_10:72:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1)}
[junit4] 2> 1994240 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 18
[junit4] 2> 1994243 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100036]} 0 1
[junit4] 2> 1994246 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994252 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_10,generation=36}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_11,generation=37}
[junit4] 2> 1994253 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 37
[junit4] 2> 1994256 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@729f2e main
[junit4] 2> 1994256 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994257 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@729f2e main{StandardDirectoryReader(segments_11:74:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1)}
[junit4] 2> 1994258 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 1994260 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100037]} 0 1
[junit4] 2> 1994263 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994275 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_11,generation=37}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_12,generation=38}
[junit4] 2> 1994276 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 38
[junit4] 2> 1994279 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@12d462c main
[junit4] 2> 1994281 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994281 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12d462c main{StandardDirectoryReader(segments_12:76:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1)}
[junit4] 2> 1994282 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 20
[junit4] 2> 1994286 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100038]} 0 1
[junit4] 2> 1994289 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994296 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_12,generation=38}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_13,generation=39}
[junit4] 2> 1994297 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 39
[junit4] 2> 1994298 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@f95afc main
[junit4] 2> 1994300 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994300 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f95afc main{StandardDirectoryReader(segments_13:78:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1)}
[junit4] 2> 1994301 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 1994304 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100039]} 0 2
[junit4] 2> 1994306 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994315 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_13,generation=39}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_14,generation=40}
[junit4] 2> 1994315 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 40
[junit4] 2> 1994317 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@15f6252 main
[junit4] 2> 1994318 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994318 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15f6252 main{StandardDirectoryReader(segments_14:80:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1)}
[junit4] 2> 1994319 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4] 2> 1994326 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100040]} 0 5
[junit4] 2> 1994332 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994338 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_14,generation=40}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_15,generation=41}
[junit4] 2> 1994338 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 41
[junit4] 2> 1994342 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@2c6f20 main
[junit4] 2> 1994343 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994344 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2c6f20 main{StandardDirectoryReader(segments_15:82:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1 _13(5.0):c1)}
[junit4] 2> 1994344 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
[junit4] 2> 1994347 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100041]} 0 2
[junit4] 2> 1994349 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994357 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_15,generation=41}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_16,generation=42}
[junit4] 2> 1994357 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 42
[junit4] 2> 1994359 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@f38125 main
[junit4] 2> 1994359 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994361 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f38125 main{StandardDirectoryReader(segments_16:84:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1 _13(5.0):c1 _14(5.0):c1)}
[junit4] 2> 1994361 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4] 2> 1994363 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100042]} 0 0
[junit4] 2> 1994365 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994374 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_16,generation=42}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_17,generation=43}
[junit4] 2> 1994374 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 43
[junit4] 2> 1994376 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@19956ad main
[junit4] 2> 1994376 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994377 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19956ad main{StandardDirectoryReader(segments_17:86:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1 _13(5.0):c1 _14(5.0):c1 _15(5.0):c1)}
[junit4] 2> 1994378 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
[junit4] 2> 1994381 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100043]} 0 3
[junit4] 2> ASYNC NEW_CORE C8573 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
[junit4] 2> 1994393 T7473 C8573 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994401 T7473 C8573 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_17,generation=43}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_18,generation=44}
[junit4] 2> 1994401 T7473 C8573 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 44
[junit4] 2> 1994403 T7473 C8573 oass.SolrIndexSearcher.<init> Opening Searcher@11da4da main
[junit4] 2> 1994403 T7473 C8573 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994406 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11da4da main{StandardDirectoryReader(segments_18:88:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1 _13(5.0):c1 _14(5.0):c1 _15(5.0):c1 _16(5.0):c1)}
[junit4] 2> 1994406 T7473 C8573 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4] 2> 1994410 T7473 C8573 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100044]} 0 1
[junit4] 2> 1994411 T7473 C8573 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994419 T7473 C8573 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_18,generation=44}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_19,generation=45}
[junit4] 2> 1994420 T7473 C8573 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 45
[junit4] 2> 1994422 T7473 C8573 oass.SolrIndexSearcher.<init> Opening Searcher@1e3ac83 main
[junit4] 2> 1994422 T7473 C8573 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1994423 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e3ac83 main{StandardDirectoryReader(segments_19:90:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1 _13(5.0):c1 _14(5.0):c1 _15(5.0):c1 _16(5.0):c1 _17(5.0):c1)}
[junit4] 2> 1994423 T7473 C8573 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 1994425 T7473 C8573 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100045]} 0 1
[junit4] 2> 1994428 T7473 C8573 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1994443 T7473 C8573 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.
[...truncated too long message...]
workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index;done=false>>]
[junit4] 2> 1995662 T7473 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index
[junit4] 2> NOTE: test params are: codec=Lucene46: {double_d=MockFixedIntBlock(blockSize=1705), float_f=MockVariableIntBlock(baseBlockSize=18), int_i=MockVariableIntBlock(baseBlockSize=18), long_l=PostingsFormat(name=MockSep), long_ld=PostingsFormat(name=MockSep), date_dtd=PostingsFormat(name=MockSep), string_sd=MockVariableIntBlock(baseBlockSize=18), int_id=MockVariableIntBlock(baseBlockSize=18), id=PostingsFormat(name=MockSep), double_dd=MockFixedIntBlock(blockSize=1705), float_fd=MockVariableIntBlock(baseBlockSize=18)}, docValues:{date_dtd=DocValuesFormat(name=Disk), doubledv=DocValuesFormat(name=Disk), string_sd=DocValuesFormat(name=Memory), float_fd=DocValuesFormat(name=Memory), datedv=DocValuesFormat(name=Lucene45), long_ld=DocValuesFormat(name=Disk), longdv=DocValuesFormat(name=SimpleText), intdv=DocValuesFormat(name=Memory), int_id=DocValuesFormat(name=Memory), stringdvm=DocValuesFormat(name=SimpleText), double_dd=DocValuesFormat(name=SimpleText), stringdv=DocValuesFormat(name=Disk), floatdv=DocValuesFormat(name=SimpleText)}, sim=DefaultSimilarity, locale=en_SG, timezone=America/St_Thomas
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=1,free=115492032,total=276701184
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrCoreProperties, SchemaVersionSpecificBehaviorTest, TestCopyFieldCollectionResource, TestSweetSpotSimilarityFactory, TestCollationField, DocValuesMultiTest, TestFunctionQuery, AnalysisAfterCoreReloadTest, SpellPossibilityIteratorTest, ZkSolrClientTest, CoreMergeIndexesAdminHandlerTest, TestBM25SimilarityFactory, TestModifyConfFiles, TestLMJelinekMercerSimilarityFactory, TestFieldResource, HdfsUnloadDistributedZkTest, TestRealTimeGet, SearchHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, MultiTermTest, OutputWriterTest, TestSolrXmlPersistor, PluginInfoTest, StressHdfsTest, EnumFieldTest, BasicFunctionalityTest, LukeRequestHandlerTest, FullSolrCloudDistribCmdsTest, TestPartialUpdateDeduplication, DeleteShardTest, TestCSVLoader, SuggesterFSTTest, DeleteInactiveReplicaTest, HighlighterTest, TestDistribDocBasedVersion, TestFileDictionaryLookup, TestNonNRTOpen, SpellCheckComponentTest, PeerSyncTest, TestStressVersions, ShardRoutingTest, TestSolrIndexConfig, TestCodecSupport, TestDFRSimilarityFactory, TestValueSourceCache, TestRecovery, SimpleFacetsTest, TestGroupingSearch, CurrencyFieldXmlFileTest, TestSolrQueryParserDefaultOperatorResource, SolrIndexSplitterTest, TestRandomDVFaceting, TestDefaultSimilarityFactory, PolyFieldTest, TestLazyCores, TestAnalyzeInfixSuggestions, FieldFacetTest, PingRequestHandlerTest, TestDefaultSearchFieldResource, LegacyHTMLStripCharFilterTest, TestConfig, TestInfoStreamLogging, HardAutoCommitTest, BadCopyFieldTest, TestSolrDeletionPolicy2, JSONWriterTest, TestNumberUtils, IndexSchemaRuntimeFieldTest, TestJmxMonitoredMap, TestSolrQueryParser, OverseerCollectionProcessorTest, JsonLoaderTest, TestFastLRUCache, ZkControllerTest, DirectUpdateHandlerOptimizeTest, DebugComponentTest, BlockDirectoryTest, SolrTestCaseJ4Test, TestSimpleQParserPlugin, TestWriterPerf, TestNRTOpen, TestPhraseSuggestions, TermVectorComponentDistributedTest, DateFieldTest, ResourceLoaderTest, HdfsCollectionsAPIDistributedZkTest, TestSolrXml, TestHashPartitioner, TriLevelCompositeIdRoutingTest, TestReload, AssignTest, NoCacheHeaderTest, NumericFieldsTest, QueryResultKeyTest, TestComponentsName, WordBreakSolrSpellCheckerTest, UpdateRequestProcessorFactoryTest, TestDynamicFieldResource, FastVectorHighlighterTest, TestAddFieldRealTimeGet, UUIDFieldTest, QueryElevationComponentTest, HighlighterConfigTest, PrimitiveFieldTypeTest, SolrCoreCheckLockOnStartupTest, SynonymTokenizerTest, LoggingHandlerTest, CacheHeaderTest, CachingDirectoryFactoryTest, TestSort, ExpressionTest, HdfsRecoveryZkTest, PreAnalyzedUpdateProcessorTest, TestXIncludeConfig, SoftAutoCommitTest, FieldAnalysisRequestHandlerTest, PrimUtilsTest, PathHierarchyTokenizerFactoryTest, TestUniqueKeyFieldResource, ScriptEngineTest, TestPHPSerializedResponseWriter, EchoParamsTest, SuggesterTSTTest, SolrRequestParserTest, TestSolrXmlPersistence, HdfsBasicDistributedZk2Test, ExternalFileFieldSortTest, RequestHandlersTest, DisMaxRequestHandlerTest, CoreAdminHandlerTest, ShardRoutingCustomTest, HdfsLockFactoryTest, TestManagedSchemaFieldResource, TestSurroundQueryParser, IndexSchemaTest, HdfsBasicDistributedZkTest, FileUtilsTest, BasicZkTest, TestFuzzyAnalyzedSuggestions, SliceStateTest, TestClassNameShortening, BJQParserTest, SimplePostToolTest, TestUtils, TestSchemaNameResource, SampleTest, DateMathParserTest, TestDocSet, TestStressUserVersions, TestBinaryField, AddBlockUpdateTest, CollectionsAPIDistributedZkTest, TestLMDirichletSimilarityFactory, TestReversedWildcardFilterFactory, SpellCheckCollatorTest, ReturnFieldsTest, SpatialFilterTest, StatsComponentTest, DistributedTermsComponentTest, DistributedDebugComponentTest, TestPerFieldSimilarity, TestFastWriter, ConvertedLegacyTest, DirectUpdateHandlerTest, TestSearchPerf, TestBinaryResponseWriter, TestDynamicFieldCollectionResource, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, RecoveryZkTest, TestReplicationHandler, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, TestZkChroot, ZkCLITest, SliceStateUpdateTest, TestDistributedSearch, AutoCommitTest, TestDistributedGrouping, TestFaceting, DistributedSpellCheckComponentTest, TestStressReorder, TestJoin, TestMultiCoreConfBootstrap, TestRangeQuery, TestCoreContainer, TestSolr4Spatial, SolrCoreTest, SolrCmdDistributorTest, BadIndexSchemaTest, TestFiltering, TestBadConfig, TestIndexSearcher, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, TestFoldingMultitermQuery, DocValuesTest, SuggesterTest, TestStressLucene, TestTrie, SuggesterWFSTTest, TestPseudoReturnFields, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, TestRemoteStreaming, DistanceFunctionTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, TestQueryUtils, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, TestOmitPositions, FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, TermVectorComponentTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, TestArbitraryIndexDir, RegexBoostProcessorTest, SolrPluginUtilsTest, TestJmxIntegration, TestCSVResponseWriter, MBeansHandlerTest, QueryParsingTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, CSVRequestHandlerTest, CoreContainerCoreInitFailuresTest, TestLFUCache, SOLR749Test, TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, TestMergePolicyConfig, MinimalSchemaTest, TestElisionMultitermQuery, TestPostingsSolrHighlighter, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestCharFilters, TestIBSimilarityFactory, TimeZoneUtilsTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, ChaosMonkeyNothingIsSafeTest, TestSolrXMLSerializer, PreAnalyzedFieldTest, TestSystemIdResolver, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, TestLRUCache, TestDocumentBuilder, ZkNodePropsTest, SystemInfoHandlerTest, CircularListTest, TestRTGBase, AbstractAnalyticsStatsTest, NoFacetTest]
[junit4] Completed in 2.44s, 12 tests, 1 failure <<< FAILURES!
[...truncated 143 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:420: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:400: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:492: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1272: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:908: There were test failures: 353 suites, 1558 tests, 1 failure, 43 ignored (11 assumptions)
Total time: 67 minutes 17 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b116 -client -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure