lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.7.0_09) - Build # 2268 - Failure!
Date Thu, 08 Nov 2012 02:43:12 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/2268/
Java: 32bit/jdk1.7.0_09 -server -XX:+UseConcMarkSweepGC

1 tests failed.
REGRESSION:  org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testSingleEntity

Error Message:


Stack Trace:
junit.framework.AssertionFailedError
	at __randomizedtesting.SeedInfo.seed([DB3B697582C80B36:B58415E9ED25E4B5]:0)
	at junit.framework.Assert.fail(Assert.java:48)
	at junit.framework.Assert.assertTrue(Assert.java:20)
	at junit.framework.Assert.assertTrue(Assert.java:27)
	at org.apache.solr.handler.dataimport.AbstractDIHJdbcTestCase.singleEntity(AbstractDIHJdbcTestCase.java:103)
	at org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testSingleEntity(TestSqlEntityProcessorDelta.java:44)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 11513 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta
[junit4:junit4]   2> 2 T59 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 2 T59 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/'
[junit4:junit4]   2> 42 T59 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 94 T59 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4:junit4]   2> 95 T59 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 103 T59 oass.IndexSchema.readSchema Schema name=dih_test
[junit4:junit4]   2> 150 T59 oass.IndexSchema.readSchema default search field in schema is desc
[junit4:junit4]   2> 151 T59 oass.IndexSchema.readSchema query parser default operator is OR
[junit4:junit4]   2> 152 T59 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 153 T59 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 154 T59 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr
[junit4:junit4]   2> 154 T59 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/'
[junit4:junit4]   2> 161 T59 oasc.CoreContainer.<init> New CoreContainer 7936785
[junit4:junit4]   2> 161 T59 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/
[junit4:junit4]   2> 162 T59 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 162 T59 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=solr,start=0,rows=10}, {q=rocks,start=0,rows=10}, {q=static newSearcher warming query from solrconfig.xml}]}
[junit4:junit4]   2> 162 T59 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   2> 163 T59 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 163 T59 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@e02677 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fd8b99) assuming 'simple'
[junit4:junit4]   2> 163 T59 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558 forceNew:false
[junit4:junit4]   2> 164 T59 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 164 T59 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index/
[junit4:junit4]   2> 165 T59 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 165 T59 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index forceNew:false
[junit4:junit4]   2> 167 T59 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 167 T59 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 167 T59 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 168 T59 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
[junit4:junit4]   2> 169 T59 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 171 T59 oasc.RequestHandlers.initHandlersFromConfig created /dataimport: org.apache.solr.handler.dataimport.DataImportHandler
[junit4:junit4]   2> 171 T59 oasc.RequestHandlers.initHandlersFromConfig created /search: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 172 T59 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 175 T59 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 180 T59 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 181 T59 oass.SolrIndexSearcher.<init> Opening Searcher@f9504b main
[junit4:junit4]   2> 182 T59 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 182 T59 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 182 T59 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 183 T59 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 183 T59 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 183 T59 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 184 T59 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 184 T59 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 184 T59 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 184 T59 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 185 T59 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 185 T59 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 189 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@f9504b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 189 T59 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 189 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 190 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f9504b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 191 T59 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 195 T59 oas.SolrTestCaseJ4.setUp ###Starting testChildEntities
[junit4:junit4]   2> ASYNC  NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@1d638bc
[junit4:junit4]   2> 206 T59 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 206 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 208 T59 C3 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 208 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 209 T59 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 10
[junit4:junit4]   2> 216 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 217 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 218 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 218 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 219 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@242e51 main
[junit4:junit4]   2> 219 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@242e51 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 219 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 220 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 220 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 221 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 221 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 221 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@242e51 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 222 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 222 T59 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4:junit4]   2> 480 T59 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 480 T59 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 481 T59 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 481 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 497 T59 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 500 T59 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 500 T59 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 516 T59 C3 oashd.JdbcDataSource$1.call Creating a connection for entity Sports with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 517 T59 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 564 T59 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 565 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 574 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_3,generation=3,filenames=[_0.inf, _0.si, _0.pst, _0.fld, segments_3]
[junit4:junit4]   2> 575 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0.inf, _0.si, _0.pst, _0.fld, segments_3]
[junit4:junit4]   2> 576 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 587 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@9ef54 main
[junit4:junit4]   2> 588 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 589 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@9ef54 main{StandardDirectoryReader(segments_3:5 _0(5.0):C20)}
[junit4:junit4]   2> 589 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 590 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 590 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 590 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 591 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9ef54 main{StandardDirectoryReader(segments_3:5 _0(5.0):C20)}
[junit4:junit4]   2> 591 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 592 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 592 T59 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 593 T59 C3 oashd.DocBuilder.execute Time taken = 0:0:0.111
[junit4:junit4]   2> 594 T59 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=true&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a<entity+name%3D"Sports"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+newColumnName%3D"sportsAdded_s"+newColumnValue%3D"sport_added"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+ID,+SPORT_NAME+FROM+PEOPLE_SPORTS+WHERE+DELETED+!%3D+'Y'+AND+PERSON_ID%3D'${People.ID}'+ORDER+BY+ID"+>+%0a<field+column%3D"SPORT_NAME"+name%3D"SPORT_NAME_mult_s"+/>+%0a<field+column%3D"id"+name%3D"SPORT_ID_mult_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=full-import&synchronous=true} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 119
[junit4:junit4]   2> 596 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=*:*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=1 
[junit4:junit4]   2> 626 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=AddAColumn_s:Added&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=16 
[junit4:junit4]   2> 643 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=SPORT_NAME_mult_s:Sailing&qt=standard&rows=20&version=2.2} hits=2 status=0 QTime=7 
[junit4:junit4]   2> 654 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=NAME_mult_s:Michael&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=5 
[junit4:junit4]   2> 663 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=sportsAdded_s:sport_added&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=2 
[junit4:junit4]   2> 671 T59 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 672 T59 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 679 T59 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 680 T59 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 680 T59 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 681 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 682 T59 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 682 T59 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: Sports
[junit4:junit4]   2> 683 T59 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: Sports rows obtained : 0
[junit4:junit4]   2> 683 T59 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: Sports rows obtained : 0
[junit4:junit4]   2> 684 T59 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: Sports
[junit4:junit4]   2> 684 T59 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 685 T59 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 686 T59 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 690 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 692 T59 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 4
[junit4:junit4]   2> 692 T59 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 3
[junit4:junit4]   2> 693 T59 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 693 T59 C3 oashd.DocBuilder.deleteAll Deleting stale documents 
[junit4:junit4]   2> 694 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 694 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 19
[junit4:junit4]   2> 694 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 695 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 14
[junit4:junit4]   2> 695 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 696 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 9
[junit4:junit4]   2> 697 T59 C3 oashd.JdbcDataSource$1.call Creating a connection for entity Sports with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 697 T59 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 704 T59 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 705 T59 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 705 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 712 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_3,generation=3,filenames=[_0.inf, _0.si, _0.pst, _0.fld, segments_3]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_4,generation=4,filenames=[_1.pst, _1.fld, _1.si, _1.inf, _0.inf, _0_1.liv, _0.si, _0.pst, _0.fld, segments_4]
[junit4:junit4]   2> 712 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[_1.pst, _1.fld, _1.si, _1.inf, _0.inf, _0_1.liv, _0.si, _0.pst, _0.fld, segments_4]
[junit4:junit4]   2> 713 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 717 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1e150fb main
[junit4:junit4]   2> 718 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 725 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1e150fb main{StandardDirectoryReader(segments_4:8 _0(5.0):C20/5 _1(5.0):C4)}
[junit4:junit4]   2> 725 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 726 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 727 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 727 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 728 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e150fb main{StandardDirectoryReader(segments_4:8 _0(5.0):C20/5 _1(5.0):C4)}
[junit4:junit4]   2> 728 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 729 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 729 T59 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 730 T59 C3 oashd.DocBuilder.execute Time taken = 0:0:0.49
[junit4:junit4]   2> 730 T59 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=false&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a<entity+name%3D"Sports"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+newColumnName%3D"sportsAdded_s"+newColumnValue%3D"sport_added"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+ID,+SPORT_NAME+FROM+PEOPLE_SPORTS+WHERE+DELETED+!%3D+'Y'+AND+PERSON_ID%3D'${People.ID}'+ORDER+BY+ID"+>+%0a<field+column%3D"SPORT_NAME"+name%3D"SPORT_NAME_mult_s"+/>+%0a<field+column%3D"id"+name%3D"SPORT_ID_mult_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=delta-import&synchronous=true} {delete=[19, 14, 9],add=[7, 1012, 3, 1005],commit=} 0 55
[junit4:junit4]   2> 732 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=*:*&qt=standard&rows=20&version=2.2} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 743 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=NAME_mult_s:MODIFIED\+Michael&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 756 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:1005&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=4 
[junit4:junit4]   2> 761 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:1012&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 765 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:19&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 769 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:9&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 771 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:14&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 782 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:3&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 787 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:7&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 791 T59 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 791 T59 oas.SolrTestCaseJ4.tearDown ###Ending testChildEntities
[junit4:junit4]   2> 797 T59 oas.SolrTestCaseJ4.setUp ###Starting testWithSimpleTransformer
[junit4:junit4]   2> 800 T59 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 801 T59 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 805 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 806 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_4,generation=4,filenames=[_1.pst, _1.fld, _1.si, _1.inf, _0.inf, _0_1.liv, _0.si, _0.pst, _0.fld, segments_4]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 807 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 5[segments_5]
[junit4:junit4]   2> 808 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 809 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@188b41 main
[junit4:junit4]   2> 809 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 810 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@188b41 main{StandardDirectoryReader(segments_5:9)}
[junit4:junit4]   2> 810 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 811 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 811 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 812 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 812 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@188b41 main{StandardDirectoryReader(segments_5:9)}
[junit4:junit4]   2> 812 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 813 T59 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4:junit4]   2> 822 T59 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 823 T59 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 823 T59 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 823 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 824 T59 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 825 T59 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 825 T59 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 838 T59 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 838 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 841 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_6,generation=6,filenames=[_2.si, _2.pst, _2.fld, _2.inf, segments_6]
[junit4:junit4]   2> 841 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 6[_2.si, _2.pst, _2.fld, _2.inf, segments_6]
[junit4:junit4]   2> 842 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 844 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@d1cea7 main
[junit4:junit4]   2> 844 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 851 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@d1cea7 main{StandardDirectoryReader(segments_6:12 _2(5.0):C20)}
[junit4:junit4]   2> 852 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 852 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 853 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 853 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 853 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d1cea7 main{StandardDirectoryReader(segments_6:12 _2(5.0):C20)}
[junit4:junit4]   2> 854 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 854 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 855 T59 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 855 T59 C3 oashd.DocBuilder.execute Time taken = 0:0:0.31
[junit4:junit4]   2> 856 T59 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=true&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=full-import&synchronous=true} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 35
[junit4:junit4]   2> 857 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=AddAColumn_s:Added&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=1 
[junit4:junit4]   2> 863 T59 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 867 T59 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 868 T59 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 868 T59 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 868 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 869 T59 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 869 T59 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 870 T59 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 870 T59 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 872 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 874 T59 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 6
[junit4:junit4]   2> 874 T59 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 7
[junit4:junit4]   2> 874 T59 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 875 T59 C3 oashd.DocBuilder.deleteAll Deleting stale documents 
[junit4:junit4]   2> 875 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 876 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 19
[junit4:junit4]   2> 876 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 877 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 6
[junit4:junit4]   2> 878 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 878 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 3
[junit4:junit4]   2> 879 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 879 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 15
[junit4:junit4]   2> 879 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 880 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 5
[junit4:junit4]   2> 880 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 880 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 14
[junit4:junit4]   2> 881 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 881 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 9
[junit4:junit4]   2> 887 T59 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 887 T59 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 887 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 892 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_6,generation=6,filenames=[_2.si, _2.pst, _2.fld, _2.inf, segments_6]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_7,generation=7,filenames=[_3.fld, _2.si, _2.pst, _3.inf, _2.fld, segments_7, _2.inf, _2_1.liv, _3.si, _3.pst]
[junit4:junit4]   2> 892 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 7[_3.fld, _2.si, _2.pst, _3.inf, _2.fld, segments_7, _2.inf, _2_1.liv, _3.si, _3.pst]
[junit4:junit4]   2> 893 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 894 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@84af6f main
[junit4:junit4]   2> 895 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 901 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@84af6f main{StandardDirectoryReader(segments_7:15 _2(5.0):C20/13 _3(5.0):C6)}
[junit4:junit4]   2> 901 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 901 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 902 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 902 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 903 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@84af6f main{StandardDirectoryReader(segments_7:15 _2(5.0):C20/13 _3(5.0):C6)}
[junit4:junit4]   2> 903 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 903 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 904 T59 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 904 T59 C3 oashd.DocBuilder.execute Time taken = 0:0:0.35
[junit4:junit4]   2> 905 T59 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=false&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=delta-import&synchronous=true} {delete=[19, 6, 3, 15, 5, 14, 9],add=[13, 17, 16, 11, 20, 4],commit=} 0 39
[junit4:junit4]   2> 906 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=AddAColumn_s:Added&qt=standard&rows=20&version=2.2} hits=13 status=0 QTime=1 
[junit4:junit4]   2> 912 T59 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 913 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:19&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 916 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:3&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 920 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:5&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 924 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:6&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 927 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:9&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 931 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:14&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 934 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:15&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 938 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:17&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 943 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:16&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 963 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:4&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 968 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:20&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 972 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:11&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 976 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:13&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 979 T59 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 980 T59 oas.SolrTestCaseJ4.tearDown ###Ending testWithSimpleTransformer
[junit4:junit4]   2> 985 T59 oas.SolrTestCaseJ4.setUp ###Starting testWithComplexTransformer
[junit4:junit4]   2> 988 T59 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 988 T59 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 993 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 993 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_7,generation=7,filenames=[_3.fld, _2.si, _2.pst, _3.inf, _2.fld, segments_7, _2.inf, _2_1.liv, _3.si, _3.pst]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4]   2> 994 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 8[segments_8]
[junit4:junit4]   2> 994 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 995 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@d4d55f main
[junit4:junit4]   2> 996 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 997 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@d4d55f main{StandardDirectoryReader(segments_8:16)}
[junit4:junit4]   2> 998 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 998 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 999 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 999 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1000 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d4d55f main{StandardDirectoryReader(segments_8:16)}
[junit4:junit4]   2> 1000 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 1005 T59 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4:junit4]   2> 1015 T59 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1016 T59 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1016 T59 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 1017 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1017 T59 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1018 T59 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1019 T59 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4:junit4]   2> 1068 T59 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 1068 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1073 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_9,generation=9,filenames=[_4.pst, segments_9, _4.fld, _4.si, _4.inf]
[junit4:junit4]   2> 1074 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 9[_4.pst, segments_9, _4.fld, _4.si, _4.inf]
[junit4:junit4]   2> 1074 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 1076 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@57c0ef main
[junit4:junit4]   2> 1077 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1098 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@57c0ef main{StandardDirectoryReader(segments_9:19 _4(5.0):C60)}
[junit4:junit4]   2> 1099 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1099 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1100 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1100 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1100 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@57c0ef main{StandardDirectoryReader(segments_9:19 _4(5.0):C60)}
[junit4:junit4]   2> 1101 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 1101 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1102 T59 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 1102 T59 C3 oashd.DocBuilder.execute Time taken = 0:0:0.85
[junit4:junit4]   2> 1102 T59 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=true&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"TripleThreatTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=full-import&synchronous=true} {deleteByQuery=*:*,add=[1, TripleThreat-1-1, TripleThreat-2-1, 2, TripleThreat-1-2, TripleThreat-2-2, 3, TripleThreat-1-3, TripleThreat-2-3, 4, ... (60 adds)],commit=} 0 90
[junit4:junit4]   2> 1214 T59 C3 REQ [collection1] webapp=null path=null params={sort=id+asc&q=*:*&rows=60} hits=60 status=0 QTime=110 
[junit4:junit4]   2> 1231 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:TripleThreat-1-*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=4 
[junit4:junit4]   2> 1237 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:TripleThreat-2-*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=1 
[junit4:junit4]   2> 1244 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=AddAColumn_s:Added&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=1 
[junit4:junit4]   2> 1250 T59 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1250 T59 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1258 T59 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1258 T59 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1259 T59 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 1260 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1260 T59 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 1261 T59 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 1262 T59 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1262 T59 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 1265 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1267 T59 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 6
[junit4:junit4]   2> 1268 T59 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 6
[junit4:junit4]   2> 1268 T59 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 1269 T59 C3 oashd.DocBuilder.deleteAll Deleting stale documents 
[junit4:junit4]   2> 1270 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1270 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 13
[junit4:junit4]   2> 1271 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1271 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 14
[junit4:junit4]   2> 1272 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1272 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 1
[junit4:junit4]   2> 1273 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1274 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 3
[junit4:junit4]   2> 1274 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1275 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 18
[junit4:junit4]   2> 1275 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1276 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 7
[junit4:junit4]   2> 1294 T59 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 1295 T59 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 1295 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1309 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_9,generation=9,filenames=[_4.pst, segments_9, _4.fld, _4.si, _4.inf]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_a,generation=10,filenames=[_5.si, _4.pst, _5.inf, _4.fld, segments_a, _4_1.liv, _5.pst, _4.si, _4.inf, _5.fld]
[junit4:junit4]   2> 1310 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 10[_5.si, _4.pst, _5.inf, _4.fld, segments_a, _4_1.liv, _5.pst, _4.si, _4.inf, _5.fld]
[junit4:junit4]   2> 1311 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 1314 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@765e02 main
[junit4:junit4]   2> 1315 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1333 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@765e02 main{StandardDirectoryReader(segments_a:22 _4(5.0):C60/21 _5(5.0):C18)}
[junit4:junit4]   2> 1334 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1334 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1335 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1335 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1335 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@765e02 main{StandardDirectoryReader(segments_a:22 _4(5.0):C60/21 _5(5.0):C18)}
[junit4:junit4]   2> 1336 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 1336 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1337 T59 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 1338 T59 C3 oashd.DocBuilder.execute Time taken = 0:0:0.78
[junit4:junit4]   2> 1338 T59 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=false&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"TripleThreatTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=delta-import&synchronous=true} {delete=[13, 14, 1, 3, 18, 7],add=[10, TripleThreat-1-10, TripleThreat-2-10, 15, TripleThreat-1-15, TripleThreat-2-15, 16, TripleThreat-1-16, TripleThreat-2-16, 1019, ... (18 adds)],commit=} 0 85
[junit4:junit4]   2> 1341 T59 C3 REQ [collection1] webapp=null path=null params={sort=id+asc&q=*:*&rows=45} hits=57 status=0 QTime=1 
[junit4:junit4]   2> 1355 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:TripleThreat-1-*&qt=standard&rows=20&version=2.2} hits=21 status=0 QTime=1 
[junit4:junit4]   2> 1360 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:TripleThreat-2-*&qt=standard&rows=20&version=2.2} hits=21 status=0 QTime=1 
[junit4:junit4]   2> 1368 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=AddAColumn_s:Added&qt=standard&rows=20&version=2.2} hits=21 status=0 QTime=0 
[junit4:junit4]   2> 1373 T59 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1374 T59 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1375 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:1019&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 1378 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:1&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1380 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:18&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1382 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:3&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1384 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:7&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1386 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:13&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1388 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:14&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1390 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:16&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 1393 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:4&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 1399 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:5&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=3 
[junit4:junit4]   2> 1405 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:10&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 1408 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:15&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 1411 T59 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 1411 T59 oas.SolrTestCaseJ4.tearDown ###Ending testWithComplexTransformer
[junit4:junit4]   2> 1423 T59 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
[junit4:junit4]   2> 1426 T59 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1427 T59 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 1433 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1434 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_a,generation=10,filenames=[_5.si, _4.pst, _5.inf, _4.fld, segments_a, _4_1.liv, _5.pst, _4.si, _4.inf, _5.fld]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_b,generation=11,filenames=[segments_b]
[junit4:junit4]   2> 1434 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 11[segments_b]
[junit4:junit4]   2> 1435 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 1436 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@13f21cc main
[junit4:junit4]   2> 1436 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1437 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@13f21cc main{StandardDirectoryReader(segments_b:23)}
[junit4:junit4]   2> 1438 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1438 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1439 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1439 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1439 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13f21cc main{StandardDirectoryReader(segments_b:23)}
[junit4:junit4]   2> 1439 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 1440 T59 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4:junit4]   2> 1446 T59 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1447 T59 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1447 T59 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 1448 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1448 T59 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1449 T59 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1449 T59 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 1465 T59 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 1465 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1469 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_b,generation=11,filenames=[segments_b]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_c,generation=12,filenames=[_6.pst, segments_c, _6.fld, _6.si, _6.inf]
[junit4:junit4]   2> 1469 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 12[_6.pst, segments_c, _6.fld, _6.si, _6.inf]
[junit4:junit4]   2> 1470 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 1471 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@abd349 main
[junit4:junit4]   2> 1472 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1479 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@abd349 main{StandardDirectoryReader(segments_c:26 _6(5.0):C20)}
[junit4:junit4]   2> 1479 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1480 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1480 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1480 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1481 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@abd349 main{StandardDirectoryReader(segments_c:26 _6(5.0):C20)}
[junit4:junit4]   2> 1481 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 1481 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1482 T59 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 1482 T59 C3 oashd.DocBuilder.execute Time taken = 0:0:0.34
[junit4:junit4]   2> 1483 T59 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=true&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=full-import&synchronous=true} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 39
[junit4:junit4]   2> 1484 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=*:*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=1 
[junit4:junit4]   2> 1490 T59 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1495 T59 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1495 T59 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1496 T59 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 1496 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1497 T59 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 1497 T59 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 1498 T59 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1499 T59 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4:junit4]   2> 1501 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1503 T59 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 15
[junit4:junit4]   2> 1503 T59 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 7
[junit4:junit4]   2> 1503 T59 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 1504 T59 C3 oashd.DocBuilder.deleteAll Deleting stale documents 
[junit4:junit4]   2> 1504 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1505 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 9
[junit4:junit4]   2> 1505 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1505 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 10
[junit4:junit4]   2> 1506 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1506 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 12
[junit4:junit4]   2> 1507 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1507 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 13
[junit4:junit4]   2> 1507 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1508 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 19
[junit4:junit4]   2> 1508 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1508 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 3
[junit4:junit4]   2> 1509 T59 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1509 T59 C3 oashd.SolrWriter.deleteDoc Deleting document: 18
[junit4:junit4]   2> 1523 T59 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 1524 T59 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 1524 T59 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1530 T59 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_c,generation=12,filenames=[_6.pst, segments_c, _6.fld, _6.si, _6.inf]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13c9936 lockFactory=org.apache.lucene.store.NativeFSLockFactory@b0c913),segFN=segments_d,generation=13,filenames=[_7.fld, _7.inf, segments_d, _7.si, _7.pst]
[junit4:junit4]   2> 1531 T59 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 13[_7.fld, _7.inf, segments_d, _7.si, _7.pst]
[junit4:junit4]   2> 1531 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558
[junit4:junit4]   2> 1532 T59 C3 oass.SolrIndexSearcher.<init> Opening Searcher@23d097 main
[junit4:junit4]   2> 1533 T59 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1539 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@23d097 main{StandardDirectoryReader(segments_d:30 _7(5.0):C15)}
[junit4:junit4]   2> 1540 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1540 T60 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1541 T60 C3 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1541 T60 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1541 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@23d097 main{StandardDirectoryReader(segments_d:30 _7(5.0):C15)}
[junit4:junit4]   2> 1541 T60 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 1542 T59 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1542 T59 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 1543 T59 C3 oashd.DocBuilder.execute Time taken = 0:0:0.47
[junit4:junit4]   2> 1543 T59 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=false&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=delta-import&synchronous=true} {delete=[9, 10, 12, 13, 19, 3, 18],add=[17, 16, 20, 8, 1008, 11, 14, 15, 1015, 1, ... (15 adds)],commit=} 0 50
[junit4:junit4]   2> 1544 T59 C3 REQ [collection1] webapp=null path=null params={start=0&q=*:*&qt=standard&rows=20&version=2.2} hits=15 status=0 QTime=0 
[junit4:junit4]   2> 1549 T59 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1549 T59 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 1549 T59 oas.SolrTestCaseJ4.tearDown ###Ending testSingleEntity
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSqlEntityProcessorDelta -Dtests.method=testSingleEntity -Dtests.seed=DB3B697582C80B36 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_EG -Dtests.timezone=Antarctica/Macquarie -Dtests.file.encoding=US-ASCII
[junit4:junit4] FAILURE 0.18s J1 | TestSqlEntityProcessorDelta.testSingleEntity <<<
[junit4:junit4]    > Throwable #1: junit.framework.AssertionFailedError
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([DB3B697582C80B36:B58415E9ED25E4B5]:0)
[junit4:junit4]    > 	at junit.framework.Assert.fail(Assert.java:48)
[junit4:junit4]    > 	at junit.framework.Assert.assertTrue(Assert.java:20)
[junit4:junit4]    > 	at junit.framework.Assert.assertTrue(Assert.java:27)
[junit4:junit4]    > 	at org.apache.solr.handler.dataimport.AbstractDIHJdbcTestCase.singleEntity(AbstractDIHJdbcTestCase.java:103)
[junit4:junit4]    > 	at org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testSingleEntity(TestSqlEntityProcessorDelta.java:44)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 1603 T59 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 1603 T59 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=7936785
[junit4:junit4]   2> 1604 T59 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1d638bc
[junit4:junit4]   2> 1604 T59 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=12,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=163,cumulative_deletesById=23,cumulative_deletesByQuery=8,cumulative_errors=0}
[junit4:junit4]   2> 1605 T59 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 1605 T59 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 1605 T59 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 1606 T59 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> 1607 T59 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 1608 T59 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352342585558/index
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=ar_EG, timezone=Antarctica/Macquarie
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic i386/Oracle Corporation 1.7.0_09 (32-bit)/cpus=8,threads=1,free=37720928,total=64880640
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestTemplateTransformer, TestDocBuilder, TestJdbcDataSource, TestPlainTextEntityProcessor, TestFileListEntityProcessor, TestVariableResolver, TestDocBuilder2, TestXPathRecordReader, TestTemplateString, TestScriptTransformer, TestContentStreamDataSource, TestSqlEntityProcessorDelta]
[junit4:junit4] Completed on J1 in 1.63s, 4 tests, 1 failure <<< FAILURES!

[...truncated 52 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:335: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:192: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:410: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1163: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:827: There were test failures: 33 suites, 138 tests, 1 failure, 5 ignored

Total time: 25 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.7.0_09 -server -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message