[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0_11) - Build # 10990 - Still Failing!

[Policeman Jenkins Server]

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10990/
Java: 64bit/jdk1.8.0_11 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

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=55, 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=55, 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([C30BFCC9157F9EDA]: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=55, 
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=55, 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([C30BFCC9157F9EDA]:0)




Build Log:
[...truncated 15086 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-C30BFCC9157F9EDA-001/init-core-data-001
   [junit4]   2> 19855 T54 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(false) and clientAuth (true)
   [junit4]   2> 20570 T54 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 20571 T54 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-C30BFCC9157F9EDA-001/core-home-001/collection1/'
   [junit4]   2> 20662 T54 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
LUCENE_5_0
   [junit4]   2> 20701 T54 oasc.SolrConfig.<init> Loaded SolrConfig: 
dataimport-solrconfig.xml
   [junit4]   2> 20702 T54 oass.IndexSchema.readSchema Reading Solr Schema from 
dataimport-schema.xml
   [junit4]   2> 20719 T54 oass.IndexSchema.readSchema [null] Schema 
name=dih_test
   [junit4]   2> 20768 T54 oass.IndexSchema.readSchema default search field in 
schema is desc
   [junit4]   2> 20770 T54 oass.IndexSchema.readSchema query parser default 
operator is OR
   [junit4]   2> 20771 T54 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 20772 T54 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 20772 T54 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-C30BFCC9157F9EDA-001/core-home-001
   [junit4]   2> 20772 T54 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-C30BFCC9157F9EDA-001/core-home-001/'
   [junit4]   2> 20846 T54 oasc.CoreContainer.<init> New CoreContainer 267490519
   [junit4]   2> 20846 T54 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-C30BFCC9157F9EDA-001/core-home-001/]
   [junit4]   2> 20847 T54 oashc.HttpShardHandlerFactory.getParameter Setting 
socketTimeout to: 0
   [junit4]   2> 20847 T54 oashc.HttpShardHandlerFactory.getParameter Setting 
urlScheme to: null
   [junit4]   2> 20847 T54 oashc.HttpShardHandlerFactory.getParameter Setting 
connTimeout to: 0
   [junit4]   2> 20848 T54 oashc.HttpShardHandlerFactory.getParameter Setting 
maxConnectionsPerHost to: 20
   [junit4]   2> 20848 T54 oashc.HttpShardHandlerFactory.getParameter Setting 
corePoolSize to: 0
   [junit4]   2> 20848 T54 oashc.HttpShardHandlerFactory.getParameter Setting 
maximumPoolSize to: 2147483647
   [junit4]   2> 20848 T54 oashc.HttpShardHandlerFactory.getParameter Setting 
maxThreadIdleTime to: 5
   [junit4]   2> 20849 T54 oashc.HttpShardHandlerFactory.getParameter Setting 
sizeOfQueue to: -1
   [junit4]   2> 20849 T54 oashc.HttpShardHandlerFactory.getParameter Setting 
fairnessPolicy to: false
   [junit4]   2> 20849 T54 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=false
   [junit4]   2> 20850 T54 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 20850 T54 oasl.LogWatcher.newRegisteredLogWatcher Registering 
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 20850 T54 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 20855 T56 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-C30BFCC9157F9EDA-001/core-home-001/collection1/'
   [junit4]   2> 20922 T56 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
LUCENE_5_0
   [junit4]   2> 20977 T56 oasc.SolrConfig.<init> Loaded SolrConfig: 
dataimport-solrconfig.xml
   [junit4]   2> 20979 T56 oass.IndexSchema.readSchema Reading Solr Schema from 
dataimport-schema.xml
   [junit4]   2> 20991 T56 oass.IndexSchema.readSchema [collection1] Schema 
name=dih_test
   [junit4]   2> 21052 T56 oass.IndexSchema.readSchema default search field in 
schema is desc
   [junit4]   2> 21052 T56 oass.IndexSchema.readSchema query parser default 
operator is OR
   [junit4]   2> 21053 T56 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 21055 T56 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-C30BFCC9157F9EDA-001/core-home-001/collection1/
   [junit4]   2> 21055 T56 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 21056 T56 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-C30BFCC9157F9EDA-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-C30BFCC9157F9EDA-001/init-core-data-001/
   [junit4]   2> 21056 T56 oasc.SolrCore.<init> JMX monitoring not detected for 
core: collection1
   [junit4]   2> 21056 T56 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> 21056 T56 oasc.SolrCore.initListeners [collection1] Added 
SolrEventListener for firstSearcher: 
org.apache.solr.core.QuerySenderListener{queries=[]}
   [junit4]   2> 21057 T56 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-C30BFCC9157F9EDA-001/init-core-data-001
   [junit4]   2> 21057 T56 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-C30BFCC9157F9EDA-001/init-core-data-001/index/
   [junit4]   2> 21057 T56 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-C30BFCC9157F9EDA-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 21058 T56 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-C30BFCC9157F9EDA-001/init-core-data-001/index
   [junit4]   2> 21059 T56 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_1,generation=1}
   [junit4]   2> 21059 T56 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 21061 T56 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "" (default)
   [junit4]   2> 21061 T56 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
   [junit4]   2> 21062 T56 oasc.RequestHandlers.initHandlersFromConfig created 
/update: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 21062 T56 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 21062 T56 oasc.RequestHandlers.initHandlersFromConfig created 
/update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 21062 T56 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 21063 T56 oasc.RequestHandlers.initHandlersFromConfig created 
standard: solr.StandardRequestHandler
   [junit4]   2> 21063 T56 oasc.RequestHandlers.initHandlersFromConfig created 
/dataimport: org.apache.solr.handler.dataimport.DataImportHandler
   [junit4]   2> 21063 T56 oasc.RequestHandlers.initHandlersFromConfig created 
/search: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 21064 T56 oasc.RequestHandlers.initHandlersFromConfig WARN 
Multiple requestHandler registered to the same name: /update ignoring: 
org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 21064 T56 oasc.RequestHandlers.initHandlersFromConfig created 
/update: solr.UpdateRequestHandler
   [junit4]   2> 21068 T56 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 21069 T56 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 21071 T56 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 21072 T56 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 21074 T56 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 21092 T56 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 21093 T56 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 21094 T56 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_1,generation=1}
   [junit4]   2> 21094 T56 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 21094 T56 oass.SolrIndexSearcher.<init> Opening 
Searcher@42dbd689[collection1] main
   [junit4]   2> 21095 T56 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-C30BFCC9157F9EDA-001/core-home-001/collection1/conf
   [junit4]   2> 21095 T56 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-C30BFCC9157F9EDA-001/core-home-001/collection1/conf}
   [junit4]   2> 21096 T56 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-C30BFCC9157F9EDA-001/core-home-001/collection1/conf
   [junit4]   2> 21096 T56 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 21097 T56 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-C30BFCC9157F9EDA-001/core-home-001/collection1/conf
   [junit4]   2> 21097 T56 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 21097 T56 oasc.CoreContainer.registerCore registering core: 
collection1
   [junit4]   2> 21097 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@42dbd689[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 21101 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 21101 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@42dbd689[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 21102 T54 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 21107 T54 oas.SolrTestCaseJ4.setUp ###Starting 
testWithComplexTransformer
   [junit4]   2> ASYNC  NEW_CORE C22 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 21120 T54 C22 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 21121 T54 C22 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 11
   [junit4]   2> ASYNC  NEW_CORE C23 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 21127 T54 C23 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 21128 T54 C23 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_2,generation=2}
   [junit4]   2> 21128 T54 C23 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 21129 T54 C23 oass.SolrIndexSearcher.<init> Opening 
Searcher@5c3f6200[collection1] main
   [junit4]   2> 21129 T54 C23 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 21130 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@5c3f6200[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 21130 T57 C23 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> 21131 T57 C23 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> 21132 T57 C23 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> 21132 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 21133 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@5c3f6200[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 21133 T54 C23 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 7
   [junit4]   2> ASYNC  NEW_CORE C24 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 22867 T54 C24 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 22868 T54 C24 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 22868 T54 C24 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 22869 T54 C24 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> ASYNC  NEW_CORE C25 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 22892 T54 C25 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 22897 T54 C25 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 22898 T54 C25 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 1
   [junit4]   2> ASYNC  NEW_CORE C26 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23046 T54 C26 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 23046 T54 C26 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> ASYNC  NEW_CORE C27 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23074 T54 C27 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_3,generation=3}
   [junit4]   2> 23074 T54 C27 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> ASYNC  NEW_CORE C28 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23080 T54 C28 oass.SolrIndexSearcher.<init> Opening 
Searcher@6dec473a[collection1] main
   [junit4]   2> 23081 T54 C28 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 23082 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@6dec473a[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):c60))}
   [junit4]   2> 23082 T57 C28 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> 23083 T57 C28 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> 23083 T57 C28 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> 23084 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 23084 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6dec473a[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):c60))}
   [junit4]   2> 23084 T54 C28 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 23085 T54 C28 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 23085 T54 C28 oashd.DocBuilder.execute Time taken = 0:0:0.215
   [junit4]   2> 23086 T54 C28 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-C30BFCC9157F9EDA-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+transformer%3D"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 222
   [junit4]   2> ASYNC  NEW_CORE C29 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23233 T54 C29 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&sort=id+asc&rows=60} hits=60 status=0 QTime=99 
   [junit4]   2> 23255 T54 C29 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=1 
   [junit4]   2> ASYNC  NEW_CORE C30 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23264 T54 C30 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 C31 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23302 T54 C31 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=6 
   [junit4]   2> 23304 T54 C31 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=0 
   [junit4]   2> ASYNC  NEW_CORE C32 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23310 T54 C32 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 23311 T54 C32 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 23312 T54 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> ASYNC  NEW_CORE C33 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23357 T54 C33 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 23358 T54 C33 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 23358 T54 C33 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 23359 T54 C33 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> ASYNC  NEW_CORE C34 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23361 T54 C34 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 23362 T54 C34 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 23362 T54 C34 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 23363 T54 C34 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> ASYNC  NEW_CORE C35 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23439 T54 C35 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 8
   [junit4]   2> 23440 T54 C35 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 5
   [junit4]   2> 23440 T54 C35 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 23440 T54 C35 oashd.DocBuilder.deleteAll Deleting stale 
documents 
   [junit4]   2> 23441 T54 C35 oashd.SolrWriter.deleteDoc Deleting document: 11
   [junit4]   2> 23441 T54 C35 oashd.SolrWriter.deleteDoc Deleting document: 9
   [junit4]   2> 23442 T54 C35 oashd.SolrWriter.deleteDoc Deleting document: 8
   [junit4]   2> 23442 T54 C35 oashd.SolrWriter.deleteDoc Deleting document: 1
   [junit4]   2> 23442 T54 C35 oashd.SolrWriter.deleteDoc Deleting document: 7
   [junit4]   2> ASYNC  NEW_CORE C36 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23521 T54 C36 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 23521 T54 C36 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 23522 T54 C36 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> ASYNC  NEW_CORE C37 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23546 T54 C37 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_4,generation=4}
   [junit4]   2> 23547 T54 C37 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 4
   [junit4]   2> ASYNC  NEW_CORE C38 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23554 T54 C38 oass.SolrIndexSearcher.<init> Opening 
Searcher@20b40007[collection1] main
   [junit4]   2> 23555 T54 C38 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 23566 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@20b40007[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):c60/23:delGen=1) 
Uninverting(_1(5.0):c24))}
   [junit4]   2> 23567 T57 C38 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> 23568 T57 C38 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> 23569 T57 C38 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> 23569 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 23570 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@20b40007[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):c60/23:delGen=1) 
Uninverting(_1(5.0):c24))}
   [junit4]   2> 23571 T54 C38 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 23571 T54 C38 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 23572 T54 C38 oashd.DocBuilder.execute Time taken = 0:0:0.211
   [junit4]   2> 23572 T54 C38 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-C30BFCC9157F9EDA-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}
 {delete=[11, 9, 8, 1, 7],add=[1019, TripleThreat-1-1019, TripleThreat-2-1019, 
13, TripleThreat-1-13, TripleThreat-2-13, 3, TripleThreat-1-3, 
TripleThreat-2-3, 19, ... (24 adds)],commit=} 0 217
   [junit4]   2> ASYNC  NEW_CORE C39 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23586 T54 C39 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&sort=id+asc&rows=51} hits=61 status=0 QTime=2 
   [junit4]   2> ASYNC  NEW_CORE C40 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23606 T54 C40 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={q=id:TripleThreat-1-*&qt=standard&start=0&rows=20&version=2.2} hits=22 
status=0 QTime=1 
   [junit4]   2> 23609 T54 C40 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={q=id:TripleThreat-2-*&qt=standard&start=0&rows=20&version=2.2} hits=22 
status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C41 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23617 T54 C41 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=AddAColumn_s:Added&qt=standard&start=0&rows=20&version=2.2} 
hits=22 status=0 QTime=0 
   [junit4]   2> 23620 T54 C41 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 23621 T54 C41 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 23622 T54 C41 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:1009&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C42 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23626 T54 C42 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> 23628 T54 C42 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:1&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C43 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23631 T54 C43 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:7&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=1 
   [junit4]   2> 23633 T54 C43 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:8&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C44 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23637 T54 C44 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:9&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=1 
   [junit4]   2> 23639 T54 C44 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=0 
   [junit4]   2> 23641 T54 C44 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:16&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C45 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23646 T54 C45 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:18&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C46 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23650 T54 C46 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:19&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C47 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23654 T54 C47 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=0 
   [junit4]   2> 23657 T54 C47 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=1 
   [junit4]   2> ASYNC  NEW_CORE C48 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 23662 T54 C48 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:13&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> 24210 T54 oas.SolrTestCaseJ4.tearDown ###Ending 
testWithComplexTransformer
   [junit4]   2> 24222 T54 oas.SolrTestCaseJ4.setUp ###Starting 
testChildEntities
   [junit4]   2> ASYNC  NEW_CORE C49 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24223 T54 C49 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 24224 T54 C49 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 2
   [junit4]   2> 24225 T54 C49 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 24231 T54 C49 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_4,generation=4}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_5,generation=5}
   [junit4]   2> 24232 T54 C49 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 5
   [junit4]   2> 24232 T54 C49 oass.SolrIndexSearcher.<init> Opening 
Searcher@57127e41[collection1] main
   [junit4]   2> 24233 T54 C49 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 24237 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@57127e41[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> ASYNC  NEW_CORE C50 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24238 T57 C50 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> 24238 T57 C50 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> 24240 T57 C50 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> 24240 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 24240 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@57127e41[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 24244 T54 C50 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 19
   [junit4]   2> ASYNC  NEW_CORE C51 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24581 T54 C51 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 24581 T54 C51 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 24582 T54 C51 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 24583 T54 C51 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 24584 T54 C51 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 24585 T54 C51 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 24586 T54 C51 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 24591 T54 C51 oashd.JdbcDataSource$1.call Creating a 
connection for entity Sports with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 24592 T54 C51 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> ASYNC  NEW_CORE C52 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24665 T54 C52 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 24665 T54 C52 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> ASYNC  NEW_CORE C53 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24678 T54 C53 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_5,generation=5}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_6,generation=6}
   [junit4]   2> 24678 T54 C53 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 6
   [junit4]   2> 24681 T54 C53 oass.SolrIndexSearcher.<init> Opening 
Searcher@1b13d825[collection1] main
   [junit4]   2> 24681 T54 C53 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 24686 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1b13d825[collection1] 
main{UninvertingDirectoryReader(Uninverting(_2(5.0):c20))}
   [junit4]   2> 24687 T57 C53 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> 24688 T57 C53 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> 24688 T57 C53 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> 24688 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 24688 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1b13d825[collection1] 
