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 2015/03/18 14:13:15 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 2061
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2061/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.schema.TestBulkSchemaConcurrent.test
Error Message:
[[], [], [], [], [CopyField source=a4,dest=hello_lol4 still exists, new type mystr4 still exists, dynamic field *_lol4 still exists, field a4 still exists]]
Stack Trace:
java.lang.AssertionError: [[], [], [], [], [CopyField source=a4,dest=hello_lol4 still exists, new type mystr4 still exists, dynamic field *_lol4 still exists, field a4 still exists]]
at __randomizedtesting.SeedInfo.seed([DC51ADC46D81DF2D:5405921EC37DB2D5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.schema.TestBulkSchemaConcurrent.test(TestBulkSchemaConcurrent.java:121)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933)
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 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:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
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 9721 lines...]
[junit4] Suite: org.apache.solr.schema.TestBulkSchemaConcurrent
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/init-core-data-001
[junit4] 2> 1323461 T6643 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1323461 T6643 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 1323466 T6643 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1323467 T6644 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1323568 T6643 oasc.ZkTestServer.run start zk server on port:53168
[junit4] 2> 1323568 T6643 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1323569 T6643 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1323574 T6651 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@47d0d65c name:ZooKeeperConnection Watcher:127.0.0.1:53168 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1323575 T6643 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1323575 T6643 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1323575 T6643 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1323581 T6643 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1323582 T6643 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1323585 T6654 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1adb1e9b name:ZooKeeperConnection Watcher:127.0.0.1:53168/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1323585 T6643 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1323585 T6643 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1323586 T6643 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1323590 T6643 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1323593 T6643 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1323596 T6643 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1323601 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-managed-schema.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1323601 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1323606 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1323606 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1323610 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1323611 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1323615 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1323615 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1323619 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1323619 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1323623 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1323623 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1323627 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1323627 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1323631 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1323631 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1323635 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1323636 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1323640 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1323640 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1323643 T6643 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1323643 T6643 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1323993 T6643 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1
[junit4] 2> 1323998 T6643 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 1324001 T6643 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@5c27f1a{/,null,AVAILABLE}
[junit4] 2> 1324006 T6643 oejs.AbstractConnector.doStart Started ServerConnector@25165d82{HTTP/1.1}{127.0.0.1:53177}
[junit4] 2> 1324007 T6643 oejs.Server.doStart Started @1328116ms
[junit4] 2> 1324007 T6643 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/tempDir-001/control/data, hostContext=/, hostPort=53177, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores}
[junit4] 2> 1324007 T6643 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1324008 T6643 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/'
[junit4] 2> 1324038 T6643 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/solr.xml
[junit4] 2> 1324047 T6643 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores
[junit4] 2> 1324047 T6643 oasc.CoreContainer.<init> New CoreContainer 250647329
[junit4] 2> 1324047 T6643 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/]
[junit4] 2> 1324047 T6643 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/lib
[junit4] 2> 1324048 T6643 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/lib).
[junit4] 2> 1324057 T6643 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1324057 T6643 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1324057 T6643 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1324057 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1324057 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1324057 T6643 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1324058 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1324059 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1324059 T6643 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1324059 T6643 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1324059 T6643 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1324060 T6643 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1324060 T6643 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1324060 T6643 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1324060 T6643 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1324060 T6643 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53168/solr
[junit4] 2> 1324061 T6643 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1324061 T6643 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1324062 T6643 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1324065 T6668 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63461123 name:ZooKeeperConnection Watcher:127.0.0.1:53168 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1324065 T6643 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1324066 T6643 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1324070 T6643 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1324073 T6671 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@30da1509 name:ZooKeeperConnection Watcher:127.0.0.1:53168/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1324073 T6643 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1324075 T6643 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1324080 T6643 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1324085 T6643 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 1324090 T6643 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 1324095 T6643 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 1324100 T6643 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1324104 T6643 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1324107 T6643 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1324110 T6643 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53177_
[junit4] 2> 1324111 T6643 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53177_
[junit4] 2> 1324115 T6643 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 1324118 T6643 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1324121 T6643 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1324124 T6643 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53177_
[junit4] 2> 1324124 T6643 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1324127 T6643 oasc.Overseer.start Overseer (id=93499124005142531-127.0.0.1:53177_-n_0000000000) starting
[junit4] 2> 1324132 T6643 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1324147 T6643 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1324148 T6643 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1324148 T6673 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1324149 T6672 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1324156 T6643 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1324158 T6643 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/
[junit4] 2> 1324158 T6643 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1324160 T6675 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 1324160 T6675 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1324161 T6671 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1324162 T6675 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1324164 T6672 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53177",
[junit4] 2> "node_name":"127.0.0.1:53177_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1324164 T6672 oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53177",
[junit4] 2> "node_name":"127.0.0.1:53177_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1324165 T6672 oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 1324165 T6672 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1324167 T6671 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> 1325164 T6675 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1325164 T6675 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 1325165 T6675 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1325165 T6675 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1325167 T6675 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1325167 T6675 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/'
[junit4] 2> 1325179 T6675 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1325183 T6675 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1325190 T6675 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1325191 T6675 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1325205 T6675 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1325206 T6675 oass.ManagedIndexSchemaFactory.create The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 1325208 T6675 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1325212 T6675 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1325311 T6675 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1325319 T6675 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1325320 T6675 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1325337 T6675 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1325340 T6675 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1325343 T6675 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1325345 T6675 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1325345 T6675 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1325345 T6675 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1325347 T6675 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1325347 T6675 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1325349 T6675 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema
[junit4] 2> 1325351 T6675 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak
[junit4] 2> 1325356 T6675 oass.ManagedIndexSchemaFactory.zkUgradeToManagedSchema After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/conf1/schema.xml to /configs/conf1/schema.xml.bak
[junit4] 2> 1325356 T6675 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 1325356 T6675 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 1325357 T6675 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/data/
[junit4] 2> 1325357 T6675 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1325357 T6675 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/data
[junit4] 2> 1325358 T6675 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/data/index/
[junit4] 2> 1325358 T6675 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1325359 T6675 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/data/index
[junit4] 2> 1325359 T6675 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=14, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1325362 T6675 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7051f20; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1325362 T6675 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1325366 T6675 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1325366 T6675 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 1325372 T6675 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1325373 T6675 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1325374 T6675 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1325375 T6675 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1325376 T6675 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1325377 T6675 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1325377 T6675 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1325377 T6675 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1325378 T6675 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1325378 T6675 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1325378 T6675 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1325379 T6675 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/control-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7051f20; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1325379 T6675 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1325379 T6675 oass.SolrIndexSearcher.<init> Opening Searcher@69b0941c[collection1] main
[junit4] 2> 1325380 T6675 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1325381 T6675 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1325381 T6675 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1325382 T6675 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1325382 T6675 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1325382 T6675 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1325383 T6675 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1325383 T6675 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1325383 T6675 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1325384 T6675 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 1325385 T6675 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1325385 T6676 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@69b0941c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1325385 T6675 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1325387 T6679 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53177 collection:control_collection shard:shard1
[junit4] 2> 1325387 T6643 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 1325388 T6643 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1325388 T6643 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1325389 T6679 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1325389 T6643 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1325393 T6682 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54fa439 name:ZooKeeperConnection Watcher:127.0.0.1:53168/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1325393 T6643 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1325394 T6643 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1325394 T6643 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1325399 T6679 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1325401 T6643 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1325401 T6643 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2
[junit4] 2> 1325401 T6643 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1325402 T6643 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1325405 T6671 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1325407 T6685 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6af8ba96 name:ZooKeeperConnection Watcher:127.0.0.1:53168/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1325407 T6679 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1325407 T6679 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1325407 T6643 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> ASYNC NEW_CORE C4281 name=collection1 org.apache.solr.core.SolrCore@541fefb4 url=http://127.0.0.1:53177/collection1 node=127.0.0.1:53177_ C4281_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:53177, node_name=127.0.0.1:53177_, state=down}
[junit4] 2> 1325407 T6679 C4281 P53177 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53177/collection1/
[junit4] 2> 1325408 T6679 C4281 P53177 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1325408 T6679 C4281 P53177 oasc.SyncStrategy.syncToMe http://127.0.0.1:53177/collection1/ has no replicas
[junit4] 2> 1325409 T6672 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 1325408 T6643 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1325409 T6679 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53177/collection1/ shard1
[junit4] 2> 1325410 T6679 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1325416 T6672 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 1
[junit4] 2> 1325417 T6672 oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 1325418 T6671 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> 1325418 T6682 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> 1325426 T6686 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1325431 T6672 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:53177",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 1325432 T6672 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json
[junit4] 2> 1325434 T6682 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> 1325434 T6671 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> 1325443 T6671 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1325546 T6671 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> 1325546 T6682 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> 1325581 T6679 oasc.ZkController.register We are http://127.0.0.1:53177/collection1/ and leader is http://127.0.0.1:53177/collection1/
[junit4] 2> 1325582 T6679 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53177
[junit4] 2> 1325582 T6679 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1325582 T6679 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 1325582 T6679 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1325584 T6671 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1325586 T6672 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53177",
[junit4] 2> "node_name":"127.0.0.1:53177_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1325587 T6672 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53177",
[junit4] 2> "node_name":"127.0.0.1:53177_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1325693 T6682 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> 1325693 T6671 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> 1325761 T6643 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1
[junit4] 2> 1325763 T6643 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001
[junit4] 2> 1325764 T6643 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 1325767 T6643 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@10556068{/,null,AVAILABLE}
[junit4] 2> 1325768 T6643 oejs.AbstractConnector.doStart Started ServerConnector@1cab5e6d{HTTP/1.1}{127.0.0.1:53185}
[junit4] 2> 1325768 T6643 oejs.Server.doStart Started @1329876ms
[junit4] 2> 1325768 T6643 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=53185, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores}
[junit4] 2> 1325768 T6643 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1325769 T6643 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/'
[junit4] 2> 1325799 T6643 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/solr.xml
[junit4] 2> 1325806 T6643 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores
[junit4] 2> 1325806 T6643 oasc.CoreContainer.<init> New CoreContainer 334322195
[junit4] 2> 1325807 T6643 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/]
[junit4] 2> 1325807 T6643 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/lib
[junit4] 2> 1325808 T6643 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/lib).
[junit4] 2> 1325817 T6643 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1325818 T6643 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1325818 T6643 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1325818 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1325818 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1325818 T6643 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1325819 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1325819 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1325819 T6643 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1325819 T6643 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1325820 T6643 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1325820 T6643 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1325820 T6643 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1325820 T6643 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1325821 T6643 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1325821 T6643 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53168/solr
[junit4] 2> 1325821 T6643 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1325821 T6643 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1325823 T6643 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1325827 T6700 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@577de714 name:ZooKeeperConnection Watcher:127.0.0.1:53168 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1325828 T6643 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1325828 T6643 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1325833 T6643 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1325836 T6703 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@32a378e6 name:ZooKeeperConnection Watcher:127.0.0.1:53168/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1325836 T6643 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1325846 T6643 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1326860 T6643 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53185_
[junit4] 2> 1326862 T6643 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53185_
[junit4] 2> 1326867 T6643 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1326870 T6643 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1326872 T6643 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/
[junit4] 2> 1326872 T6643 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1326876 T6704 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1326876 T6704 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1326879 T6671 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1326880 T6704 oasc.ZkController.preRegister Registering watch for external collection collection1
[junit4] 2> 1326880 T6672 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53185",
[junit4] 2> "node_name":"127.0.0.1:53185_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 1326880 T6704 oascc.ZkStateReader.addZkWatch addZkWatch collection1
[junit4] 2> 1326881 T6672 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53185",
[junit4] 2> "node_name":"127.0.0.1:53185_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1326882 T6672 oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 1326882 T6672 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
[junit4] 2> 1326882 T6704 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1326884 T6704 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0
[junit4] 2> 1326884 T6704 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1326988 T6672 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0
[junit4] 2> 1326989 T6703 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2)
[junit4] 2> 1326990 T6703 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1
[junit4] 2> 1327886 T6704 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1327886 T6704 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1327887 T6704 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1327887 T6704 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1327890 T6704 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1327890 T6704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/'
[junit4] 2> 1327915 T6704 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1327920 T6704 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1327929 T6704 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1327931 T6704 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1327949 T6704 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1327952 T6704 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/managed-schema
[junit4] 2> 1327959 T6704 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1328124 T6704 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1328136 T6704 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1328137 T6704 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1328141 T6704 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1328146 T6704 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1328160 T6704 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1328163 T6704 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1328163 T6704 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1328163 T6704 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1328165 T6704 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1328165 T6704 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1328173 T6704 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1328173 T6704 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 1328174 T6704 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/data/
[junit4] 2> 1328174 T6704 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1328175 T6704 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/data
[junit4] 2> 1328176 T6704 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/data/index/
[junit4] 2> 1328176 T6704 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1328177 T6704 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/data/index
[junit4] 2> 1328178 T6704 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=14, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1328182 T6704 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194b172a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1328182 T6704 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1328187 T6704 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1328188 T6704 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 1328197 T6704 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1328198 T6704 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1328199 T6704 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1328201 T6704 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1328206 T6704 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1328207 T6704 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1328208 T6704 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1328208 T6704 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1328209 T6704 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1328209 T6704 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1328210 T6704 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1328211 T6704 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-1-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194b172a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1328211 T6704 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1328211 T6704 oass.SolrIndexSearcher.<init> Opening Searcher@44449049[collection1] main
[junit4] 2> 1328211 T6704 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1328214 T6704 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1328214 T6704 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1328216 T6704 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1328216 T6704 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1328216 T6704 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1328218 T6704 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1328218 T6704 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1328218 T6704 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1328218 T6704 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 1328220 T6704 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1328220 T6704 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1328220 T6705 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@44449049[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1328223 T6708 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53185 collection:collection1 shard:shard2
[junit4] 2> 1328224 T6643 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 1328224 T6643 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1328225 T6708 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 1328238 T6708 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 1328241 T6671 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1328242 T6708 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1328242 T6708 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4282 name=collection1 org.apache.solr.core.SolrCore@330584b3 url=http://127.0.0.1:53185/collection1 node=127.0.0.1:53185_ C4282_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:53185, node_name=127.0.0.1:53185_, state=down}
[junit4] 2> 1328242 T6708 C4282 P53185 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53185/collection1/
[junit4] 2> 1328243 T6708 C4282 P53185 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1328243 T6708 C4282 P53185 oasc.SyncStrategy.syncToMe http://127.0.0.1:53185/collection1/ has no replicas
[junit4] 2> 1328244 T6708 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53185/collection1/ shard2
[junit4] 2> 1328244 T6708 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 1328244 T6672 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 1328248 T6672 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1
[junit4] 2> 1328249 T6703 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2)
[junit4] 2> 1328251 T6703 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2
[junit4] 2> 1328258 T6672 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:53185",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 1328260 T6672 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 2
[junit4] 2> 1328261 T6703 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2)
[junit4] 2> 1328262 T6703 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3
[junit4] 2> 1328267 T6671 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1328309 T6708 oasc.ZkController.register We are http://127.0.0.1:53185/collection1/ and leader is http://127.0.0.1:53185/collection1/
[junit4] 2> 1328309 T6708 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53185
[junit4] 2> 1328309 T6708 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1328309 T6708 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 1328309 T6708 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1328312 T6671 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1328314 T6672 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53185",
[junit4] 2> "node_name":"127.0.0.1:53185_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 1328316 T6672 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53185",
[junit4] 2> "node_name":"127.0.0.1:53185_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1328319 T6672 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 3
[junit4] 2> 1328321 T6703 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2)
[junit4] 2> 1328324 T6708 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4
[junit4] 2> 1328325 T6703 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4
[junit4] 2> 1328591 T6643 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1
[junit4] 2> 1328593 T6643 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001
[junit4] 2> 1328594 T6643 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 1328601 T6643 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@457c3473{/,null,AVAILABLE}
[junit4] 2> 1328602 T6643 oejs.AbstractConnector.doStart Started ServerConnector@66349e2{HTTP/1.1}{127.0.0.1:53192}
[junit4] 2> 1328603 T6643 oejs.Server.doStart Started @1332709ms
[junit4] 2> 1328603 T6643 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/, hostPort=53192, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores}
[junit4] 2> 1328603 T6643 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1328603 T6643 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/'
[junit4] 2> 1328647 T6643 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/solr.xml
[junit4] 2> 1328657 T6643 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores
[junit4] 2> 1328657 T6643 oasc.CoreContainer.<init> New CoreContainer 413121755
[junit4] 2> 1328657 T6643 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/]
[junit4] 2> 1328658 T6643 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/lib
[junit4] 2> 1328659 T6643 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/lib).
[junit4] 2> 1328667 T6643 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1328668 T6643 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1328668 T6643 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1328668 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1328668 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1328668 T6643 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1328669 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1328669 T6643 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1328669 T6643 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1328669 T6643 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1328669 T6643 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1328670 T6643 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1328670 T6643 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1328670 T6643 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1328671 T6643 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1328671 T6643 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53168/solr
[junit4] 2> 1328671 T6643 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1328671 T6643 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1328672 T6643 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1328678 T6722 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@539486a4 name:ZooKeeperConnection Watcher:127.0.0.1:53168 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1328680 T6643 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1328681 T6643 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1328687 T6643 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1328692 T6725 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c10dfbc name:ZooKeeperConnection Watcher:127.0.0.1:53168/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1328693 T6643 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1328704 T6643 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1329722 T6643 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53192_
[junit4] 2> 1329724 T6643 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53192_
[junit4] 2> 1329731 T6643 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1329736 T6643 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1, collection=collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1329739 T6643 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/
[junit4] 2> 1329740 T6643 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1329746 T6726 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1329746 T6726 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1329751 T6671 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1329753 T6726 oasc.ZkController.preRegister Registering watch for external collection collection1
[junit4] 2> 1329753 T6726 oascc.ZkStateReader.addZkWatch addZkWatch collection1
[junit4] 2> 1329753 T6672 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53192",
[junit4] 2> "node_name":"127.0.0.1:53192_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 1329754 T6726 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1329754 T6672 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53192",
[junit4] 2> "node_name":"127.0.0.1:53192_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1329755 T6672 oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 1329755 T6672 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1329756 T6672 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 4
[junit4] 2> 1329757 T6726 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4
[junit4] 2> 1329757 T6726 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1329757 T6725 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 3)
[junit4] 2> 1329758 T6703 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 3)
[junit4] 2> 1329758 T6725 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5
[junit4] 2> 1329759 T6703 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5
[junit4] 2> 1330759 T6726 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1330759 T6726 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1330761 T6726 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1330761 T6726 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1330764 T6726 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1330764 T6726 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/'
[junit4] 2> 1330779 T6726 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1330783 T6726 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1330793 T6726 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1330796 T6726 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1330806 T6726 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1330809 T6726 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/managed-schema
[junit4] 2> 1330812 T6726 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1330975 T6726 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1330984 T6726 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1330985 T6726 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1330990 T6726 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1330994 T6726 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1331015 T6726 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1331018 T6726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1331018 T6726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1331019 T6726 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1331021 T6726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1331022 T6726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1331030 T6726 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1331031 T6726 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 1331032 T6726 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/data/
[junit4] 2> 1331032 T6726 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1331035 T6726 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/data
[junit4] 2> 1331036 T6726 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/data/index/
[junit4] 2> 1331037 T6726 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1331039 T6726 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/data/index
[junit4] 2> 1331040 T6726 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=14, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1331044 T6726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10639db2; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1331044 T6726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1331049 T6726 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1331049 T6726 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 1331058 T6726 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1331061 T6726 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1331062 T6726 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1331064 T6726 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1331067 T6726 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1331068 T6726 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1331069 T6726 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1331069 T6726 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1331070 T6726 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1331070 T6726 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1331070 T6726 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1331071 T6726 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-2-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10639db2; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1331071 T6726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1331072 T6726 oass.SolrIndexSearcher.<init> Opening Searcher@68112ce6[collection1] main
[junit4] 2> 1331072 T6726 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1331075 T6726 oascc.ZkStateReader.readConfigName path=/co
[...truncated too long message...]
solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001/shard-4-001/cores/collection1/data/index
[junit4] 2> 1443504 T6870 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 1443504 T6870 oasc.SolrException.log ERROR There was a problem trying to register as the leader:org.apache.solr.common.SolrException: Could not register as the leader because creating the ephemeral registration node in ZooKeeper failed
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:147)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:323)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:198)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:159)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:390)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:264)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:294)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:291)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:291)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:486)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:455)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:442)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:139)
[junit4] 2> at org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:31)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:135)
[junit4] 2> ... 11 more
[junit4] 2>
[junit4] 2> 1444983 T6893 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1444985 T6643 oejs.AbstractConnector.doStop Stopped ServerConnector@12f0a284{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 1444985 T6643 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@4fdf3847{/,null,UNAVAILABLE}
[junit4] 2> 1444988 T6643 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:53168 53168
[junit4] 2> 1445022 T6644 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:53168 53168
[junit4] 2> 1446390 T6644 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 40 /solr/configs/conf1/managed-schema
[junit4] 2> 40 /solr/configs/conf1
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/configs/conf1/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBulkSchemaConcurrent -Dtests.method=test -Dtests.seed=DC51ADC46D81DF2D -Dtests.slow=true -Dtests.locale=es_CR -Dtests.timezone=Atlantic/Faeroe -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 123s J1 | TestBulkSchemaConcurrent.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: [[], [], [], [], [CopyField source=a4,dest=hello_lol4 still exists, new type mystr4 still exists, dynamic field *_lol4 still exists, field a4 still exists]]
[junit4] > at __randomizedtesting.SeedInfo.seed([DC51ADC46D81DF2D:5405921EC37DB2D5]:0)
[junit4] > at org.apache.solr.schema.TestBulkSchemaConcurrent.test(TestBulkSchemaConcurrent.java:121)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1446399 T6643 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent DC51ADC46D81DF2D-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=es_CR, timezone=Atlantic/Faeroe
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_40 (64-bit)/cpus=3,threads=1,free=108838920,total=230494208
[junit4] 2> NOTE: All tests run in this JVM: [DateMathParserTest, LeaderInitiatedRecoveryOnCommitTest, BadIndexSchemaTest, SSLMigrationTest, TestSolrQueryParserResource, DisMaxRequestHandlerTest, TestDocBasedVersionConstraints, TestLuceneMatchVersion, TestRealTimeGet, DistributedQueryComponentOptimizationTest, TestTolerantSearch, TestTrie, CustomCollectionTest, SpellCheckCollatorTest, AliasIntegrationTest, TestExpandComponent, ChaosMonkeySafeLeaderTest, SearchHandlerTest, TestSearchPerf, TestSuggestSpellingConverter, RAMDirectoryFactoryTest, BadCopyFieldTest, ReplicationFactorTest, TestRandomMergePolicy, SimpleCollectionCreateDeleteTest, DistributedFacetPivotSmallAdvancedTest, AnalysisErrorHandlingTest, AsyncMigrateRouteKeyTest, TestHighlightDedupGrouping, TestDefaultSimilarityFactory, TestZkChroot, ExternalCollectionsTest, TestSystemIdResolver, TestConfigReload, CSVRequestHandlerTest, TestReplicationHandlerBackup, DirectUpdateHandlerOptimizeTest, TestLMDirichletSimilarityFactory, TestReversedWildcardFilterFactory, OverseerRolesTest, TestSchemaVersionResource, FastVectorHighlighterTest, TestObjectReleaseTracker, TestNonDefinedSimilarityFactory, TestRecovery, VMParamsZkACLAndCredentialsProvidersTest, TestSolrQueryParserDefaultOperatorResource, TestFastOutputStream, ExternalFileFieldSortTest, HdfsDirectoryFactoryTest, OpenCloseCoreStressTest, FileBasedSpellCheckerTest, TestStressUserVersions, CursorPagingTest, DistributedMLTComponentTest, MergeStrategyTest, HdfsCollectionsAPIDistributedZkTest, IndexSchemaTest, TestFuzzyAnalyzedSuggestions, InfoHandlerTest, TestConfig, HdfsWriteToMultipleCollectionsTest, HdfsBasicDistributedZk2Test, MBeansHandlerTest, TestFiltering, SchemaVersionSpecificBehaviorTest, DirectSolrSpellCheckerTest, ShardRoutingCustomTest, TestSolrDeletionPolicy2, ExitableDirectoryReaderTest, FullSolrCloudDistribCmdsTest, TestCloudManagedSchemaConcurrent, TestRandomFaceting, TestReload, DistributedTermsComponentTest, TestDFRSimilarityFactory, BlockCacheTest, TriLevelCompositeIdRoutingTest, CopyFieldTest, AutoCommitTest, LoggingHandlerTest, TestFieldTypeResource, PolyFieldTest, UpdateParamsTest, TermVectorComponentTest, TestDefaultStatsCache, OpenExchangeRatesOrgProviderTest, StandardRequestHandlerTest, TestCodecSupport, TermVectorComponentDistributedTest, TestSolrDeletionPolicy1, TestBulkSchemaConcurrent]
[junit4] Completed on J1 in 122.97s, 1 test, 1 failure <<< FAILURES!
[...truncated 792 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:191: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1351: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:958: There were test failures: 475 suites, 1891 tests, 1 failure, 53 ignored (20 assumptions)
Total time: 60 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 2062
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2062/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores
Error Message:
ERROR: SolrIndexSearcher opens=51 closes=50
Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=51 closes=50
at __randomizedtesting.SeedInfo.seed([2534ADF8263ED9AB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:496)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:232)
at sun.reflect.GeneratedMethodAccessor35.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
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 9481 lines...]
[junit4] Suite: org.apache.solr.core.TestLazyCores
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/init-core-data-001
[junit4] 2> 672931 T3080 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 672932 T3080 oas.SolrTestCaseJ4.setUp ###Starting testCreateTransientFromAdmin
[junit4] 2> 672978 T3080 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/'
[junit4] 2> 673017 T3080 oasc.CoreContainer.<init> New CoreContainer 2086332776
[junit4] 2> 673017 T3080 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/]
[junit4] 2> 673017 T3080 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/lib
[junit4] 2> 673018 T3080 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/lib).
[junit4] 2> 673030 T3080 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 600000
[junit4] 2> 673031 T3080 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 673031 T3080 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 60000
[junit4] 2> 673031 T3080 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 673031 T3080 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 673031 T3080 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 673031 T3080 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 673032 T3080 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 673033 T3080 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 673033 T3080 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 673033 T3080 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 673033 T3080 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 673033 T3080 oasc.SolrCores.allocateLazyCores Allocating transient cache for 4 transient cores
[junit4] 2> 673036 T3080 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 673036 T3080 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 673037 T3080 oasc.CoreContainer.load Node Name: testNode
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, instanceDir=collection1, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/, schema=schema.xml, transient=false, dataDir=data/}
[junit4] 1> CORE DESCRIPTOR: {name=collectionLazy2, config=solrconfig.xml, instanceDir=collection2, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/, schema=schema.xml, transient=true, dataDir=data/}
[junit4] 1> CORE DESCRIPTOR: {name=collectionLazy3, config=solrconfig.xml, instanceDir=collection3, loadOnStartup=false, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection3/, schema=schema.xml, transient=on, dataDir=data/}
[junit4] 1> CORE DESCRIPTOR: {name=collectionLazy4, config=solrconfig.xml, instanceDir=collection4, loadOnStartup=false, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection4/, schema=schema.xml, transient=false, dataDir=data/}
[junit4] 1> CORE DESCRIPTOR: {name=collectionLazy5, config=solrconfig.xml, instanceDir=collection5, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/, schema=schema.xml, transient=false, dataDir=data/}
[junit4] 1> CORE DESCRIPTOR: {name=collectionLazy6, config=solrconfig.xml, instanceDir=collection6, loadOnStartup=false, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection6/, schema=schema.xml, transient=true, dataDir=data/}
[junit4] 1> CORE DESCRIPTOR: {name=collectionLazy7, config=solrconfig.xml, instanceDir=collection7, loadOnStartup=false, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection7/, schema=schema.xml, transient=true, dataDir=data/}
[junit4] 1> CORE DESCRIPTOR: {name=collectionLazy8, config=solrconfig.xml, instanceDir=collection8, loadOnStartup=false, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection8/, schema=schema.xml, transient=true, dataDir=data/}
[junit4] 1> CORE DESCRIPTOR: {name=collectionLazy9, config=solrconfig.xml, instanceDir=collection9, loadOnStartup=false, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection9/, schema=schema.xml, transient=true, dataDir=data/}
[junit4] 2> 673043 T3081 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/'
[junit4] 2> 673044 T3083 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/'
[junit4] 2> 673044 T3082 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/'
[junit4] 2> 673074 T3083 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 673077 T3083 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 673074 T3081 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 673081 T3083 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 673082 T3081 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 673093 T3083 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/conf/schema.xml
[junit4] 2> 673096 T3083 oass.IndexSchema.readSchema [collectionLazy5] Schema name=tiny
[junit4] 2> 673097 T3082 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 673098 T3082 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 673100 T3083 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 673100 T3083 oasc.CoreContainer.create Creating SolrCore 'collectionLazy5' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/
[junit4] 2> 673100 T3083 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 673101 T3083 oasc.SolrCore.<init> [collectionLazy5] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/data/
[junit4] 2> 673101 T3083 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy5
[junit4] 2> 673101 T3083 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/data
[junit4] 2> 673101 T3083 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/data/index/
[junit4] 2> 673101 T3083 oasc.SolrCore.initIndex WARN [collectionLazy5] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/data/index' doesn't exist. Creating new index...
[junit4] 2> 673102 T3083 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/data/index
[junit4] 2> 673102 T3083 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=1.419921875, floorSegmentMB=1.9599609375, forceMergeDeletesPctAllowed=2.1902033467403514, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 673104 T3083 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3bccfdef lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b0ef912),segFN=segments_1,generation=1}
[junit4] 2> 673104 T3083 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673105 T3081 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 673106 T3081 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/conf/schema.xml
[junit4] 2> 673108 T3081 oass.IndexSchema.readSchema [collection1] Schema name=tiny
[junit4] 2> 673109 T3082 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 673110 T3082 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/conf/schema.xml
[junit4] 2> 673111 T3081 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 673111 T3081 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/
[junit4] 2> 673111 T3081 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 673112 T3081 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/data/
[junit4] 2> 673112 T3081 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 673112 T3081 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/data
[junit4] 2> 673112 T3081 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/data/index/
[junit4] 2> 673112 T3081 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 673115 T3081 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/data/index
[junit4] 2> 673116 T3083 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 673116 T3081 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=1.419921875, floorSegmentMB=1.9599609375, forceMergeDeletesPctAllowed=2.1902033467403514, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 673115 T3082 oass.IndexSchema.readSchema [collectionLazy2] Schema name=tiny
[junit4] 2> 673117 T3081 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@75dd7f6b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29622e6b),segFN=segments_1,generation=1}
[junit4] 2> 673117 T3081 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673120 T3082 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 673120 T3082 oasc.CoreContainer.create Creating SolrCore 'collectionLazy2' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/
[junit4] 2> 673120 T3082 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 673121 T3082 oasc.SolrCore.<init> [collectionLazy2] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data/
[junit4] 2> 673121 T3082 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy2
[junit4] 2> 673121 T3082 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data
[junit4] 2> 673121 T3082 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data/index/
[junit4] 2> 673121 T3082 oasc.SolrCore.initIndex WARN [collectionLazy2] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data/index' doesn't exist. Creating new index...
[junit4] 2> 673121 T3082 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data/index
[junit4] 2> 673122 T3082 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=1.419921875, floorSegmentMB=1.9599609375, forceMergeDeletesPctAllowed=2.1902033467403514, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 673123 T3081 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 673125 T3082 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ca95f7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ca23014),segFN=segments_1,generation=1}
[junit4] 2> 673125 T3082 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673126 T3081 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673127 T3081 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673130 T3081 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673122 T3083 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673132 T3083 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673132 T3081 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673133 T3083 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673136 T3083 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673128 T3082 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 673138 T3082 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673135 T3081 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 673141 T3081 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 673141 T3082 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673142 T3081 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 673142 T3081 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 673142 T3081 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=152353839, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 673143 T3081 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@75dd7f6b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29622e6b),segFN=segments_1,generation=1}
[junit4] 2> 673143 T3081 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673143 T3081 oass.SolrIndexSearcher.<init> Opening Searcher@67c406b8[collection1] main
[junit4] 2> 673140 T3083 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 673143 T3082 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673144 T3083 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 673144 T3081 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/conf
[junit4] 2> 673145 T3081 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/conf}
[junit4] 2> 673145 T3081 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection1/conf
[junit4] 2> 673145 T3082 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673145 T3081 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 673145 T3081 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 673146 T3083 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 673146 T3083 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 673148 T3082 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 673148 T3082 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 673148 T3083 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=152353839, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 673146 T3081 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 673146 T3084 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67c406b8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 673149 T3083 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3bccfdef lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b0ef912),segFN=segments_1,generation=1}
[junit4] 2> 673149 T3083 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673148 T3082 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 673150 T3082 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 673150 T3083 oass.SolrIndexSearcher.<init> Opening Searcher@1406c7a1[collectionLazy5] main
[junit4] 2> 673151 T3082 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=152353839, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 673151 T3083 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/conf
[junit4] 2> 673151 T3083 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/conf}
[junit4] 2> 673151 T3083 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection5/conf
[junit4] 2> 673151 T3082 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ca95f7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ca23014),segFN=segments_1,generation=1}
[junit4] 2> 673152 T3082 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673152 T3083 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 673153 T3083 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 673152 T3082 oass.SolrIndexSearcher.<init> Opening Searcher@37c5d510[collectionLazy2] main
[junit4] 2> 673153 T3085 oasc.SolrCore.registerSearcher [collectionLazy5] Registered new searcher Searcher@1406c7a1[collectionLazy5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 673153 T3083 oasc.CoreContainer.registerCore registering core: collectionLazy5
[junit4] 2> 673154 T3082 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/conf
[junit4] 2> 673154 T3082 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/conf}
[junit4] 2> 673154 T3082 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/conf
[junit4] 2> 673155 T3082 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 673155 T3082 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 673155 T3082 oasc.SolrCores.putTransientCore Opening transient core collectionLazy2
[junit4] 2> 673156 T3086 oasc.SolrCore.registerSearcher [collectionLazy2] Registered new searcher Searcher@37c5d510[collectionLazy2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 673156 T3082 oasc.CoreContainer.registerCore registering core: collectionLazy2
[junit4] 2> 673189 T3080 oasha.CoreAdminHandler.handleCreateAction core create command loadOnStartup=true&transient=true&name=core1&action=CREATE&instanceDir=./core1&wt=xml
[junit4] 1> CORE DESCRIPTOR: {name=core1, config=solrconfig.xml, instanceDir=./core1, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/, schema=schema.xml, transient=true, dataDir=data/}
[junit4] 2> 673190 T3080 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/'
[junit4] 2> 673210 T3080 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 673212 T3080 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 673217 T3080 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 673217 T3080 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/conf/schema.xml
[junit4] 2> 673220 T3080 oass.IndexSchema.readSchema [core1] Schema name=tiny
[junit4] 2> 673229 T3080 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 673229 T3080 oasc.CoreContainer.create Creating SolrCore 'core1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/
[junit4] 2> 673230 T3080 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 673230 T3080 oasc.SolrCore.<init> [core1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/
[junit4] 2> 673231 T3080 oasc.SolrCore.<init> JMX monitoring not detected for core: core1
[junit4] 2> 673231 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data
[junit4] 2> 673231 T3080 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/index/
[junit4] 2> 673231 T3080 oasc.SolrCore.initIndex WARN [core1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/index' doesn't exist. Creating new index...
[junit4] 2> 673232 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/index
[junit4] 2> 673232 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=32.5869140625, floorSegmentMB=1.869140625, forceMergeDeletesPctAllowed=24.23548055902986, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2606648103099983
[junit4] 2> 673233 T3080 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5c0a4378 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c638127),segFN=segments_1,generation=1}
[junit4] 2> 673233 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673235 T3080 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 673236 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673238 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673239 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673239 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673241 T3080 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 673242 T3080 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 673242 T3080 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 673242 T3080 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 673243 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5745237754383954]
[junit4] 2> 673243 T3080 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5c0a4378 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c638127),segFN=segments_1,generation=1}
[junit4] 2> 673243 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673243 T3080 oass.SolrIndexSearcher.<init> Opening Searcher@76e65654[core1] main
[junit4] 2> 673244 T3080 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/conf
[junit4] 2> 673244 T3080 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/conf}
[junit4] 2> 673245 T3080 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/conf
[junit4] 2> 673245 T3080 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 673245 T3080 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 673246 T3080 oasc.SolrCores.putTransientCore Opening transient core core1
[junit4] 2> 673246 T3080 oasc.CoreContainer.registerCore registering core: core1
[junit4] 2> 673246 T3088 oasc.SolrCore.registerSearcher [core1] Registered new searcher Searcher@76e65654[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 673247 T3080 oasha.CoreAdminHandler.handleCreateAction core create command loadOnStartup=false&transient=true&name=core2&action=CREATE&instanceDir=./core2&wt=xml
[junit4] 1> CORE DESCRIPTOR: {name=core2, config=solrconfig.xml, instanceDir=./core2, loadOnStartup=false, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/, schema=schema.xml, transient=true, dataDir=data/}
[junit4] 2> 673248 T3080 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/'
[junit4] 2> 673278 T3080 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 673279 T3080 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 673284 T3080 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 673284 T3080 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/conf/schema.xml
[junit4] 2> 673287 T3080 oass.IndexSchema.readSchema [core2] Schema name=tiny
[junit4] 2> 673289 T3080 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 673289 T3080 oasc.CoreContainer.create Creating SolrCore 'core2' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/
[junit4] 2> 673289 T3080 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 673290 T3080 oasc.SolrCore.<init> [core2] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/
[junit4] 2> 673290 T3080 oasc.SolrCore.<init> JMX monitoring not detected for core: core2
[junit4] 2> 673290 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data
[junit4] 2> 673291 T3080 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/index/
[junit4] 2> 673291 T3080 oasc.SolrCore.initIndex WARN [core2] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/index' doesn't exist. Creating new index...
[junit4] 2> 673291 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/index
[junit4] 2> 673291 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=11.3232421875, floorSegmentMB=1.5400390625, forceMergeDeletesPctAllowed=0.8430488637280575, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 673292 T3080 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6ca52f7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@256b4726),segFN=segments_1,generation=1}
[junit4] 2> 673292 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673295 T3080 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 673296 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673298 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673299 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673300 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673301 T3080 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 673301 T3080 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 673302 T3080 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 673302 T3080 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 673303 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 673303 T3080 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6ca52f7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@256b4726),segFN=segments_1,generation=1}
[junit4] 2> 673303 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673304 T3080 oass.SolrIndexSearcher.<init> Opening Searcher@527ef29b[core2] main
[junit4] 2> 673304 T3080 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/conf
[junit4] 2> 673304 T3080 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/conf}
[junit4] 2> 673304 T3080 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/conf
[junit4] 2> 673305 T3080 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 673305 T3080 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 673305 T3080 oasc.SolrCores.putTransientCore Opening transient core core2
[junit4] 2> 673305 T3080 oasc.CoreContainer.registerCore registering core: core2
[junit4] 2> 673305 T3090 oasc.SolrCore.registerSearcher [core2] Registered new searcher Searcher@527ef29b[core2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 673306 T3080 oasha.CoreAdminHandler.handleCreateAction core create command loadOnStartup=true&transient=true&name=core3&action=CREATE&instanceDir=./core3&wt=xml
[junit4] 1> CORE DESCRIPTOR: {name=core3, config=solrconfig.xml, instanceDir=./core3, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/, schema=schema.xml, transient=true, dataDir=data/}
[junit4] 2> 673307 T3080 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/'
[junit4] 2> 673324 T3080 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 673325 T3080 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 673329 T3080 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 673331 T3080 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/conf/schema.xml
[junit4] 2> 673338 T3080 oass.IndexSchema.readSchema [core3] Schema name=tiny
[junit4] 2> 673340 T3080 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 673340 T3080 oasc.CoreContainer.create Creating SolrCore 'core3' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/
[junit4] 2> 673340 T3080 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 673340 T3080 oasc.SolrCore.<init> [core3] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/data/
[junit4] 2> 673340 T3080 oasc.SolrCore.<init> JMX monitoring not detected for core: core3
[junit4] 2> 673341 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/data
[junit4] 2> 673341 T3080 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/data/index/
[junit4] 2> 673341 T3080 oasc.SolrCore.initIndex WARN [core3] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/data/index' doesn't exist. Creating new index...
[junit4] 2> 673341 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/data/index
[junit4] 2> 673342 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=20, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 673343 T3080 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@109cbd5f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4ce0befc),segFN=segments_1,generation=1}
[junit4] 2> 673343 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673345 T3080 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 673346 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673347 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673348 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673349 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673351 T3080 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 673351 T3080 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 673352 T3080 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 673352 T3080 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 673352 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=94.830078125, floorSegmentMB=2.0517578125, forceMergeDeletesPctAllowed=2.265921911422356, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3614343605048469
[junit4] 2> 673352 T3080 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@109cbd5f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4ce0befc),segFN=segments_1,generation=1}
[junit4] 2> 673352 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673353 T3080 oass.SolrIndexSearcher.<init> Opening Searcher@4fdcff2b[core3] main
[junit4] 2> 673353 T3080 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/conf
[junit4] 2> 673353 T3080 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/conf}
[junit4] 2> 673353 T3080 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/conf
[junit4] 2> 673354 T3080 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 673354 T3080 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 673355 T3080 oasc.SolrCores.putTransientCore Opening transient core core3
[junit4] 2> 673355 T3080 oasc.CoreContainer.registerCore registering core: core3
[junit4] 2> 673355 T3092 oasc.SolrCore.registerSearcher [core3] Registered new searcher Searcher@4fdcff2b[core3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 673355 T3080 oasha.CoreAdminHandler.handleCreateAction core create command loadOnStartup=false&transient=true&name=core4&action=CREATE&instanceDir=./core4&wt=xml
[junit4] 1> CORE DESCRIPTOR: {name=core4, config=solrconfig.xml, instanceDir=./core4, loadOnStartup=false, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/, schema=schema.xml, transient=true, dataDir=data/}
[junit4] 2> 673356 T3080 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/'
[junit4] 2> 673380 T3080 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 673382 T3080 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 673399 T3080 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 673400 T3080 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/conf/schema.xml
[junit4] 2> 673402 T3080 oass.IndexSchema.readSchema [core4] Schema name=tiny
[junit4] 2> 673408 T3080 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 673408 T3080 oasc.CoreContainer.create Creating SolrCore 'core4' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/
[junit4] 2> 673409 T3080 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 673409 T3080 oasc.SolrCore.<init> [core4] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/data/
[junit4] 2> 673409 T3080 oasc.SolrCore.<init> JMX monitoring not detected for core: core4
[junit4] 2> 673409 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/data
[junit4] 2> 673410 T3080 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/data/index/
[junit4] 2> 673410 T3080 oasc.SolrCore.initIndex WARN [core4] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/data/index' doesn't exist. Creating new index...
[junit4] 2> 673410 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/data/index
[junit4] 2> 673410 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=27.087890625, floorSegmentMB=1.041015625, forceMergeDeletesPctAllowed=25.044313468620373, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 673411 T3080 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2beb6922 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24d92e50),segFN=segments_1,generation=1}
[junit4] 2> 673411 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673413 T3080 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 673419 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673420 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673421 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673422 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673425 T3080 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 673426 T3080 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 673427 T3080 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 673427 T3080 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 673428 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=38.228515625, floorSegmentMB=0.9345703125, forceMergeDeletesPctAllowed=9.258651720273637, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.397365367126984
[junit4] 2> 673428 T3080 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2beb6922 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24d92e50),segFN=segments_1,generation=1}
[junit4] 2> 673429 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673429 T3080 oass.SolrIndexSearcher.<init> Opening Searcher@13e859d5[core4] main
[junit4] 2> 673430 T3080 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/conf
[junit4] 2> 673430 T3080 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/conf}
[junit4] 2> 673430 T3080 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core4/conf
[junit4] 2> 673431 T3080 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 673431 T3080 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 673432 T3094 oasc.SolrCore.registerSearcher [core4] Registered new searcher Searcher@13e859d5[core4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 673432 T3080 oasc.SolrCores.putTransientCore Opening transient core core4
[junit4] 2> 673433 T3080 oasc.SolrCores$1.removeEldestEntry Closing transient core [collectionLazy2]
[junit4] 2> 673433 T3080 oasc.CoreContainer.registerCore registering core: core4
[junit4] 2> 673433 T3080 oasha.CoreAdminHandler.handleCreateAction core create command loadOnStartup=false&transient=true&name=core5&action=CREATE&instanceDir=./core5&wt=xml
[junit4] 2> 673434 T3087 oasc.SolrCore.close [collectionLazy2] CLOSING SolrCore org.apache.solr.core.SolrCore@513a0d65
[junit4] 2> 673434 T3087 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 673434 T3087 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 673435 T3087 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 673435 T3087 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 1> CORE DESCRIPTOR: {name=core5, config=solrconfig.xml, instanceDir=./core5, loadOnStartup=false, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/, schema=schema.xml, transient=true, dataDir=data/}
[junit4] 2> 673435 T3080 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/'
[junit4] 2> 673446 T3087 oasc.SolrCore.closeSearcher [collectionLazy2] Closing main searcher on request.
[junit4] 2> 673447 T3087 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 673447 T3087 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data;done=false>>]
[junit4] 2> 673447 T3087 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data
[junit4] 2> 673447 T3087 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data/index;done=false>>]
[junit4] 2> 673447 T3087 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/collection2/data/index
[junit4] 2> 673462 T3080 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 673464 T3080 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 673469 T3080 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 673469 T3080 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/conf/schema.xml
[junit4] 2> 673471 T3080 oass.IndexSchema.readSchema [core5] Schema name=tiny
[junit4] 2> 673473 T3080 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 673473 T3080 oasc.CoreContainer.create Creating SolrCore 'core5' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/
[junit4] 2> 673473 T3080 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 673473 T3080 oasc.SolrCore.<init> [core5] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/data/
[junit4] 2> 673473 T3080 oasc.SolrCore.<init> JMX monitoring not detected for core: core5
[junit4] 2> 673474 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/data
[junit4] 2> 673475 T3080 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/data/index/
[junit4] 2> 673475 T3080 oasc.SolrCore.initIndex WARN [core5] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/data/index' doesn't exist. Creating new index...
[junit4] 2> 673475 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/data/index
[junit4] 2> 673475 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=93.2587890625, floorSegmentMB=1.6103515625, forceMergeDeletesPctAllowed=19.760886783761134, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8632559759195321
[junit4] 2> 673476 T3080 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4a89230a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4217de0c),segFN=segments_1,generation=1}
[junit4] 2> 673476 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673479 T3080 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 673480 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673481 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673481 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673483 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673485 T3080 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 673485 T3080 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 673486 T3080 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 673486 T3080 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 673487 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=47, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 673487 T3080 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4a89230a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4217de0c),segFN=segments_1,generation=1}
[junit4] 2> 673487 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673487 T3080 oass.SolrIndexSearcher.<init> Opening Searcher@1f9e3de7[core5] main
[junit4] 2> 673488 T3080 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/conf
[junit4] 2> 673488 T3080 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/conf}
[junit4] 2> 673488 T3080 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core5/conf
[junit4] 2> 673490 T3080 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 673490 T3080 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 673490 T3080 oasc.SolrCores.putTransientCore Opening transient core core5
[junit4] 2> 673490 T3080 oasc.SolrCores$1.removeEldestEntry Closing transient core [core1]
[junit4] 2> 673490 T3080 oasc.CoreContainer.registerCore registering core: core5
[junit4] 2> 673490 T3096 oasc.SolrCore.registerSearcher [core5] Registered new searcher Searcher@1f9e3de7[core5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 673490 T3087 oasc.SolrCore.close [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@5ffdf932
[junit4] 2> 673491 T3087 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 673491 T3087 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 673491 T3087 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 673491 T3087 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 673493 T3087 oasc.SolrCore.closeSearcher [core1] Closing main searcher on request.
[junit4] 2> 673493 T3087 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 673493 T3087 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data;done=false>>]
[junit4] 2> 673494 T3087 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data
[junit4] 2> 673494 T3087 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/index;done=false>>]
[junit4] 2> 673494 T3087 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/index
[junit4] 2> 673494 T3080 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/'
[junit4] 2> 673514 T3080 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 673516 T3080 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 673521 T3080 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 673521 T3080 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/conf/schema.xml
[junit4] 2> 673524 T3080 oass.IndexSchema.readSchema [core1] Schema name=tiny
[junit4] 2> 673527 T3080 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 673527 T3080 oasc.CoreContainer.create Creating SolrCore 'core1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/
[junit4] 2> 673527 T3080 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 673527 T3080 oasc.SolrCore.<init> [core1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/
[junit4] 2> 673527 T3080 oasc.SolrCore.<init> JMX monitoring not detected for core: core1
[junit4] 2> 673528 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data
[junit4] 2> 673528 T3080 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/index/
[junit4] 2> 673528 T3080 oasc.SolrCore.initIndex WARN [core1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/index' doesn't exist. Creating new index...
[junit4] 2> 673528 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/data/index
[junit4] 2> 673529 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@65e839a0
[junit4] 2> 673529 T3080 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5d766a62 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32024b43),segFN=segments_1,generation=1}
[junit4] 2> 673529 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673532 T3080 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 673533 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673534 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673536 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673537 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673538 T3080 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 673539 T3080 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 673540 T3080 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 673540 T3080 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 673540 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1998269004, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 673540 T3080 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5d766a62 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32024b43),segFN=segments_1,generation=1}
[junit4] 2> 673540 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673541 T3080 oass.SolrIndexSearcher.<init> Opening Searcher@3548ec82[core1] main
[junit4] 2> 673541 T3080 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/conf
[junit4] 2> 673541 T3080 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/conf}
[junit4] 2> 673541 T3080 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core1/conf
[junit4] 2> 673542 T3080 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 673542 T3080 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 673542 T3080 oasc.SolrCores.putTransientCore Opening transient core core1
[junit4] 2> 673542 T3080 oasc.SolrCores$1.removeEldestEntry Closing transient core [core2]
[junit4] 2> 673542 T3098 oasc.SolrCore.registerSearcher [core1] Registered new searcher Searcher@3548ec82[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 673543 T3087 oasc.SolrCore.close [core2] CLOSING SolrCore org.apache.solr.core.SolrCore@5bc26615
[junit4] 2> 673544 T3087 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 673544 T3087 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 673544 T3087 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 673544 T3087 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 673543 T3080 oasc.CoreContainer.registerCore registering core: core1
[junit4] 2> 673546 T3087 oasc.SolrCore.closeSearcher [core2] Closing main searcher on request.
[junit4] 2> 673546 T3087 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 673546 T3087 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/index;done=false>>]
[junit4] 2> 673546 T3087 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/index
[junit4] 2> 673546 T3087 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data;done=false>>]
[junit4] 2> 673546 T3087 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data
[junit4] 2> 673547 T3080 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/'
[junit4] 2> 673572 T3080 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 673575 T3080 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 673580 T3080 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 673580 T3080 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/conf/schema.xml
[junit4] 2> 673582 T3080 oass.IndexSchema.readSchema [core2] Schema name=tiny
[junit4] 2> 673585 T3080 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 673585 T3080 oasc.CoreContainer.create Creating SolrCore 'core2' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/
[junit4] 2> 673585 T3080 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 673585 T3080 oasc.SolrCore.<init> [core2] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/
[junit4] 2> 673585 T3080 oasc.SolrCore.<init> JMX monitoring not detected for core: core2
[junit4] 2> 673586 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data
[junit4] 2> 673586 T3080 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/index/
[junit4] 2> 673587 T3080 oasc.SolrCore.initIndex WARN [core2] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/index' doesn't exist. Creating new index...
[junit4] 2> 673587 T3080 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/data/index
[junit4] 2> 673588 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=28, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3628827990625456]
[junit4] 2> 673588 T3080 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@56174bd0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@768ca87a),segFN=segments_1,generation=1}
[junit4] 2> 673588 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673591 T3080 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 673592 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673594 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673595 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673596 T3080 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 673598 T3080 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 673598 T3080 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 673599 T3080 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 673599 T3080 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 673600 T3080 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=99.052734375, floorSegmentMB=2.06640625, forceMergeDeletesPctAllowed=15.723146608562477, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 673600 T3080 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@56174bd0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@768ca87a),segFN=segments_1,generation=1}
[junit4] 2> 673600 T3080 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 673600 T3080 oass.SolrIndexSearcher.<init> Opening Searcher@2e1da80f[core2] main
[junit4] 2> 673601 T3080 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/conf
[junit4] 2> 673601 T3080 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/conf}
[junit4] 2> 673601 T3080 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core2/conf
[junit4] 2> 673601 T3080 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 673601 T3080 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 673602 T3080 oasc.SolrCores.putTransientCore Opening transient core core2
[junit4] 2> 673602 T3080 oasc.SolrCores$1.removeEldestEntry Closing transient core [core3]
[junit4] 2> 673602 T3080 oasc.CoreContainer.registerCore registering core: core2
[junit4] 2> 673602 T3100 oasc.SolrCore.registerSearcher [core2] Registered new searcher Searcher@2e1da80f[core2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 673602 T3087 oasc.SolrCore.close [core3] CLOSING SolrCore org.apache.solr.core.SolrCore@1ec0f309
[junit4] 2> 673603 T3087 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 673604 T3087 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 673604 T3087 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 673604 T3087 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 673606 T3087 oasc.SolrCore.closeSearcher [core3] Closing main searcher on request.
[junit4] 2> 673606 T3087 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 673606 T3087 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-001/./core3/data;done=false>>]
[junit4] 2> 673
[...truncated too long message...]
.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection4/data/index;done=false>>]
[junit4] 2> 677506 T3080 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection4/data/index
[junit4] 2> 677506 T3080 oasc.SolrCore.close [collectionLazy2] CLOSING SolrCore org.apache.solr.core.SolrCore@12179e21
[junit4] 2> 677507 T3080 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 677507 T3080 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 677507 T3080 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 677507 T3080 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 677508 T3080 oasc.SolrCore.closeSearcher [collectionLazy2] Closing main searcher on request.
[junit4] 2> 677508 T3080 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 677508 T3080 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection2/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection2/data;done=false>>]
[junit4] 2> 677509 T3080 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection2/data
[junit4] 2> 677509 T3080 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection2/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection2/data/index;done=false>>]
[junit4] 2> 677509 T3080 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection2/data/index
[junit4] 2> 677509 T3080 oasc.SolrCore.close [collectionLazy6] CLOSING SolrCore org.apache.solr.core.SolrCore@7577fc8f
[junit4] 2> 677509 T3080 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 677510 T3080 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 677510 T3080 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 677510 T3080 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 677512 T3080 oasc.SolrCore.closeSearcher [collectionLazy6] Closing main searcher on request.
[junit4] 2> 677512 T3080 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 677513 T3080 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection6/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection6/data;done=false>>]
[junit4] 2> 677513 T3080 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection6/data
[junit4] 2> 677513 T3080 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection6/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection6/data/index;done=false>>]
[junit4] 2> 677513 T3080 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores 2534ADF8263ED9AB-001/tempDir-007/collection6/data/index
[junit4] 2> 677513 T3080 oas.SolrTestCaseJ4.tearDown ###Ending testCreateSame
[junit4] 2> 677514 T3080 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 677514 T3080 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1119854300
[junit4] 2> 798856 T3080 oas.SolrTestCaseJ4.endTrackingSearchers ERROR ERROR: SolrIndexSearcher opens=51 closes=50
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {v_t=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=LuceneFixedGap), v_s=Lucene50(blocksize=128), v_f=FSTOrd50}, docValues:{}, sim=DefaultSimilarity, locale=es_US, timezone=UTC
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_40 (64-bit)/cpus=3,threads=1,free=161662824,total=353894400
[junit4] 2> NOTE: All tests run in this JVM: [TestOrdValues, TestComplexPhraseQParserPlugin, DirectUpdateHandlerTest, TestFreeTextSuggestions, DOMUtilTest, TestChildDocTransformer, TestHashPartitioner, TestBM25SimilarityFactory, SimpleFacetsTest, DeleteReplicaTest, SuggesterTest, TestComponentsName, AddSchemaFieldsUpdateProcessorFactoryTest, TestMaxScoreQueryParser, TestRawResponseWriter, StressHdfsTest, DistribCursorPagingTest, DistributedQueryElevationComponentTest, TestOverriddenPrefixQueryForCustomFieldType, TestManagedSchemaFieldTypeResource, UniqFieldsUpdateProcessorFactoryTest, TestManagedSchemaDynamicFieldResource, TestFastLRUCache, TestLFUCache, DocumentAnalysisRequestHandlerTest, TestRandomDVFaceting, QueryElevationComponentTest, TestReRankQParserPlugin, TestXIncludeConfig, OverriddenZkACLAndCredentialsProvidersTest, TestManagedResourceStorage, TestJettySolrRunner, AssignTest, ZkCLITest, TestSweetSpotSimilarityFactory, UnloadDistributedZkTest, TestPerFieldSimilarity, TestShardHandlerFactory, HdfsRecoveryZkTest, RollingRestartTest, TestQueryUtils, DistanceUnitsTest, TestFieldResource, TestExtendedDismaxParser, TestGroupingSearch, TimeZoneUtilsTest, XmlUpdateRequestHandlerTest, SynonymTokenizerTest, DistributedExpandComponentTest, TestReplicaProperties, JSONWriterTest, TestInitQParser, TestDistribDocBasedVersion, ActionThrottleTest, TestConfigOverlay, TestFieldSortValues, TestSort, TestSolrXml, DefaultValueUpdateProcessorTest, RemoteQueryErrorTest, TestDocumentBuilder, TestPartialUpdateDeduplication, PeerSyncTest, DistribDocExpirationUpdateProcessorTest, BlockDirectoryTest, TestFunctionQuery, MigrateRouteKeyTest, TestIntervalFaceting, TestSortingResponseWriter, TestStressReorder, SolrCoreCheckLockOnStartupTest, RecoveryZkTest, UUIDUpdateProcessorFallbackTest, TestCoreContainer, TestLazyCores]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores -Dtests.seed=2534ADF8263ED9AB -Dtests.slow=true -Dtests.locale=es_US -Dtests.timezone=UTC -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | TestLazyCores (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=51 closes=50
[junit4] > at __randomizedtesting.SeedInfo.seed([2534ADF8263ED9AB]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:496)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:232)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed on J0 in 126.12s, 7 tests, 1 failure <<< FAILURES!
[...truncated 909 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:191: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1351: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:958: There were test failures: 475 suites, 1891 tests, 1 suite-level error, 53 ignored (20 assumptions)
Total time: 54 minutes 2 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any