Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10989/
Java: 64bit/jdk1.8.0_20-ea-b23 -XX:-UseCompressedOops -XX:+UseG1GC

2 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta

Error Message:
1 thread leaked from SUITE scope at 
org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta:     1) 
Thread[id=22, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]         at java.lang.Object.wait(Native 
Method)         at java.lang.Object.wait(Object.java:502)         at 
java.util.TimerThread.mainLoop(Timer.java:526)         at 
java.util.TimerThread.run(Timer.java:505)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta: 
   1) Thread[id=22, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:502)
        at java.util.TimerThread.mainLoop(Timer.java:526)
        at java.util.TimerThread.run(Timer.java:505)
        at __randomizedtesting.SeedInfo.seed([16621DA5B5936ACB]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta

Error Message:
There are still zombie threads that couldn't be terminated:    1) Thread[id=22, 
name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessorDelta]         at 
java.lang.Object.wait(Native Method)         at 
java.lang.Object.wait(Object.java:502)         at 
java.util.TimerThread.mainLoop(Timer.java:526)         at 
java.util.TimerThread.run(Timer.java:505)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   1) Thread[id=22, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:502)
        at java.util.TimerThread.mainLoop(Timer.java:526)
        at java.util.TimerThread.run(Timer.java:505)
        at __randomizedtesting.SeedInfo.seed([16621DA5B5936ACB]:0)




Build Log:
[...truncated 15441 lines...]
   [junit4] Suite: 
org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta
   [junit4]   2> Creating dataDir: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001
   [junit4]   2> 5758 T21 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(true) and clientAuth (false)
   [junit4]   2> 6532 T21 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 6533 T21 oasc.SolrResourceLoader.<init> new SolrResourceLoader 
for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001/collection1/'
   [junit4]   2> 6585 T21 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
LUCENE_5_0
   [junit4]   2> 6615 T21 oasc.SolrConfig.<init> Loaded SolrConfig: 
dataimport-solrconfig.xml
   [junit4]   2> 6616 T21 oass.IndexSchema.readSchema Reading Solr Schema from 
dataimport-schema.xml
   [junit4]   2> 6651 T21 oass.IndexSchema.readSchema [null] Schema 
name=dih_test
   [junit4]   2> 6683 T21 oass.IndexSchema.readSchema default search field in 
schema is desc
   [junit4]   2> 6683 T21 oass.IndexSchema.readSchema query parser default 
operator is OR
   [junit4]   2> 6684 T21 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 6685 T21 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 6685 T21 oasc.SolrResourceLoader.locateSolrHome using system 
property solr.solr.home: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001
   [junit4]   2> 6686 T21 oasc.SolrResourceLoader.<init> new SolrResourceLoader 
for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001/'
   [junit4]   2> 6738 T21 oasc.CoreContainer.<init> New CoreContainer 1726977122
   [junit4]   2> 6739 T21 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001/]
   [junit4]   2> 6739 T21 oashc.HttpShardHandlerFactory.getParameter Setting 
socketTimeout to: 0
   [junit4]   2> 6740 T21 oashc.HttpShardHandlerFactory.getParameter Setting 
urlScheme to: null
   [junit4]   2> 6740 T21 oashc.HttpShardHandlerFactory.getParameter Setting 
connTimeout to: 0
   [junit4]   2> 6741 T21 oashc.HttpShardHandlerFactory.getParameter Setting 
maxConnectionsPerHost to: 20
   [junit4]   2> 6741 T21 oashc.HttpShardHandlerFactory.getParameter Setting 
corePoolSize to: 0
   [junit4]   2> 6741 T21 oashc.HttpShardHandlerFactory.getParameter Setting 
maximumPoolSize to: 2147483647
   [junit4]   2> 6742 T21 oashc.HttpShardHandlerFactory.getParameter Setting 
maxThreadIdleTime to: 5
   [junit4]   2> 6742 T21 oashc.HttpShardHandlerFactory.getParameter Setting 
sizeOfQueue to: -1
   [junit4]   2> 6742 T21 oashc.HttpShardHandlerFactory.getParameter Setting 
fairnessPolicy to: false
   [junit4]   2> 6794 T21 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=false
   [junit4]   2> 6797 T21 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 6797 T21 oasl.LogWatcher.newRegisteredLogWatcher Registering 
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 6798 T21 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 6802 T23 oasc.SolrResourceLoader.<init> new SolrResourceLoader 
for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001/collection1/'
   [junit4]   2> 6845 T23 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
LUCENE_5_0
   [junit4]   2> 6870 T23 oasc.SolrConfig.<init> Loaded SolrConfig: 
dataimport-solrconfig.xml
   [junit4]   2> 6871 T23 oass.IndexSchema.readSchema Reading Solr Schema from 
dataimport-schema.xml
   [junit4]   2> 6878 T23 oass.IndexSchema.readSchema [collection1] Schema 
name=dih_test
   [junit4]   2> 6898 T23 oass.IndexSchema.readSchema default search field in 
schema is desc
   [junit4]   2> 6898 T23 oass.IndexSchema.readSchema query parser default 
operator is OR
   [junit4]   2> 6899 T23 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 6903 T23 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001/collection1/
   [junit4]   2> 6904 T23 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 6904 T23 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001/collection1/,
 
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/
   [junit4]   2> 6904 T23 oasc.SolrCore.<init> JMX monitoring not detected for 
core: collection1
   [junit4]   2> 6904 T23 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> 6905 T23 oasc.SolrCore.initListeners [collection1] Added 
SolrEventListener for firstSearcher: 
org.apache.solr.core.QuerySenderListener{queries=[]}
   [junit4]   2> 6905 T23 oasc.CachingDirectoryFactory.get return new directory 
for 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001
   [junit4]   2> 6906 T23 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index/
   [junit4]   2> 6906 T23 oasc.SolrCore.initIndex WARN [collection1] Solr index 
directory 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 6906 T23 oasc.CachingDirectoryFactory.get return new directory 
for 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index
   [junit4]   2> 6908 T23 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_1,generation=1}
   [junit4]   2> 6908 T23 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 6909 T23 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "" (default)
   [junit4]   2> 6910 T23 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
   [junit4]   2> 6910 T23 oasc.RequestHandlers.initHandlersFromConfig created 
/update: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 6910 T23 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 6910 T23 oasc.RequestHandlers.initHandlersFromConfig created 
/update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 6911 T23 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 6911 T23 oasc.RequestHandlers.initHandlersFromConfig created 
standard: solr.StandardRequestHandler
   [junit4]   2> 6911 T23 oasc.RequestHandlers.initHandlersFromConfig created 
/dataimport: org.apache.solr.handler.dataimport.DataImportHandler
   [junit4]   2> 6912 T23 oasc.RequestHandlers.initHandlersFromConfig created 
/search: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 6912 T23 oasc.RequestHandlers.initHandlersFromConfig WARN 
Multiple requestHandler registered to the same name: /update ignoring: 
org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 6912 T23 oasc.RequestHandlers.initHandlersFromConfig created 
/update: solr.UpdateRequestHandler
   [junit4]   2> 6914 T23 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 6916 T23 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 6918 T23 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 6919 T23 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 6920 T23 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 6926 T23 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 6926 T23 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 6928 T23 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_1,generation=1}
   [junit4]   2> 6928 T23 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 6929 T23 oass.SolrIndexSearcher.<init> Opening 
Searcher@4b9d2ce0[collection1] main
   [junit4]   2> 6929 T23 oasr.ManagedResourceStorage$FileStorageIO.configure 
File-based storage initialized to use dir: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001/collection1/conf
   [junit4]   2> 6929 T23 oasr.RestManager.init Initializing RestManager with 
initArgs: 
{storageDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001/collection1/conf}
   [junit4]   2> 6930 T23 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using 
file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001/collection1/conf
   [junit4]   2> 6930 T23 oasr.ManagedResource.reloadFromStorage WARN No stored 
data found for /rest/managed
   [junit4]   2> 6931 T23 oasr.ManagedResourceStorage$JsonStorage.store Saved 
JSON object to path _rest_managed.json using 
file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/core-home-001/collection1/conf
   [junit4]   2> 6931 T23 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 6931 T23 oasc.CoreContainer.registerCore registering core: 
collection1
   [junit4]   2> 6931 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@4b9d2ce0[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 6932 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 6932 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@4b9d2ce0[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 6933 T21 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 6939 T21 oas.SolrTestCaseJ4.setUp ###Starting testChildEntities
   [junit4]   2> ASYNC  NEW_CORE C7 name=collection1 
org.apache.solr.core.SolrCore@25076577
   [junit4]   2> 7063 T21 C7 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 7066 T21 C7 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 123
   [junit4]   2> 7073 T21 C7 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 7075 T21 C7 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_2,generation=2}
   [junit4]   2> 7075 T21 C7 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 7076 T21 C7 oass.SolrIndexSearcher.<init> Opening 
Searcher@61820aee[collection1] main
   [junit4]   2> 7078 T21 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 7078 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@61820aee[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 7079 T24 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=1 
   [junit4]   2> 7079 T24 C7 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> 7080 T24 C7 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=1 
   [junit4]   2> 7080 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 7080 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@61820aee[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 7080 T21 C7 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 7
   [junit4]   2> 9036 T21 C7 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 9036 T21 C7 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 9037 T21 C7 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 9037 T21 C7 oashd.SimplePropertiesWriter.readIndexerProperties 
WARN Unable to read: the.properties
   [junit4]   2> 9053 T21 C7 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 9060 T21 C7 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 9062 T21 C7 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 1
   [junit4]   2> 9073 T21 C7 oashd.JdbcDataSource$1.call Creating a connection 
for entity Countries with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 9075 T21 C7 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 1
   [junit4]   2> 9250 T21 C7 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 9251 T21 C7 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 9281 T21 C7 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_3,generation=3}
   [junit4]   2> 9282 T21 C7 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 9286 T21 C7 oass.SolrIndexSearcher.<init> Opening 
Searcher@46b07aea[collection1] main
   [junit4]   2> 9287 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@46b07aea[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):C20))}
   [junit4]   2> 9287 T24 C7 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> 9288 T24 C7 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> 9289 T24 C7 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=1 
   [junit4]   2> 9289 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 9289 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@46b07aea[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):C20))}
   [junit4]   2> 9290 T21 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 9290 T21 C7 oashd.SimplePropertiesWriter.readIndexerProperties 
WARN Unable to read: the.properties
   [junit4]   2> 9291 T21 C7 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 9291 T21 C7 oashd.DocBuilder.execute Time taken = 0:0:0.253
   [junit4]   2> 9292 T21 C7 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-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"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&command=full-import}
 {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 
0 259
   [junit4]   2> ASYNC  NEW_CORE C8 name=collection1 
org.apache.solr.core.SolrCore@25076577
   [junit4]   2> 9317 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=20 
status=0 QTime=22 
   [junit4]   2> 9375 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=NAME_mult_s:Jayden&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=22 
   [junit4]   2> 9416 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={q=COUNTRY_CODES_mult_s:NR&qt=standard&start=0&rows=20&version=2.2} 
hits=2 status=0 QTime=1 
   [junit4]   2> 9419 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 9420 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 9422 T21 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 9499 T21 C8 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 9499 T21 C8 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 9500 T21 C8 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 9501 T21 C8 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 9502 T21 C8 oashd.DocBuilder.doDelta Starting delta collection.
   [junit4]   2> 9503 T21 C8 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: Countries
   [junit4]   2> 9503 T21 C8 oashd.JdbcDataSource$1.call Creating a connection 
for entity Countries with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 9505 T21 C8 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 1
   [junit4]   2> 9537 T21 C8 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: Countries rows obtained : 6
   [junit4]   2> 9538 T21 C8 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: Countries rows obtained : 0
   [junit4]   2> 9539 T21 C8 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 9546 T21 C8 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 9554 T21 C8 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 9562 T21 C8 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 9569 T21 C8 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 9580 T21 C8 oashd.SqlEntityProcessor.nextModifiedParentRowKey 
Running parentDeltaQuery for Entity: Countries
   [junit4]   2> 9588 T21 C8 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: Countries
   [junit4]   2> 9588 T21 C8 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 9589 T21 C8 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 9590 T21 C8 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 1
   [junit4]   2> 9606 T21 C8 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 9607 T21 C8 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 9607 T21 C8 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 9692 T21 C8 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 9693 T21 C8 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 9693 T21 C8 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 9700 T21 C8 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_4,generation=4}
   [junit4]   2> 9700 T21 C8 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 4
   [junit4]   2> 9706 T21 C8 oass.SolrIndexSearcher.<init> Opening 
Searcher@6bec323e[collection1] main
   [junit4]   2> 9707 T21 C8 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 9710 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@6bec323e[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):C20/9:delGen=1) 
Uninverting(_1(5.0):C9))}
   [junit4]   2> 9711 T24 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=1 
   [junit4]   2> 9711 T24 C8 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> 9712 T24 C8 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=1 
   [junit4]   2> 9712 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 9712 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6bec323e[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):C20/9:delGen=1) 
Uninverting(_1(5.0):C9))}
   [junit4]   2> 9713 T21 C8 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 9714 T21 C8 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 9714 T21 C8 oashd.DocBuilder.execute Time taken = 0:0:0.212
   [junit4]   2> 9715 T21 C8 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-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"+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"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=false&command=delta-import}
 {add=[8, 13, 12, 3, 17, 16, 7, 4, 20],commit=} 0 220
   [junit4]   2> 9720 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=20 
status=0 QTime=2 
   [junit4]   2> 9732 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=NAME_mult_s:Jayden&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=1 
   [junit4]   2> 9741 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={q=COUNTRY_CODES_mult_s:NR&qt=standard&start=0&rows=20&version=2.2} 
hits=2 status=0 QTime=0 
   [junit4]   2> 9760 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=COUNTRY_CODE_s:NP&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=16 
   [junit4]   2> 9776 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=COUNTRY_CODE_s:NA&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=13 
   [junit4]   2> 9782 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=COUNTRY_CODE_s:NR&qt=standard&start=0&rows=20&version=2.2} 
hits=2 status=0 QTime=3 
   [junit4]   2> 9800 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=COUNTRY_CODE_s:NC&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=15 
   [junit4]   2> 9816 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=COUNTRY_CODE_s:NG&qt=standard&start=0&rows=20&version=2.2} 
hits=2 status=0 QTime=14 
   [junit4]   2> 9834 T21 C8 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=COUNTRY_CODE_s:NI&qt=standard&start=0&rows=20&version=2.2} 
hits=2 status=0 QTime=15 
   [junit4]   2> 10467 T21 oas.SolrTestCaseJ4.tearDown ###Ending 
testChildEntities
   [junit4]   2> 10477 T21 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
   [junit4]   2> ASYNC  NEW_CORE C9 name=collection1 
org.apache.solr.core.SolrCore@25076577
   [junit4]   2> 10478 T21 C9 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 10479 T21 C9 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 10480 T21 C9 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10481 T21 C9 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_4,generation=4}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_5,generation=5}
   [junit4]   2> 10481 T21 C9 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 5
   [junit4]   2> 10482 T21 C9 oass.SolrIndexSearcher.<init> Opening 
Searcher@38b43812[collection1] main
   [junit4]   2> 10483 T21 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 10484 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@38b43812[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 10484 T24 C9 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> 10485 T24 C9 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> 10486 T24 C9 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=1 
   [junit4]   2> 10486 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10487 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@38b43812[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 10488 T21 C9 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 8
   [junit4]   2> 10664 T21 C9 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 10665 T21 C9 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 10665 T21 C9 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 10666 T21 C9 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 10666 T21 C9 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 10667 T21 C9 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 10667 T21 C9 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 10686 T21 C9 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 10686 T21 C9 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10689 T21 C9 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_5,generation=5}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_6,generation=6}
   [junit4]   2> 10690 T21 C9 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 6
   [junit4]   2> 10691 T21 C9 oass.SolrIndexSearcher.<init> Opening 
Searcher@2f5cee86[collection1] main
   [junit4]   2> 10692 T21 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 10693 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@2f5cee86[collection1] 
main{UninvertingDirectoryReader(Uninverting(_2(5.0):C20))}
   [junit4]   2> 10693 T24 C9 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> 10693 T24 C9 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> 10694 T24 C9 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> 10694 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10694 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@2f5cee86[collection1] 
main{UninvertingDirectoryReader(Uninverting(_2(5.0):C20))}
   [junit4]   2> 10695 T21 C9 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 10695 T21 C9 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 10696 T21 C9 oashd.DocBuilder.execute Time taken = 0:0:0.29
   [junit4]   2> 10696 T21 C9 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-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=true&command=full-import}
 {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 
0 34
   [junit4]   2> 10701 T21 C9 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> 10705 T21 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 10706 T21 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 10707 T21 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 10712 T21 C9 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 10712 T21 C9 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 10712 T21 C9 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 10713 T21 C9 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 10714 T21 C9 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 10714 T21 C9 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 10714 T21 C9 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 10715 T21 C9 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 10719 T21 C9 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 4
   [junit4]   2> 10719 T21 C9 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 3
   [junit4]   2> 10720 T21 C9 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 10720 T21 C9 oashd.DocBuilder.deleteAll Deleting stale 
documents 
   [junit4]   2> 10720 T21 C9 oashd.SolrWriter.deleteDoc Deleting document: 11
   [junit4]   2> 10720 T21 C9 oashd.SolrWriter.deleteDoc Deleting document: 14
   [junit4]   2> 10721 T21 C9 oashd.SolrWriter.deleteDoc Deleting document: 17
   [junit4]   2> 10724 T21 C9 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 10724 T21 C9 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 10724 T21 C9 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10727 T21 C9 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_6,generation=6}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_7,generation=7}
   [junit4]   2> 10728 T21 C9 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 7
   [junit4]   2> 10729 T21 C9 oass.SolrIndexSearcher.<init> Opening 
Searcher@44579817[collection1] main
   [junit4]   2> 10730 T21 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 10731 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@44579817[collection1] 
main{UninvertingDirectoryReader(Uninverting(_2(5.0):C20/5:delGen=1) 
Uninverting(_3(5.0):C4))}
   [junit4]   2> 10732 T24 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=1 
   [junit4]   2> 10732 T24 C9 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> 10732 T24 C9 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> 10733 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10733 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@44579817[collection1] 
main{UninvertingDirectoryReader(Uninverting(_2(5.0):C20/5:delGen=1) 
Uninverting(_3(5.0):C4))}
   [junit4]   2> 10733 T21 C9 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 10734 T21 C9 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 10734 T21 C9 oashd.DocBuilder.execute Time taken = 0:0:0.21
   [junit4]   2> 10735 T21 C9 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-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=false&command=delta-import}
 {delete=[11, 14, 17],add=[3, 1008, 16, 1014],commit=} 0 25
   [junit4]   2> 10736 T21 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=19 
status=0 QTime=0 
   [junit4]   2> 10741 T21 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 10742 T21 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 10743 T21 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:1008&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> 10747 T21 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:1014&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> 10750 T21 C9 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=1 
   [junit4]   2> 10753 T21 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:11&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=1 
   [junit4]   2> 10755 T21 C9 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=1 
   [junit4]   2> 10757 T21 C9 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=1 
   [junit4]   2> 10760 T21 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:3&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> 10766 T21 oas.SolrTestCaseJ4.tearDown ###Ending 
testSingleEntity
   [junit4]   2> 10774 T21 oas.SolrTestCaseJ4.setUp ###Starting 
testWithComplexTransformer
   [junit4]   2> 10775 T21 C9 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 10776 T21 C9 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 10778 T21 C9 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10779 T21 C9 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_7,generation=7}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_8,generation=8}
   [junit4]   2> 10779 T21 C9 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 8
   [junit4]   2> 10780 T21 C9 oass.SolrIndexSearcher.<init> Opening 
Searcher@331f1503[collection1] main
   [junit4]   2> 10781 T21 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 10781 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@331f1503[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 10782 T24 C9 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=1 
   [junit4]   2> 10782 T24 C9 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> 10783 T24 C9 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=1 
   [junit4]   2> 10783 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10783 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@331f1503[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 10785 T21 C9 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 7
   [junit4]   2> ASYNC  NEW_CORE C10 name=collection1 
org.apache.solr.core.SolrCore@25076577
   [junit4]   2> 11045 T21 C10 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 11046 T21 C10 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 11046 T21 C10 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 11047 T21 C10 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 11048 T21 C10 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 11049 T21 C10 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 11050 T21 C10 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 11106 T21 C10 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 11107 T21 C10 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 11122 T21 C10 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_8,generation=8}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_9,generation=9}
   [junit4]   2> 11123 T21 C10 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 9
   [junit4]   2> 11124 T21 C10 oass.SolrIndexSearcher.<init> Opening 
Searcher@adef87e[collection1] main
   [junit4]   2> 11128 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@adef87e[collection1] 
main{UninvertingDirectoryReader(Uninverting(_4(5.0):C60))}
   [junit4]   2> 11129 T24 C10 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> 11130 T24 C10 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=1 
   [junit4]   2> 11130 T24 C10 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> 11131 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 11131 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@adef87e[collection1] 
main{UninvertingDirectoryReader(Uninverting(_4(5.0):C60))}
   [junit4]   2> 11131 T21 C10 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 11132 T21 C10 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 11133 T21 C10 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 11133 T21 C10 oashd.DocBuilder.execute Time taken = 0:0:0.85
   [junit4]   2> 11133 T21 C10 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-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"+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=true&command=full-import}
 {deleteByQuery=*:*,add=[1, TripleThreat-1-1, TripleThreat-2-1, 2, 
TripleThreat-1-2, TripleThreat-2-2, 3, TripleThreat-1-3, TripleThreat-2-3, 4, 
... (60 adds)],commit=} 0 91
   [junit4]   2> 11260 T21 C10 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&sort=id+asc&rows=60} hits=60 status=0 QTime=115 
   [junit4]   2> 11292 T21 C10 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={q=id:TripleThreat-1-*&qt=standard&start=0&rows=20&version=2.2} hits=20 
status=0 QTime=18 
   [junit4]   2> 11297 T21 C10 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={q=id:TripleThreat-2-*&qt=standard&start=0&rows=20&version=2.2} hits=20 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C11 name=collection1 
org.apache.solr.core.SolrCore@25076577
   [junit4]   2> 11343 T21 C11 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=26 
   [junit4]   2> 11346 T21 C11 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> 11350 T21 C11 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 11351 T21 C11 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 11352 T21 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 11374 T21 C11 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 11375 T21 C11 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 11375 T21 C11 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 11376 T21 C11 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 11377 T21 C11 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 11378 T21 C11 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 11378 T21 C11 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 11379 T21 C11 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 11446 T21 C11 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 2
   [junit4]   2> 11446 T21 C11 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 11446 T21 C11 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 11462 T21 C11 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 11462 T21 C11 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 11463 T21 C11 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 11466 T21 C11 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_9,generation=9}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_a,generation=10}
   [junit4]   2> 11467 T21 C11 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 10
   [junit4]   2> 11469 T21 C11 oass.SolrIndexSearcher.<init> Opening 
Searcher@4778e237[collection1] main
   [junit4]   2> 11469 T21 C11 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 11475 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@4778e237[collection1] 
main{UninvertingDirectoryReader(Uninverting(_4(5.0):C60/3:delGen=1) 
Uninverting(_5(5.0):C6))}
   [junit4]   2> 11476 T24 C11 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> 11476 T24 C11 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> 11477 T24 C11 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=1 
   [junit4]   2> 11477 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 11477 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@4778e237[collection1] 
main{UninvertingDirectoryReader(Uninverting(_4(5.0):C60/3:delGen=1) 
Uninverting(_5(5.0):C6))}
   [junit4]   2> 11478 T21 C11 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 11478 T21 C11 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 11479 T21 C11 oashd.DocBuilder.execute Time taken = 0:0:0.102
   [junit4]   2> 11479 T21 C11 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-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"+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&command=delta-import}
 {add=[1019, TripleThreat-1-1019, TripleThreat-2-1019, 2, TripleThreat-1-2, 
TripleThreat-2-2],commit=} 0 108
   [junit4]   2> 11485 T21 C11 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&sort=id+asc&rows=63} hits=63 status=0 QTime=1 
   [junit4]   2> 11497 T21 C11 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=0 
   [junit4]   2> 11501 T21 C11 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=1 
   [junit4]   2> 11507 T21 C11 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> 11509 T21 C11 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> 11512 T21 C11 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 11513 T21 C11 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 11514 T21 C11 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:1019&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> 11517 T21 C11 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:2&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> 12026 T21 oas.SolrTestCaseJ4.tearDown ###Ending 
testWithComplexTransformer
   [junit4]   2> 12036 T21 oas.SolrTestCaseJ4.setUp ###Starting 
testDeltaImportWithoutInitialFullImport
   [junit4]   2> 12037 T21 C11 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 12038 T21 C11 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 12040 T21 C11 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 12040 T21 C11 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_a,generation=10}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_b,generation=11}
   [junit4]   2> 12041 T21 C11 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 11
   [junit4]   2> 12041 T21 C11 oass.SolrIndexSearcher.<init> Opening 
Searcher@2211c65c[collection1] main
   [junit4]   2> 12042 T21 C11 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 12044 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@2211c65c[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 12045 T24 C11 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> 12046 T24 C11 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> 12047 T24 C11 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=1 
   [junit4]   2> 12047 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 12047 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@2211c65c[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 12049 T21 C11 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> 12070 T21 C11 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 12071 T21 C11 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 12071 T21 C11 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 12072 T21 C11 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 12073 T21 C11 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 12074 T21 C11 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 12075 T21 C11 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 12075 T21 C11 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 12079 T21 C11 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 12080 T21 C11 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 20
   [junit4]   2> 12081 T21 C11 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 12081 T21 C11 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 12094 T21 C11 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 12095 T21 C11 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 12095 T21 C11 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 12099 T21 C11 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_b,generation=11}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_c,generation=12}
   [junit4]   2> 12099 T21 C11 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 12
   [junit4]   2> 12101 T21 C11 oass.SolrIndexSearcher.<init> Opening 
Searcher@5896d12a[collection1] main
   [junit4]   2> 12101 T21 C11 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 12105 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@5896d12a[collection1] 
main{UninvertingDirectoryReader(Uninverting(_6(5.0):C20))}
   [junit4]   2> 12105 T24 C11 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> 12106 T24 C11 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> 12107 T24 C11 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=1 
   [junit4]   2> 12107 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 12107 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@5896d12a[collection1] 
main{UninvertingDirectoryReader(Uninverting(_6(5.0):C20))}
   [junit4]   2> 12108 T21 C11 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 12109 T21 C11 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 12109 T21 C11 oashd.DocBuilder.execute Time taken = 0:0:0.36
   [junit4]   2> 12109 T21 C11 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-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&command=delta-import}
 {add=[19, 18, 17, 16, 20, 11, 10, 9, 8, 15, ... (20 adds)],commit=} 0 41
   [junit4]   2> 12112 T21 C11 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> 12116 T21 C11 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 12117 T21 C11 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 12118 T21 oas.SolrTestCaseJ4.tearDown ###Ending 
testDeltaImportWithoutInitialFullImport
   [junit4]   2> 12128 T21 oas.SolrTestCaseJ4.setUp ###Starting 