main{UninvertingDirectoryReader(Uninverting(_2(5.0):c20))}
   [junit4]   2> 24689 T54 C53 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> ASYNC  NEW_CORE C54 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24697 T54 C54 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 24698 T54 C54 oashd.DocBuilder.execute Time taken = 0:0:0.114
   [junit4]   2> 24698 T54 C54 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-C30BFCC9157F9EDA-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}'+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a<entity+name%3D"Sports"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+newColumnName%3D"sportsAdded_s"+newColumnValue%3D"sport_added"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+ID,+SPORT_NAME+FROM+PEOPLE_SPORTS+WHERE+DELETED+!%3D+'Y'+AND+PERSON_ID%3D${People.ID}+ORDER+BY+ID"+>+%0a<field+column%3D"SPORT_NAME"+name%3D"SPORT_NAME_mult_s"+/>+%0a<field+column%3D"id"+name%3D"SPORT_ID_mult_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&clean=true&command=full-import}
 {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 
0 124
   [junit4]   2> 24701 T54 C54 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> 24709 T54 C54 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={q=SPORT_NAME_mult_s:Sailing&qt=standard&start=0&rows=20&version=2.2} 
hits=2 status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C55 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24724 T54 C55 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={q=NAME_mult_s:Michael&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C56 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24734 T54 C56 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={q=sportsAdded_s:sport_added&qt=standard&start=0&rows=20&version=2.2} 
hits=20 status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C57 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24743 T54 C57 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 24744 T54 C57 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 24745 T54 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 24750 T54 C57 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 24750 T54 C57 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 24751 T54 C57 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 24751 T54 C57 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> ASYNC  NEW_CORE C58 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24776 T54 C58 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 24776 T54 C58 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: Sports
   [junit4]   2> 24777 T54 C58 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: Sports rows obtained : 0
   [junit4]   2> 24777 T54 C58 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: Sports rows obtained : 0
   [junit4]   2> 24778 T54 C58 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: Sports
   [junit4]   2> 24778 T54 C58 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 24778 T54 C58 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 24779 T54 C58 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 24784 T54 C58 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 7
   [junit4]   2> 24785 T54 C58 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 5
   [junit4]   2> 24785 T54 C58 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 24786 T54 C58 oashd.DocBuilder.deleteAll Deleting stale 
documents 
   [junit4]   2> 24786 T54 C58 oashd.SolrWriter.deleteDoc Deleting document: 12
   [junit4]   2> 24787 T54 C58 oashd.SolrWriter.deleteDoc Deleting document: 17
   [junit4]   2> 24787 T54 C58 oashd.SolrWriter.deleteDoc Deleting document: 5
   [junit4]   2> 24787 T54 C58 oashd.SolrWriter.deleteDoc Deleting document: 20
   [junit4]   2> 24788 T54 C58 oashd.SolrWriter.deleteDoc Deleting document: 15
   [junit4]   2> 24789 T54 C58 oashd.JdbcDataSource$1.call Creating a 
connection for entity Sports with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 24790 T54 C58 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 24815 T54 C58 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 24816 T54 C58 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 24816 T54 C58 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> ASYNC  NEW_CORE C59 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24841 T54 C59 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_6,generation=6}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_7,generation=7}
   [junit4]   2> 24842 T54 C59 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 7
   [junit4]   2> 24844 T54 C59 oass.SolrIndexSearcher.<init> Opening 
Searcher@6a8f3971[collection1] main
   [junit4]   2> ASYNC  NEW_CORE C60 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24861 T54 C60 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 24871 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@6a8f3971[collection1] 
main{UninvertingDirectoryReader(Uninverting(_2(5.0):c20/11:delGen=1) 
Uninverting(_3(5.0):c7))}
   [junit4]   2> 24872 T57 C60 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> 24872 T57 C60 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> 24873 T57 C60 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> 24873 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 24874 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6a8f3971[collection1] 
main{UninvertingDirectoryReader(Uninverting(_2(5.0):c20/11:delGen=1) 
Uninverting(_3(5.0):c7))}
   [junit4]   2> 24874 T54 C60 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 24875 T54 C60 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 24876 T54 C60 oashd.DocBuilder.execute Time taken = 0:0:0.124
   [junit4]   2> 24876 T54 C60 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-C30BFCC9157F9EDA-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}'+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a<entity+name%3D"Sports"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+newColumnName%3D"sportsAdded_s"+newColumnValue%3D"sport_added"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+ID,+SPORT_NAME+FROM+PEOPLE_SPORTS+WHERE+DELETED+!%3D+'Y'+AND+PERSON_ID%3D${People.ID}+ORDER+BY+ID"+>+%0a<field+column%3D"SPORT_NAME"+name%3D"SPORT_NAME_mult_s"+/>+%0a<field+column%3D"id"+name%3D"SPORT_ID_mult_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&clean=false&command=delta-import}
 {delete=[12, 17, 5, 20, 15],add=[3, 19, 1009, 7, 4, 8, 14],commit=} 0 128
   [junit4]   2> ASYNC  NEW_CORE C61 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24903 T54 C61 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=16 
status=0 QTime=2 
   [junit4]   2> 24910 T54 C61 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={q=NAME_mult_s:MODIFIED\+Michael&qt=standard&start=0&rows=20&version=2.2}
 hits=1 status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C62 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24923 T54 C62 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:1009&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C63 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24933 T54 C63 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:17&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0 
   [junit4]   2> 24935 T54 C63 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:20&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C64 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24958 T54 C64 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:5&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=1 
   [junit4]   2> 24960 T54 C64 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=1 
   [junit4]   2> ASYNC  NEW_CORE C65 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24976 T54 C65 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> 24978 T54 C65 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:19&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C66 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24985 T54 C66 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=0 
   [junit4]   2> ASYNC  NEW_CORE C67 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 24994 T54 C67 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> 24997 T54 C67 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:7&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C68 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25008 T54 C68 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:8&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C69 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25018 T54 C69 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=1 
   [junit4]   2> 25034 T54 oas.SolrTestCaseJ4.tearDown ###Ending 
