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/08/10 20:37:59 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0-ea-b99) - Build
# 3131 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3131/
Java: 32bit/jdk1.8.0-ea-b99 -server -XX:+UseConcMarkSweepGC
2 tests failed.
REGRESSION: org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate
Error Message:
expected:<[in]active> but was:<[]active>
Stack Trace:
org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
at __randomizedtesting.SeedInfo.seed([5A4AC4D8AB0F97E8:8A284994F5DA4D6C]:0)
at org.junit.Assert.assertEquals(Assert.java:125)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestCodecSupport
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([5A4AC4D8AB0F97E8]:0)
Build Log:
[...truncated 9480 lines...]
[junit4] Suite: org.apache.solr.cloud.SliceStateUpdateTest
[junit4] 2> 409161 T410 oas.SolrTestCaseJ4.setUp ###Starting testSliceStateUpdate
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078
[junit4] 2> 409171 T410 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 409177 T411 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 409433 T410 oasc.ZkTestServer.run start zk server on port:60851
[junit4] 2> 409644 T410 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 409785 T417 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5b457c name:ZooKeeperConnection Watcher:127.0.0.1:60851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 409785 T410 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 409793 T410 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 409919 T410 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 409925 T419 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c09dca name:ZooKeeperConnection Watcher:127.0.0.1:60851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 409925 T410 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 409933 T410 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 409947 T410 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 409961 T410 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 409971 T410 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 409993 T410 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 409996 T410 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 410018 T410 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> 410019 T410 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 410031 T410 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> 410032 T410 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 410042 T410 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> 410043 T410 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 410054 T410 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> 410056 T410 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 410064 T410 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> 410064 T410 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 410072 T410 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> 410072 T410 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 410080 T410 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> 410080 T410 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 410089 T410 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> 410090 T410 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 410098 T410 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> 410099 T410 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 410111 T410 oasc.SliceStateUpdateTest.setUp ####SETUP_START testSliceStateUpdate
[junit4] 2> 410111 T412 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14068f828250001, 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:724)
[junit4] 2>
[junit4] 2> 410115 T410 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 410123 T421 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@377bed name:ZooKeeperConnection Watcher:127.0.0.1:60851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 410123 T410 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 410124 T410 oascc.SolrZkClient.makePath makePath: /collections/testcore
[junit4] 2> 410133 T410 oascc.SolrZkClient.makePath makePath: /collections/testcore/shards
[junit4] 2> 410145 T412 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14068f828250002, 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:724)
[junit4] 2>
[junit4] 2> 410151 T410 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 410151 T410 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> 410152 T410 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 410197 T410 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4] 2> 410217 T410 oasc.CoreContainer.<init> New CoreContainer 32249711
[junit4] 2> 410218 T410 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 410218 T410 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> 410218 T410 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 410238 T410 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4] 2> 410249 T410 oasc.CoreContainer.<init> New CoreContainer 3058925
[junit4] 2> 410249 T410 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> 410251 T410 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 410251 T410 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 410252 T410 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 410252 T410 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 410252 T410 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 410253 T410 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 410254 T410 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 410254 T410 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 410255 T410 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 410257 T410 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 410272 T410 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60851/solr
[junit4] 2> 410296 T410 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 410300 T410 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 410309 T423 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d3734e name:ZooKeeperConnection Watcher:127.0.0.1:60851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 410309 T410 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 410316 T412 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14068f828250003, 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:724)
[junit4] 2>
[junit4] 2> 410325 T410 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 410342 T410 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 410350 T425 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@135acf7 name:ZooKeeperConnection Watcher:127.0.0.1:60851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 410350 T410 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 410366 T410 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 410391 T410 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 410414 T410 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 410421 T410 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 410432 T410 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 410443 T410 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 410488 T410 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 410498 T410 oasc.Overseer.start Overseer (id=90187407582101508-127.0.0.1:8983_solr-n_0000000000) starting
[junit4] 2> 410528 T410 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 410555 T410 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 410556 T427 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 410571 T410 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 410577 T410 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 410609 T426 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 410618 T428 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 410619 T428 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 410623 T428 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 412541 T426 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 412557 T426 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 412557 T426 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 412599 T425 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 413167 T428 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 413167 T428 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> 413168 T428 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 413169 T428 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 413169 T428 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 413172 T428 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> 413176 T428 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> 413177 T428 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> 413256 T428 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 413292 T428 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 413297 T428 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 413305 T428 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 413480 T428 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 413490 T428 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 413492 T428 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 413537 T428 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 413543 T428 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 413553 T428 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 413556 T428 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 413556 T428 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 413557 T428 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 413559 T428 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 413559 T428 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 413559 T428 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 413559 T428 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-SliceStateUpdateTest-1376150628078\data2\
[junit4] 2> 413559 T428 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d45660
[junit4] 2> 413561 T428 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\
[junit4] 2> 413561 T428 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-SliceStateUpdateTest-1376150628078\data2\index/
[junit4] 2> 413562 T428 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\index' doesn't exist. Creating new index...
[junit4] 2> 413562 T428 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\index
[junit4] 2> 413563 T428 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@16ad347 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@107abbd)),segFN=segments_1,generation=1}
[junit4] 2> 413563 T428 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 413563 T428 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 413567 T428 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 413568 T428 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 413568 T428 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 413568 T428 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 413569 T428 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 413569 T428 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 413571 T428 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 413571 T428 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 413571 T428 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 413572 T428 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 413572 T428 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 413573 T428 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 413573 T428 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 413573 T428 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 413574 T428 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 413574 T428 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 413574 T428 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 413576 T428 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 413576 T428 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 413577 T428 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 413577 T428 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 413577 T428 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 413578 T428 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 413578 T428 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 413579 T428 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 413581 T428 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 413592 T428 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 413593 T428 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 413597 T428 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML]
[junit4] 2> 413598 T428 oass.SolrIndexSearcher.<init> Opening Searcher@96ead4 main
[junit4] 2> 413600 T428 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 413602 T428 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 413602 T428 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 413615 T428 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 413690 T429 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@96ead4 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 413695 T428 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 413695 T428 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 413710 T428 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 413726 T428 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 413764 T428 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 413774 T428 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 413774 T428 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 413784 T428 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 413784 T428 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 414512 T426 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 414564 T425 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 414623 T428 oasc.ZkController.register We are http://127.0.0.1:8983/solr/collection1/ and leader is http://127.0.0.1:8983/solr/collection1/
[junit4] 2> 414623 T428 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 414623 T428 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 414624 T428 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 414624 T428 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 414632 T428 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 414634 T410 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> 414635 T410 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 414635 T410 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 414635 T410 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 414635 T410 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 414635 T410 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 414635 T410 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 414635 T410 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 414637 T410 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 414637 T410 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 414637 T410 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 414643 T410 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60851/solr
[junit4] 2> 414644 T410 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 414646 T410 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 414652 T432 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@173a482 name:ZooKeeperConnection Watcher:127.0.0.1:60851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 414652 T410 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 414655 T410 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 414664 T410 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 414673 T434 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b3ed8a name:ZooKeeperConnection Watcher:127.0.0.1:60851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 414673 T410 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 414683 T410 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 415835 T410 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 415839 T425 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 415840 T410 oasc.ZkController.createEphemeralLiveNode Found a previous node that still exists while trying to register a new live node /live_nodes/127.0.0.1:8983_solr - removing existing node to create another.
[junit4] 2> 415840 T434 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 415840 T410 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 415844 T425 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 415848 T425 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 415850 T434 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 415854 T425 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 415868 T435 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 415868 T435 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 415873 T435 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 415873 T435 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 415873 T435 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> 415873 T435 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 415875 T435 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 415875 T435 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 415879 T435 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> 415883 T435 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> 415884 T435 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> 415956 T435 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 415988 T435 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 415991 T435 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 415998 T435 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 416161 T435 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 416174 T435 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 416176 T435 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 416205 T435 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 416210 T435 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 416218 T435 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 416221 T435 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 416221 T435 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 416221 T435 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 416224 T435 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 416224 T435 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 416225 T435 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 416225 T435 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-SliceStateUpdateTest-1376150628078\data2\
[junit4] 2> 416225 T435 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d45660
[junit4] 2> 416227 T435 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\
[junit4] 2> 416227 T435 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-SliceStateUpdateTest-1376150628078\data2\index/
[junit4] 2> 416227 T435 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\index' doesn't exist. Creating new index...
[junit4] 2> 416228 T435 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\index
[junit4] 2> 416229 T435 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1079263 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f13aa7)),segFN=segments_1,generation=1}
[junit4] 2> 416229 T435 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 416230 T435 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 416235 T435 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 416236 T435 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 416236 T435 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 416236 T435 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 416237 T435 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 416237 T435 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 416238 T435 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 416238 T435 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 416239 T435 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 416239 T435 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 416240 T435 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 416240 T435 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 416242 T435 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 416242 T435 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 416242 T435 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 416243 T435 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 416243 T435 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 416244 T435 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 416245 T435 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 416245 T435 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 416246 T435 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 416246 T435 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 416247 T435 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 416247 T435 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 416248 T435 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 416249 T435 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 416255 T435 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 416256 T435 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 416258 T435 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML]
[junit4] 2> 416259 T435 oass.SolrIndexSearcher.<init> Opening Searcher@538a0e main
[junit4] 2> 416262 T435 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 416263 T435 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 416263 T435 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 416266 T435 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 416289 T436 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@538a0e main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 416297 T435 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 416299 T435 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 416299 T435 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 416330 T435 oasc.ZkController.register We are http://127.0.0.1:8983/solr/collection1/ and leader is http://127.0.0.1:8983/solr/collection1/
[junit4] 2> 416331 T435 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 416331 T435 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 416331 T435 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 416331 T435 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 416332 T426 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 416338 T435 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 416340 T426 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 416343 T410 oasc.SliceStateUpdateTest.setUp ####SETUP_END testSliceStateUpdate
[junit4] 2> 416347 T410 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 416367 T439 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@112bd15 name:ZooKeeperConnection Watcher:127.0.0.1:60851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 416368 T410 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 416377 T434 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 416377 T425 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 416386 T426 oasc.Overseer$ClusterStateUpdater.updateState node=core_node1 is already registered
[junit4] 2> 416386 T426 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 416386 T426 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 416425 T426 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 416437 T434 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 453125 T410 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=32249711
[junit4] 2> 453249 T426 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 453250 T426 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 453250 T426 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 453259 T425 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 453259 T434 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 454695 T410 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 454695 T410 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 454699 T410 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1be532e
[junit4] 2> 454706 T410 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 454707 T410 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 454707 T410 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 454707 T410 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 454707 T410 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 454709 T410 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 454709 T410 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\;done=false>>]
[junit4] 2> 454709 T410 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\
[junit4] 2> 454709 T410 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\index;done=false>>]
[junit4] 2> 454710 T410 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\index
[junit4] 2> 454714 T426 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90187407582101508-127.0.0.1:8983_solr-n_0000000000) am no longer a leader.
[junit4] 2> 454718 T410 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=3058925
[junit4] 2> 454721 T434 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 454726 T434 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4] 2> 454726 T434 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4] 2> 454726 T434 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 454726 T434 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 454726 T434 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 454740 T434 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 454744 T434 oasc.Overseer.start Overseer (id=90187407582101510-127.0.0.1:8983_solr-n_0000000001) starting
[junit4] 2> 454754 T441 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 454755 T440 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 454759 T440 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 454760 T440 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 454785 T434 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 455469 T410 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 455469 T410 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 455474 T410 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1addfea
[junit4] 2> 455483 T410 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 455484 T410 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 455484 T410 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 455484 T410 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 455485 T410 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 455486 T410 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 455486 T410 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\index;done=false>>]
[junit4] 2> 455486 T410 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\index
[junit4] 2> 455486 T410 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\;done=false>>]
[junit4] 2> 455486 T410 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376150628078\data2\
[junit4] 2> 455487 T440 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90187407582101510-127.0.0.1:8983_solr-n_0000000001) am no longer a leader.
[junit4] 2> 455489 T434 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 455494 T410 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:60851 60851
[junit4] 2> 456468 T434 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 457186 T434 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 457187 T410 oas.SolrTestCaseJ4.tearDown ###Ending testSliceStateUpdate
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SliceStateUpdateTest -Dtests.method=testSliceStateUpdate -Dtests.seed=5A4AC4D8AB0F97E8 -Dtests.slow=true -Dtests.locale=lv -Dtests.timezone=America/Boa_Vista -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 48.0s | SliceStateUpdateTest.testSliceStateUpdate <<<
[junit4] > Throwable #1: org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
[junit4] > at __randomizedtesting.SeedInfo.seed([5A4AC4D8AB0F97E8:8A284994F5DA4D6C]:0)
[junit4] > at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 457201 T410 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=lv, timezone=America/Boa_Vista
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=1,free=23423408,total=72241152
[junit4] 2> NOTE: All tests run in this JVM: [HdfsCollectionsAPIDistributedZkTest, TestDocumentBuilder, HighlighterTest, QueryResultKeyTest, DebugComponentTest, DateMathParserTest, TestSolrQueryParserResource, TestCoreDiscovery, SolrRequestParserTest, CircularListTest, UUIDFieldTest, SimplePostToolTest, WordBreakSolrSpellCheckerTest, MultiTermTest, TestSchemaResource, QueryEqualityTest, TestMergePolicyConfig, HardAutoCommitTest, TestExtendedDismaxParser, DistributedQueryElevationComponentTest, TestPseudoReturnFields, RegexBoostProcessorTest, ParsingFieldUpdateProcessorsTest, TestAddFieldRealTimeGet, SpellPossibilityIteratorTest, TestFastLRUCache, SolrInfoMBeanTest, TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, TestPHPSerializedResponseWriter, PathHierarchyTokenizerFactoryTest, DocumentBuilderTest, TestRTGBase, TestQueryUtils, TestQuerySenderNoQuery, TestFastOutputStream, CoreAdminHandlerTest, NoCacheHeaderTest, TestRecoveryHdfs, TestStressVersions, TestStressRecovery, SuggesterTest, AddSchemaFieldsUpdateProcessorFactoryTest, XsltUpdateRequestHandlerTest, NumericFieldsTest, SolrIndexSplitterTest, HdfsDirectoryTest, TestJoin, TestSort, CacheHeaderTest, TestSearchPerf, SliceStateUpdateTest]
[junit4] Completed in 48.12s, 1 test, 1 failure <<< FAILURES!
[...truncated 754 lines...]
[junit4] Suite: org.apache.solr.core.TestCodecSupport
[junit4] 2> 2132233 T6646 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestCodecSupport-1376152351151
[junit4] 2> 2132236 T6646 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> 2132240 T6646 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> 2132242 T6646 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> 2132328 T6646 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2132336 T6646 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig_codec.xml
[junit4] 2> 2132338 T6646 oass.IndexSchema.readSchema Reading Solr Schema from schema_codec.xml
[junit4] 2> 2132341 T6646 oass.IndexSchema.readSchema [null] Schema name=codec
[junit4] 2> 2132349 T6646 oass.IndexSchema.readSchema default search field in schema is string_f
[junit4] 2> 2132350 T6646 oass.IndexSchema.readSchema unique key field: string_f
[junit4] 2> 2132351 T6646 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2132351 T6646 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> 2132351 T6646 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 2132369 T6646 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2132369 T6646 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> 2132370 T6646 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> 2132394 T6646 oasc.CoreContainer.<init> New CoreContainer 15250106
[junit4] 2> 2132394 T6646 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> 2132395 T6646 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2132395 T6646 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2132395 T6646 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2132395 T6646 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2132395 T6646 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2132396 T6646 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2132396 T6646 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2132396 T6646 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2132396 T6646 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2132396 T6646 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2132407 T6646 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2132409 T6647 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> 2132409 T6647 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> 2132410 T6647 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> 2132410 T6647 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> 2132435 T6647 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2132439 T6647 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig_codec.xml
[junit4] 2> 2132440 T6647 oass.IndexSchema.readSchema Reading Solr Schema from schema_codec.xml
[junit4] 2> 2132444 T6647 oass.IndexSchema.readSchema [collection1] Schema name=codec
[junit4] 2> 2132447 T6647 oass.IndexSchema.readSchema default search field in schema is string_f
[junit4] 2> 2132449 T6647 oass.IndexSchema.readSchema unique key field: string_f
[junit4] 2> 2132449 T6647 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2132450 T6647 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-TestCodecSupport-1376152351151\
[junit4] 2> 2132450 T6647 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2132451 T6647 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestCodecSupport-1376152351151\
[junit4] 2> 2132451 T6647 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-TestCodecSupport-1376152351151\index/
[junit4] 2> 2132451 T6647 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestCodecSupport-1376152351151\index' doesn't exist. Creating new index...
[junit4] 2> 2132452 T6647 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestCodecSupport-1376152351151\index
[junit4] 2> 2132452 T6647 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@159a0f6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16298e2),segFN=segments_1,generation=1}
[junit4] 2> 2132453 T6647 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2132454 T6647 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2132455 T6647 oass.SolrIndexSearcher.<init> Opening Searcher@c3a1f1 main
[junit4] 2> 2132455 T6647 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2132455 T6647 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2132455 T6647 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2132456 T6648 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c3a1f1 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2132464 T6646 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 2132478 T6646 oas.SolrTestCaseJ4.setUp ###Starting testDocValuesFormats
[junit4] 2> 2132479 T6646 oas.SolrTestCaseJ4.tearDown ###Ending testDocValuesFormats
[junit4] 2> 2132483 T6646 oas.SolrTestCaseJ4.setUp ###Starting testUnknownField
[junit4] 2> 2132483 T6646 oas.SolrTestCaseJ4.tearDown ###Ending testUnknownField
[junit4] 2> 2132488 T6646 oas.SolrTestCaseJ4.setUp ###Starting testDynamicFieldsPostingsFormats
[junit4] 2> 2132489 T6646 oas.SolrTestCaseJ4.tearDown ###Ending testDynamicFieldsPostingsFormats
[junit4] 2> 2132494 T6646 oas.SolrTestCaseJ4.setUp ###Starting testPostingsFormats
[junit4] 2> 2132494 T6646 oas.SolrTestCaseJ4.tearDown ###Ending testPostingsFormats
[junit4] 2> 2132502 T6646 oas.SolrTestCaseJ4.setUp ###Starting testDynamicFieldsDocValuesFormats
[junit4] 2> 2132502 T6646 oas.SolrTestCaseJ4.tearDown ###Ending testDynamicFieldsDocValuesFormats
[junit4] 2> 2132503 T6646 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 2132503 T6646 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=15250106
[junit4] 2> 7257751 T6645 ccr.ThreadLeakControl$2.evaluate WARNING Suite execution timed out: org.apache.solr.core.TestCodecSupport
[junit4] 2> ==== jstack at approximately timeout time ====
[junit4] 2> "Thread-2806" ID=6649 WAITING on java.lang.Object@46e28b
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> - waiting on java.lang.Object@46e28b
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at org.apache.solr.core.CloserThread.run(CoreContainer.java:961)
[junit4] 2>
[junit4] 2> "searcherExecutor-4136-thread-1" ID=6648 WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@17155e9
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> - waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@17155e9
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2037)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:439)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> "SUITE-TestCodecSupport-seed#[5A4AC4D8AB0F97E8]-worker" ID=6646 WAITING on org.apache.solr.core.CloserThread@114113f
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> - waiting on org.apache.solr.core.CloserThread@114113f
[junit4] 2> at java.lang.Thread.join(Thread.java:1224)
[junit4] 2> at java.lang.Thread.join(Thread.java:1298)
[junit4] 2> at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:358)
[junit4] 2> at org.apache.solr.util.TestHarness.close(TestHarness.java:338)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:535)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:130)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor23.invoke(Unknown Source)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:491)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> "SUITE-TestCodecSupport-seed#[5A4AC4D8AB0F97E8]" ID=6645 RUNNABLE
[junit4] 2> at sun.management.ThreadImpl.dumpThreads0(Native Method)
[junit4] 2> at sun.management.ThreadImpl.dumpAllThreads(ThreadImpl.java:446)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.formatThreadStacksFull(ThreadLeakControl.java:655)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.access$800(ThreadLeakControl.java:60)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$2.evaluate(ThreadLeakControl.java:396)
[junit4] 2> - locked java.lang.Object@aa3994
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:556)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$200(RandomizedRunner.java:79)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$1.run(RandomizedRunner.java:492)
[junit4] 2>
[junit4] 2> "TimeLimitedCollector timer thread" ID=5191 TIMED_WAITING
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.lucene.search.TimeLimitingCollector$TimerThread.run(TimeLimitingCollector.java:267)
[junit4] 2>
[junit4] 2> "Attach Listener" ID=6 RUNNABLE
[junit4] 2>
[junit4] 2> "Signal Dispatcher" ID=5 RUNNABLE
[junit4] 2>
[junit4] 2> "Finalizer" ID=3 WAITING on java.lang.ref.ReferenceQueue$Lock@b237e0
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> - waiting on java.lang.ref.ReferenceQueue$Lock@b237e0
[junit4] 2> at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
[junit4] 2> at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151)
[junit4] 2> at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:189)
[junit4] 2>
[junit4] 2> "Reference Handler" ID=2 WAITING on java.lang.ref.Reference$Lock@1400cee
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> - waiting on java.lang.ref.Reference$Lock@1400cee
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:156)
[junit4] 2>
[junit4] 2> "main" ID=1 WAITING on com.carrotsearch.randomizedtesting.RandomizedRunner$1@4c5fd2
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> - waiting on com.carrotsearch.randomizedtesting.RandomizedRunner$1@4c5fd2
[junit4] 2> at java.lang.Thread.join(Thread.java:1224)
[junit4] 2> at java.lang.Thread.join(Thread.java:1298)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:502)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.run(RandomizedRunner.java:399)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:180)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:275)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:12)
[junit4] 2>
[junit4] 2> ^^==============================================
[junit4] 2>
[junit4] 2> 7257753 T6645 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> 7277828 T6645 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 3 threads leaked from SUITE scope at org.apache.solr.core.TestCodecSupport:
[junit4] 2> 1) Thread[id=6649, name=Thread-2806, state=WAITING, group=TGRP-TestCodecSupport]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at org.apache.solr.core.CloserThread.run(CoreContainer.java:961)
[junit4] 2> 2) Thread[id=6646, name=SUITE-TestCodecSupport-seed#[5A4AC4D8AB0F97E8]-worker, state=WAITING, group=TGRP-TestCodecSupport]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Thread.join(Thread.java:1224)
[junit4] 2> at java.lang.Thread.join(Thread.java:1298)
[junit4] 2> at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:358)
[junit4] 2> at org.apache.solr.util.TestHarness.close(TestHarness.java:338)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:535)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:130)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor23.invoke(Unknown Source)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:491)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 3) Thread[id=6648, name=searcherExecutor-4136-thread-1, state=WAITING, group=TGRP-TestCodecSupport]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2037)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:439)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 7277829 T6645 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=6649, name=Thread-2806, state=WAITING, group=TGRP-TestCodecSupport]
[junit4] 2> 2) Thread[id=6646, name=SUITE-TestCodecSupport-seed#[5A4AC4D8AB0F97E8]-worker, state=WAITING, group=TGRP-TestCodecSupport]
[junit4] 2> 3) Thread[id=6648, name=searcherExecutor-4136-thread-1, state=WAITING, group=TGRP-TestCodecSupport]
[junit4] 2> 9410059 T6646 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@93435
[junit4] 2> 9410060 T6646 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 9410060 T6646 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 9410060 T6646 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 9410060 T6646 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 9410061 T6646 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 9410062 T6646 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 9410062 T6646 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestCodecSupport-1376152351151\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestCodecSupport-1376152351151\;done=false>>]
[junit4] 2> 9410062 T6646 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestCodecSupport-1376152351151\
[junit4] 2> 9410062 T6646 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestCodecSupport-1376152351151\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestCodecSupport-1376152351151\index;done=false>>]
[junit4] 2> 9410063 T6646 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestCodecSupport-1376152351151\index
[junit4] 2> 7277834 T6645 ccr.ThreadLeakControl.tryToInterruptAll All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=27), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=27)), sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=es_MX, timezone=Brazil/East
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=1,free=255117248,total=409255936
[junit4] 2> NOTE: All tests run in this JVM: [HdfsCollectionsAPIDistributedZkTest, TestDocumentBuilder, HighlighterTest, QueryResultKeyTest, DebugComponentTest, DateMathParserTest, TestSolrQueryParserResource, TestCoreDiscovery, SolrRequestParserTest, CircularListTest, UUIDFieldTest, SimplePostToolTest, WordBreakSolrSpellCheckerTest, MultiTermTest, TestSchemaResource, QueryEqualityTest, TestMergePolicyConfig, HardAutoCommitTest, TestExtendedDismaxParser, DistributedQueryElevationComponentTest, TestPseudoReturnFields, RegexBoostProcessorTest, ParsingFieldUpdateProcessorsTest, TestAddFieldRealTimeGet, SpellPossibilityIteratorTest, TestFastLRUCache, SolrInfoMBeanTest, TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, TestPHPSerializedResponseWriter, PathHierarchyTokenizerFactoryTest, DocumentBuilderTest, TestRTGBase, TestQueryUtils, TestQuerySenderNoQuery, TestFastOutputStream, CoreAdminHandlerTest, NoCacheHeaderTest, TestRecoveryHdfs, TestStressVersions, TestStressRecovery, SuggesterTest, AddSchemaFieldsUpdateProcessorFactoryTest, XsltUpdateRequestHandlerTest, NumericFieldsTest, SolrIndexSplitterTest, HdfsDirectoryTest, TestJoin, TestSort, CacheHeaderTest, TestSearchPerf, SliceStateUpdateTest, TestQuerySenderListener, TestWordDelimiterFilterFactory, UpdateRequestProcessorFactoryTest, CustomCollectionTest, CachingDirectoryFactoryTest, PeerSyncTest, LukeRequestHandlerTest, AliasIntegrationTest, BadIndexSchemaTest, DateFieldTest, TestTrie, TestNumberUtils, TestOmitPositions, TestSurroundQueryParser, TestFastWriter, TestCollationField, BasicDistributedZkTest, SuggesterFSTTest, TestLuceneMatchVersion, ZkSolrClientTest, OpenCloseCoreStressTest, DeleteShardTest, HdfsChaosMonkeySafeLeaderTest, PrimUtilsTest, TestAnalyzedSuggestions, ClusterStateTest, OverseerCollectionProcessorTest, PluginInfoTest, CollectionsAPIDistributedZkTest, TestArbitraryIndexDir, TestSchemaNameResource, TestInfoStreamLogging, TestManagedSchema, SolrPluginUtilsTest, TestCopyFieldCollectionResource, DistributedTermsComponentTest, RequiredFieldsTest, SynonymTokenizerTest, BadComponentTest, BasicZkTest, QueryParsingTest, DOMUtilTest, CoreAdminCreateDiscoverTest, AssignTest, ResponseLogComponentTest, FieldMutatingUpdateProcessorTest, TestElisionMultitermQuery, SpellingQueryConverterTest, SignatureUpdateProcessorFactoryTest, TestClassNameShortening, TestUniqueKeyFieldResource, TestLFUCache, TestFiltering, TestFieldResource, SampleTest, TestUpdate, IndexBasedSpellCheckerTest, SliceStateTest, TestSolrJ, TestValueSourceCache, ConvertedLegacyTest, TestReplicationHandler, SolrCmdDistributorTest, TestRandomFaceting, CSVRequestHandlerTest, TestXIncludeConfig, TestCharFilters, TestSuggestSpellingConverter, ShardRoutingTest, TestFoldingMultitermQuery, TestSolrXml, PingRequestHandlerTest, ScriptEngineTest, StressHdfsTest, ShardRoutingCustomTest, TestFuzzyAnalyzedSuggestions, SolrCoreTest, SolrIndexConfigTest, DocumentAnalysisRequestHandlerTest, BadCopyFieldTest, TestRandomMergePolicy, UnloadDistributedZkTest, TestSweetSpotSimilarityFactory, PrimitiveFieldTypeTest, TestCloudManagedSchemaAddField, TestFieldTypeCollectionResource, HdfsBasicDistributedZkTest, ClusterStateUpdateTest, ZkControllerTest, SpatialFilterTest, SortByFunctionTest, TestLMDirichletSimilarityFactory, TestSystemIdResolver, BasicDistributedZk2Test, JsonLoaderTest, TestCSVLoader, SOLR749Test, TestCloudManagedSchema, ChaosMonkeySafeLeaderTest, SyncSliceTest, OverseerTest, LeaderElectionIntegrationTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, LeaderElectionTest, TestZkChroot, TestRandomDVFaceting, ZkCLITest, TestDistributedSearch, AutoCommitTest, TestDistributedGrouping, TestFaceting, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestRealTimeGet, TestStressReorder, TestMultiCoreConfBootstrap, TestReload, TestRangeQuery, TestCoreContainer, SimpleFacetsTest, TestSolr4Spatial, StatsComponentTest, SpellCheckComponentTest, TestGroupingSearch, QueryElevationComponentTest, TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, DirectUpdateHandlerTest, TestBadConfig, TestIndexSearcher, SoftAutoCommitTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, CurrencyFieldXmlFileTest, AnalysisAfterCoreReloadTest, SpellCheckCollatorTest, DocValuesTest, SuggesterTSTTest, TestStressLucene, SuggesterWFSTTest, PolyFieldTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestWriterPerf, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, TestQueryTypes, FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, TermVectorComponentTest, TestIndexingPerformance, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, IndexSchemaTest, TestJmxIntegration, ReturnFieldsTest, JSONWriterTest, TestCSVResponseWriter, MBeansHandlerTest, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, TestComponentsName, SearchHandlerTest, CoreContainerCoreInitFailuresTest, TestBinaryResponseWriter, HighlighterConfigTest, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, CopyFieldTest, TestSolrDeletionPolicy2, TestDocSet, TestBinaryField, MinimalSchemaTest, TestConfig, OutputWriterTest, ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestPhraseSuggestions, TestCodecSupport]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCodecSupport -Dtests.seed=5A4AC4D8AB0F97E8 -Dtests.slow=true -Dtests.locale=es_MX -Dtests.timezone=Brazil/East -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s | TestCodecSupport (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([5A4AC4D8AB0F97E8]:0)
[junit4] Completed in 7277.87s, 5 tests, 1 error <<< FAILURES!
[...truncated 176 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:389: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:369: 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\solr\build.xml:181: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:449: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1230: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:873: There were test failures: 318 suites, 1345 tests, 1 suite-level error, 1 failure, 44 ignored (11 assumptions)
Total time: 185 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b99 -server -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure