[JENKINS] Lucene-Solr-6.x-Linux (32bit/jdk1.8.0_72) - Build # 327 - Failure!

[Policeman Jenkins Server]

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/327/
Java: 32bit/jdk1.8.0_72 -server -XX:+UseParallelGC

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

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([468CA039EFDFE19F:2833DCA580320E1C]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:775)
        at 
org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.singleEntity(AbstractSqlEntityProcessorTestCase.java:125)
        at 
org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testSingleEntity(TestSqlEntityProcessorDelta.java:54)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//*[@numFound='21']
        xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int><lst name="params"><str name="q">*:*</str><str 
name="qt">standard</str><str name="start">0</str><str name="rows">20</str><str 
name="version">2.2</str></lst></lst><result name="response" numFound="20" 
start="0"><doc><str name="id">1</str><arr 
name="NAME_mult_s"><str>Jacob</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">2</str><arr name="NAME_mult_s"><str>Ethan</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NU</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">3</str><arr name="NAME_mult_s"><str>Michael</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NR</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">4</str><arr name="NAME_mult_s"><str>Jayden</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NP</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">5</str><arr name="NAME_mult_s"><str>William</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NO</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">6</str><arr name="NAME_mult_s"><str>Alexander</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NL</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">7</str><arr name="NAME_mult_s"><str>Noah</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NI</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">8</str><arr name="NAME_mult_s"><str>Daniel</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NG</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">9</str><arr name="NAME_mult_s"><str>Aiden</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NF</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">11</str><arr name="NAME_mult_s"><str>Emma</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NL</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">12</str><arr name="NAME_mult_s"><str>Grace</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NI</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">13</str><arr name="NAME_mult_s"><str>Hailey</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NG</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">14</str><arr name="NAME_mult_s"><str>Isabella</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NF</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">15</str><arr name="NAME_mult_s"><str>Lily</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NE</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">16</str><arr name="NAME_mult_s"><str>Madison</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NC</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">17</str><arr name="NAME_mult_s"><str>Mia</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NA</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">18</str><arr name="NAME_mult_s"><str>Natalie</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">19</str><arr name="NAME_mult_s"><str>Olivia</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NU</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">20</str><arr name="NAME_mult_s"><str>Samantha</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NR</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">21</str><arr name="NAME_mult_s"><str>Anthony</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NE</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc></result>
</response>

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




Build Log:
[...truncated 16327 lines...]
   [junit4] Suite: 
org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001
   [junit4]   2> 10740 T43 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(false) and clientAuth (false)
   [junit4]   2> 10750 T43 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 10750 T43 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001/collection1'
   [junit4]   2> 10750 T43 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 10751 T43 oasc.SolrResourceLoader.locateSolrHome using system 
property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001
   [junit4]   2> 10756 T43 oasc.SolrConfig.refreshRequestParams current version 
of requestparams : -1
   [junit4]   2> 10760 T43 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
6.1.0
   [junit4]   2> 10768 T43 oasc.SolrConfig.<init> Loaded SolrConfig: 
dataimport-solrconfig.xml
   [junit4]   2> 10774 T43 oass.IndexSchema.readSchema [null] Schema 
name=dih_test
   [junit4]   2> 10786 T43 oass.IndexSchema.readSchema default search field in 
schema is desc
   [junit4]   2> 10786 T43 oass.IndexSchema.readSchema query parser default 
operator is OR
   [junit4]   2> 10787 T43 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 10787 T43 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 10787 T43 oasc.SolrResourceLoader.locateSolrHome using system 
property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001
   [junit4]   2> 10787 T43 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001'
   [junit4]   2> 10787 T43 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 10787 T43 oasc.SolrResourceLoader.locateSolrHome using system 
property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001
   [junit4]   2> 10788 T43 oasc.CoreContainer.<init> New CoreContainer 32599612
   [junit4]   2> 10788 T43 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001]
   [junit4]   2> 10788 T43 oasc.CoreContainer.load WARN Couldn't add files from 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001/lib
 to classpath: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001/lib
   [junit4]   2> 10788 T43 oashc.HttpShardHandlerFactory.init created with 
socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : 
false,useRetries : false,
   [junit4]   2> 10790 T43 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 10790 T43 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 10790 T43 oasl.LogWatcher.newRegisteredLogWatcher Registering 
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 10790 T43 oasc.CoreContainer.initializeAuthorizationPlugin 
Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 10790 T43 oasc.CoreContainer.initializeAuthenticationPlugin No 
authentication plugin used.
   [junit4]   2> 10791 T43 oasc.CoreDescriptor.<init> Created CoreDescriptor: 
{name=collection1, config=dataimport-solrconfig.xml, transient=false, 
schema=dataimport-schema.xml, loadOnStartup=true, 
configSetProperties=configsetprops.json, collection=collection1, 
dataDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001,
 shard=shard1}
   [junit4]   2> 10796 T45 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001/collection1'
   [junit4]   2> 10797 T45 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 10797 T45 oasc.SolrResourceLoader.locateSolrHome using system 
property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001
   [junit4]   2> 10805 T45 oasc.SolrConfig.refreshRequestParams current version 
of requestparams : -1
   [junit4]   2> 10810 T45 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
6.1.0
   [junit4]   2> 10817 T45 oasc.SolrConfig.<init> Loaded SolrConfig: 
dataimport-solrconfig.xml
   [junit4]   2> 10821 T45 oass.IndexSchema.readSchema [collection1] Schema 
name=dih_test
   [junit4]   2> 10832 T45 oass.IndexSchema.readSchema default search field in 
schema is desc
   [junit4]   2> 10832 T45 oass.IndexSchema.readSchema query parser default 
operator is OR
   [junit4]   2> 10832 T45 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 10834 T45 oasc.ConfigSetProperties.readFromResourceLoader Did 
not find ConfigSet properties, assuming default properties: Can't find resource 
'configsetprops.json' in classpath or 
'/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001/collection1'
   [junit4]   2> 10834 T45 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001/collection1
   [junit4]   2> 10834 T45 x:x:collection1 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 10835 T45 x:x:collection1 oasc.SolrCore.<init> [[collection1] 
] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001/]
   [junit4]   2> 10835 T45 x:x:collection1 oasc.SolrCore.initInfoRegistry JMX 
monitoring not detected for core: collection1
   [junit4]   2> 10835 T45 x:x:collection1 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]   2> 10835 T45 x:x:collection1 oasc.SolrCore.initListeners 
[[collection1] ] Added SolrEventListener for firstSearcher: 
[org.apache.solr.core.QuerySenderListener{queries=[]}]
   [junit4]   2> 10836 T45 x:x:collection1 oasc.CachingDirectoryFactory.get 
return new directory for 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001
   [junit4]   2> 10836 T45 x:x:collection1 oasc.SolrCore.getNewIndexDir New 
index directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001/index/
   [junit4]   2> 10836 T45 x:x:collection1 oasc.SolrCore.initIndex WARN 
[collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 10837 T45 x:x:collection1 oasc.CachingDirectoryFactory.get 
return new directory for 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001/index
   [junit4]   2> 10838 T45 x:x:collection1 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_1,generation=1}
   [junit4]   2> 10838 T45 x:x:collection1 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 10838 T47 x:x:collection1 oasc.SolrCore$11.run Looking for old 
index directories to cleanup for core collection1 in 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001/
   [junit4]   2> 10840 T45 x:x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "" 
(default)
   [junit4]   2> 10840 T45 x:x:collection1 
oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
   [junit4]   2> 10841 T45 x:x:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 10842 T45 x:x:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 10843 T45 x:x:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 10844 T45 x:x:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 10845 T45 x:x:collection1 
oasc.RequestHandlers.initHandlersFromConfig Registered paths: 
/admin/mbeans,standard,,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/search,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/dataimport
   [junit4]   2> 10845 T45 x:x:collection1 oasc.SolrCore.initStatsCache Using 
default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 10845 T45 x:x:collection1 oasu.CommitTracker.<init> Hard 
AutoCommit: disabled
   [junit4]   2> 10846 T45 x:x:collection1 oasu.CommitTracker.<init> Soft 
AutoCommit: disabled
   [junit4]   2> 10846 T45 x:x:collection1 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_1,generation=1}
   [junit4]   2> 10846 T45 x:x:collection1 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 10846 T45 x:x:collection1 oass.SolrIndexSearcher.<init> 
Opening [Searcher@1449cbc[collection1] main]
   [junit4]   2> 10847 T45 x:x:collection1 
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage 
initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001/collection1/conf
   [junit4]   2> 10847 T45 x:x:collection1 oasr.RestManager.init Initializing 
RestManager with initArgs: 
{storageDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001/collection1/conf}
   [junit4]   2> 10847 T45 x:x:collection1 oasr.ManagedResourceStorage.load 
Reading _rest_managed.json using 
file:dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/core-home-001/collection1/conf
   [junit4]   2> 10847 T45 x:x:collection1 oasr.RestManager.init Initializing 0 
registered ManagedResources
   [junit4]   2> 10847 T45 x:x:collection1 oash.ReplicationHandler.inform 
Commits will be reserved for  10000
   [junit4]   2> 10847 T48 x:x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1449cbc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 10847 T48 x:x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10848 T45 x:x:collection1 oasc.CoreContainer.registerCore 
registering core: collection1
   [junit4]   2> 10848 T48 x:x:collection1 oasc.SolrCore.registerSearcher 
[collection1] Registered new searcher Searcher@1449cbc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 10849 T43 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 10852 T43 oas.SolrTestCaseJ4.setUp ###Starting 
testWithSimpleTransformer
   [junit4]   2> ASYNC  NEW_CORE C4 name=collection1 
org.apache.solr.core.SolrCore@1b7dc1b
   [junit4]   2> 10853 T43 C4 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 10853 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 10856 T43 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10857 T43 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_2,generation=2}
   [junit4]   2> 10857 T43 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 10858 T43 C4 oass.SolrIndexSearcher.<init> Opening 
[Searcher@da0ff8[collection1] main]
   [junit4]   2> 10858 T43 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 10859 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@da0ff8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 10859 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 10859 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 10860 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher}
 hits=0 status=0 QTime=0
   [junit4]   2> 10860 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10860 T48 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@da0ff8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 10860 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null params={}{commit=} 0 5
   [junit4]   2> 10956 T43 C4 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 10956 T43 C4 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 10956 T43 C4 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 10957 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 10957 T43 C4 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 10958 T43 C4 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 10959 T43 C4 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 10966 T43 C4 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 10967 T43 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10971 T43 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_3,generation=3}
   [junit4]   2> 10971 T43 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 10974 T43 C4 oass.SolrIndexSearcher.<init> Opening 
[Searcher@5f66bf[collection1] main]
   [junit4]   2> 10974 T43 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 10975 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@5f66bf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.1.0):c20)))}
   [junit4]   2> 10975 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 10975 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 10976 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher}
 hits=0 status=0 QTime=0
   [junit4]   2> 10976 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10976 T48 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@5f66bf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.1.0):c20)))}
   [junit4]   2> 10977 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 10977 T43 C4 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 10978 T43 C4 oashd.DocBuilder.execute Time taken = 0:0:0.20
   [junit4]   2> 10978 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+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.People.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.People.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.People.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&clean=true&wt=xml&command=full-import}{deleteByQuery=*:*,add=[1,
 2, 3, 4, 5, 6, 7, 8, 9, 21, ... (20 adds)],commit=} 0 23
   [junit4]   2> 10985 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=AddAColumn_s:Added&qt=standard&start=0&rows=20&version=2.2} 
hits=20 status=0 QTime=1
   [junit4]   2> 10988 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 10989 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 10990 T43 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 11018 T43 C4 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 11018 T43 C4 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 11018 T43 C4 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 11019 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 11020 T43 C4 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 11020 T43 C4 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 11020 T43 C4 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 11021 T43 C4 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 11043 T43 C4 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 11049 T43 C4 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 8
   [junit4]   2> 11049 T43 C4 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 4
   [junit4]   2> 11049 T43 C4 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 11049 T43 C4 oashd.DocBuilder.deleteAll Deleting stale 
documents 
   [junit4]   2> 11050 T43 C4 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 11050 T43 C4 oashd.SolrWriter.deleteDoc Deleting document: 2
   [junit4]   2> 11050 T43 C4 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 11051 T43 C4 oashd.SolrWriter.deleteDoc Deleting document: 1
   [junit4]   2> 11051 T43 C4 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 11051 T43 C4 oashd.SolrWriter.deleteDoc Deleting document: 17
   [junit4]   2> 11051 T43 C4 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 11052 T43 C4 oashd.SolrWriter.deleteDoc Deleting document: 16
   [junit4]   2> 11086 T43 C4 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 11086 T43 C4 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 11086 T43 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 11097 T43 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_4,generation=4}
   [junit4]   2> 11098 T43 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 4
   [junit4]   2> 11098 T43 C4 oass.SolrIndexSearcher.<init> Opening 
[Searcher@1fe54e5[collection1] main]
   [junit4]   2> 11099 T43 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 11100 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1fe54e5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.1.0):c20/11:delGen=1)
 Uninverting(_1(6.1.0):c8)))}
   [junit4]   2> 11101 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11101 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11101 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher}
 hits=0 status=0 QTime=0
   [junit4]   2> 11101 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 11101 T48 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1fe54e5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.1.0):c20/11:delGen=1)
 Uninverting(_1(6.1.0):c8)))}
   [junit4]   2> 11102 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 11102 T43 C4 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 11112 T43 C4 oashd.DocBuilder.execute Time taken = 0:0:0.92
   [junit4]   2> 11112 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+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.People.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.People.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.People.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&clean=false&wt=xml&command=delta-import}{delete=[2,
 1, 17, 16],add=[18, 7, 6, 5, 4, 11, 1002, 15],commit=} 0 96
   [junit4]   2> 11115 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=AddAColumn_s:Added&qt=standard&start=0&rows=20&version=2.2} 
hits=17 status=0 QTime=0
   [junit4]   2> 11116 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 11117 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 11117 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:1002&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 11118 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:16&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0
   [junit4]   2> 11119 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:1&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0
   [junit4]   2> 11120 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:17&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0
   [junit4]   2> 11121 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:2&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0
   [junit4]   2> 11122 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:18&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 11124 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:4&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 11126 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:5&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 11128 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:6&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 11129 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:7&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 11131 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:11&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 11133 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:15&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 11637 T43 oas.SolrTestCaseJ4.tearDown ###Ending 
testWithSimpleTransformer
   [junit4]   2> 11640 T43 oas.SolrTestCaseJ4.setUp ###Starting 
testDeltaImportWithoutInitialFullImport
   [junit4]   2> 11641 T43 C4 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 11641 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null params={}{deleteByQuery=*:*} 0 1
   [junit4]   2> 11642 T43 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 11642 T43 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_4,generation=4}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_5,generation=5}
   [junit4]   2> 11643 T43 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 5
   [junit4]   2> 11643 T43 C4 oass.SolrIndexSearcher.<init> Opening 
[Searcher@19e8119[collection1] main]
   [junit4]   2> 11643 T43 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 11644 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@19e8119[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 11644 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11644 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11645 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher}
 hits=0 status=0 QTime=0
   [junit4]   2> 11645 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 11645 T48 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@19e8119[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 11647 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null params={}{commit=} 0 5
   [junit4]   2> 11657 T43 C4 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 11657 T43 C4 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 11657 T43 C4 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 11657 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 11658 T43 C4 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 11658 T43 C4 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 11659 T43 C4 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 11659 T43 C4 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 11665 T43 C4 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 11666 T43 C4 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 20
   [junit4]   2> 11666 T43 C4 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 11666 T43 C4 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 11681 T43 C4 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 11681 T43 C4 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 11682 T43 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 11686 T43 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_5,generation=5}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_6,generation=6}
   [junit4]   2> 11686 T43 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 6
   [junit4]   2> 11687 T43 C4 oass.SolrIndexSearcher.<init> Opening 
[Searcher@9c053f[collection1] main]
   [junit4]   2> 11688 T43 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 11690 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@9c053f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.1.0):c20)))}
   [junit4]   2> 11690 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11691 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11691 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher}
 hits=0 status=0 QTime=0
   [junit4]   2> 11691 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 11691 T48 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@9c053f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.1.0):c20)))}
   [junit4]   2> 11692 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 11692 T43 C4 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 11692 T43 C4 oashd.DocBuilder.execute Time taken = 0:0:0.35
   [junit4]   2> 11693 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%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.People.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.People.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.People.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&clean=false&wt=xml&command=delta-import}{add=[19,
 18, 17, 16, 21, 20, 11, 9, 8, 15, ... (20 adds)],commit=} 0 37
   [junit4]   2> 11694 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=20 
status=0 QTime=0
   [junit4]   2> 11699 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 11699 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 11700 T43 oas.SolrTestCaseJ4.tearDown ###Ending 
testDeltaImportWithoutInitialFullImport
   [junit4]   2> 11703 T43 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
   [junit4]   2> 11704 T43 C4 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 11704 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 11705 T43 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 11705 T43 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_6,generation=6}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_7,generation=7}
   [junit4]   2> 11706 T43 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 7
   [junit4]   2> 11706 T43 C4 oass.SolrIndexSearcher.<init> Opening 
[Searcher@1b1dccb[collection1] main]
   [junit4]   2> 11707 T43 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 11707 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1b1dccb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 11708 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11708 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11709 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher}
 hits=0 status=0 QTime=0
   [junit4]   2> 11709 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 11709 T48 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1b1dccb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 11710 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null params={}{commit=} 0 4
   [junit4]   2> 11718 T43 C4 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 11718 T43 C4 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 11718 T43 C4 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 11719 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 11720 T43 C4 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 11728 T43 C4 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 11729 T43 C4 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 11734 T43 C4 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 11734 T43 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 11737 T43 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_7,generation=7}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_8,generation=8}
   [junit4]   2> 11737 T43 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 8
   [junit4]   2> 11739 T43 C4 oass.SolrIndexSearcher.<init> Opening 
[Searcher@1e7106d[collection1] main]
   [junit4]   2> 11739 T43 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 11742 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1e7106d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.1.0):c20)))}
   [junit4]   2> 11743 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11743 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11743 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher}
 hits=0 status=0 QTime=0
   [junit4]   2> 11743 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 11744 T48 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1e7106d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.1.0):c20)))}
   [junit4]   2> 11744 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 11745 T43 C4 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 11745 T43 C4 oashd.DocBuilder.execute Time taken = 0:0:0.26
   [junit4]   2> 11745 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%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.People.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.People.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.People.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&clean=true&wt=xml&command=full-import}{deleteByQuery=*:*,add=[1,
 2, 3, 4, 5, 6, 7, 8, 9, 11, ... (20 adds)],commit=} 0 29
   [junit4]   2> 11763 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=20 
status=0 QTime=1
   [junit4]   2> 11766 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 11766 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 11766 T43 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 11770 T43 C4 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 11770 T43 C4 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 11771 T43 C4 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 11771 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 11772 T43 C4 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 11772 T43 C4 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 11772 T43 C4 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 11773 T43 C4 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 11775 T43 C4 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 11775 T43 C4 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 11775 T43 C4 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 11776 T43 C4 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 11776 T43 C4 oashd.DocBuilder.execute Time taken = 0:0:0.4
   [junit4]   2> 11776 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%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.People.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.People.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.People.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&clean=false&wt=xml&command=delta-import}{}
 0 6
   [junit4]   2> 11777 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=20 
status=0 QTime=0
   [junit4]   2> 11787 T43 oas.SolrTestCaseJ4.assertQ ERROR REQUEST FAILED: 
xpath=//*[@numFound='21']
   [junit4]   2>                xml response was: <?xml version="1.0" 
encoding="UTF-8"?>
   [junit4]   2>        <response>
   [junit4]   2>        <lst name="responseHeader"><int 
name="status">0</int><int name="QTime">0</int><lst name="params"><str 
name="q">*:*</str><str name="qt">standard</str><str name="start">0</str><str 
name="rows">20</str><str name="version">2.2</str></lst></lst><result 
name="response" numFound="20" start="0"><doc><str name="id">1</str><arr 
name="NAME_mult_s"><str>Jacob</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">2</str><arr name="NAME_mult_s"><str>Ethan</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NU</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">3</str><arr name="NAME_mult_s"><str>Michael</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NR</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">4</str><arr name="NAME_mult_s"><str>Jayden</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NP</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">5</str><arr name="NAME_mult_s"><str>William</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NO</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">6</str><arr name="NAME_mult_s"><str>Alexander</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NL</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">7</str><arr name="NAME_mult_s"><str>Noah</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NI</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">8</str><arr name="NAME_mult_s"><str>Daniel</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NG</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">9</str><arr name="NAME_mult_s"><str>Aiden</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NF</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">11</str><arr name="NAME_mult_s"><str>Emma</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NL</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">12</str><arr name="NAME_mult_s"><str>Grace</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NI</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">13</str><arr name="NAME_mult_s"><str>Hailey</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NG</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">14</str><arr name="NAME_mult_s"><str>Isabella</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NF</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">15</str><arr name="NAME_mult_s"><str>Lily</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NE</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">16</str><arr name="NAME_mult_s"><str>Madison</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NC</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">17</str><arr name="NAME_mult_s"><str>Mia</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NA</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">18</str><arr name="NAME_mult_s"><str>Natalie</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">19</str><arr name="NAME_mult_s"><str>Olivia</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NU</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">20</str><arr name="NAME_mult_s"><str>Samantha</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NR</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">21</str><arr name="NAME_mult_s"><str>Anthony</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NE</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc></result>
   [junit4]   2>        </response>
   [junit4]   2>        
   [junit4]   2>                request 
was:q=*:*&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2> 11788 T43 oasc.SolrException.log ERROR REQUEST FAILED: 
q=*:*&qt=standard&start=0&rows=20&version=2.2:java.lang.RuntimeException: 
REQUEST FAILED: xpath=//*[@numFound='21']
   [junit4]   2>                xml response was: <?xml version="1.0" 
encoding="UTF-8"?>
   [junit4]   2>        <response>
   [junit4]   2>        <lst name="responseHeader"><int 
name="status">0</int><int name="QTime">0</int><lst name="params"><str 
name="q">*:*</str><str name="qt">standard</str><str name="start">0</str><str 
name="rows">20</str><str name="version">2.2</str></lst></lst><result 
name="response" numFound="20" start="0"><doc><str name="id">1</str><arr 
name="NAME_mult_s"><str>Jacob</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">2</str><arr name="NAME_mult_s"><str>Ethan</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NU</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">3</str><arr name="NAME_mult_s"><str>Michael</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NR</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">4</str><arr name="NAME_mult_s"><str>Jayden</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NP</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">5</str><arr name="NAME_mult_s"><str>William</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NO</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">6</str><arr name="NAME_mult_s"><str>Alexander</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NL</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">7</str><arr name="NAME_mult_s"><str>Noah</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NI</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">8</str><arr name="NAME_mult_s"><str>Daniel</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NG</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">9</str><arr name="NAME_mult_s"><str>Aiden</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NF</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">11</str><arr name="NAME_mult_s"><str>Emma</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NL</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">12</str><arr name="NAME_mult_s"><str>Grace</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NI</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">13</str><arr name="NAME_mult_s"><str>Hailey</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NG</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">14</str><arr name="NAME_mult_s"><str>Isabella</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NF</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">15</str><arr name="NAME_mult_s"><str>Lily</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NE</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">16</str><arr name="NAME_mult_s"><str>Madison</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NC</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">17</str><arr name="NAME_mult_s"><str>Mia</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NA</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">18</str><arr name="NAME_mult_s"><str>Natalie</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">19</str><arr name="NAME_mult_s"><str>Olivia</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NU</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">20</str><arr name="NAME_mult_s"><str>Samantha</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NR</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">21</str><arr name="NAME_mult_s"><str>Anthony</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NE</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc></result>
   [junit4]   2>        </response>
   [junit4]   2>        
   [junit4]   2>                request 
was:q=*:*&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2>                at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:768)
   [junit4]   2>                at 
org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.singleEntity(AbstractSqlEntityProcessorTestCase.java:125)
   [junit4]   2>                at 
org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testSingleEntity(TestSqlEntityProcessorDelta.java:54)
   [junit4]   2>                at 
sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2>                at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2>                at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>                at 
java.lang.reflect.Method.invoke(Method.java:498)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
   [junit4]   2>                at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>                at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2>                at java.lang.Thread.run(Thread.java:745)
   [junit4]   2>        
   [junit4]   2> 11790 T43 oas.SolrTestCaseJ4.tearDown ###Ending 
testSingleEntity
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSqlEntityProcessorDelta -Dtests.method=testSingleEntity 
-Dtests.seed=468CA039EFDFE19F -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=en-AU -Dtests.timezone=Australia/South -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.10s J2 | TestSqlEntityProcessorDelta.testSingleEntity <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during 
query
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([468CA039EFDFE19F:2833DCA580320E1C]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:775)
   [junit4]    >        at 
org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.singleEntity(AbstractSqlEntityProcessorTestCase.java:125)
   [junit4]    >        at 
org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testSingleEntity(TestSqlEntityProcessorDelta.java:54)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//*[@numFound='21']
   [junit4]    >        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]    > <response>
   [junit4]    > <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int><lst name="params"><str name="q">*:*</str><str 
name="qt">standard</str><str name="start">0</str><str name="rows">20</str><str 
name="version">2.2</str></lst></lst><result name="response" numFound="20" 
start="0"><doc><str name="id">1</str><arr 
name="NAME_mult_s"><str>Jacob</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">2</str><arr name="NAME_mult_s"><str>Ethan</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NU</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">3</str><arr name="NAME_mult_s"><str>Michael</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NR</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">4</str><arr name="NAME_mult_s"><str>Jayden</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NP</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">5</str><arr name="NAME_mult_s"><str>William</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NO</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">6</str><arr name="NAME_mult_s"><str>Alexander</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NL</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">7</str><arr name="NAME_mult_s"><str>Noah</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NI</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">8</str><arr name="NAME_mult_s"><str>Daniel</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NG</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">9</str><arr name="NAME_mult_s"><str>Aiden</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NF</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">11</str><arr name="NAME_mult_s"><str>Emma</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NL</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">12</str><arr name="NAME_mult_s"><str>Grace</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NI</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">13</str><arr name="NAME_mult_s"><str>Hailey</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NG</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">14</str><arr name="NAME_mult_s"><str>Isabella</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NF</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">15</str><arr name="NAME_mult_s"><str>Lily</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NE</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">16</str><arr name="NAME_mult_s"><str>Madison</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NC</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">17</str><arr name="NAME_mult_s"><str>Mia</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NA</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">18</str><arr name="NAME_mult_s"><str>Natalie</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NZ</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">19</str><arr name="NAME_mult_s"><str>Olivia</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NU</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">20</str><arr name="NAME_mult_s"><str>Samantha</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NR</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc><doc><str 
name="id">21</str><arr name="NAME_mult_s"><str>Anthony</str></arr><arr 
name="COUNTRY_CODES_mult_s"><str>NE</str></arr><date 
name="timestamp">2016-04-02T15:43:39.312Z</date></doc></result>
   [junit4]    > </response>
   [junit4]    >        request 
was:q=*:*&qt=standard&start=0&rows=20&version=2.2
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:768)
   [junit4]    >        ... 41 more
   [junit4]   2> 11801 T43 oas.SolrTestCaseJ4.setUp ###Starting 
testChildEntities
   [junit4]   2> 11802 T43 C4 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 11802 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 11804 T43 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 11804 T43 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_8,generation=8}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_9,generation=9}
   [junit4]   2> 11805 T43 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 9
   [junit4]   2> 11805 T43 C4 oass.SolrIndexSearcher.<init> Opening 
[Searcher@7063bf[collection1] main]
   [junit4]   2> 11806 T43 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 11807 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@7063bf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 11807 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11807 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 11808 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher}
 hits=0 status=0 QTime=0
   [junit4]   2> 11808 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 11808 T48 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@7063bf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 11808 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null params={}{commit=} 0 4
   [junit4]   2> 11921 T43 C4 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 11922 T43 C4 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 11922 T43 C4 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 11922 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 11923 T43 C4 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 11924 T43 C4 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 11925 T43 C4 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 11930 T43 C4 oashd.JdbcDataSource$1.call Creating a connection 
for entity Countries with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 11931 T43 C4 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 11999 T43 C4 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 11999 T43 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 12003 T43 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_9,generation=9}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1cf73bd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0f28b),segFN=segments_a,generation=10}
   [junit4]   2> 12003 T43 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 10
   [junit4]   2> 12004 T43 C4 oass.SolrIndexSearcher.<init> Opening 
[Searcher@1da0ce9[collection1] main]
   [junit4]   2> 12005 T43 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 12007 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1da0ce9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.1.0):c20)))}
   [junit4]   2> 12008 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 12008 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=0
   [junit4]   2> 12009 T48 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher}
 hits=0 status=0 QTime=0
   [junit4]   2> 12009 T48 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 12009 T48 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1da0ce9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.1.0):c20)))}
   [junit4]   2> 12010 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 12010 T43 C4 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 12010 T43 C4 oashd.DocBuilder.execute Time taken = 0:0:0.87
   [junit4]   2> 12011 T43 C4 
oasup.LogUpdateProcessorFactory$LogUpdateProcessor.finish [collection1]  
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+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.People.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.People.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"derby"+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&clean=true&wt=xml&command=full-import}{deleteByQuery=*:*,add=[1,
 2, 3, 4, 5, 6, 7, 8, 9, 21, ... (20 adds)],commit=} 0 91
   [junit4]   2> 12014 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=20 
status=0 QTime=1
   [junit4]   2> 12029 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q={!term+f%3DNAME_mult_s}Samantha&qt=standard&start=0&rows=20&version=2.2}
 hits=1 status=0 QTime=0
   [junit4]   2> 12047 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=COUNTRY_CODES_mult_s:NP&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=0
   [junit4]   2> 12049 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 12049 T43 C4 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 12050 T43 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 12059 T43 C4 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 12060 T43 C4 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 12060 T43 C4 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 12060 T43 C4 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 12061 T43 C4 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 12062 T43 C4 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: Countries
   [junit4]   2> 12062 T43 C4 oashd.JdbcDataSource$1.call Creating a connection 
for entity Countries with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 12063 T43 C4 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 12067 T43 C4 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: Countries rows obtained : 6
   [junit4]   2> 12067 T43 C4 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: Countries rows obtained : 0
   [junit4]   2> 12067 T43 C4 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 12071 T43 C4 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 12075 T43 C4 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 12079 T43 C4 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 12084 T43 C4 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 12087 T43 C4 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for E

[...truncated too long message...]

ssor%3D"SqlEntityProcessor"+dataSource%3D"derby"+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.People.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.People.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.People.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&clean=false&wt=xml&command=delta-import}{delete=[15,
 14, 2, 1, 17],add=[11, TripleThreat-1-11, TripleThreat-2-11, 9, 
TripleThreat-1-9, TripleThreat-2-9, 13, TripleThreat-1-13, TripleThreat-2-13, 
12, ... (21 adds)],commit=} 0 82
   [junit4]   2> 13074 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=*:*&sort=id+asc&rows=48&wt=xml} hits=58 status=0 QTime=0
   [junit4]   2> 13079 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=id:TripleThreat-1-*&qt=standard&start=0&rows=20&version=2.2} hits=21 
