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 2014/05/06 01:19:44 UTC
[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.7.0_55) - Build #
10242 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10242/
Java: 64bit/jdk1.7.0_55 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.testDistribSearch
Error Message:
shard1 is not consistent. Got 199 from https://127.0.0.1:46991/ntma/collection1lastClient and got 190 from https://127.0.0.1:56210/ntma/collection1
Stack Trace:
java.lang.AssertionError: shard1 is not consistent. Got 199 from https://127.0.0.1:46991/ntma/collection1lastClient and got 190 from https://127.0.0.1:56210/ntma/collection1
at __randomizedtesting.SeedInfo.seed([5E45A61A63FFAFE6:DFA3280214A0CFDA]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1130)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1109)
at org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.doTest(ChaosMonkeySafeLeaderTest.java:150)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
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:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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:65)
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:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:360)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11226 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/init-core-data-001
[junit4] 2> 448857 T1737 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
[junit4] 2> 448857 T1737 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /ntma/
[junit4] 2> 448860 T1737 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 448861 T1737 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 448861 T1738 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 448961 T1737 oasc.ZkTestServer.run start zk server on port:44639
[junit4] 2> 448962 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 449039 T1744 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2a7a59d8 name:ZooKeeperConnection Watcher:127.0.0.1:44639 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 449039 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 449039 T1737 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 449043 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 449044 T1746 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ca10e57 name:ZooKeeperConnection Watcher:127.0.0.1:44639/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 449045 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 449045 T1737 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 449047 T1737 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 449049 T1737 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 449050 T1737 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 449051 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 449052 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 449054 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 449055 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 449057 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 449057 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 449059 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 449059 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 449061 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 449061 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 449062 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 449063 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 449064 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 449065 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 449067 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 449068 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 449071 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 449071 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 449074 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 449074 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 449076 T1737 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 449076 T1737 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 449079 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 449080 T1748 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7760de2 name:ZooKeeperConnection Watcher:127.0.0.1:44639/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 449080 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 449295 T1737 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 449298 T1737 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 449300 T1737 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:42377
[junit4] 2> 449301 T1737 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 449302 T1737 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 449302 T1737 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-002
[junit4] 2> 449302 T1737 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-002/'
[junit4] 2> 449312 T1737 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-002/solr.xml
[junit4] 2> 449344 T1737 oasc.CoreContainer.<init> New CoreContainer 1818110029
[junit4] 2> 449344 T1737 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-002/]
[junit4] 2> 449345 T1737 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 449345 T1737 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 449345 T1737 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 449346 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 449346 T1737 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 449346 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 449346 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 449346 T1737 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 449347 T1737 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 449349 T1737 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 449349 T1737 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 449349 T1737 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 449349 T1737 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:44639/solr
[junit4] 2> 449350 T1737 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 449350 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 449352 T1759 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3b189eeb name:ZooKeeperConnection Watcher:127.0.0.1:44639 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 449352 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 449354 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 449355 T1761 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6fd48dd6 name:ZooKeeperConnection Watcher:127.0.0.1:44639/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 449355 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 449356 T1737 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 449359 T1737 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 449360 T1737 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 449362 T1737 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 449364 T1737 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 449369 T1737 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 449371 T1737 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42377_ntma
[junit4] 2> 449372 T1737 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42377_ntma
[junit4] 2> 449374 T1737 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 449375 T1737 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 449378 T1737 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:42377_ntma
[junit4] 2> 449378 T1737 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 449379 T1737 oasc.Overseer.start Overseer (id=91706563220930564-127.0.0.1:42377_ntma-n_0000000000) starting
[junit4] 2> 449381 T1737 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 449388 T1763 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 449388 T1737 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 449388 T1763 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes at 127.0.0.1:42377_ntma
[junit4] 2> 449390 T1737 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 449391 T1737 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 449392 T1762 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 449396 T1764 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 449396 T1764 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 449397 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 449397 T1764 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 449399 T1762 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 449399 T1762 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42377/ntma",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42377_ntma",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 449400 T1762 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 449400 T1762 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 449402 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 449406 T1761 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> 450398 T1764 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 450398 T1764 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 450399 T1764 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 450399 T1764 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 450400 T1764 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 450400 T1764 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-002/collection1/'
[junit4] 2> 450400 T1764 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 450401 T1764 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 450401 T1764 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 450439 T1764 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 450471 T1764 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 450472 T1764 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 450477 T1764 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 450719 T1764 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 450721 T1764 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 450722 T1764 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 450725 T1764 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 450740 T1764 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 450741 T1764 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 450741 T1764 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-002/collection1/, dataDir=./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/control/data/
[junit4] 2> 450741 T1764 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@67949c1e
[junit4] 2> 450741 T1764 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/control/data
[junit4] 2> 450742 T1764 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/control/data/index/
[junit4] 2> 450742 T1764 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 450742 T1764 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/control/data/index
[junit4] 2> 450742 T1764 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=51.890625, floorSegmentMB=1.16796875, forceMergeDeletesPctAllowed=9.811031848224959, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 450746 T1764 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 450746 T1764 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 450749 T1764 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 450749 T1764 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 450749 T1764 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 450750 T1764 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 450750 T1764 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 450750 T1764 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 450750 T1764 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 450750 T1764 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 450751 T1764 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 450751 T1764 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 450751 T1764 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 450751 T1764 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 450752 T1764 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 450752 T1764 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 450752 T1764 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 450752 T1764 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 450753 T1764 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 450755 T1764 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 450757 T1764 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 450757 T1764 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 450757 T1764 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 450758 T1764 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 450758 T1764 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 450759 T1764 oass.SolrIndexSearcher.<init> Opening Searcher@187c98b7[collection1] main
[junit4] 2> 450759 T1764 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 450760 T1764 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 450760 T1764 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 450760 T1764 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 450760 T1764 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 450761 T1764 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 450761 T1764 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 450761 T1764 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 450761 T1764 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 450761 T1764 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 450764 T1765 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@187c98b7[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 450765 T1764 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 450766 T1768 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:42377/ntma collection:control_collection shard:shard1
[junit4] 2> 450766 T1737 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 450767 T1737 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 450767 T1768 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 450769 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 450771 T1770 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2a79a19b name:ZooKeeperConnection Watcher:127.0.0.1:44639/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 450771 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 450772 T1768 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 450772 T1737 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 450773 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 450773 T1768 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 450774 T1768 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C719 name=collection1 org.apache.solr.core.SolrCore@22f62fae url=https://127.0.0.1:42377/ntma/collection1 node=127.0.0.1:42377_ntma C719_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:42377/ntma, core=collection1, node_name=127.0.0.1:42377_ntma}
[junit4] 2> 450774 T1768 C719 P42377 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:42377/ntma/collection1/
[junit4] 2> 450774 T1737 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 450774 T1762 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 450774 T1768 C719 P42377 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 450775 T1768 C719 P42377 oasc.SyncStrategy.syncToMe https://127.0.0.1:42377/ntma/collection1/ has no replicas
[junit4] 2> 450775 T1768 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:42377/ntma/collection1/ shard1
[junit4] 2> 450775 T1768 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 450777 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 450780 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 450782 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 450883 T1761 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> 450883 T1770 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> 450930 T1768 oasc.ZkController.register We are https://127.0.0.1:42377/ntma/collection1/ and leader is https://127.0.0.1:42377/ntma/collection1/
[junit4] 2> 450930 T1768 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:42377/ntma
[junit4] 2> 450931 T1768 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 450931 T1768 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 450931 T1768 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 450932 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 450932 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 450932 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 450932 T1768 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 450934 T1762 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 450934 T1762 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:42377/ntma",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42377_ntma",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 450938 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 450998 T1737 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 450999 T1737 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 451002 T1737 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 451003 T1737 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:44949
[junit4] 2> 451017 T1737 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 451017 T1737 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 451017 T1737 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-003
[junit4] 2> 451018 T1737 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-003/'
[junit4] 2> 451027 T1737 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-003/solr.xml
[junit4] 2> 451040 T1761 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> 451040 T1770 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> 451062 T1737 oasc.CoreContainer.<init> New CoreContainer 2085279841
[junit4] 2> 451063 T1737 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-003/]
[junit4] 2> 451063 T1737 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 451064 T1737 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 451064 T1737 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 451064 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 451064 T1737 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 451064 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 451065 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 451065 T1737 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 451065 T1737 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 451067 T1737 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 451067 T1737 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 451068 T1737 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 451068 T1737 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:44639/solr
[junit4] 2> 451068 T1737 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 451069 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 451070 T1781 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f78420e name:ZooKeeperConnection Watcher:127.0.0.1:44639 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 451070 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 451072 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 451073 T1783 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12660c0f name:ZooKeeperConnection Watcher:127.0.0.1:44639/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 451073 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 451081 T1737 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 452083 T1737 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44949_ntma
[junit4] 2> 452084 T1737 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44949_ntma
[junit4] 2> 452093 T1784 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 452093 T1784 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 452094 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 452094 T1784 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 452094 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 452094 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 452095 T1762 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 452096 T1762 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:44949/ntma",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44949_ntma",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 452096 T1762 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3]
[junit4] 2> 452096 T1762 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
[junit4] 2> 452098 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 452199 T1783 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 452199 T1770 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 452199 T1761 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 453094 T1784 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 453094 T1784 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 453095 T1784 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 453095 T1784 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 453096 T1784 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 453096 T1784 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-003/collection1/'
[junit4] 2> 453097 T1784 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 453097 T1784 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 453097 T1784 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 453128 T1784 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 453162 T1784 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 453164 T1784 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 453169 T1784 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 453417 T1784 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 453419 T1784 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 453420 T1784 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 453423 T1784 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 453439 T1784 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 453439 T1784 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 453439 T1784 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-003/collection1/, dataDir=./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty1/
[junit4] 2> 453440 T1784 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@67949c1e
[junit4] 2> 453440 T1784 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty1
[junit4] 2> 453440 T1784 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty1/index/
[junit4] 2> 453441 T1784 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 453441 T1784 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty1/index
[junit4] 2> 453441 T1784 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=51.890625, floorSegmentMB=1.16796875, forceMergeDeletesPctAllowed=9.811031848224959, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 453446 T1784 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 453447 T1784 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 453450 T1784 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 453451 T1784 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 453451 T1784 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 453451 T1784 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 453451 T1784 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 453451 T1784 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 453452 T1784 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 453452 T1784 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 453452 T1784 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 453452 T1784 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 453453 T1784 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 453453 T1784 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 453453 T1784 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 453453 T1784 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 453454 T1784 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 453454 T1784 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 453454 T1784 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 453456 T1784 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 453458 T1784 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 453459 T1784 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 453459 T1784 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 453460 T1784 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 453460 T1784 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 453461 T1784 oass.SolrIndexSearcher.<init> Opening Searcher@31b7b555[collection1] main
[junit4] 2> 453461 T1784 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 453462 T1784 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 453462 T1784 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 453463 T1784 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 453463 T1784 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 453463 T1784 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 453464 T1784 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 453464 T1784 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 453464 T1784 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 453464 T1784 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 453466 T1785 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@31b7b555[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 453469 T1784 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 453469 T1788 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:44949/ntma collection:collection1 shard:shard3
[junit4] 2> 453469 T1737 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 453470 T1737 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 453470 T1788 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4] 2> 453475 T1788 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3
[junit4] 2> 453478 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 453478 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 453478 T1788 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 453479 T1788 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 453478 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> ASYNC NEW_CORE C720 name=collection1 org.apache.solr.core.SolrCore@f4caacf url=https://127.0.0.1:44949/ntma/collection1 node=127.0.0.1:44949_ntma C720_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:44949/ntma, core=collection1, node_name=127.0.0.1:44949_ntma}
[junit4] 2> 453479 T1788 C720 P44949 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:44949/ntma/collection1/
[junit4] 2> 453479 T1788 C720 P44949 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 453479 T1788 C720 P44949 oasc.SyncStrategy.syncToMe https://127.0.0.1:44949/ntma/collection1/ has no replicas
[junit4] 2> 453480 T1788 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:44949/ntma/collection1/ shard3
[junit4] 2> 453480 T1762 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 453480 T1788 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
[junit4] 2> 453482 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 453484 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 453486 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 453589 T1770 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 453589 T1783 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 453589 T1761 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 453634 T1788 oasc.ZkController.register We are https://127.0.0.1:44949/ntma/collection1/ and leader is https://127.0.0.1:44949/ntma/collection1/
[junit4] 2> 453635 T1788 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:44949/ntma
[junit4] 2> 453635 T1788 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 453635 T1788 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 453635 T1788 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 453636 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 453637 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 453637 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 453637 T1788 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 453638 T1762 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 453639 T1762 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:44949/ntma",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44949_ntma",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 453641 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 453694 T1737 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 453695 T1737 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 453698 T1737 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 453699 T1737 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:46991
[junit4] 2> 453701 T1737 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 453701 T1737 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 453701 T1737 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-004
[junit4] 2> 453701 T1737 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-004/'
[junit4] 2> 453711 T1737 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-004/solr.xml
[junit4] 2> 453743 T1737 oasc.CoreContainer.<init> New CoreContainer 1550603287
[junit4] 2> 453744 T1737 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-004/]
[junit4] 2> 453746 T1761 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 453746 T1770 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 453746 T1783 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 453747 T1737 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 453747 T1737 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 453747 T1737 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 453748 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 453748 T1737 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 453748 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 453748 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 453749 T1737 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 453749 T1737 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 453751 T1737 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 453751 T1737 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 453752 T1737 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 453752 T1737 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:44639/solr
[junit4] 2> 453752 T1737 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 453753 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 453754 T1799 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@503ed6a name:ZooKeeperConnection Watcher:127.0.0.1:44639 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 453754 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 453756 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 453757 T1801 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3a3c390 name:ZooKeeperConnection Watcher:127.0.0.1:44639/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 453757 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 453763 T1737 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 454765 T1737 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46991_ntma
[junit4] 2> 454766 T1737 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46991_ntma
[junit4] 2> 454774 T1802 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 454775 T1802 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 454777 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 454777 T1802 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 454777 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 454777 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 454779 T1762 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 454779 T1762 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46991/ntma",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:46991_ntma",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 454779 T1762 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3
[junit4] 2> 454780 T1762 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 454781 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 454885 T1761 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 454885 T1770 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 454885 T1783 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 454885 T1801 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 455777 T1802 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 455778 T1802 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 455778 T1802 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 455778 T1802 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 455780 T1802 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 455780 T1802 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-004/collection1/'
[junit4] 2> 455780 T1802 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 455781 T1802 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 455781 T1802 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 455812 T1802 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 455845 T1802 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 455846 T1802 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 455852 T1802 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 456105 T1802 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 456107 T1802 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 456108 T1802 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 456111 T1802 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 456129 T1802 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 456129 T1802 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 456129 T1802 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-004/collection1/, dataDir=./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty2/
[junit4] 2> 456129 T1802 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@67949c1e
[junit4] 2> 456130 T1802 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty2
[junit4] 2> 456130 T1802 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty2/index/
[junit4] 2> 456130 T1802 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 456131 T1802 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty2/index
[junit4] 2> 456131 T1802 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=51.890625, floorSegmentMB=1.16796875, forceMergeDeletesPctAllowed=9.811031848224959, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 456134 T1802 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 456134 T1802 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 456138 T1802 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 456138 T1802 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 456138 T1802 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 456139 T1802 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 456139 T1802 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 456139 T1802 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 456139 T1802 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 456140 T1802 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 456140 T1802 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 456140 T1802 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 456140 T1802 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 456141 T1802 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 456141 T1802 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 456141 T1802 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 456141 T1802 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 456142 T1802 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 456142 T1802 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 456144 T1802 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 456146 T1802 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 456146 T1802 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 456147 T1802 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 456148 T1802 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 456148 T1802 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 456148 T1802 oass.SolrIndexSearcher.<init> Opening Searcher@69e24abf[collection1] main
[junit4] 2> 456149 T1802 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 456149 T1802 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 456150 T1802 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 456150 T1802 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 456150 T1802 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 456150 T1802 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 456151 T1802 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 456151 T1802 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 456151 T1802 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 456151 T1802 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 456154 T1803 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@69e24abf[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 456156 T1802 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 456157 T1806 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:46991/ntma collection:collection1 shard:shard1
[junit4] 2> 456158 T1737 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 456158 T1737 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 456158 T1806 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 456162 T1806 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 456164 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 456164 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 456164 T1806 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 456164 T1806 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 456164 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> ASYNC NEW_CORE C721 name=collection1 org.apache.solr.core.SolrCore@25b5489b url=https://127.0.0.1:46991/ntma/collection1 node=127.0.0.1:46991_ntma C721_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:46991/ntma, core=collection1, node_name=127.0.0.1:46991_ntma}
[junit4] 2> 456164 T1806 C721 P46991 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:46991/ntma/collection1/
[junit4] 2> 456165 T1806 C721 P46991 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 456165 T1806 C721 P46991 oasc.SyncStrategy.syncToMe https://127.0.0.1:46991/ntma/collection1/ has no replicas
[junit4] 2> 456165 T1806 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:46991/ntma/collection1/ shard1
[junit4] 2> 456165 T1806 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 456165 T1762 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 456167 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 456168 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 456170 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 456273 T1770 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 456273 T1801 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 456273 T1783 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 456274 T1761 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 456319 T1806 oasc.ZkController.register We are https://127.0.0.1:46991/ntma/collection1/ and leader is https://127.0.0.1:46991/ntma/collection1/
[junit4] 2> 456319 T1806 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:46991/ntma
[junit4] 2> 456319 T1806 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 456319 T1806 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 456320 T1806 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 456322 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 456323 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 456323 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 456323 T1806 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 456324 T1762 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 456325 T1762 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:46991/ntma",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:46991_ntma",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 456327 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 456383 T1737 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 456384 T1737 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 456387 T1737 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 456387 T1737 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:36581
[junit4] 2> 456389 T1737 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 456389 T1737 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 456390 T1737 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-005
[junit4] 2> 456390 T1737 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-005/'
[junit4] 2> 456399 T1737 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-005/solr.xml
[junit4] 2> 456428 T1761 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 456428 T1801 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 456428 T1783 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 456428 T1770 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 456432 T1737 oasc.CoreContainer.<init> New CoreContainer 1866412029
[junit4] 2> 456432 T1737 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-005/]
[junit4] 2> 456433 T1737 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 456433 T1737 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 456434 T1737 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 456434 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 456434 T1737 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 456434 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 456435 T1737 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 456435 T1737 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 456435 T1737 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 456437 T1737 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 456437 T1737 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 456438 T1737 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 456438 T1737 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:44639/solr
[junit4] 2> 456438 T1737 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 456439 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 456440 T1817 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@481600a6 name:ZooKeeperConnection Watcher:127.0.0.1:44639 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 456440 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 456442 T1737 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 456443 T1819 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a6a6244 name:ZooKeeperConnection Watcher:127.0.0.1:44639/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 456443 T1737 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 456449 T1737 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 457451 T1737 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36581_ntma
[junit4] 2> 457452 T1737 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36581_ntma
[junit4] 2> 457462 T1820 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 457462 T1820 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 457463 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 457463 T1820 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 457463 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 457463 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 457464 T1762 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 457465 T1762 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:36581/ntma",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:36581_ntma",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 457465 T1762 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3
[junit4] 2> 457465 T1762 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 457467 T1761 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 457571 T1761 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 457571 T1819 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 457571 T1770 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 457571 T1801 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 457571 T1783 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 458463 T1820
[...truncated too long message...]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:260)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:257)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:257)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:94)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:303)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> ASYNC NEW_CORE C1827 name=collection1 org.apache.solr.core.SolrCore@55527559 url=https://127.0.0.1:43056/ntma/collection1 node=127.0.0.1:43056_ntma C1827_STATE=coll:collection1 core:collection1 props:{state=active, base_url=https://127.0.0.1:43056/ntma, core=collection1, node_name=127.0.0.1:43056_ntma}
[junit4] 2> 540739 T2115 C1827 P43056 oasc.SyncStrategy.sync WARN Closed, skipping sync up.
[junit4] 2> 540741 T2115 oasc.ShardLeaderElectionContext.rejoinLeaderElection Not rejoining election because CoreContainer is shutdown
[junit4] 2> 540741 T2115 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@55527559
[junit4] 2> 540743 T2115 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=303,cumulative_deletesById=151,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=26517,transaction_logs_total_number=1}
[junit4] 2> 540744 T2115 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 540744 T2115 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 540744 T2115 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 540747 T2115 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 540748 T2115 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 540749 T2115 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty11 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty11;done=false>>]
[junit4] 2> 540749 T2115 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty11
[junit4] 2> 540749 T2115 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty11/index.20140505130556058 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty11/index.20140505130556058;done=false>>]
[junit4] 2> 540750 T2115 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001/tempDir-001/jetty11/index.20140505130556058
[junit4] 2> 540750 T2115 oasc.LeaderElector$ElectionWatcher.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:260)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:257)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:257)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:94)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:303)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> 540751 T2115 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-5E45A61A63FFAFE6-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=sr_RS, timezone=HST
[junit4] 2> NOTE: Linux 3.8.0-39-generic amd64/Oracle Corporation 1.7.0_55 (64-bit)/cpus=8,threads=1,free=179386744,total=498073600
[junit4] 2> NOTE: All tests run in this JVM: [TimeZoneUtilsTest, TestManagedSynonymFilterFactory, HdfsBasicDistributedZkTest, TestQueryUtils, SuggesterTest, ConnectionManagerTest, TestSearchPerf, SuggesterFSTTest, TestNoOpRegenerator, CopyFieldTest, TestNonDefinedSimilarityFactory, AutoCommitTest, TestExtendedDismaxParser, OverseerCollectionProcessorTest, TestJoin, DistribCursorPagingTest, TestBadConfig, TestSolrDeletionPolicy1, TestPerFieldSimilarity, ScriptEngineTest, LoggingHandlerTest, TestUtils, TestBM25SimilarityFactory, TestSystemIdResolver, FastVectorHighlighterTest, ExternalFileFieldSortTest, TestAddFieldRealTimeGet, TestSuggestSpellingConverter, TestLMJelinekMercerSimilarityFactory, TestReversedWildcardFilterFactory, TestSolrXml, CursorMarkTest, BadCopyFieldTest, JsonLoaderTest, LukeRequestHandlerTest, TestFileDictionaryLookup, EchoParamsTest, PreAnalyzedUpdateProcessorTest, TestCopyFieldCollectionResource, TestPostingsSolrHighlighter, PeerSyncTest, DocValuesTest, SimpleFacetsTest, FieldFacetExtrasTest, TestSolrQueryParser, DocExpirationUpdateProcessorFactoryTest, TestAnalyzeInfixSuggestions, BadComponentTest, TestRandomDVFaceting, RegexBoostProcessorTest, DistributedQueryElevationComponentTest, TestValueSourceCache, AbstractAnalyticsFacetTest, ModifyConfFileTest, DistributedTermsComponentTest, SliceStateTest, TestFunctionQuery, TestSchemaVersionResource, HdfsSyncSliceTest, TestExpandComponent, MoreLikeThisHandlerTest, TestFastLRUCache, ShardSplitTest, CoreMergeIndexesAdminHandlerTest, BasicFunctionalityTest, DocumentBuilderTest, DistributedSpellCheckComponentTest, TestGroupingSearch, TestManagedResource, TestHighlightDedupGrouping, TestFaceting, TestCustomSort, TestCharFilters, SynonymTokenizerTest, TestNonNRTOpen, ChangedSchemaMergeTest, TestInfoStreamLogging, ParsingFieldUpdateProcessorsTest, DirectUpdateHandlerOptimizeTest, TestRecoveryHdfs, BasicDistributedZkTest, TestWriterPerf, TestInitQParser, ZkControllerTest, TestSort, AnalysisAfterCoreReloadTest, TestOverriddenPrefixQueryForCustomFieldType, SolrXmlInZkTest, SchemaVersionSpecificBehaviorTest, TestConfig, TestSurroundQueryParser, TestStressVersions, CacheHeaderTest, StatelessScriptUpdateProcessorFactoryTest, HdfsWriteToMultipleCollectionsTest, UpdateRequestProcessorFactoryTest, TestDynamicFieldResource, ChaosMonkeySafeLeaderTest]
[junit4] Completed on J0 in 91.93s, 1 test, 1 failure <<< FAILURES!
[...truncated 650 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1296: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:920: There were test failures: 398 suites, 1673 tests, 1 failure, 37 ignored (16 assumptions)
Total time: 55 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_55 -XX:-UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.7.0_60-ea-b15) -
Build # 10243 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10243/
Java: 32bit/jdk1.7.0_60-ea-b15 -client -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.morphlines.cell.SolrCellMorphlineTest.testSolrCellDocumentTypes2
Error Message:
key:ignored_creation_date expected:<[2007-10-01T16:13:56Z]> but was:<[२००७-१०-०१T१६:१३:५६Z]>
Stack Trace:
java.lang.AssertionError: key:ignored_creation_date expected:<[2007-10-01T16:13:56Z]> but was:<[२००७-१०-०१T१६:१३:५६Z]>
at __randomizedtesting.SeedInfo.seed([F0853FAA008FEFB2:7B05844A07797C4C]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.apache.solr.morphlines.solr.AbstractSolrMorphlineTestBase.testDocumentTypesInternal(AbstractSolrMorphlineTestBase.java:170)
at org.apache.solr.morphlines.cell.SolrCellMorphlineTest.testSolrCellDocumentTypes2(SolrCellMorphlineTest.java:255)
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:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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:65)
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:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:360)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.morphlines.cell.SolrCellMorphlineTest.testSolrCellDocumentTypes
Error Message:
key:ignored_creation_date expected:<[2011-09-02T10:11:00Z]> but was:<[२०११-०९-०२T१०:११:००Z]>
Stack Trace:
java.lang.AssertionError: key:ignored_creation_date expected:<[2011-09-02T10:11:00Z]> but was:<[२०११-०९-०२T१०:११:००Z]>
at __randomizedtesting.SeedInfo.seed([F0853FAA008FEFB2:6A469C346554B167]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.apache.solr.morphlines.solr.AbstractSolrMorphlineTestBase.testDocumentTypesInternal(AbstractSolrMorphlineTestBase.java:170)
at org.apache.solr.morphlines.cell.SolrCellMorphlineTest.testSolrCellDocumentTypes(SolrCellMorphlineTest.java:193)
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:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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:65)
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:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:360)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 18895 lines...]
[junit4] Suite: org.apache.solr.morphlines.cell.SolrCellMorphlineTest
[junit4] 2> log4j:WARN No such property [conversionPattern] in org.apache.solr.util.SolrLogLayout.
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001
[junit4] 2> 532 T9 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 557 T9 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 585 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 1119 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 1120 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 1122 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 1122 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 1123 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 1124 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 1125 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 1126 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 1126 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 1127 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 1208 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 1409 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1415 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1462 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 2150 T9 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.ThaiWordFilterFactory]. Please consult documentation how to replace it accordingly.
[junit4] 2> 2188 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2355 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2364 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2447 T9 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2447 T9 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr
[junit4] 2> 2448 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/'
[junit4] 2> 2541 T9 oasc.CoreContainer.<init> New CoreContainer 17371297
[junit4] 2> 2542 T9 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/]
[junit4] 2> 2559 T9 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2560 T9 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 2565 T9 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2566 T9 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2566 T9 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2567 T9 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2567 T9 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2567 T9 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2568 T9 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2751 T9 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2753 T9 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2755 T9 oasc.CoreContainer.load Host Name:
[junit4] 2> 2859 T10 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 2913 T10 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 2913 T10 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 2914 T10 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 2914 T10 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 2915 T10 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 2915 T10 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 2915 T10 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 2916 T10 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 2916 T10 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 2917 T10 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 2959 T10 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 3039 T10 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3039 T10 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3060 T10 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 3537 T10 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3557 T10 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3563 T10 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3598 T10 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/
[junit4] 2> 3625 T10 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3638 T10 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/
[junit4] 2> 3641 T10 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 3659 T10 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 3660 T10 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4] 2> 3700 T10 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001
[junit4] 2> 3700 T10 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index/
[junit4] 2> 3701 T10 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 3710 T10 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index
[junit4] 2> 3754 T10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ad86c lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index),segFN=segments_1,generation=1}
[junit4] 2> 3755 T10 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3766 T10 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4] 2> 3766 T10 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 3767 T10 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4] 2> 3781 T10 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4] 2> 3782 T10 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4] 2> 4037 T10 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4064 T10 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 4065 T10 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4] 2> 4070 T10 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 4070 T10 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4] 2> 4078 T10 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 4083 T10 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.JsonUpdateRequestHandler]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4083 T10 oasc.RequestHandlers.initHandlersFromConfig created /update/json: solr.JsonUpdateRequestHandler
[junit4] 2> 4088 T10 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.CSVRequestHandler]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4089 T10 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: solr.CSVRequestHandler
[junit4] 2> 4089 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 2> 4090 T10 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 2> 4090 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 2> 4091 T10 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 2> 4097 T10 oasc.RequestHandlers.initHandlersFromConfig created /admin/: solr.admin.AdminHandlers
[junit4] 2> 4102 T10 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 4108 T10 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 4123 T10 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 4123 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 4124 T10 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4] 2> 4124 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 4124 T10 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4] 2> 4124 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 4125 T10 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4] 2> 4125 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 4126 T10 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4] 2> 4163 T10 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4168 T10 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4169 T10 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4196 T10 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 60000ms;
[junit4] 2> 4196 T10 oasu.CommitTracker.<init> Soft AutoCommit: if uncommited for 1000ms;
[junit4] 2> 4199 T10 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ad86c lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index),segFN=segments_1,generation=1}
[junit4] 2> 4199 T10 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 4213 T10 oass.SolrIndexSearcher.<init> Opening Searcher@173b55[collection1] main
[junit4] 2> 4226 T10 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/conf
[junit4] 2> 4227 T10 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/conf/}
[junit4] 2> 4251 T10 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/conf
[junit4] 2> 4252 T10 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 4252 T10 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 4252 T10 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 4253 T10 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 4272 T10 oass.DirectSolrSpellChecker.init init: {name=default,field=name,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 4291 T10 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4] 2> 4295 T10 oashc.QueryElevationComponent.inform Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 4362 T10 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 4363 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@173b55[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 4364 T10 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 4372 T9 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 4393 T9 oas.SolrTestCaseJ4.setUp ###Starting testSolrCellJPGCompressed
[junit4] 2> ASYNC NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 4465 T11 C0 oasc.SolrCore.execute [collection1] webapp=null path=null params={event=firstSearcher&distrib=false&q=static+firstSearcher+warming+in+solrconfig.xml} hits=0 status=0 QTime=100
[junit4] 2> 4467 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 4468 T11 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 4468 T11 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 4468 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@173b55[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 4562 T9 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1467319696367812608)} 0 144
[junit4] 2> 4565 T9 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4565 T9 C1 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 4566 T9 C1 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 4567 T9 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 4567 T9 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 3
[junit4] 2> 4774 T9 okma.MorphlineContext.importCommandBuilders Importing commands
[junit4] 2> 6162 T9 okma.MorphlineContext.importCommandBuilders Done importing commands
[junit4] 2> 7883 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 7928 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 7928 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 7929 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 7929 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 7930 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 7930 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 7931 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 7931 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 7932 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 7932 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 7977 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 8049 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 8050 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 8063 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 8455 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 8478 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 8482 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 8536 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 8565 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 8566 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 8567 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 8567 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 8567 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 8568 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 8568 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 8569 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 8569 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 8570 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 8608 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 8674 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 8674 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 8689 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 9058 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 9072 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 9076 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> ASYNC NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 9108 T9 C2 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1467319701197553664)} 0 1
[junit4] 2> 9109 T9 C2 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 9109 T9 C2 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 9110 T9 C2 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 9110 T9 C2 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 9111 T9 C2 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 2
[junit4] 2> ASYNC NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 9448 T9 C3 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testJPEG_EXIF.jpg#0 (1467319701499543552)]} 0 57
[junit4] 2> 9451 T9 C3 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> ASYNC NEW_CORE C4 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 9650 T9 C4 oass.SolrIndexSearcher.<init> Opening Searcher@b076d1[collection1] main
[junit4] 2> 9652 T9 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 9652 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@b076d1[collection1] main{StandardDirectoryReader(segments_1:3:nrt _0(5.0):C1)}
[junit4] 2> 9653 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 9653 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b076d1[collection1] main{StandardDirectoryReader(segments_1:3:nrt _0(5.0):C1)}
[junit4] 2> 9653 T9 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 202
[junit4] 2> 9664 T9 C4 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=1 status=0 QTime=8
[junit4] 2> 9716 T9 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testJPEG_EXIF.jpg.gz#0 (1467319701835087872)]} 0 5
[junit4] 2> 9717 T9 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 9742 T9 C4 oass.SolrIndexSearcher.<init> Opening Searcher@3887e3[collection1] main
[junit4] 2> 9743 T9 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 9743 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3887e3[collection1] main{StandardDirectoryReader(segments_1:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4] 2> 9744 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 9744 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3887e3[collection1] main{StandardDirectoryReader(segments_1:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4] 2> 9744 T9 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 27
[junit4] 2> 9745 T9 C4 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=2 status=0 QTime=0
[junit4] 2> 9769 T9 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[ce02190b-abbc-45dd-beee-bda71f397364#0 (1467319701889613824)]} 0 5
[junit4] 2> 9771 T9 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 9776 T9 C4 oass.SolrIndexSearcher.<init> Opening Searcher@1c6f5a7[collection1] main
[junit4] 2> 9777 T9 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 9777 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1c6f5a7[collection1] main{StandardDirectoryReader(segments_1:7:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4] 2> 9778 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 9778 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c6f5a7[collection1] main{StandardDirectoryReader(segments_1:7:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4] 2> 9779 T9 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 8
[junit4] 2> 9780 T9 C4 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=3 status=0 QTime=0
[junit4] 2> 9781 T9 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 9782 T9 C4 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 9782 T9 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 9783 T9 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 2
[junit4] 2> 9784 T9 C4 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=3 status=0 QTime=0
[junit4] 2> 9785 T9 oas.SolrTestCaseJ4.tearDown ###Ending testSolrCellJPGCompressed
[junit4] 2> 9798 T9 oas.SolrTestCaseJ4.setUp ###Starting testSolrCellDocumentTypes2
[junit4] 2> 9827 T9 C4 oass.SolrIndexSearcher.<init> Opening Searcher@6e7b63[collection1] realtime
[junit4] 2> 9828 T9 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1467319701923168256)} 0 29
[junit4] 2> 9829 T9 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 9829 T9 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ad86c lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ad86c lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index),segFN=segments_2,generation=2}
[junit4] 2> 9830 T9 C4 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 9831 T9 C4 oass.SolrIndexSearcher.<init> Opening Searcher@9b878f[collection1] main
[junit4] 2> 9831 T9 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 9831 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@9b878f[collection1] main{StandardDirectoryReader(segments_1:9:nrt)}
[junit4] 2> 9832 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 9832 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9b878f[collection1] main{StandardDirectoryReader(segments_1:9:nrt)}
[junit4] 2> 9834 T9 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 5
[junit4] 2> 9849 T9 okma.MorphlineContext.importCommandBuilders Importing commands
[junit4] 2> 10374 T9 okma.MorphlineContext.importCommandBuilders Done importing commands
[junit4] 2> 10895 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 10922 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 10923 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 10923 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 10924 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 10924 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 10924 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 10925 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 10925 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 10926 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 10926 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 10973 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 11046 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 11046 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 11057 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 11428 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 11442 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 11447 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 11473 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 11499 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 11500 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 11500 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 11500 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 11501 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 11501 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 11502 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 11502 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 11503 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 11503 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 11547 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 11615 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 11616 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 11625 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 12010 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 12022 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 12026 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 12559 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 12602 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 12603 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 12603 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 12604 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 12605 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 12606 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 12606 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 12607 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 12608 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 12609 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 12647 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 12714 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 12714 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 12723 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 13100 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 13110 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 13114 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 13133 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 13163 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 13163 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 13164 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 13164 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 13164 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 13165 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 13165 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 13166 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 13166 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 13167 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 13199 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 13260 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 13261 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 13270 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 13634 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 13645 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 13649 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 13798 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 13823 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 13824 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 13824 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 13825 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 13825 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 13826 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 13826 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 13827 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 13827 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 13827 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 13866 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 13929 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 13930 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 13938 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 14291 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 14300 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 14304 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> ASYNC NEW_CORE C5 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 14327 T9 C5 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1467319706670071808)} 0 1
[junit4] 2> 14327 T9 C5 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 14328 T9 C5 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 14328 T9 C5 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 14329 T9 C5 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 14329 T9 C5 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 2
[junit4] 2> ASYNC NEW_CORE C6 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 14698 T9 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testPPT_various.ppt#0 (1467319707053850624)]} 0 7
[junit4] 2> 14699 T9 C6 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 14707 T9 C6 oass.SolrIndexSearcher.<init> Opening Searcher@9fff91[collection1] main
[junit4] 2> 14707 T9 C6 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 14707 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@9fff91[collection1] main{StandardDirectoryReader(segments_2:11:nrt _3(5.0):C1)}
[junit4] 2> 14708 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 14708 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9fff91[collection1] main{StandardDirectoryReader(segments_2:11:nrt _3(5.0):C1)}
[junit4] 2> 14709 T9 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 10
[junit4] 2> 14710 T9 C6 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=1 status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C7 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 16208 T9 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testPPT_various.pptx#0 (1467319708638248960)]} 0 6
[junit4] 2> 16209 T9 C7 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 16216 T9 C7 oass.SolrIndexSearcher.<init> Opening Searcher@eea5ef[collection1] main
[junit4] 2> 16217 T9 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 16217 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@eea5ef[collection1] main{StandardDirectoryReader(segments_2:13:nrt _3(5.0):C1 _4(5.0):C1)}
[junit4] 2> 16218 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 16218 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@eea5ef[collection1] main{StandardDirectoryReader(segments_2:13:nrt _3(5.0):C1 _4(5.0):C1)}
[junit4] 2> 16218 T9 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 10
[junit4] 2> 16219 T9 C7 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=2 status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C8 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 16411 T9 C8 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testEXCEL.xlsx#0 (1467319708853207040)]} 0 4
[junit4] 2> 16412 T9 C8 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 16418 T9 C8 oass.SolrIndexSearcher.<init> Opening Searcher@18b9ad4[collection1] main
[junit4] 2> 16419 T9 C8 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 16419 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@18b9ad4[collection1] main{StandardDirectoryReader(segments_2:15:nrt _3(5.0):C1 _4(5.0):C1 _5(5.0):C1)}
[junit4] 2> 16419 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 16420 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18b9ad4[collection1] main{StandardDirectoryReader(segments_2:15:nrt _3(5.0):C1 _4(5.0):C1 _5(5.0):C1)}
[junit4] 2> 16420 T9 C8 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 8
[junit4] 2> 16421 T9 C8 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=3 status=0 QTime=0
[junit4] 2> 16424 T9 oas.SolrTestCaseJ4.tearDown ###Ending testSolrCellDocumentTypes2
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrCellMorphlineTest -Dtests.method=testSolrCellDocumentTypes2 -Dtests.seed=F0853FAA008FEFB2 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=hi_IN -Dtests.timezone=Asia/Makassar -Dtests.file.encoding=UTF-8
[junit4] FAILURE 6.66s | SolrCellMorphlineTest.testSolrCellDocumentTypes2 <<<
[junit4] > Throwable #1: java.lang.AssertionError: key:ignored_creation_date expected:<[2007-10-01T16:13:56Z]> but was:<[२००७-१०-०१T१६:१३:५६Z]>
[junit4] > at __randomizedtesting.SeedInfo.seed([F0853FAA008FEFB2:7B05844A07797C4C]:0)
[junit4] > at org.apache.solr.morphlines.solr.AbstractSolrMorphlineTestBase.testDocumentTypesInternal(AbstractSolrMorphlineTestBase.java:170)
[junit4] > at org.apache.solr.morphlines.cell.SolrCellMorphlineTest.testSolrCellDocumentTypes2(SolrCellMorphlineTest.java:255)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 16455 T9 oas.SolrTestCaseJ4.setUp ###Starting testSolrCellXML
[junit4] 2> 16457 T9 C8 oass.SolrIndexSearcher.<init> Opening Searcher@128ec39[collection1] realtime
[junit4] 2> 16458 T9 C8 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1467319708903538688)} 0 2
[junit4] 2> 16458 T9 C8 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 16459 T9 C8 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ad86c lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ad86c lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index),segFN=segments_3,generation=3}
[junit4] 2> 16459 T9 C8 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 16460 T9 C8 oass.SolrIndexSearcher.<init> Opening Searcher@e3f6a3[collection1] main
[junit4] 2> 16460 T9 C8 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 16460 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@e3f6a3[collection1] main{StandardDirectoryReader(segments_2:17:nrt)}
[junit4] 2> 16461 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 16461 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e3f6a3[collection1] main{StandardDirectoryReader(segments_2:17:nrt)}
[junit4] 2> 16464 T9 C8 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 6
[junit4] 2> 16470 T9 okma.MorphlineContext.importCommandBuilders Importing commands
[junit4] 2> 17093 T9 okma.MorphlineContext.importCommandBuilders Done importing commands
[junit4] 2> 17095 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 17122 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 17123 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 17123 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 17124 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 17124 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 17125 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 17125 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 17126 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 17126 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 17126 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 17159 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 17223 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 17223 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 17233 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 17583 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 17592 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 17596 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 17615 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 17646 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 17647 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 17647 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 17648 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 17648 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 17648 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 17649 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 17649 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 17650 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 17650 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 17682 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 17746 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 17746 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 17755 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 18104 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 18113 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 18117 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 18136 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 18162 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 18162 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 18163 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 18163 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 18164 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 18164 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 18164 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 18165 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 18165 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 18166 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 18201 T9 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 18260 T9 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 18260 T9 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 18269 T9 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 18617 T9 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 18630 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 18634 T9 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> ASYNC NEW_CORE C9 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 18655 T9 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1467319711208308736)} 0 1
[junit4] 2> 18655 T9 C9 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18656 T9 C9 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 18656 T9 C9 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 18657 T9 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 18657 T9 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 2
[junit4] 2> 18665 T9 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testXML2.xml#0 (1467319711218794496)]} 0 1
[junit4] 2> 18665 T9 C9 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 18667 T9 C9 oass.SolrIndexSearcher.<init> Opening Searcher@64cf35[collection1] main
[junit4] 2> 18668 T9 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 18668 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@64cf35[collection1] main{StandardDirectoryReader(segments_3:19:nrt _6(5.0):C1)}
[junit4] 2> 18669 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 18669 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64cf35[collection1] main{StandardDirectoryReader(segments_3:19:nrt _6(5.0):C1)}
[junit4] 2> 18669 T9 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 4
[junit4] 2> 18670 T9 C9 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=1 status=0 QTime=0
[junit4] 2> 18671 T9 C9 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 18671 T9 C9 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 18671 T9 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 18672 T9 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 1
[junit4] 2> 18672 T9 C9 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=1 status=0 QTime=0
[junit4] 2> 18673 T9 oas.SolrTestCaseJ4.tearDown ###Ending testSolrCellXML
[junit4] 2> 18679 T9 oas.SolrTestCaseJ4.setUp ###Starting testSolrCellDocumentTypes
[junit4] 2> 18680 T9 C9 oass.SolrIndexSearcher.<init> Opening Searcher@1a67731[collection1] realtime
[junit4] 2> 18680 T9 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1467319711234523136)} 0 1
[junit4] 2> 18681 T9 C9 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18681 T9 C9 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ad86c lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ad86c lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index),segFN=segments_4,generation=4}
[junit4] 2> 18682 T9 C9 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 18682 T9 C9 oass.SolrIndexSearcher.<init> Opening Searcher@1412d31[collection1] main
[junit4] 2> 18683 T9 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 18683 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1412d31[collection1] main{StandardDirectoryReader(segments_3:21:nrt)}
[junit4] 2> 18683 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 18684 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1412d31[collection1] main{StandardDirectoryReader(segments_3:21:nrt)}
[junit4] 2> 18685 T9 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 4
[junit4] 2> 18694 T9 okma.MorphlineContext.importCommandBuilders Importing commands
[junit4] 2> 19163 T9 okma.MorphlineContext.importCommandBuilders Done importing commands
[junit4] 2> 19531 T9 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 19557 T9 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 19558 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 19558 T9 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2>
[...truncated too long message...]
oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 16
[junit4] 2> 22812 T9 C11 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=3 status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C12 name=collection1 org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 23053 T9 C12 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testWORD_various.doc#0 (1467319715818897408)]} 0 2
[junit4] 2> 23054 T9 C12 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 23059 T9 C12 oass.SolrIndexSearcher.<init> Opening Searcher@1a4b72a[collection1] main
[junit4] 2> 23059 T9 C12 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 23059 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1a4b72a[collection1] main{StandardDirectoryReader(segments_4:29:nrt _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1)}
[junit4] 2> 23060 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 23060 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a4b72a[collection1] main{StandardDirectoryReader(segments_4:29:nrt _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1)}
[junit4] 2> 23061 T9 C12 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 7
[junit4] 2> 23061 T9 C12 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=4 status=0 QTime=0
[junit4] 2> 23063 T9 oas.SolrTestCaseJ4.tearDown ###Ending testSolrCellDocumentTypes
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrCellMorphlineTest -Dtests.method=testSolrCellDocumentTypes -Dtests.seed=F0853FAA008FEFB2 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=hi_IN -Dtests.timezone=Asia/Makassar -Dtests.file.encoding=UTF-8
[junit4] FAILURE 4.39s | SolrCellMorphlineTest.testSolrCellDocumentTypes <<<
[junit4] > Throwable #1: java.lang.AssertionError: key:ignored_creation_date expected:<[2011-09-02T10:11:00Z]> but was:<[२०११-०९-०२T१०:११:००Z]>
[junit4] > at __randomizedtesting.SeedInfo.seed([F0853FAA008FEFB2:6A469C346554B167]:0)
[junit4] > at org.apache.solr.morphlines.solr.AbstractSolrMorphlineTestBase.testDocumentTypesInternal(AbstractSolrMorphlineTestBase.java:170)
[junit4] > at org.apache.solr.morphlines.cell.SolrCellMorphlineTest.testSolrCellDocumentTypes(SolrCellMorphlineTest.java:193)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 23073 T9 oas.SolrTestCaseJ4.setUp ###Starting testTransformValue
[junit4] 2> 23074 T9 C12 oass.SolrIndexSearcher.<init> Opening Searcher@f89555[collection1] realtime
[junit4] 2> 23075 T9 C12 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1467319715841966080)} 0 2
[junit4] 2> 23075 T9 C12 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 23076 T9 C12 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ad86c lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ad86c lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index),segFN=segments_5,generation=5}
[junit4] 2> 23076 T9 C12 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 23077 T9 C12 oass.SolrIndexSearcher.<init> Opening Searcher@12d0c28[collection1] main
[junit4] 2> 23077 T9 C12 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 23077 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@12d0c28[collection1] main{StandardDirectoryReader(segments_4:31:nrt)}
[junit4] 2> 23078 T11 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 23079 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12d0c28[collection1] main{StandardDirectoryReader(segments_4:31:nrt)}
[junit4] 2> 23083 T9 C12 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 8
[junit4] 2> 23086 T9 oas.SolrTestCaseJ4.tearDown ###Ending testTransformValue
[junit4] 2> 23088 T9 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 23088 T9 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=17371297
[junit4] 2> 23089 T9 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@bc9162
[junit4] 2> 23090 T9 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=22,autocommit maxTime=60000ms,autocommits=0,soft autocommit maxTime=1000ms,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=11,cumulative_deletesById=0,cumulative_deletesByQuery=9,cumulative_errors=0,transaction_logs_total_size=33638,transaction_logs_total_number=9}
[junit4] 2> 23091 T9 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 23091 T9 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 23092 T9 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 23093 T9 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 23093 T9 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 23094 T9 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index;done=false>>]
[junit4] 2> 23094 T9 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001/index
[junit4] 2> 23095 T9 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001;done=false>>]
[junit4] 2> 23095 T9 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001/init-core-data-001
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-F0853FAA008FEFB2-001
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=DefaultSimilarity, locale=hi_IN, timezone=Asia/Makassar
[junit4] 2> NOTE: Linux 3.8.0-39-generic i386/Oracle Corporation 1.7.0_60-ea (32-bit)/cpus=8,threads=1,free=57726304,total=130510848
[junit4] 2> NOTE: All tests run in this JVM: [SolrCellMorphlineTest]
[junit4] Completed in 23.26s, 5 tests, 2 failures <<< FAILURES!
[...truncated 10 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:209: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:440: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1296: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:920: There were test failures: 1 suite, 5 tests, 2 failures
Total time: 66 minutes 3 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_60-ea-b15 -client -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure