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 (64bit/jdk1.6.0_37) - Build # 2271 - Failure!
Date Thu, 08 Nov 2012 05:58:05 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/2271/
Java: 64bit/jdk1.6.0_37 -XX:+UseParallelGC

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

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([F5C66CEFF959FDC0:7AA5830BC2D0E043]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:515)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:482)
	at org.apache.solr.handler.dataimport.AbstractDIHJdbcTestCase.withChildEntities(AbstractDIHJdbcTestCase.java:180)
	at org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testChildEntities(TestSqlEntityProcessorDelta.java:66)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	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:662)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//*[@numFound='19']
	xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int><lst name="params"><str name="start">0</str><str name="q">*:*</str><str name="qt">standard</str><str name="rows">20</str><str name="version">2.2</str></lst></lst><result name="response" numFound="20" start="0"><doc><arr name="NAME_mult_s"><str>Jacob</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><str name="id">1</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Zealand</str><str name="COUNTRY_CODE_s">NZ</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Ethan</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NU</str></arr><str name="id">2</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niue</str><str name="COUNTRY_CODE_s">NU</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Michael</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NR</str></arr><str name="id">3</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nauru</str><str name="COUNTRY_CODE_s">NR</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Jayden</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NP</str></arr><str name="id">4</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nepal</str><str name="COUNTRY_CODE_s">NP</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>William</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NO</str></arr><str name="id">5</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norway</str><str name="COUNTRY_CODE_s">NO</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Alexander</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NL</str></arr><str name="id">6</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Netherlands</str><str name="COUNTRY_CODE_s">NL</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Noah</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NI</str></arr><str name="id">7</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nicaragua</str><str name="COUNTRY_CODE_s">NI</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Daniel</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NG</str></arr><str name="id">8</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nigeria</str><str name="COUNTRY_CODE_s">NG</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Aiden</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NF</str></arr><str name="id">9</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norfolk Island</str><str name="COUNTRY_CODE_s">NF</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Anthony</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NE</str></arr><str name="id">10</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niger</str><str name="COUNTRY_CODE_s">NE</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Emma</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NL</str></arr><str name="id">11</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Netherlands</str><str name="COUNTRY_CODE_s">NL</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Grace</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NI</str></arr><str name="id">12</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nicaragua</str><str name="COUNTRY_CODE_s">NI</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Hailey</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NG</str></arr><str name="id">13</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nigeria</str><str name="COUNTRY_CODE_s">NG</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Isabella</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NF</str></arr><str name="id">14</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norfolk Island</str><str name="COUNTRY_CODE_s">NF</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Lily</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NE</str></arr><str name="id">15</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niger</str><str name="COUNTRY_CODE_s">NE</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Madison</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NC</str></arr><str name="id">16</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Caledonia</str><str name="COUNTRY_CODE_s">NC</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Mia</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NA</str></arr><str name="id">17</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Namibia</str><str name="COUNTRY_CODE_s">NA</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Natalie</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><str name="id">18</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Zealand</str><str name="COUNTRY_CODE_s">NZ</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Olivia</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NU</str></arr><str name="id">19</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niue</str><str name="COUNTRY_CODE_s">NU</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Samantha</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NR</str></arr><str name="id">20</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nauru</str><str name="COUNTRY_CODE_s">NR</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc></result>
</response>

	request was:start=0&q=*:*&qt=standard&rows=20&version=2.2
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:508)
	... 43 more




Build Log:
[...truncated 10828 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta
[junit4:junit4]   2> 3 T55 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-1352354277683
[junit4:junit4]   2> 4 T55 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> 56 T55 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 114 T55 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4:junit4]   2> 115 T55 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 124 T55 oass.IndexSchema.readSchema Schema name=dih_test
[junit4:junit4]   2> 174 T55 oass.IndexSchema.readSchema default search field in schema is desc
[junit4:junit4]   2> 176 T55 oass.IndexSchema.readSchema query parser default operator is OR
[junit4:junit4]   2> 177 T55 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 178 T55 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 179 T55 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> 179 T55 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> 186 T55 oasc.CoreContainer.<init> New CoreContainer 1981134680
[junit4:junit4]   2> 187 T55 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-1352354277683/
[junit4:junit4]   2> 187 T55 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 187 T55 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> 188 T55 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   2> 188 T55 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 189 T55 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@bb82ef9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6913e371) assuming 'simple'
[junit4:junit4]   2> 189 T55 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683 forceNew:false
[junit4:junit4]   2> 189 T55 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 190 T55 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-1352354277683/index/
[junit4:junit4]   2> 190 T55 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-1352354277683/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 191 T55 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index forceNew:false
[junit4:junit4]   2> 261 T55 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 262 T55 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 263 T55 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 264 T55 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
[junit4:junit4]   2> 265 T55 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 266 T55 oasc.RequestHandlers.initHandlersFromConfig created /dataimport: org.apache.solr.handler.dataimport.DataImportHandler
[junit4:junit4]   2> 267 T55 oasc.RequestHandlers.initHandlersFromConfig created /search: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 269 T55 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 271 T55 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 274 T55 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 276 T55 oass.SolrIndexSearcher.<init> Opening Searcher@6fd3633c main
[junit4:junit4]   2> 276 T55 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 277 T55 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 277 T55 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 278 T55 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 278 T55 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 278 T55 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 279 T55 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 279 T55 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 279 T55 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 280 T55 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 280 T55 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 280 T55 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 284 T55 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 286 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6fd3633c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 286 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 287 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6fd3633c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 287 T55 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 291 T55 oas.SolrTestCaseJ4.setUp ###Starting testWithComplexTransformer
[junit4:junit4]   2> ASYNC  NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@45a8a7e
[junit4:junit4]   2> 295 T55 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 295 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 298 T55 C3 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 298 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 299 T55 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 5
[junit4:junit4]   2> 306 T55 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 316 T55 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 316 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 317 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 319 T55 C3 oass.SolrIndexSearcher.<init> Opening Searcher@4a0cc3e8 main
[junit4:junit4]   2> 320 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 320 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4a0cc3e8 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 321 T56 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> 321 T56 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> 322 T56 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> 322 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 323 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4a0cc3e8 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 323 T56 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 324 T55 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 18
[junit4:junit4]   2> 546 T55 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 546 T55 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 547 T55 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 548 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 569 T55 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 573 T55 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 573 T55 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 649 T55 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 650 T55 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 696 T55 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_3,generation=3,filenames=[_0_Lucene40_0.tim, _0.fnm, _0_Lucene40_0.frq, _0.fdx, _0.si, _0.fdt, segments_3, _0_Lucene40_0.tip]
[junit4:junit4]   2> 696 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0_Lucene40_0.tim, _0.fnm, _0_Lucene40_0.frq, _0.fdx, _0.si, _0.fdt, segments_3, _0_Lucene40_0.tip]
[junit4:junit4]   2> 697 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 705 T55 C3 oass.SolrIndexSearcher.<init> Opening Searcher@33bd06a0 main
[junit4:junit4]   2> 706 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 706 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@33bd06a0 main{StandardDirectoryReader(segments_3:5 _0(5.0):C60)}
[junit4:junit4]   2> 707 T56 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> 707 T56 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> 708 T56 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> 708 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 709 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@33bd06a0 main{StandardDirectoryReader(segments_3:5 _0(5.0):C60)}
[junit4:junit4]   2> 709 T56 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 709 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 710 T55 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 711 T55 C3 oashd.DocBuilder.execute Time taken = 0:0:0.162
[junit4:junit4]   2> 711 T55 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"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 170
[junit4:junit4]   2> 765 T55 C3 REQ [collection1] webapp=null path=null params={sort=id+asc&q=*:*&rows=60} hits=60 status=0 QTime=51 
[junit4:junit4]   2> 799 T55 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=6 
[junit4:junit4]   2> 812 T55 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=2 
[junit4:junit4]   2> 823 T55 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> 831 T55 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=1 
[junit4:junit4]   2> 831 T55 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 836 T55 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 836 T55 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 837 T55 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 837 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 838 T55 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 839 T55 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 839 T55 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 840 T55 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 847 T55 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 849 T55 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 1
[junit4:junit4]   2> 850 T55 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 0
[junit4:junit4]   2> 850 T55 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 854 T55 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 855 T55 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 856 T55 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 976 T55 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_3,generation=3,filenames=[_0_Lucene40_0.tim, _0.fnm, _0_Lucene40_0.frq, _0.fdx, _0.si, _0.fdt, segments_3, _0_Lucene40_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_4,generation=4,filenames=[_1_Lucene40_0.tim, _0_Lucene40_0.tim, _0.fnm, _1_Lucene40_0.frq, _1.fnm, _1_Lucene40_0.tip, _0_Lucene40_0.tip, _1.fdx, _0_Lucene40_0.frq, _1.fdt, _1.si, _0.fdx, _0.si, _0.fdt, segments_4]
[junit4:junit4]   2> 976 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[_1_Lucene40_0.tim, _0_Lucene40_0.tim, _0.fnm, _1_Lucene40_0.frq, _1.fnm, _1_Lucene40_0.tip, _0_Lucene40_0.tip, _1.fdx, _0_Lucene40_0.frq, _1.fdt, _1.si, _0.fdx, _0.si, _0.fdt, segments_4]
[junit4:junit4]   2> 977 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 985 T55 C3 oass.SolrIndexSearcher.<init> Opening Searcher@231bf785 main
[junit4:junit4]   2> 987 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 993 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@231bf785 main{StandardDirectoryReader(segments_4:7 _0(5.0):C60 _1(5.0):C3)}
[junit4:junit4]   2> 994 T56 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> 995 T56 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> 996 T56 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> 996 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 997 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@231bf785 main{StandardDirectoryReader(segments_4:7 _0(5.0):C60 _1(5.0):C3)}
[junit4:junit4]   2> 997 T56 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 998 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 999 T55 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 999 T55 C3 oashd.DocBuilder.execute Time taken = 0:0:0.161
[junit4:junit4]   2> 999 T55 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} {add=[1006, TripleThreat-1-1006, TripleThreat-2-1006],commit=} 0 166
[junit4:junit4]   2> 1002 T55 C3 REQ [collection1] webapp=null path=null params={sort=id+asc&q=*:*&rows=63} hits=63 status=0 QTime=1 
[junit4:junit4]   2> 1025 T55 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> 1033 T55 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> 1042 T55 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> 1048 T55 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1049 T55 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1051 T55 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:1006&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 1053 T55 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> 1054 T55 oas.SolrTestCaseJ4.tearDown ###Ending testWithComplexTransformer
[junit4:junit4]   2> 1067 T55 oas.SolrTestCaseJ4.setUp ###Starting testChildEntities
[junit4:junit4]   2> 1071 T55 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1072 T55 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 2
[junit4:junit4]   2> 1077 T55 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1105 T55 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_4,generation=4,filenames=[_1_Lucene40_0.tim, _0_Lucene40_0.tim, _0.fnm, _1_Lucene40_0.frq, _1.fnm, _1_Lucene40_0.tip, _0_Lucene40_0.tip, _1.fdx, _0_Lucene40_0.frq, _1.fdt, _1.si, _0.fdx, _0.si, _0.fdt, segments_4]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 1106 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 5[segments_5]
[junit4:junit4]   2> 1108 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 1112 T55 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1df95673 main
[junit4:junit4]   2> 1112 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1113 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1df95673 main{StandardDirectoryReader(segments_5:8)}
[junit4:junit4]   2> 1114 T56 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> 1114 T56 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> 1115 T56 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> 1116 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1116 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1df95673 main{StandardDirectoryReader(segments_5:8)}
[junit4:junit4]   2> 1118 T56 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 1118 T55 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 41
[junit4:junit4]   2> 1132 T55 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1133 T55 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1133 T55 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 1134 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1135 T55 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1137 T55 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1137 T55 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 1151 T55 C3 oashd.JdbcDataSource$1.call Creating a connection for entity Countries with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1152 T55 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 1178 T55 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 1179 T55 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1245 T55 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_6,generation=6,filenames=[_2.si, _2_Lucene40_0.tip, _2_Lucene40_0.frq, _2.fdt, _2_Lucene40_0.tim, _2.fdx, segments_6, _2.fnm]
[junit4:junit4]   2> 1246 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 6[_2.si, _2_Lucene40_0.tip, _2_Lucene40_0.frq, _2.fdt, _2_Lucene40_0.tim, _2.fdx, segments_6, _2.fnm]
[junit4:junit4]   2> 1247 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 1252 T55 C3 oass.SolrIndexSearcher.<init> Opening Searcher@3125ee71 main
[junit4:junit4]   2> 1253 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1256 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3125ee71 main{StandardDirectoryReader(segments_6:11 _2(5.0):C20)}
[junit4:junit4]   2> 1257 T56 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> 1257 T56 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> 1258 T56 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> 1258 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1259 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3125ee71 main{StandardDirectoryReader(segments_6:11 _2(5.0):C20)}
[junit4:junit4]   2> 1259 T56 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 1260 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1260 T55 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 1261 T55 C3 oashd.DocBuilder.execute Time taken = 0:0:0.127
[junit4:junit4]   2> 1261 T55 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"Countries"+pk%3D"Countries.CODE"+dataSource%3D"hsqldb"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+CODE,+COUNTRY_NAME+FROM+COUNTRIES+WHERE+DELETED+!%3D+'Y'+AND+CODE%3D'${People.COUNTRY_CODE}'+"+deltaQuery%3D"SELECT+CODE+FROM+COUNTRIES+WHERE+DELETED+!%3D+'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"++parentDeltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+AND+COUNTRY_CODE%3D'${Countries.CODE}'+"+>+%0a<field+column%3D"CODE"+name%3D"COUNTRY_CODE_s"+/>+%0a<field+column%3D"COUNTRY_NAME"+name%3D"COUNTRY_NAME_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 132
[junit4:junit4]   2> 1263 T55 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> 1273 T55 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> 1282 T55 C3 REQ [collection1] webapp=null path=null params={start=0&q=NAME_mult_s:Jayden&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 1288 T55 C3 REQ [collection1] webapp=null path=null params={start=0&q=COUNTRY_CODES_mult_s:NR&qt=standard&rows=20&version=2.2} hits=2 status=0 QTime=1 
[junit4:junit4]   2> 1292 T55 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1293 T55 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1300 T55 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1300 T55 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1301 T55 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 1301 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1302 T55 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 1302 T55 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: Countries
[junit4:junit4]   2> 1303 T55 C3 oashd.JdbcDataSource$1.call Creating a connection for entity Countries with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1303 T55 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 1304 T55 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: Countries rows obtained : 0
[junit4:junit4]   2> 1305 T55 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: Countries rows obtained : 0
[junit4:junit4]   2> 1305 T55 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: Countries
[junit4:junit4]   2> 1306 T55 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 1306 T55 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1307 T55 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4:junit4]   2> 1310 T55 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 0
[junit4:junit4]   2> 1310 T55 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 0
[junit4:junit4]   2> 1311 T55 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 1311 T55 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 1311 T55 C3 oashd.DocBuilder.execute Time taken = 0:0:0.10
[junit4:junit4]   2> 1312 T55 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}'+"+>+%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"Countries"+pk%3D"CODE"+dataSource%3D"hsqldb"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+CODE,+COUNTRY_NAME+FROM+COUNTRIES+WHERE+DELETED+!%3D+'Y'+AND+CODE%3D'${People.COUNTRY_CODE}'+"+deltaQuery%3D"SELECT+CODE+FROM+COUNTRIES+WHERE+DELETED+!%3D+'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"++parentDeltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+AND+COUNTRY_CODE%3D'${Countries.CODE}'+"+>+%0a<field+column%3D"CODE"+name%3D"COUNTRY_CODE_s"+/>+%0a<field+column%3D"COUNTRY_NAME"+name%3D"COUNTRY_NAME_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=delta-import&synchronous=true} {} 0 15
[junit4:junit4]   2> 1313 T55 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> 1329 T55 oas.SolrTestCaseJ4.assertQ SEVERE REQUEST FAILED: xpath=//*[@numFound='19']
[junit4:junit4]   2> 		xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4:junit4]   2> 	<response>
[junit4:junit4]   2> 	<lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int><lst name="params"><str name="start">0</str><str name="q">*:*</str><str name="qt">standard</str><str name="rows">20</str><str name="version">2.2</str></lst></lst><result name="response" numFound="20" start="0"><doc><arr name="NAME_mult_s"><str>Jacob</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><str name="id">1</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Zealand</str><str name="COUNTRY_CODE_s">NZ</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Ethan</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NU</str></arr><str name="id">2</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niue</str><str name="COUNTRY_CODE_s">NU</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Michael</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NR</str></arr><str name="id">3</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nauru</str><str name="COUNTRY_CODE_s">NR</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Jayden</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NP</str></arr><str name="id">4</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nepal</str><str name="COUNTRY_CODE_s">NP</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>William</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NO</str></arr><str name="id">5</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norway</str><str name="COUNTRY_CODE_s">NO</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Alexander</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NL</str></arr><str name="id">6</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Netherlands</str><str name="COUNTRY_CODE_s">NL</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Noah</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NI</str></arr><str name="id">7</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nicaragua</str><str name="COUNTRY_CODE_s">NI</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Daniel</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NG</str></arr><str name="id">8</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nigeria</str><str name="COUNTRY_CODE_s">NG</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Aiden</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NF</str></arr><str name="id">9</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norfolk Island</str><str name="COUNTRY_CODE_s">NF</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Anthony</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NE</str></arr><str name="id">10</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niger</str><str name="COUNTRY_CODE_s">NE</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Emma</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NL</str></arr><str name="id">11</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Netherlands</str><str name="COUNTRY_CODE_s">NL</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Grace</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NI</str></arr><str name="id">12</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nicaragua</str><str name="COUNTRY_CODE_s">NI</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Hailey</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NG</str></arr><str name="id">13</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nigeria</str><str name="COUNTRY_CODE_s">NG</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Isabella</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NF</str></arr><str name="id">14</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norfolk Island</str><str name="COUNTRY_CODE_s">NF</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Lily</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NE</str></arr><str name="id">15</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niger</str><str name="COUNTRY_CODE_s">NE</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Madison</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NC</str></arr><str name="id">16</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Caledonia</str><str name="COUNTRY_CODE_s">NC</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Mia</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NA</str></arr><str name="id">17</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Namibia</str><str name="COUNTRY_CODE_s">NA</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Natalie</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><str name="id">18</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Zealand</str><str name="COUNTRY_CODE_s">NZ</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Olivia</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NU</str></arr><str name="id">19</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niue</str><str name="COUNTRY_CODE_s">NU</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Samantha</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NR</str></arr><str name="id">20</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nauru</str><str name="COUNTRY_CODE_s">NR</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc></result>
[junit4:junit4]   2> 	</response>
[junit4:junit4]   2> 	
[junit4:junit4]   2> 		request was:start=0&q=*:*&qt=standard&rows=20&version=2.2
[junit4:junit4]   2> 1330 T55 oasc.SolrException.log SEVERE REQUEST FAILED: start=0&q=*:*&qt=standard&rows=20&version=2.2:java.lang.RuntimeException: REQUEST FAILED: xpath=//*[@numFound='19']
[junit4:junit4]   2> 		xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4:junit4]   2> 	<response>
[junit4:junit4]   2> 	<lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int><lst name="params"><str name="start">0</str><str name="q">*:*</str><str name="qt">standard</str><str name="rows">20</str><str name="version">2.2</str></lst></lst><result name="response" numFound="20" start="0"><doc><arr name="NAME_mult_s"><str>Jacob</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><str name="id">1</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Zealand</str><str name="COUNTRY_CODE_s">NZ</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Ethan</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NU</str></arr><str name="id">2</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niue</str><str name="COUNTRY_CODE_s">NU</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Michael</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NR</str></arr><str name="id">3</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nauru</str><str name="COUNTRY_CODE_s">NR</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Jayden</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NP</str></arr><str name="id">4</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nepal</str><str name="COUNTRY_CODE_s">NP</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>William</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NO</str></arr><str name="id">5</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norway</str><str name="COUNTRY_CODE_s">NO</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Alexander</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NL</str></arr><str name="id">6</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Netherlands</str><str name="COUNTRY_CODE_s">NL</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Noah</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NI</str></arr><str name="id">7</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nicaragua</str><str name="COUNTRY_CODE_s">NI</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Daniel</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NG</str></arr><str name="id">8</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nigeria</str><str name="COUNTRY_CODE_s">NG</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Aiden</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NF</str></arr><str name="id">9</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norfolk Island</str><str name="COUNTRY_CODE_s">NF</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Anthony</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NE</str></arr><str name="id">10</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niger</str><str name="COUNTRY_CODE_s">NE</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Emma</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NL</str></arr><str name="id">11</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Netherlands</str><str name="COUNTRY_CODE_s">NL</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Grace</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NI</str></arr><str name="id">12</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nicaragua</str><str name="COUNTRY_CODE_s">NI</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Hailey</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NG</str></arr><str name="id">13</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nigeria</str><str name="COUNTRY_CODE_s">NG</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Isabella</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NF</str></arr><str name="id">14</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norfolk Island</str><str name="COUNTRY_CODE_s">NF</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Lily</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NE</str></arr><str name="id">15</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niger</str><str name="COUNTRY_CODE_s">NE</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Madison</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NC</str></arr><str name="id">16</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Caledonia</str><str name="COUNTRY_CODE_s">NC</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Mia</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NA</str></arr><str name="id">17</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Namibia</str><str name="COUNTRY_CODE_s">NA</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Natalie</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><str name="id">18</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Zealand</str><str name="COUNTRY_CODE_s">NZ</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Olivia</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NU</str></arr><str name="id">19</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niue</str><str name="COUNTRY_CODE_s">NU</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Samantha</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NR</str></arr><str name="id">20</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nauru</str><str name="COUNTRY_CODE_s">NR</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc></result>
[junit4:junit4]   2> 	</response>
[junit4:junit4]   2> 	
[junit4:junit4]   2> 		request was:start=0&q=*:*&qt=standard&rows=20&version=2.2
[junit4:junit4]   2> 		at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:508)
[junit4:junit4]   2> 		at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:482)
[junit4:junit4]   2> 		at org.apache.solr.handler.dataimport.AbstractDIHJdbcTestCase.withChildEntities(AbstractDIHJdbcTestCase.java:180)
[junit4:junit4]   2> 		at org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testChildEntities(TestSqlEntityProcessorDelta.java:66)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:597)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 1332 T55 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> 1333 T55 oas.SolrTestCaseJ4.tearDown ###Ending testChildEntities
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSqlEntityProcessorDelta -Dtests.method=testChildEntities -Dtests.seed=F5C66CEFF959FDC0 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_SY -Dtests.timezone=PST8PDT -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR   0.31s J1 | TestSqlEntityProcessorDelta.testChildEntities <<<
[junit4:junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([F5C66CEFF959FDC0:7AA5830BC2D0E043]:0)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:515)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:482)
[junit4:junit4]    > 	at org.apache.solr.handler.dataimport.AbstractDIHJdbcTestCase.withChildEntities(AbstractDIHJdbcTestCase.java:180)
[junit4:junit4]    > 	at org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testChildEntities(TestSqlEntityProcessorDelta.java:66)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:597)
[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:662)
[junit4:junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//*[@numFound='19']
[junit4:junit4]    > 	xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4:junit4]    > <response>
[junit4:junit4]    > <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int><lst name="params"><str name="start">0</str><str name="q">*:*</str><str name="qt">standard</str><str name="rows">20</str><str name="version">2.2</str></lst></lst><result name="response" numFound="20" start="0"><doc><arr name="NAME_mult_s"><str>Jacob</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><str name="id">1</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Zealand</str><str name="COUNTRY_CODE_s">NZ</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Ethan</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NU</str></arr><str name="id">2</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niue</str><str name="COUNTRY_CODE_s">NU</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Michael</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NR</str></arr><str name="id">3</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nauru</str><str name="COUNTRY_CODE_s">NR</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Jayden</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NP</str></arr><str name="id">4</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nepal</str><str name="COUNTRY_CODE_s">NP</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>William</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NO</str></arr><str name="id">5</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norway</str><str name="COUNTRY_CODE_s">NO</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Alexander</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NL</str></arr><str name="id">6</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Netherlands</str><str name="COUNTRY_CODE_s">NL</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Noah</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NI</str></arr><str name="id">7</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nicaragua</str><str name="COUNTRY_CODE_s">NI</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Daniel</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NG</str></arr><str name="id">8</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nigeria</str><str name="COUNTRY_CODE_s">NG</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Aiden</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NF</str></arr><str name="id">9</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norfolk Island</str><str name="COUNTRY_CODE_s">NF</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Anthony</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NE</str></arr><str name="id">10</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niger</str><str name="COUNTRY_CODE_s">NE</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Emma</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NL</str></arr><str name="id">11</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Netherlands</str><str name="COUNTRY_CODE_s">NL</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Grace</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NI</str></arr><str name="id">12</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nicaragua</str><str name="COUNTRY_CODE_s">NI</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Hailey</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NG</str></arr><str name="id">13</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nigeria</str><str name="COUNTRY_CODE_s">NG</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Isabella</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NF</str></arr><str name="id">14</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Norfolk Island</str><str name="COUNTRY_CODE_s">NF</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Lily</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NE</str></arr><str name="id">15</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niger</str><str name="COUNTRY_CODE_s">NE</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Madison</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NC</str></arr><str name="id">16</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Caledonia</str><str name="COUNTRY_CODE_s">NC</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Mia</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NA</str></arr><str name="id">17</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Namibia</str><str name="COUNTRY_CODE_s">NA</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Natalie</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><str name="id">18</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">New Zealand</str><str name="COUNTRY_CODE_s">NZ</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Olivia</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NU</str></arr><str name="id">19</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Niue</str><str name="COUNTRY_CODE_s">NU</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc><doc><arr name="NAME_mult_s"><str>Samantha</str></arr><arr name="COUNTRY_CODES_mult_s"><str>NR</str></arr><str name="id">20</str><str name="AddAColumn_s">Added</str><str name="COUNTRY_NAME_s">Nauru</str><str name="COUNTRY_CODE_s">NR</str><date name="timestamp">2012-11-08T05:57:58.808Z</date></doc></result>
[junit4:junit4]    > </response>
[junit4:junit4]    > 
[junit4:junit4]    > 	request was:start=0&q=*:*&qt=standard&rows=20&version=2.2
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:508)
[junit4:junit4]    > 	... 43 more
[junit4:junit4]   2> 1377 T55 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
[junit4:junit4]   2> 1381 T55 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1382 T55 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 2
[junit4:junit4]   2> 1386 T55 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1436 T55 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_6,generation=6,filenames=[_2.si, _2_Lucene40_0.tip, _2_Lucene40_0.frq, _2.fdt, _2_Lucene40_0.tim, _2.fdx, segments_6, _2.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 1436 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 7[segments_7]
[junit4:junit4]   2> 1438 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 1440 T55 C3 oass.SolrIndexSearcher.<init> Opening Searcher@76fdbc27 main
[junit4:junit4]   2> 1441 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1441 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@76fdbc27 main{StandardDirectoryReader(segments_7:12)}
[junit4:junit4]   2> 1442 T56 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> 1443 T56 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> 1444 T56 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> 1444 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1444 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@76fdbc27 main{StandardDirectoryReader(segments_7:12)}
[junit4:junit4]   2> 1445 T56 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 1446 T55 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 60
[junit4:junit4]   2> 1455 T55 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1456 T55 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1456 T55 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 1457 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1458 T55 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1459 T55 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1460 T55 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 1474 T55 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 1474 T55 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1526 T55 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_8,generation=8,filenames=[_3.fdx, _3_Lucene40_0.tip, _3_Lucene40_0.frq, _3_Lucene40_0.tim, segments_8, _3.si, _3.fdt, _3.fnm]
[junit4:junit4]   2> 1527 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 8[_3.fdx, _3_Lucene40_0.tip, _3_Lucene40_0.frq, _3_Lucene40_0.tim, segments_8, _3.si, _3.fdt, _3.fnm]
[junit4:junit4]   2> 1528 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 1539 T55 C3 oass.SolrIndexSearcher.<init> Opening Searcher@654d8a08 main
[junit4:junit4]   2> 1540 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1546 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@654d8a08 main{StandardDirectoryReader(segments_8:15 _3(5.0):C20)}
[junit4:junit4]   2> 1548 T56 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> 1548 T56 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> 1549 T56 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> 1549 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1550 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@654d8a08 main{StandardDirectoryReader(segments_8:15 _3(5.0):C20)}
[junit4:junit4]   2> 1550 T56 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 1550 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1551 T55 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 1552 T55 C3 oashd.DocBuilder.execute Time taken = 0:0:0.95
[junit4:junit4]   2> 1552 T55 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 100
[junit4:junit4]   2> 1553 T55 C3 REQ [collection1] webapp=null path=null params={start=0&q=*:*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=0 
[junit4:junit4]   2> 1560 T55 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1564 T55 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1565 T55 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1565 T55 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 1566 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1566 T55 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 1567 T55 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 1567 T55 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1568 T55 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4:junit4]   2> 1572 T55 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 0
[junit4:junit4]   2> 1572 T55 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 0
[junit4:junit4]   2> 1572 T55 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 1573 T55 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 1573 T55 C3 oashd.DocBuilder.execute Time taken = 0:0:0.7
[junit4:junit4]   2> 1574 T55 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} {} 0 12
[junit4:junit4]   2> 1575 T55 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> 1580 T55 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1580 T55 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> 1581 T55 oas.SolrTestCaseJ4.tearDown ###Ending testSingleEntity
[junit4:junit4]   2> 1586 T55 oas.SolrTestCaseJ4.setUp ###Starting testWithSimpleTransformer
[junit4:junit4]   2> 1590 T55 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1591 T55 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 1596 T55 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1617 T55 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_8,generation=8,filenames=[_3.fdx, _3_Lucene40_0.tip, _3_Lucene40_0.frq, _3_Lucene40_0.tim, segments_8, _3.si, _3.fdt, _3.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_9,generation=9,filenames=[segments_9]
[junit4:junit4]   2> 1617 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 9[segments_9]
[junit4:junit4]   2> 1619 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 1621 T55 C3 oass.SolrIndexSearcher.<init> Opening Searcher@5f996bbd main
[junit4:junit4]   2> 1621 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1622 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5f996bbd main{StandardDirectoryReader(segments_9:16)}
[junit4:junit4]   2> 1622 T56 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> 1623 T56 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> 1623 T56 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> 1624 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1624 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5f996bbd main{StandardDirectoryReader(segments_9:16)}
[junit4:junit4]   2> 1625 T56 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 1625 T55 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 30
[junit4:junit4]   2> 1634 T55 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1634 T55 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1634 T55 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 1635 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1636 T55 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1637 T55 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1638 T55 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4:junit4]   2> 1652 T55 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 1652 T55 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1726 T55 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_9,generation=9,filenames=[segments_9]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_a,generation=10,filenames=[_4.fdx, segments_a, _4_Lucene40_0.frq, _4_Lucene40_0.tip, _4.fnm, _4.si, _4.fdt, _4_Lucene40_0.tim]
[junit4:junit4]   2> 1726 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 10[_4.fdx, segments_a, _4_Lucene40_0.frq, _4_Lucene40_0.tip, _4.fnm, _4.si, _4.fdt, _4_Lucene40_0.tim]
[junit4:junit4]   2> 1727 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 1731 T55 C3 oass.SolrIndexSearcher.<init> Opening Searcher@34bdb859 main
[junit4:junit4]   2> 1732 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1735 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@34bdb859 main{StandardDirectoryReader(segments_a:19 _4(5.0):C20)}
[junit4:junit4]   2> 1735 T56 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> 1736 T56 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> 1737 T56 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> 1737 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1737 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34bdb859 main{StandardDirectoryReader(segments_a:19 _4(5.0):C20)}
[junit4:junit4]   2> 1738 T56 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 1738 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1739 T55 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 1739 T55 C3 oashd.DocBuilder.execute Time taken = 0:0:0.104
[junit4:junit4]   2> 1739 T55 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}'+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 108
[junit4:junit4]   2> 1741 T55 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> 1748 T55 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1752 T55 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1752 T55 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1753 T55 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 1753 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1754 T55 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 1754 T55 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 1755 T55 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 1755 T55 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 1758 T55 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1760 T55 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 3
[junit4:junit4]   2> 1760 T55 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 1
[junit4:junit4]   2> 1761 T55 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 1761 T55 C3 oashd.DocBuilder.deleteAll Deleting stale documents 
[junit4:junit4]   2> 1762 T55 C3 oashd.DocBuilder.findMatchingPkColumn Resolving deltaQuery column 'ID' to match entity's declared pk 'People.ID'
[junit4:junit4]   2> 1762 T55 C3 oashd.SolrWriter.deleteDoc Deleting document: 11
[junit4:junit4]   2> 1766 T55 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 1766 T55 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 1767 T55 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1836 T55 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_a,generation=10,filenames=[_4.fdx, segments_a, _4_Lucene40_0.frq, _4_Lucene40_0.tip, _4.fnm, _4.si, _4.fdt, _4_Lucene40_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/index6122261223tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4edfe491; maxCacheMB=0.7978515625 maxMergeSizeMB=0.4501953125)),segFN=segments_b,generation=11,filenames=[_5.fnm, _5_Lucene40_0.frq, _5.fdt, segments_b, _4.fnm, _4.fdt, _4.si, _4.fdx, _5.si, _5_Lucene40_0.tim, _5_Lucene40_0.tip, _4_Lucene40_0.frq, _5.fdx, _4_Lucene40_0.tip, _4_1.del, _4_Lucene40_0.tim]
[junit4:junit4]   2> 1836 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 11[_5.fnm, _5_Lucene40_0.frq, _5.fdt, segments_b, _4.fnm, _4.fdt, _4.si, _4.fdx, _5.si, _5_Lucene40_0.tim, _5_Lucene40_0.tip, _4_Lucene40_0.frq, _5.fdx, _4_Lucene40_0.tip, _4_1.del, _4_Lucene40_0.tim]
[junit4:junit4]   2> 1837 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683
[junit4:junit4]   2> 1845 T55 C3 oass.SolrIndexSearcher.<init> Opening Searcher@145a25f3 main
[junit4:junit4]   2> 1845 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1849 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@145a25f3 main{StandardDirectoryReader(segments_b:22 _4(5.0):C20/2 _5(5.0):C3)}
[junit4:junit4]   2> 1850 T56 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> 1850 T56 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> 1851 T56 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> 1851 T56 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1852 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@145a25f3 main{StandardDirectoryReader(segments_b:22 _4(5.0):C20/2 _5(5.0):C3)}
[junit4:junit4]   2> 1852 T56 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 1853 T55 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1853 T55 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 1854 T55 C3 oashd.DocBuilder.execute Time taken = 0:0:0.101
[junit4:junit4]   2> 1854 T55 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=[11],add=[1018, 5, 1004],commit=} 0 104
[junit4:junit4]   2> 1856 T55 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=1 
[junit4:junit4]   2> 1863 T55 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1865 T55 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:1018&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 1868 T55 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:1004&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 1871 T55 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:11&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1874 T55 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=1 
[junit4:junit4]   2> 1878 T55 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> 1879 T55 oas.SolrTestCaseJ4.tearDown ###Ending testWithSimpleTransformer
[junit4:junit4]   2> 1882 T55 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 1882 T55 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1981134680
[junit4:junit4]   2> 1883 T55 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@45a8a7e
[junit4:junit4]   2> 1883 T55 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=10,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=126,cumulative_deletesById=1,cumulative_deletesByQuery=8,cumulative_errors=0}
[junit4:junit4]   2> 1883 T55 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 1884 T55 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 1884 T55 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 1885 T55 C3 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> 1885 T55 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 1886 T55 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352354277683/index
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=ar_SY, timezone=PST8PDT
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic amd64/Sun Microsystems Inc. 1.6.0_37 (64-bit)/cpus=8,threads=1,free=111816496,total=137560064
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestFileListEntityProcessor, TestJdbcDataSource, TestErrorHandling, TestContentStreamDataSource, TestXPathRecordReader, TestTemplateString, TestPlainTextEntityProcessor, TestDocBuilder, TestVariableResolver, TestSqlEntityProcessorDelta]
[junit4:junit4] Completed on J1 in 1.91s, 4 tests, 1 error <<< FAILURES!

[...truncated 58 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 error, 5 ignored

Total time: 24 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.6.0_37 -XX:+UseParallelGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message