testChildEntities
   [junit4]   2> 25043 T54 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
   [junit4]   2> ASYNC  NEW_CORE C70 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25045 T54 C70 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 25046 T54 C70 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 2
   [junit4]   2> 25048 T54 C70 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 25049 T54 C70 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_7,generation=7}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_8,generation=8}
   [junit4]   2> ASYNC  NEW_CORE C71 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25055 T54 C71 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 8
   [junit4]   2> 25056 T54 C71 oass.SolrIndexSearcher.<init> Opening 
Searcher@3cf6cd2a[collection1] main
   [junit4]   2> 25057 T54 C71 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 25063 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@3cf6cd2a[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 25064 T57 C71 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> 25064 T57 C71 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> 25065 T57 C71 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> 25065 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 25065 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3cf6cd2a[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 25068 T54 C71 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 21
   [junit4]   2> ASYNC  NEW_CORE C72 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25108 T54 C72 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 25109 T54 C72 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 25109 T54 C72 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 25110 T54 C72 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 25111 T54 C72 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 25112 T54 C72 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 25112 T54 C72 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 25121 T54 C72 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 25122 T54 C72 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> ASYNC  NEW_CORE C73 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25129 T54 C73 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_8,generation=8}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_9,generation=9}
   [junit4]   2> 25135 T54 C73 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 9
   [junit4]   2> 25137 T54 C73 oass.SolrIndexSearcher.<init> Opening 
Searcher@2801935b[collection1] main
   [junit4]   2> 25138 T54 C73 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 25143 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@2801935b[collection1] 
main{UninvertingDirectoryReader(Uninverting(_4(5.0):c20))}
   [junit4]   2> 25144 T57 C73 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> ASYNC  NEW_CORE C74 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25148 T57 C74 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} 
hits=0 status=0 QTime=2 
   [junit4]   2> 25149 T57 C74 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> 25149 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 25149 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@2801935b[collection1] 
main{UninvertingDirectoryReader(Uninverting(_4(5.0):c20))}
   [junit4]   2> 25150 T54 C74 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 25151 T54 C74 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 25151 T54 C74 oashd.DocBuilder.execute Time taken = 0:0:0.41
   [junit4]   2> 25152 T54 C74 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-C30BFCC9157F9EDA-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 53
   [junit4]   2> 25154 T54 C74 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> ASYNC  NEW_CORE C75 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25163 T54 C75 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 25164 T54 C75 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 25165 T54 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 25182 T54 C75 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 25183 T54 C75 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 25184 T54 C75 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 25185 T54 C75 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 25186 T54 C75 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 25187 T54 C75 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 25187 T54 C75 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 25188 T54 C75 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 25190 T54 C75 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 25192 T54 C75 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 3
   [junit4]   2> 25192 T54 C75 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 3
   [junit4]   2> 25192 T54 C75 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 25193 T54 C75 oashd.DocBuilder.deleteAll Deleting stale 
documents 
   [junit4]   2> ASYNC  NEW_CORE C76 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25198 T54 C76 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 25198 T54 C76 oashd.SolrWriter.deleteDoc Deleting document: 11
   [junit4]   2> 25199 T54 C76 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 25199 T54 C76 oashd.SolrWriter.deleteDoc Deleting document: 9
   [junit4]   2> 25200 T54 C76 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 25200 T54 C76 oashd.SolrWriter.deleteDoc Deleting document: 5
   [junit4]   2> 25204 T54 C76 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 25204 T54 C76 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 25204 T54 C76 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 25208 T54 C76 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_9,generation=9}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_a,generation=10}
   [junit4]   2> 25208 T54 C76 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 10
   [junit4]   2> ASYNC  NEW_CORE C77 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25216 T54 C77 oass.SolrIndexSearcher.<init> Opening 
Searcher@506be5c[collection1] main
   [junit4]   2> 25217 T54 C77 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 25225 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@506be5c[collection1] 
main{UninvertingDirectoryReader(Uninverting(_4(5.0):c20/5:delGen=1) 
Uninverting(_5(5.0):c3))}
   [junit4]   2> 25226 T57 C77 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> 25226 T57 C77 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> 25227 T57 C77 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> 25227 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 25228 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@506be5c[collection1] 
