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/11/17 15:48:19 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.7.0_67) - Build #
11626 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11626/
Java: 32bit/jdk1.7.0_67 -server -XX:+UseG1GC (asserts: true)
1 tests failed.
REGRESSION: org.apache.solr.search.mlt.CloudMLTQParserTest.testDistribSearch
Error Message:
java.lang.String cannot be cast to java.util.ArrayList
Stack Trace:
java.lang.ClassCastException: java.lang.String cannot be cast to java.util.ArrayList
at __randomizedtesting.SeedInfo.seed([47B099DBE8078105:C65617C39F58E139]:0)
at org.apache.solr.search.mlt.CloudMLTQParserTest.doTest(CloudMLTQParserTest.java:124)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
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.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:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
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:54)
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:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11218 lines...]
[junit4] Suite: org.apache.solr.search.mlt.CloudMLTQParserTest
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/init-core-data-001
[junit4] 2> 1028853 T2777 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1028853 T2777 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /zb_pq/j
[junit4] 2> 1028858 T2777 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 1028858 T2777 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1028859 T2778 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1028959 T2777 oasc.ZkTestServer.run start zk server on port:35353
[junit4] 2> 1028960 T2777 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1028960 T2777 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1028963 T2784 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7c5896 name:ZooKeeperConnection Watcher:127.0.0.1:35353 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1028963 T2777 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1028964 T2777 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1028964 T2777 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1028967 T2777 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1028969 T2777 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1028969 T2786 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19f59bb name:ZooKeeperConnection Watcher:127.0.0.1:35353/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1028970 T2777 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1028970 T2777 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1028971 T2777 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1028974 T2777 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1028977 T2777 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1028980 T2777 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1028983 T2777 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1028984 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1028988 T2777 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1028989 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1028993 T2777 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> 1028994 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1028997 T2777 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> 1028998 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1029001 T2777 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> 1029002 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1029004 T2777 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> 1029005 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1029008 T2777 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> 1029009 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1029012 T2777 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> 1029012 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1029015 T2777 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> 1029016 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1029018 T2777 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> 1029018 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1029021 T2777 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> 1029022 T2777 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1029407 T2777 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1029412 T2777 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53495
[junit4] 2> 1029413 T2777 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1029413 T2777 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1029414 T2777 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-002
[junit4] 2> 1029414 T2777 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-002/'
[junit4] 2> 1029433 T2777 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-002/solr.xml
[junit4] 2> 1029462 T2777 oasc.CoreContainer.<init> New CoreContainer 8098953
[junit4] 2> 1029463 T2777 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-002/]
[junit4] 2> 1029464 T2777 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1029464 T2777 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1029464 T2777 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1029465 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1029465 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1029466 T2777 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1029466 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1029466 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1029467 T2777 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1029467 T2777 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1029468 T2777 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1029468 T2777 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1029469 T2777 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1029469 T2777 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1029469 T2777 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:35353/solr
[junit4] 2> 1029470 T2777 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1029470 T2777 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1029471 T2777 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1029472 T2797 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@154e3f6 name:ZooKeeperConnection Watcher:127.0.0.1:35353 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1029473 T2777 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1029474 T2777 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1029477 T2777 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1029478 T2799 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@798f0b name:ZooKeeperConnection Watcher:127.0.0.1:35353/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1029478 T2777 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1029481 T2777 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1029485 T2777 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1029489 T2777 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 1029493 T2777 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 1029496 T2777 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 1029500 T2777 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1029503 T2777 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53495_zb_pq%2Fj
[junit4] 2> 1029504 T2777 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53495_zb_pq%2Fj
[junit4] 2> 1029508 T2777 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 1029510 T2777 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1029513 T2777 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1029515 T2777 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53495_zb_pq%2Fj
[junit4] 2> 1029516 T2777 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1029519 T2777 oasc.Overseer.start Overseer (id=92814272273842179-127.0.0.1:53495_zb_pq%2Fj-n_0000000000) starting
[junit4] 2> 1029523 T2777 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1029537 T2777 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1029538 T2801 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1029539 T2777 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1029542 T2777 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1029545 T2777 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1029551 T2800 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1029555 T2803 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 1029555 T2803 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1029557 T2803 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1029557 T2804 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1029563 T2800 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53495/zb_pq/j",
[junit4] 2> "numShards":"1",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:53495_zb_pq%2Fj",
[junit4] 2> "state":"down"}
[junit4] 2> 1029563 T2800 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 1029563 T2800 oasc.Overseer$ClusterStateUpdater.createCollection state version control_collection 1
[junit4] 2> 1029564 T2800 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1029567 T2804 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> 1030557 T2803 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1030558 T2803 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 1030559 T2803 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1030559 T2803 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1030561 T2803 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1030561 T2803 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-002/collection1/'
[junit4] 2> 1030562 T2803 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 1030562 T2803 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 1030563 T2803 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 1030622 T2803 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1030676 T2803 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1030677 T2803 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1030686 T2803 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1030956 T2803 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1030960 T2803 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1030962 T2803 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1030971 T2803 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1030974 T2803 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1030976 T2803 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1030977 T2803 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1030977 T2803 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1030977 T2803 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1030978 T2803 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1030978 T2803 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1030979 T2803 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 1030979 T2803 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1030979 T2803 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-002/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/control/data/
[junit4] 2> 1030979 T2803 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1da0143
[junit4] 2> 1030980 T2803 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/control/data
[junit4] 2> 1030980 T2803 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/control/data/index/
[junit4] 2> 1030981 T2803 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 1030981 T2803 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/control/data/index
[junit4] 2> 1030982 T2803 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=79.8798828125, floorSegmentMB=1.125, forceMergeDeletesPctAllowed=18.8949318909279, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7281777660785291
[junit4] 2> 1031084 T2803 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b8fba9)),segFN=segments_1,generation=1}
[junit4] 2> 1031085 T2803 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1031086 T2803 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1031092 T2803 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1031093 T2803 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1031093 T2803 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1031094 T2803 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1031094 T2803 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1031095 T2803 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1031095 T2803 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1031096 T2803 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1031096 T2803 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1031097 T2803 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1031097 T2803 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1031098 T2803 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1031099 T2803 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1031099 T2803 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1031100 T2803 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1031100 T2803 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1031100 T2803 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1031100 T2803 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1031101 T2803 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1031101 T2803 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1031101 T2803 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1031102 T2803 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1031102 T2803 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1031102 T2803 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1031103 T2803 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1031103 T2803 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1031103 T2803 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1031103 T2803 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1031104 T2803 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1031104 T2803 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1031105 T2803 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1031105 T2803 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1031105 T2803 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1031105 T2803 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1031106 T2803 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1031106 T2803 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1031106 T2803 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1031107 T2803 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1031107 T2803 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1031110 T2803 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1031111 T2803 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1031111 T2803 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1031112 T2803 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1031113 T2803 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1031115 T2803 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1031116 T2803 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1031116 T2803 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1031117 T2803 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b8fba9)),segFN=segments_1,generation=1}
[junit4] 2> 1031118 T2803 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1031118 T2803 oass.SolrIndexSearcher.<init> Opening Searcher@1d1be3e[collection1] main
[junit4] 2> 1031118 T2803 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1031119 T2803 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1031119 T2803 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1031119 T2803 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1031120 T2803 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 1031120 T2803 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 1031120 T2803 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1031120 T2803 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1031123 T2803 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1031142 T2805 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d1be3e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1031148 T2803 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1031149 T2808 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53495/zb_pq/j collection:control_collection shard:shard1
[junit4] 2> 1031149 T2777 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1031150 T2808 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1031150 T2777 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1031151 T2777 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1031151 T2777 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1031152 T2810 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@189f741 name:ZooKeeperConnection Watcher:127.0.0.1:35353/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1031153 T2777 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1031153 T2777 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1031155 T2777 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1031155 T2808 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1031157 T2804 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1031157 T2808 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1031157 T2808 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C541 name=collection1 org.apache.solr.core.SolrCore@71924c url=http://127.0.0.1:53495/zb_pq/j/collection1 node=127.0.0.1:53495_zb_pq%2Fj C541_STATE=coll:control_collection core:collection1 props:{base_url=http://127.0.0.1:53495/zb_pq/j, core=collection1, node_name=127.0.0.1:53495_zb_pq%2Fj, state=down}
[junit4] 2> 1031157 T2808 C541 P53495 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53495/zb_pq/j/collection1/
[junit4] 2> 1031158 T2808 C541 P53495 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1031158 T2777 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1031158 T2808 C541 P53495 oasc.SyncStrategy.syncToMe http://127.0.0.1:53495/zb_pq/j/collection1/ has no replicas
[junit4] 2> 1031159 T2808 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53495/zb_pq/j/collection1/ shard1
[junit4] 2> 1031159 T2808 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1031160 T2804 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> 1031160 T2811 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> 1031162 T2812 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1031266 T2812 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> 1031266 T2811 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> 1031313 T2808 oasc.ZkController.register We are http://127.0.0.1:53495/zb_pq/j/collection1/ and leader is http://127.0.0.1:53495/zb_pq/j/collection1/
[junit4] 2> 1031314 T2808 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53495/zb_pq/j
[junit4] 2> 1031314 T2808 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1031314 T2808 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 1031314 T2808 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1031315 T2812 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1031318 T2800 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "roles":null,
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "base_url":"http://127.0.0.1:53495/zb_pq/j",
[junit4] 2> "numShards":"2",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "node_name":"127.0.0.1:53495_zb_pq%2Fj",
[junit4] 2> "state":"active"}
[junit4] 2> 1031416 T2777 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 1031417 T2777 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1031418 T2777 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60581
[junit4] 2> 1031419 T2777 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1031420 T2777 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1031420 T2812 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> 1031420 T2811 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> 1031420 T2777 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-003
[junit4] 2> 1031421 T2777 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-003/'
[junit4] 2> 1031434 T2777 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/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-003/solr.xml
[junit4] 2> 1031461 T2777 oasc.CoreContainer.<init> New CoreContainer 20318169
[junit4] 2> 1031461 T2777 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-003/]
[junit4] 2> 1031462 T2777 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1031463 T2777 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1031463 T2777 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1031463 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1031464 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1031464 T2777 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1031464 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1031465 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1031465 T2777 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1031465 T2777 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1031466 T2777 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1031466 T2777 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1031466 T2777 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1031467 T2777 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1031467 T2777 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:35353/solr
[junit4] 2> 1031467 T2777 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1031468 T2777 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1031468 T2777 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1031470 T2823 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3d6343 name:ZooKeeperConnection Watcher:127.0.0.1:35353 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1031470 T2777 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1031471 T2777 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1031473 T2777 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1031474 T2825 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bd40c6 name:ZooKeeperConnection Watcher:127.0.0.1:35353/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1031474 T2777 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1031478 T2777 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1032481 T2777 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60581_zb_pq%2Fj
[junit4] 2> 1032482 T2777 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60581_zb_pq%2Fj
[junit4] 2> 1032486 T2777 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1032490 T2827 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1032490 T2827 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1032491 T2812 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1032491 T2827 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1032493 T2800 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:60581/zb_pq/j",
[junit4] 2> "numShards":"2",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:60581_zb_pq%2Fj",
[junit4] 2> "state":"down"}
[junit4] 2> 1032493 T2800 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 1032493 T2800 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 1
[junit4] 2> 1032493 T2800 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 1032596 T2826 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> 1032596 T2811 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> 1032596 T2812 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> 1033491 T2827 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1033492 T2827 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1033493 T2827 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1033493 T2827 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1033494 T2827 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1033494 T2827 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-003/collection1/'
[junit4] 2> 1033495 T2827 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 1033496 T2827 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 1033496 T2827 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 1033543 T2827 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1033585 T2827 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1033586 T2827 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1033592 T2827 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1033803 T2827 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1033808 T2827 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1033810 T2827 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1033818 T2827 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1033821 T2827 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1033824 T2827 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1033825 T2827 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1033826 T2827 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1033826 T2827 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1033827 T2827 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1033828 T2827 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1033828 T2827 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1033828 T2827 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1033828 T2827 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-003/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty1/
[junit4] 2> 1033829 T2827 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1da0143
[junit4] 2> 1033829 T2827 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty1
[junit4] 2> 1033830 T2827 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty1/index/
[junit4] 2> 1033830 T2827 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 1033831 T2827 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty1/index
[junit4] 2> 1033831 T2827 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=79.8798828125, floorSegmentMB=1.125, forceMergeDeletesPctAllowed=18.8949318909279, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7281777660785291
[junit4] 2> 1033838 T2827 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/index-SimpleFSDirectory-002 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b8fba9)),segFN=segments_1,generation=1}
[junit4] 2> 1033839 T2827 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1033840 T2827 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1033845 T2827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1033846 T2827 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1033846 T2827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1033846 T2827 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1033847 T2827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1033847 T2827 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1033847 T2827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1033848 T2827 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1033848 T2827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1033849 T2827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1033849 T2827 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1033850 T2827 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1033850 T2827 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1033851 T2827 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1033851 T2827 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1033852 T2827 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1033852 T2827 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1033853 T2827 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1033853 T2827 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1033854 T2827 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1033854 T2827 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1033855 T2827 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1033855 T2827 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1033856 T2827 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1033856 T2827 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1033857 T2827 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1033857 T2827 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1033857 T2827 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1033858 T2827 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1033859 T2827 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1033859 T2827 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1033859 T2827 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1033860 T2827 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1033860 T2827 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1033861 T2827 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1033861 T2827 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1033861 T2827 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1033862 T2827 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1033862 T2827 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1033865 T2827 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1033866 T2827 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1033867 T2827 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1033868 T2827 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1033869 T2827 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1033870 T2827 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1033870 T2827 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1033902 T2827 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1033903 T2827 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/index-SimpleFSDirectory-002 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b8fba9)),segFN=segments_1,generation=1}
[junit4] 2> 1033904 T2827 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1033904 T2827 oass.SolrIndexSearcher.<init> Opening Searcher@1aa7161[collection1] main
[junit4] 2> 1033904 T2827 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1033905 T2827 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1033906 T2827 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1033906 T2827 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1033906 T2827 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 1033907 T2827 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 1033907 T2827 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1033907 T2827 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1033911 T2827 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1033936 T2828 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1aa7161[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1033944 T2827 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1033945 T2831 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60581/zb_pq/j collection:collection1 shard:shard2
[junit4] 2> 1033945 T2777 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1033946 T2777 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1033946 T2831 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 1033950 T2831 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 1033952 T2812 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1033952 T2831 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1033952 T2831 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C542 name=collection1 org.apache.solr.core.SolrCore@152fb3a url=http://127.0.0.1:60581/zb_pq/j/collection1 node=127.0.0.1:60581_zb_pq%2Fj C542_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:60581/zb_pq/j, core=collection1, node_name=127.0.0.1:60581_zb_pq%2Fj, state=down}
[junit4] 2> 1033952 T2831 C542 P60581 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60581/zb_pq/j/collection1/
[junit4] 2> 1033952 T2831 C542 P60581 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1033953 T2831 C542 P60581 oasc.SyncStrategy.syncToMe http://127.0.0.1:60581/zb_pq/j/collection1/ has no replicas
[junit4] 2> 1033953 T2831 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60581/zb_pq/j/collection1/ shard2
[junit4] 2> 1033953 T2831 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 1033957 T2812 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1034059 T2812 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> 1034060 T2826 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> 1034060 T2811 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> 1034108 T2831 oasc.ZkController.register We are http://127.0.0.1:60581/zb_pq/j/collection1/ and leader is http://127.0.0.1:60581/zb_pq/j/collection1/
[junit4] 2> 1034108 T2831 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60581/zb_pq/j
[junit4] 2> 1034108 T2831 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1034109 T2831 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 1034109 T2831 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1034111 T2812 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1034113 T2800 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "roles":null,
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "base_url":"http://127.0.0.1:60581/zb_pq/j",
[junit4] 2> "numShards":"2",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "node_name":"127.0.0.1:60581_zb_pq%2Fj",
[junit4] 2> "state":"active"}
[junit4] 2> 1034208 T2777 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 1034209 T2777 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1034211 T2777 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60451
[junit4] 2> 1034212 T2777 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1034212 T2777 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1034213 T2777 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-004
[junit4] 2> 1034213 T2777 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-004/'
[junit4] 2> 1034215 T2812 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> 1034215 T2826 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> 1034215 T2811 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> 1034226 T2777 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-004/solr.xml
[junit4] 2> 1034253 T2777 oasc.CoreContainer.<init> New CoreContainer 8260393
[junit4] 2> 1034254 T2777 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-004/]
[junit4] 2> 1034255 T2777 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1034255 T2777 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1034255 T2777 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1034256 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1034256 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1034256 T2777 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1034257 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1034257 T2777 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1034257 T2777 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1034258 T2777 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1034258 T2777 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1034258 T2777 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1034259 T2777 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1034259 T2777 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1034260 T2777 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:35353/solr
[junit4] 2> 1034260 T2777 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1034260 T2777 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1034261 T2777 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1034262 T2842 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17895c3 name:ZooKeeperConnection Watcher:127.0.0.1:35353 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1034263 T2777 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1034263 T2777 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1034265 T2777 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1034266 T2844 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f289a9 name:ZooKeeperConnection Watcher:127.0.0.1:35353/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1034266 T2777 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1034271 T2777 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1035274 T2777 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60451_zb_pq%2Fj
[junit4] 2> 1035276 T2777 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60451_zb_pq%2Fj
[junit4] 2> 1035281 T2777 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1035289 T2846 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1035289 T2846 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1035290 T2812 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1035290 T2846 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1035294 T2800 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:60451/zb_pq/j",
[junit4] 2> "numShards":"2",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:60451_zb_pq%2Fj",
[junit4] 2> "state":"down"}
[junit4] 2> 1035294 T2800 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 1035294 T2800 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1035398 T2812 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> 1035398 T2845 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> 1035398 T2811 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> 1035398 T2826 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> 1036291 T2846 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1036291 T2846 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1036292 T2846 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1036293 T2846 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1036294 T2846 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1036295 T2846 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-004/collection1/'
[junit4] 2> 1036296 T2846 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 1036296 T2846 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 1036297 T2846 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 1036348 T2846 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1036391 T2846 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1036392 T2846 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1036398 T2846 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1036675 T2846 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1036680 T2846 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1036682 T2846 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1036691 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1036694 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1036696 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1036697 T2846 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1036698 T2846 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1036698 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1036699 T2846 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1036699 T2846 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1036699 T2846 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1036699 T2846 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1036700 T2846 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-004/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty2/
[junit4] 2> 1036700 T2846 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1da0143
[junit4] 2> 1036700 T2846 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty2
[junit4] 2> 1036701 T2846 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty2/index/
[junit4] 2> 1036701 T2846 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 1036702 T2846 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty2/index
[junit4] 2> 1036702 T2846 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=79.8798828125, floorSegmentMB=1.125, forceMergeDeletesPctAllowed=18.8949318909279, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7281777660785291
[junit4] 2> 1036708 T2846 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/index-SimpleFSDirectory-003 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b8fba9)),segFN=segments_1,generation=1}
[junit4] 2> 1036708 T2846 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1036709 T2846 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1036712 T2846 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1036713 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1036713 T2846 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1036713 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1036713 T2846 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1036713 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1036714 T2846 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1036714 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1036714 T2846 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1036714 T2846 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1036715 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1036715 T2846 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1036715 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1036716 T2846 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1036716 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1036717 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1036717 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1036717 T2846 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1036717 T2846 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1036718 T2846 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1036718 T2846 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1036719 T2846 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1036719 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1036719 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1036720 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1036720 T2846 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1036720 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1036721 T2846 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1036721 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1036721 T2846 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1036722 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1036722 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1036722 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1036723 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1036723 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1036724 T2846 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1036724 T2846 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1036724 T2846 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1036725 T2846 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1036727 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1036728 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1036729 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1036730 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1036731 T2846 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1036733 T2846 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1036733 T2846 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1036734 T2846 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1036735 T2846 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/index-SimpleFSDirectory-003 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b8fba9)),segFN=segments_1,generation=1}
[junit4] 2> 1036735 T2846 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1036735 T2846 oass.SolrIndexSearcher.<init> Opening Searcher@38cb9c[collection1] main
[junit4] 2> 1036736 T2846 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1036737 T2846 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1036737 T2846 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1036737 T2846 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1036737 T2846 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 1036737 T2846 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 1036738 T2846 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1036738 T2846 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1036741 T2846 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1036762 T2847 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@38cb9c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1036768 T2846 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1036769 T2850 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60451/zb_pq/j collection:collection1 shard:shard1
[junit4] 2> 1036769 T2777 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1036770 T2777 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1036770 T2850 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 1036773 T2850 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1036775 T2812 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1036775 T2850 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1036775 T2850 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C543 name=collection1 org.apache.solr.core.SolrCore@91a724 url=http://127.0.0.1:60451/zb_pq/j/collection1 node=127.0.0.1:60451_zb_pq%2Fj C543_S
[...truncated too long message...]
sting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1071678 T2777 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1071679 T2777 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty4/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty4/index;done=false>>]
[junit4] 2> 1071679 T2777 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty4/index
[junit4] 2> 1071680 T2777 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty4 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty4;done=false>>]
[junit4] 2> 1071680 T2777 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001/tempDir-001/jetty4
[junit4] 2> 1071681 T2777 oasc.Overseer.close Overseer (id=92814272273842188-127.0.0.1:47011_zb_pq%2Fj-n_0000000004) closing
[junit4] 2> 1071682 T2922 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:47011_zb_pq%2Fj
[junit4] 2> 1071684 T2884 oaz.ClientCnxn$EventThread.processEvent ERROR Error while calling watcher java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@1fe6b85 rejected from java.util.concurrent.ThreadPoolExecutor@450267[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 20]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2048)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:821)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1372)
[junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:110)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:264)
[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> 1071708 T2777 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/zb_pq/j,null}
[junit4] 2> 1076697 T2777 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 1076699 T2777 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:35353 35353
[junit4] 2> 1077349 T2778 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:35353 35353
[junit4] 2> 1077350 T2778 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CloudMLTQParserTest -Dtests.method=testDistribSearch -Dtests.seed=47B099DBE8078105 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=zh -Dtests.timezone=America/Louisville -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 48.5s J0 | CloudMLTQParserTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.ClassCastException: java.lang.String cannot be cast to java.util.ArrayList
[junit4] > at __randomizedtesting.SeedInfo.seed([47B099DBE8078105:C65617C39F58E139]:0)
[junit4] > at org.apache.solr.search.mlt.CloudMLTQParserTest.doTest(CloudMLTQParserTest.java:124)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1077357 T2777 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.CloudMLTQParserTest-47B099DBE8078105-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {lowerfilt=PostingsFormat(name=Memory doPackFST= true), id=PostingsFormat(name=Direct), timestamp=PostingsFormat(name=Memory doPackFST= true), intDefault=PostingsFormat(name=Memory doPackFST= false), lowerfilt1and2=PostingsFormat(name=Memory doPackFST= true), multiDefault=PostingsFormat(name=Memory doPackFST= true), range_facet_l=PostingsFormat(name=Memory doPackFST= true), _version_=PostingsFormat(name=Memory doPackFST= false)}, docValues:{timestamp=DocValuesFormat(name=Asserting)}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=zh, timezone=America/Louisville
[junit4] 2> NOTE: Linux 3.13.0-39-generic i386/Oracle Corporation 1.7.0_67 (32-bit)/cpus=8,threads=1,free=90631088,total=510656512
[junit4] 2> NOTE: All tests run in this JVM: [TestBinaryResponseWriter, SyncSliceTest, TestFastWriter, TestWordDelimiterFilterFactory, TestCoreDiscovery, AnalysisErrorHandlingTest, TestChildDocTransformer, TestAnalyzedSuggestions, TestStressVersions, HdfsSyncSliceTest, TestNonDefinedSimilarityFactory, TestOrdValues, TestRTGBase, BasicFunctionalityTest, HdfsRecoveryZkTest, QueryEqualityTest, DistributedQueryComponentOptimizationTest, CustomCollectionTest, TestIntervalFaceting, XmlUpdateRequestHandlerTest, CoreAdminHandlerTest, ExitableDirectoryReaderTest, ZkControllerTest, ReplicationFactorTest, OverseerCollectionProcessorTest, TestPartialUpdateDeduplication, DocumentBuilderTest, TestSystemIdResolver, TestConfig, TestComponentsName, TestModifyConfFiles, JsonLoaderTest, SpellCheckCollatorTest, ShardRoutingTest, TestLuceneMatchVersion, TestOverriddenPrefixQueryForCustomFieldType, TestSolrQueryParserDefaultOperatorResource, UnloadDistributedZkTest, SuggesterTSTTest, DirectUpdateHandlerTest, PingRequestHandlerTest, TestManagedResource, DeleteInactiveReplicaTest, TestFaceting, TestArbitraryIndexDir, VMParamsZkACLAndCredentialsProvidersTest, TestStressRecovery, HdfsUnloadDistributedZkTest, TestShardHandlerFactory, TestDistributedSearch, TestDocBasedVersionConstraints, TestFieldResource, TestAddFieldRealTimeGet, TestShortCircuitedRequests, SolrCmdDistributorTest, StatelessScriptUpdateProcessorFactoryTest, AtomicUpdatesTest, DateRangeFieldTest, RegexBoostProcessorTest, CloudMLTQParserTest]
[junit4] Completed on J0 in 48.54s, 1 test, 1 error <<< FAILURES!
[...truncated 958 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:525: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:473: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:186: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:508: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1358: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:965: There were test failures: 447 suites, 1820 tests, 1 error, 45 ignored (21 assumptions)
Total time: 111 minutes 6 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.7.0_67 -server -XX:+UseG1GC (asserts: true)
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any