testWithSimpleTransformer
   [junit4]   2> 12129 T21 C11 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 12130 T21 C11 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 12132 T21 C11 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 12133 T21 C11 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_c,generation=12}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_d,generation=13}
   [junit4]   2> 12133 T21 C11 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 13
   [junit4]   2> 12134 T21 C11 oass.SolrIndexSearcher.<init> Opening 
Searcher@706cbd3f[collection1] main
   [junit4]   2> ASYNC  NEW_CORE C12 name=collection1 
org.apache.solr.core.SolrCore@25076577
   [junit4]   2> 12134 T21 C12 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 12154 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@706cbd3f[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 12156 T24 C12 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=2 
   [junit4]   2> 12156 T24 C12 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> 12157 T24 C12 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> 12157 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 12157 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@706cbd3f[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 12158 T21 C12 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 27
   [junit4]   2> 12172 T21 C12 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 12173 T21 C12 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 12173 T21 C12 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 12174 T21 C12 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 12174 T21 C12 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 12175 T21 C12 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 12175 T21 C12 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 12195 T21 C12 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 12195 T21 C12 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 12197 T21 C12 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_d,generation=13}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_e,generation=14}
   [junit4]   2> 12198 T21 C12 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 14
   [junit4]   2> 12199 T21 C12 oass.SolrIndexSearcher.<init> Opening 
Searcher@30339ba7[collection1] main
   [junit4]   2> 12200 T21 C12 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 12202 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@30339ba7[collection1] 
main{UninvertingDirectoryReader(Uninverting(_7(5.0):C20))}
   [junit4]   2> 12202 T24 C12 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> 12203 T24 C12 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> 12203 T24 C12 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> 12203 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 12204 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@30339ba7[collection1] 
main{UninvertingDirectoryReader(Uninverting(_7(5.0):C20))}
   [junit4]   2> 12204 T21 C12 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 12204 T21 C12 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 12205 T21 C12 oashd.DocBuilder.execute Time taken = 0:0:0.30
   [junit4]   2> 12205 T21 C12 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-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"+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&command=full-import}
 {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 
0 35
   [junit4]   2> 12207 T21 C12 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> 12210 T21 C12 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 12211 T21 C12 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 12211 T21 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 12216 T21 C12 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 12216 T21 C12 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 12217 T21 C12 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 12217 T21 C12 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 12218 T21 C12 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 12218 T21 C12 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 12218 T21 C12 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 12219 T21 C12 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 12221 T21 C12 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 9
   [junit4]   2> 12221 T21 C12 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 5
   [junit4]   2> 12221 T21 C12 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 12222 T21 C12 oashd.DocBuilder.deleteAll Deleting stale 
documents 
   [junit4]   2> 12222 T21 C12 oashd.SolrWriter.deleteDoc Deleting document: 12
   [junit4]   2> 12222 T21 C12 oashd.SolrWriter.deleteDoc Deleting document: 1
   [junit4]   2> 12222 T21 C12 oashd.SolrWriter.deleteDoc Deleting document: 6
   [junit4]   2> 12223 T21 C12 oashd.SolrWriter.deleteDoc Deleting document: 10
   [junit4]   2> 12223 T21 C12 oashd.SolrWriter.deleteDoc Deleting document: 15
   [junit4]   2> 12228 T21 C12 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 12229 T21 C12 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 12229 T21 C12 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 12333 T21 C12 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_e,generation=14}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@25562e06 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index),segFN=segments_f,generation=15}
   [junit4]   2> 12334 T21 C12 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 15
   [junit4]   2> 12337 T21 C12 oass.SolrIndexSearcher.<init> Opening 
Searcher@521721b6[collection1] main
   [junit4]   2> 12338 T21 C12 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 12345 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@521721b6[collection1] 
main{UninvertingDirectoryReader(Uninverting(_7(5.0):C20/13:delGen=1) 
Uninverting(_8(5.0):C9))}
   [junit4]   2> 12346 T24 C12 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=1 
   [junit4]   2> 12346 T24 C12 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> 12347 T24 C12 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> 12348 T24 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 12348 T24 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@521721b6[collection1] 
main{UninvertingDirectoryReader(Uninverting(_7(5.0):C20/13:delGen=1) 
Uninverting(_8(5.0):C9))}
   [junit4]   2> 12349 T21 C12 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 12350 T21 C12 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 12350 T21 C12 oashd.DocBuilder.execute Time taken = 0:0:0.132
   [junit4]   2> 12351 T21 C12 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-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"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WH

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