status=0 QTime=1
   [junit4]   2> 13081 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=id:TripleThreat-2-*&qt=standard&start=0&rows=20&version=2.2} hits=21 
status=0 QTime=0
   [junit4]   2> 13085 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null 
params={q=%2Bid:TripleThreat-1-3+%2BNAME_mult_s:Michael+%2BNAME_mult_s:leahciM++%2BCOUNTRY_CODES_mult_s:NR+%2BCOUNTRY_CODES_mult_s:RN&qt=standard&start=0&rows=20&version=2.2}
 hits=1 status=0 QTime=0
   [junit4]   2> 13086 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=AddAColumn_s:Added&qt=standard&start=0&rows=20&version=2.2} 
hits=21 status=0 QTime=0
   [junit4]   2> 13087 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 13088 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={indent=true&wt=xml} status=0 QTime=0
   [junit4]   2> 13091 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:1012&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=2
   [junit4]   2> 13092 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:17&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0
   [junit4]   2> 13093 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:1&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0
   [junit4]   2> 13094 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:2&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0
   [junit4]   2> 13095 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:14&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0
   [junit4]   2> 13096 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:15&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0
   [junit4]   2> 13096 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:16&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 13098 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:20&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 13100 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:9&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 13101 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:11&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 13103 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:12&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 13104 T43 C5 oasc.SolrCore.execute [collection1]  webapp=null 
path=null params={q=id:13&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0
   [junit4]   2> 13609 T43 oas.SolrTestCaseJ4.tearDown ###Ending 
testWithComplexTransformer
   [junit4]   2> 13613 T43 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 13613 T43 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=32599612
   [junit4]   2> 13614 T53 x:x:collection1 oasc.SolrCore.close [collection1]  
CLOSING SolrCore org.apache.solr.core.SolrCore@1b7dc1b
   [junit4]   2> 13614 T53 x:x:collection1 oasu.DirectUpdateHandler2.close 
closing DirectUpdateHandler2{commits=13,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=180,cumulative_deletesById=9,cumulative_deletesByQuery=9,cumulative_errors=0}
   [junit4]   2> 13614 T53 x:x:collection1 
oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 13614 T53 x:x:collection1 
oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 
0 - closing IndexWriter
   [junit4]   2> 13614 T53 x:x:collection1 
oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 13616 T53 x:x:collection1 oasc.SolrCore.closeSearcher 
[collection1] Closing main searcher on request.
   [junit4]   2> 13617 T53 x:x:collection1 oasc.CachingDirectoryFactory.close 
Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 13617 T53 x:x:collection1 
oasc.CachingDirectoryFactory.closeCacheValue looking to close 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001;done=false>>]
   [junit4]   2> 13617 T53 x:x:collection1 oasc.CachingDirectoryFactory.close 
Closing directory, CoreContainer#isShutdown=true
   [junit4]   2> 13617 T53 x:x:collection1 oasc.CachingDirectoryFactory.close 
Closing directory: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001
   [junit4]   2> 13617 T53 x:x:collection1 
oasc.CachingDirectoryFactory.closeCacheValue looking to close 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001/index
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 13617 T53 x:x:collection1 oasc.CachingDirectoryFactory.close 
Closing directory, CoreContainer#isShutdown=true
   [junit4]   2> 13618 T53 x:x:collection1 oasc.CachingDirectoryFactory.close 
Closing directory: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001/init-core-data-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J2/temp/solr.handler.dataimport.TestSqlEntityProcessorDelta_468CA039EFDFE19F-001
   [junit4]   2> NOTE: test params are: 
codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST,
 chunkSize=22572, maxDocsPerChunk=259, blockSize=214), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, 
chunkSize=22572, blockSize=214)), sim=ClassicSimilarity, locale=en-AU, 
timezone=Australia/South
   [junit4]   2> NOTE: Linux 4.2.0-34-generic i386/Oracle Corporation 1.8.0_72 
(32-bit)/cpus=12,threads=1,free=173074680,total=230948864
   [junit4]   2> NOTE: All tests run in this JVM: [TestJdbcDataSource, 
TestXPathEntityProcessor, TestSqlEntityProcessor, TestNonWritablePersistFile, 
TestSqlEntityProcessorDelta]
   [junit4] Completed [21/38 (1!)] on J2 in 2.90s, 5 tests, 1 error <<< 
FAILURES!

[...truncated 62 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:740: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:684: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:59: The following error 
occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build.xml:253: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-build.xml:464: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-build.xml:529: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:1457: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:1014: 
There were test failures: 38 suites, 157 tests, 1 error, 3 ignored [seed: 
468CA039EFDFE19F]

Total time: 61 minutes 45 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org