main{UninvertingDirectoryReader(Uninverting(_4(5.0):c20/5:delGen=1) 
Uninverting(_5(5.0):c3))}
   [junit4]   2> 25229 T54 C77 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> ASYNC  NEW_CORE C78 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25233 T54 C78 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 25234 T54 C78 oashd.DocBuilder.execute Time taken = 0:0:0.48
   [junit4]   2> 25234 T54 C78 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-C30BFCC9157F9EDA-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}
 {delete=[11, 9, 5],add=[1019, 17, 20],commit=} 0 54
   [junit4]   2> 25237 T54 C78 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=18 
status=0 QTime=1 
   [junit4]   2> 25248 T54 C78 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 25248 T54 C78 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C79 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25260 T54 C79 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> 25262 T54 C79 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:5&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=1 
   [junit4]   2> 25263 T54 C79 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:9&qt=standard&start=0&rows=20&version=2.2} hits=0 
status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C80 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25271 T54 C80 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=0 
   [junit4]   2> 25273 T54 C80 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=1 
   [junit4]   2> ASYNC  NEW_CORE C81 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25284 T54 C81 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> 25289 T54 oas.SolrTestCaseJ4.tearDown ###Ending 
testSingleEntity
   [junit4]   2> 25297 T54 oas.SolrTestCaseJ4.setUp ###Starting 
testDeltaImportWithoutInitialFullImport
   [junit4]   2> ASYNC  NEW_CORE C82 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25298 T54 C82 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 25299 T54 C82 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> ASYNC  NEW_CORE C83 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25303 T54 C83 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 25304 T54 C83 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_a,generation=10}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_b,generation=11}
   [junit4]   2> 25305 T54 C83 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 11
   [junit4]   2> 25306 T54 C83 oass.SolrIndexSearcher.<init> Opening 
Searcher@76c8d925[collection1] main
   [junit4]   2> 25306 T54 C83 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 25308 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@76c8d925[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 25309 T57 C83 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> 25309 T57 C83 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> 25310 T57 C83 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> 25310 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 25310 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@76c8d925[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 25312 T54 C83 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> ASYNC  NEW_CORE C84 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25734 T54 C84 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 25734 T54 C84 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 25735 T54 C84 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 25736 T54 C84 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 25737 T54 C84 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 25738 T54 C84 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 25738 T54 C84 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> ASYNC  NEW_CORE C85 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25750 T54 C85 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 8
   [junit4]   2> 25772 T54 C85 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 25793 T54 C85 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 20
   [junit4]   2> 25793 T54 C85 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 25794 T54 C85 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> ASYNC  NEW_CORE C86 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25954 T54 C86 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 25955 T54 C86 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 25955 T54 C86 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 25962 T54 C86 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_b,generation=11}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_c,generation=12}
   [junit4]   2> 25963 T54 C86 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 12
   [junit4]   2> ASYNC  NEW_CORE C87 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25970 T54 C87 oass.SolrIndexSearcher.<init> Opening 
Searcher@6cc69e15[collection1] main
   [junit4]   2> 25971 T54 C87 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 25975 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@6cc69e15[collection1] 
main{UninvertingDirectoryReader(Uninverting(_6(5.0):c20))}
   [junit4]   2> 25976 T57 C87 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> 25976 T57 C87 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> 25977 T57 C87 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> 25977 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 25977 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6cc69e15[collection1] 
main{UninvertingDirectoryReader(Uninverting(_6(5.0):c20))}
   [junit4]   2> 25978 T54 C87 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 25978 T54 C87 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 25979 T54 C87 oashd.DocBuilder.execute Time taken = 0:0:0.242
   [junit4]   2> 25979 T54 C87 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-C30BFCC9157F9EDA-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}'+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 248
   [junit4]   2> ASYNC  NEW_CORE C88 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 25987 T54 C88 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=20 
status=0 QTime=3 
   [junit4]   2> 25991 T54 C88 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=1 
   [junit4]   2> 25991 T54 C88 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 26498 T54 oas.SolrTestCaseJ4.tearDown ###Ending 
testDeltaImportWithoutInitialFullImport
   [junit4]   2> 26510 T54 oas.SolrTestCaseJ4.setUp ###Starting 
testWithSimpleTransformer
   [junit4]   2> ASYNC  NEW_CORE C89 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 26512 T54 C89 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 26513 T54 C89 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 26515 T54 C89 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 26516 T54 C89 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_c,generation=12}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_d,generation=13}
   [junit4]   2> 26516 T54 C89 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 13
   [junit4]   2> 26517 T54 C89 oass.SolrIndexSearcher.<init> Opening 