ersion=2.2} hits=0 status=0 QTime=0 
   [junit4]   2> 12362 T21 C12 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:6&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0 
   [junit4]   2> 12363 T21 C12 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:10&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0 
   [junit4]   2> 12365 T21 C12 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:12&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0 
   [junit4]   2> 12367 T21 C12 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=1 
   [junit4]   2> 12368 T21 C12 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:17&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> 12370 T21 C12 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:2&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> 12372 T21 C12 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> 12374 T21 C12 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> 12376 T21 C12 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> 12378 T21 C12 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=1 
   [junit4]   2> 12380 T21 C12 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=1 
   [junit4]   2> 12381 T21 C12 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:14&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> 12393 T21 oas.SolrTestCaseJ4.tearDown ###Ending 
testWithSimpleTransformer
   [junit4]   2> 12425 T21 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 12425 T21 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=1726977122
   [junit4]   2> 12426 T21 oasc.SolrCore.close [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@25076577
   [junit4]   2> 12426 T21 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=14,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=168,cumulative_deletesById=8,cumulative_deletesByQuery=9,cumulative_errors=0}
   [junit4]   2> 12426 T21 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 12426 T21 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 12426 T21 oasu.DefaultSolrCoreState.closeIndexWriter closing 
IndexWriter with IndexWriterCloser
   [junit4]   2> 12427 T21 oasc.SolrCore.closeSearcher [collection1] Closing 
main searcher on request.
   [junit4]   2> 12428 T21 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 12429 T21 oasc.CachingDirectoryFactory.closeCacheValue looking 
to close 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index
 
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 12429 T21 oasc.CachingDirectoryFactory.close Closing 
directory: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001/index
   [junit4]   2> 12429 T21 oasc.CachingDirectoryFactory.closeCacheValue looking 
to close 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001
 
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001;done=false>>]
   [junit4]   2> 12429 T21 oasc.CachingDirectoryFactory.close Closing 
directory: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-16621DA5B5936ACB-001/init-core-data-001
   [junit4]   2> 6697 T20 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will 
linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> 26765 T20 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 
thread leaked from SUITE scope at 
org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta: 
   [junit4]   2>           1) Thread[id=22, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]
   [junit4]   2>                at java.lang.Object.wait(Native Method)
   [junit4]   2>                at java.lang.Object.wait(Object.java:502)
   [junit4]   2>                at 
java.util.TimerThread.mainLoop(Timer.java:526)
   [junit4]   2>                at java.util.TimerThread.run(Timer.java:505)
   [junit4]   2> 26766 T20 ccr.ThreadLeakControl.tryToInterruptAll Starting to 
interrupt leaked threads:
   [junit4]   2>           1) Thread[id=22, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]
   [junit4]   2> 29768 T20 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There 
are still zombie threads that couldn't be terminated:
   [junit4]   2>           1) Thread[id=22, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]
   [junit4]   2>                at java.lang.Object.wait(Native Method)
   [junit4]   2>                at java.lang.Object.wait(Object.java:502)
   [junit4]   2>                at 
java.util.TimerThread.mainLoop(Timer.java:526)
   [junit4]   2>                at java.util.TimerThread.run(Timer.java:505)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene49), 
sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=hr_HR, 
timezone=Asia/Katmandu
   [junit4]   2> NOTE: Linux 3.13.0-32-generic amd64/Oracle Corporation 
1.8.0_20-ea (64-bit)/cpus=8,threads=2,free=31336104,total=73400320
   [junit4]   2> NOTE: All tests run in this JVM: [TestURLDataSource, 
TestDocBuilder2, TestSqlEntityProcessorDelta]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSqlEntityProcessorDelta -Dtests.seed=16621DA5B5936ACB 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=hr_HR 
-Dtests.timezone=Asia/Katmandu -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J1 | TestSqlEntityProcessorDelta (suite) <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta: 
   [junit4]    >    1) Thread[id=22, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]
   [junit4]    >         at java.lang.Object.wait(Native Method)
   [junit4]    >         at java.lang.Object.wait(Object.java:502)
   [junit4]    >         at java.util.TimerThread.mainLoop(Timer.java:526)
   [junit4]    >         at java.util.TimerThread.run(Timer.java:505)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([16621DA5B5936ACB]:0)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=22, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]
   [junit4]    >         at java.lang.Object.wait(Native Method)
   [junit4]    >         at java.lang.Object.wait(Object.java:502)
   [junit4]    >         at java.util.TimerThread.mainLoop(Timer.java:526)
   [junit4]    >         at java.util.TimerThread.run(Timer.java:505)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([16621DA5B5936ACB]:0)
   [junit4] Completed on J1 in 29.87s, 5 tests, 2 errors <<< FAILURES!

[...truncated 109 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:474: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:454: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:45: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The 
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:209: The 
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:440: 
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: 
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1298:
 The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:922: 
There were test failures: 37 suites, 148 tests, 2 suite-level errors, 120 
ignored

Total time: 90 minutes 17 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_20-ea-b23 
-XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to