Searcher@7b33d07c[collection1] main
   [junit4]   2> 26518 T54 C89 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 26526 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@7b33d07c[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> ASYNC  NEW_CORE C90 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 26527 T57 C90 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> 26527 T57 C90 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> 26528 T57 C90 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> 26528 T57 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 26528 T57 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@7b33d07c[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 26529 T54 C90 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 15
   [junit4]   2> ASYNC  NEW_CORE C91 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 26796 T54 C91 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 26796 T54 C91 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 26796 T54 C91 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 26797 T54 C91 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 26798 T54 C91 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 26798 T54 C91 oashd.JdbcDataSource$1.call Creating a 
connection for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 26799 T54 C91 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 26810 T54 C91 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 26810 T54 C91 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> ASYNC  NEW_CORE C92 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 26817 T54 C92 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/index),segFN=segments_d,generation=13}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@1aa8173f 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-C30BFCC9157F9EDA-001/init-core-data-001/i

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

eddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.People.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.People.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.People.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&clean=false&command=delta-import}
 {add=[1017, 1001],commit=} 0 63
   [junit4]   2> ASYNC  NEW_CORE C99 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 26958 T54 C99 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=AddAColumn_s:Added&qt=standard&start=0&rows=20&version=2.2} 
hits=22 status=0 QTime=2 
   [junit4]   2> 26961 T54 C99 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 26961 T54 C99 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 26962 T54 C99 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:1001&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C100 name=collection1 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 26966 T54 C100 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=id:1017&qt=standard&start=0&rows=20&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> 27472 T54 oas.SolrTestCaseJ4.tearDown ###Ending 
testWithSimpleTransformer
   [junit4]   2> 27499 T54 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 27500 T54 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=267490519
   [junit4]   2> 27500 T54 oasc.SolrCore.close [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5e417e2b
   [junit4]   2> 27500 T54 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=176,cumulative_deletesById=13,cumulative_deletesByQuery=9,cumulative_errors=0}
   [junit4]   2> 27501 T54 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 27501 T54 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 27501 T54 oasu.DefaultSolrCoreState.closeIndexWriter closing 
IndexWriter with IndexWriterCloser
   [junit4]   2> 27502 T54 oasc.SolrCore.closeSearcher [collection1] Closing 
main searcher on request.
   [junit4]   2> 27503 T54 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 27504 T54 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-C30BFCC9157F9EDA-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-C30BFCC9157F9EDA-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 27504 T54 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-C30BFCC9157F9EDA-001/init-core-data-001/index
   [junit4]   2> 27504 T54 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-C30BFCC9157F9EDA-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-C30BFCC9157F9EDA-001/init-core-data-001;done=false>>]
   [junit4]   2> 27504 T54 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-C30BFCC9157F9EDA-001/init-core-data-001
   [junit4]   2> 7676 T53 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will 
linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> 27742 T53 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 
thread leaked from SUITE scope at 
org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta: 
   [junit4]   2>           1) Thread[id=55, 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> 27745 T53 ccr.ThreadLeakControl.tryToInterruptAll Starting to 
interrupt leaked threads:
   [junit4]   2>           1) Thread[id=55, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]
   [junit4]   2> 30747 T53 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There 
are still zombie threads that couldn't be terminated:
   [junit4]   2>           1) Thread[id=55, 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=Lucene42, sim=DefaultSimilarity, 
locale=es_CR, timezone=Australia/South
   [junit4]   2> NOTE: Linux 3.13.0-32-generic amd64/Oracle Corporation 
1.8.0_11 (64-bit)/cpus=8,threads=2,free=90539856,total=131989504
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestFileListWithLineEntityProcessor, TestURLDataSource, 
TestContentStreamDataSource, TestDateFormatTransformer, TestContextImpl, 
TestSqlEntityProcessorDelta]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSqlEntityProcessorDelta -Dtests.seed=C30BFCC9157F9EDA 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_CR 
-Dtests.timezone=Australia/South -Dtests.file.encoding=ISO-8859-1
   [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=55, 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([C30BFCC9157F9EDA]:0)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=55, 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([C30BFCC9157F9EDA]:0)
   [junit4] Completed on J1 in 30.91s, 5 tests, 2 errors <<< FAILURES!

[...truncated 94 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, 106 
ignored

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



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