Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11001/
Java: 64bit/jdk1.7.0_65 -XX:-UseCompressedOops -XX:+UseSerialGC

4 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestSimplePropertiesWriter

Error Message:
1 thread leaked from SUITE scope at 
org.apache.solr.handler.dataimport.TestSimplePropertiesWriter:     1) 
Thread[id=41, name=Timer-0, state=WAITING, 
group=TGRP-TestSimplePropertiesWriter]         at java.lang.Object.wait(Native 
Method)         at java.lang.Object.wait(Object.java:503)         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.TestSimplePropertiesWriter: 
   1) Thread[id=41, name=Timer-0, state=WAITING, 
group=TGRP-TestSimplePropertiesWriter]
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:503)
        at java.util.TimerThread.mainLoop(Timer.java:526)
        at java.util.TimerThread.run(Timer.java:505)
        at __randomizedtesting.SeedInfo.seed([513ECE12E95D7320]:0)


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

Error Message:
There are still zombie threads that couldn't be terminated:    1) Thread[id=41, 
name=Timer-0, state=WAITING, group=TGRP-TestSimplePropertiesWriter]         at 
java.lang.Object.wait(Native Method)         at 
java.lang.Object.wait(Object.java:503)         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=41, name=Timer-0, state=WAITING, 
group=TGRP-TestSimplePropertiesWriter]
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:503)
        at java.util.TimerThread.mainLoop(Timer.java:526)
        at java.util.TimerThread.run(Timer.java:505)
        at __randomizedtesting.SeedInfo.seed([513ECE12E95D7320]:0)


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=12, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]         at java.lang.Object.wait(Native 
Method)         at java.lang.Object.wait(Object.java:503)         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=12, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:503)
        at java.util.TimerThread.mainLoop(Timer.java:526)
        at java.util.TimerThread.run(Timer.java:505)
        at __randomizedtesting.SeedInfo.seed([513ECE12E95D7320]: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=12, 
name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessorDelta]         at 
java.lang.Object.wait(Native Method)         at 
java.lang.Object.wait(Object.java:503)         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=12, name=Timer-0, state=WAITING, 
group=TGRP-TestSqlEntityProcessorDelta]
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:503)
        at java.util.TimerThread.mainLoop(Timer.java:526)
        at java.util.TimerThread.run(Timer.java:505)
        at __randomizedtesting.SeedInfo.seed([513ECE12E95D7320]:0)




Build Log:
[...truncated 14982 lines...]
   [junit4] Suite: 
org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta
   [junit4]   2> log4j:WARN No such property [conversionPattern] in 
org.apache.solr.util.SolrLogLayout.
   [junit4]   2> Creating dataDir: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001
   [junit4]   2> 1382 T11 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(false) and clientAuth (false)
   [junit4]   2> 2315 T11 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 2353 T11 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-513ECE12E95D7320-001/core-home-001/collection1/'
   [junit4]   2> 2712 T11 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
LUCENE_5_0
   [junit4]   2> 2948 T11 oasc.SolrConfig.<init> Loaded SolrConfig: 
dataimport-solrconfig.xml
   [junit4]   2> 2958 T11 oass.IndexSchema.readSchema Reading Solr Schema from 
dataimport-schema.xml
   [junit4]   2> 2983 T11 oass.IndexSchema.readSchema [null] Schema 
name=dih_test
   [junit4]   2> 3154 T11 oass.IndexSchema.readSchema default search field in 
schema is desc
   [junit4]   2> 3156 T11 oass.IndexSchema.readSchema query parser default 
operator is OR
   [junit4]   2> 3157 T11 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 3165 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 3165 T11 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-513ECE12E95D7320-001/core-home-001
   [junit4]   2> 3166 T11 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-513ECE12E95D7320-001/core-home-001/'
   [junit4]   2> 3280 T11 oasc.CoreContainer.<init> New CoreContainer 1926958343
   [junit4]   2> 3283 T11 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-513ECE12E95D7320-001/core-home-001/]
   [junit4]   2> 3304 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
socketTimeout to: 0
   [junit4]   2> 3307 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
urlScheme to: null
   [junit4]   2> 3307 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
connTimeout to: 0
   [junit4]   2> 3308 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
maxConnectionsPerHost to: 20
   [junit4]   2> 3308 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
corePoolSize to: 0
   [junit4]   2> 3309 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
maximumPoolSize to: 2147483647
   [junit4]   2> 3310 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
maxThreadIdleTime to: 5
   [junit4]   2> 3310 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
sizeOfQueue to: -1
   [junit4]   2> 3311 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
fairnessPolicy to: false
   [junit4]   2> 3602 T11 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=false
   [junit4]   2> 3607 T11 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 3609 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering 
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 3611 T11 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 3727 T13 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-513ECE12E95D7320-001/core-home-001/collection1/'
   [junit4]   2> 3828 T13 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
LUCENE_5_0
   [junit4]   2> 3898 T13 oasc.SolrConfig.<init> Loaded SolrConfig: 
dataimport-solrconfig.xml
   [junit4]   2> 3899 T13 oass.IndexSchema.readSchema Reading Solr Schema from 
dataimport-schema.xml
   [junit4]   2> 3918 T13 oass.IndexSchema.readSchema [collection1] Schema 
name=dih_test
   [junit4]   2> 3979 T13 oass.IndexSchema.readSchema default search field in 
schema is desc
   [junit4]   2> 3981 T13 oass.IndexSchema.readSchema query parser default 
operator is OR
   [junit4]   2> 3982 T13 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 3984 T13 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-513ECE12E95D7320-001/core-home-001/collection1/
   [junit4]   2> 4015 T13 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 4030 T13 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-513ECE12E95D7320-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-513ECE12E95D7320-001/init-core-data-001/
   [junit4]   2> 4031 T13 oasc.SolrCore.<init> JMX monitoring not detected for 
core: collection1
   [junit4]   2> 4041 T13 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> 4042 T13 oasc.SolrCore.initListeners [collection1] Added 
SolrEventListener for firstSearcher: 
org.apache.solr.core.QuerySenderListener{queries=[]}
   [junit4]   2> 4066 T13 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-513ECE12E95D7320-001/init-core-data-001
   [junit4]   2> 4067 T13 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-513ECE12E95D7320-001/init-core-data-001/index/
   [junit4]   2> 4068 T13 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-513ECE12E95D7320-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 4077 T13 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-513ECE12E95D7320-001/init-core-data-001/index
   [junit4]   2> 4126 T13 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_1,generation=1}
   [junit4]   2> 4128 T13 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 4362 T13 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "" (default)
   [junit4]   2> 4401 T13 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
   [junit4]   2> 4411 T13 oasc.RequestHandlers.initHandlersFromConfig created 
/update: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 4412 T13 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 4413 T13 oasc.RequestHandlers.initHandlersFromConfig created 
/update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 4414 T13 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 4426 T13 oasc.RequestHandlers.initHandlersFromConfig created 
standard: solr.StandardRequestHandler
   [junit4]   2> 4431 T13 oasc.RequestHandlers.initHandlersFromConfig created 
/dataimport: org.apache.solr.handler.dataimport.DataImportHandler
   [junit4]   2> 4432 T13 oasc.RequestHandlers.initHandlersFromConfig created 
/search: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 4436 T13 oasc.RequestHandlers.initHandlersFromConfig WARN 
Multiple requestHandler registered to the same name: /update ignoring: 
org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 4436 T13 oasc.RequestHandlers.initHandlersFromConfig created 
/update: solr.UpdateRequestHandler
   [junit4]   2> 4472 T13 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 4478 T13 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 4480 T13 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 4482 T13 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 4483 T13 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 4502 T13 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 4503 T13 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 4506 T13 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_1,generation=1}
   [junit4]   2> 4507 T13 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 4533 T13 oass.SolrIndexSearcher.<init> Opening 
Searcher@1f6e91d[collection1] main
   [junit4]   2> 4557 T13 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-513ECE12E95D7320-001/core-home-001/collection1/conf
   [junit4]   2> 4559 T13 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-513ECE12E95D7320-001/core-home-001/collection1/conf}
   [junit4]   2> 4584 T13 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-513ECE12E95D7320-001/core-home-001/collection1/conf
   [junit4]   2> 4585 T13 oasr.ManagedResource.reloadFromStorage WARN No stored 
data found for /rest/managed
   [junit4]   2> 4590 T13 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-513ECE12E95D7320-001/core-home-001/collection1/conf
   [junit4]   2> 4591 T13 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 4623 T13 oasc.CoreContainer.registerCore registering core: 
collection1
   [junit4]   2> 4625 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1f6e91d[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 4630 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 4631 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1f6e91d[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 4631 T11 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 4652 T11 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
   [junit4]   2> ASYNC  NEW_CORE C0 name=collection1 
org.apache.solr.core.SolrCore@6e45faff
   [junit4]   2> 4849 T11 C0 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4855 T11 C0 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 199
   [junit4]   2> 4879 T11 C0 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4883 T11 C0 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_2,generation=2}
   [junit4]   2> 4884 T11 C0 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 4886 T11 C0 oass.SolrIndexSearcher.<init> Opening 
Searcher@29a6b700[collection1] main
   [junit4]   2> 4890 T11 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 4890 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@29a6b700[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 4918 T14 C0 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=25 
   [junit4]   2> 4920 T14 C0 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 4933 T14 C0 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=12 
   [junit4]   2> 4933 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 4934 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@29a6b700[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 4935 T11 C0 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 57
   [junit4]   2> ASYNC  NEW_CORE C1 name=collection1 
org.apache.solr.core.SolrCore@6e45faff
   [junit4]   2> 5525 T11 C1 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 5526 T11 C1 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 5530 T11 C1 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 5554 T11 C1 oashd.SimplePropertiesWriter.readIndexerProperties 
WARN Unable to read: the.properties
   [junit4]   2> 5580 T11 C1 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 5595 T11 C1 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 5596 T11 C1 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 5722 T11 C1 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 5723 T11 C1 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> ASYNC  NEW_CORE C2 name=collection1 
org.apache.solr.core.SolrCore@6e45faff
   [junit4]   2> 5878 T11 C2 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_3,generation=3}
   [junit4]   2> 5879 T11 C2 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 5907 T11 C2 oass.SolrIndexSearcher.<init> Opening 
Searcher@3df2e8e[collection1] main
   [junit4]   2> 5908 T11 C2 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 5919 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@3df2e8e[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):C20))}
   [junit4]   2> 5920 T14 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=1 
   [junit4]   2> 5920 T14 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 5921 T14 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=0 
   [junit4]   2> 5922 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 5922 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3df2e8e[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):C20))}
   [junit4]   2> 5923 T11 C2 oashd.SimplePropertiesWriter.readIndexerProperties 
WARN Unable to read: the.properties
   [junit4]   2> 5924 T11 C2 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 5925 T11 C2 oashd.DocBuilder.execute Time taken = 0:0:0.370
   [junit4]   2> 5926 T11 C2 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={synchronous=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-513ECE12E95D7320-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.People.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.People.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.People.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=full-import&commit=true&indent=true&clean=true}
 {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 
0 421
   [junit4]   2> 5932 T11 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=*:*&rows=20} hits=20 
status=0 QTime=2 
   [junit4]   2> 5955 T11 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 5957 T11 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 5958 T11 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 5966 T11 C2 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 5967 T11 C2 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 5967 T11 C2 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 5968 T11 C2 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 5970 T11 C2 oashd.DocBuilder.doDelta Starting delta collection.
   [junit4]   2> 5971 T11 C2 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 5971 T11 C2 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 5972 T11 C2 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 5977 T11 C2 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 5979 T11 C2 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 1
   [junit4]   2> 5980 T11 C2 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 5980 T11 C2 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 5983 T11 C2 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 5984 T11 C2 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 5984 T11 C2 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 6005 T11 C2 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_4,generation=4}
   [junit4]   2> 6006 T11 C2 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 4
   [junit4]   2> 6010 T11 C2 oass.SolrIndexSearcher.<init> Opening 
Searcher@5c6408bb[collection1] main
   [junit4]   2> 6011 T11 C2 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 6012 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@5c6408bb[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):C20) 
Uninverting(_1(5.0):C1))}
   [junit4]   2> 6013 T14 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 6014 T14 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 6016 T14 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 6016 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 6017 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@5c6408bb[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0):C20) 
Uninverting(_1(5.0):C1))}
   [junit4]   2> 6018 T11 C2 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 6019 T11 C2 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 6020 T11 C2 oashd.DocBuilder.execute Time taken = 0:0:0.51
   [junit4]   2> 6021 T11 C2 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={synchronous=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-513ECE12E95D7320-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&command=delta-import&commit=true&indent=true&clean=false}
 {add=[1016],commit=} 0 60
   [junit4]   2> 6023 T11 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=*:*&rows=20} hits=21 
status=0 QTime=1 
   [junit4]   2> 6035 T11 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 6037 T11 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 6041 T11 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:1016&rows=20} hits=1 
status=0 QTime=3 
   [junit4]   2> 6047 T11 oas.SolrTestCaseJ4.tearDown ###Ending testSingleEntity
   [junit4]   2> 6071 T11 oas.SolrTestCaseJ4.setUp ###Starting 
testDeltaImportWithoutInitialFullImport
   [junit4]   2> 6072 T11 C2 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 6073 T11 C2 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 6076 T11 C2 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 6077 T11 C2 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_4,generation=4}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_5,generation=5}
   [junit4]   2> 6078 T11 C2 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 5
   [junit4]   2> 6078 T11 C2 oass.SolrIndexSearcher.<init> Opening 
Searcher@7526fc24[collection1] main
   [junit4]   2> 6079 T11 C2 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 6079 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@7526fc24[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 6080 T14 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 6081 T14 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 6082 T14 C2 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 6082 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 6083 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@7526fc24[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 6085 T11 C2 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> ASYNC  NEW_CORE C3 name=collection1 
org.apache.solr.core.SolrCore@6e45faff
   [junit4]   2> 7892 T11 C3 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 7892 T11 C3 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 7893 T11 C3 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 7894 T11 C3 oashd.SimplePropertiesWriter.readIndexerProperties 
WARN Unable to read: the.properties
   [junit4]   2> 7895 T11 C3 oashd.DocBuilder.doDelta Starting delta collection.
   [junit4]   2> 7896 T11 C3 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 7897 T11 C3 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 7899 T11 C3 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 1
   [junit4]   2> 7975 T11 C3 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 20
   [junit4]   2> 7976 T11 C3 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 7977 T11 C3 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> ASYNC  NEW_CORE C4 name=collection1 
org.apache.solr.core.SolrCore@6e45faff
   [junit4]   2> 8175 T11 C4 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 8176 T11 C4 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 8176 T11 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 8235 T11 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_5,generation=5}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_6,generation=6}
   [junit4]   2> 8235 T11 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 6
   [junit4]   2> 8238 T11 C4 oass.SolrIndexSearcher.<init> Opening 
Searcher@3a1e3bc2[collection1] main
   [junit4]   2> 8239 T11 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 8240 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@3a1e3bc2[collection1] 
main{UninvertingDirectoryReader(Uninverting(_2(5.0):C20))}
   [junit4]   2> 8240 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 8241 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 8242 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 8242 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 8243 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3a1e3bc2[collection1] 
main{UninvertingDirectoryReader(Uninverting(_2(5.0):C20))}
   [junit4]   2> 8243 T11 C4 oashd.SimplePropertiesWriter.readIndexerProperties 
WARN Unable to read: the.properties
   [junit4]   2> 8244 T11 C4 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 8245 T11 C4 oashd.DocBuilder.execute Time taken = 0:0:0.350
   [junit4]   2> 8245 T11 C4 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={synchronous=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-513ECE12E95D7320-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.People.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.People.last_index_time}'+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&command=delta-import&commit=true&indent=true&clean=false}
 {add=[15, 14, 13, 12, 11, 10, 9, 8, 7, 6, ... (20 adds)],commit=} 0 358
   [junit4]   2> 8251 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=*:*&rows=20} hits=20 
status=0 QTime=2 
   [junit4]   2> 8265 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=1 
   [junit4]   2> 8267 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=1 
   [junit4]   2> 8797 T11 oas.SolrTestCaseJ4.tearDown ###Ending 
testDeltaImportWithoutInitialFullImport
   [junit4]   2> 8812 T11 oas.SolrTestCaseJ4.setUp ###Starting 
testWithComplexTransformer
   [junit4]   2> 8814 T11 C4 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 8814 T11 C4 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 8817 T11 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 8818 T11 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_6,generation=6}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_7,generation=7}
   [junit4]   2> 8819 T11 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 7
   [junit4]   2> 8820 T11 C4 oass.SolrIndexSearcher.<init> Opening 
Searcher@15f30f0a[collection1] main
   [junit4]   2> 8820 T11 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 8821 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@15f30f0a[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 8822 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 8823 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 8824 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=0 
   [junit4]   2> 8825 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 8825 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@15f30f0a[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 8827 T11 C4 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> 8850 T11 C4 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 8851 T11 C4 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 8851 T11 C4 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 8852 T11 C4 oashd.SimplePropertiesWriter.readIndexerProperties 
WARN Unable to read: the.properties
   [junit4]   2> 8853 T11 C4 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 8855 T11 C4 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 8855 T11 C4 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 8904 T11 C4 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 8905 T11 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 8931 T11 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_7,generation=7}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_8,generation=8}
   [junit4]   2> 8932 T11 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 8
   [junit4]   2> 8934 T11 C4 oass.SolrIndexSearcher.<init> Opening 
Searcher@53a84bc3[collection1] main
   [junit4]   2> 8935 T11 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 8937 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@53a84bc3[collection1] 
main{UninvertingDirectoryReader(Uninverting(_3(5.0):C60))}
   [junit4]   2> 8938 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=1 
   [junit4]   2> 8939 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=1 
   [junit4]   2> 8940 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 8940 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 8940 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@53a84bc3[collection1] 
main{UninvertingDirectoryReader(Uninverting(_3(5.0):C60))}
   [junit4]   2> 8941 T11 C4 oashd.SimplePropertiesWriter.readIndexerProperties 
WARN Unable to read: the.properties
   [junit4]   2> 8942 T11 C4 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 8943 T11 C4 oashd.DocBuilder.execute Time taken = 0:0:0.90
   [junit4]   2> 8944 T11 C4 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={synchronous=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-513ECE12E95D7320-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"+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&command=full-import&commit=true&indent=true&clean=true}
 {deleteByQuery=*:*,add=[1, TripleThreat-1-1, TripleThreat-2-1, 2, 
TripleThreat-1-2, TripleThreat-2-2, 3, TripleThreat-1-3, TripleThreat-2-3, 4, 
... (60 adds)],commit=} 0 99
   [junit4]   2> 9027 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&rows=60&sort=id+asc} hits=60 status=0 QTime=79 
   [junit4]   2> 9081 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={qt=standard&version=2.2&start=0&q=id:TripleThreat-1-*&rows=20} hits=20 
status=0 QTime=10 
   [junit4]   2> 9093 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={qt=standard&version=2.2&start=0&q=id:TripleThreat-2-*&rows=20} hits=20 
status=0 QTime=2 
   [junit4]   2> 9113 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={qt=standard&version=2.2&start=0&q=%2Bid:TripleThreat-1-3+%2BNAME_mult_s:Michael+%2BNAME_mult_s:leahciM++%2BCOUNTRY_CODES_mult_s:NR+%2BCOUNTRY_CODES_mult_s:RN&rows=20}
 hits=1 status=0 QTime=6 
   [junit4]   2> 9117 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=AddAColumn_s:Added&rows=20} 
hits=20 status=0 QTime=1 
   [junit4]   2> 9126 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 9127 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 9128 T11 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 9137 T11 C4 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 9137 T11 C4 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 9138 T11 C4 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 9138 T11 C4 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 9140 T11 C4 oashd.DocBuilder.doDelta Starting delta collection.
   [junit4]   2> 9141 T11 C4 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 9141 T11 C4 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 9142 T11 C4 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 9146 T11 C4 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 6
   [junit4]   2> 9146 T11 C4 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 2
   [junit4]   2> 9147 T11 C4 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 9147 T11 C4 oashd.DocBuilder.deleteAll Deleting stale 
documents 
   [junit4]   2> 9148 T11 C4 oashd.SolrWriter.deleteDoc Deleting document: 11
   [junit4]   2> 9149 T11 C4 oashd.SolrWriter.deleteDoc Deleting document: 16
   [junit4]   2> 9160 T11 C4 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 9161 T11 C4 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 9161 T11 C4 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 9175 T11 C4 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_8,generation=8}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_9,generation=9}
   [junit4]   2> 9176 T11 C4 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 9
   [junit4]   2> 9181 T11 C4 oass.SolrIndexSearcher.<init> Opening 
Searcher@12a43fb[collection1] main
   [junit4]   2> 9182 T11 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 9191 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@12a43fb[collection1] 
main{UninvertingDirectoryReader(Uninverting(_3(5.0):C60/17:delGen=1) 
Uninverting(_4(5.0):C18))}
   [junit4]   2> 9192 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=1 
   [junit4]   2> 9193 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=1 
   [junit4]   2> 9194 T14 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 9194 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 9194 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@12a43fb[collection1] 
main{UninvertingDirectoryReader(Uninverting(_3(5.0):C60/17:delGen=1) 
Uninverting(_4(5.0):C18))}
   [junit4]   2> 9195 T11 C4 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 9196 T11 C4 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 9197 T11 C4 oashd.DocBuilder.execute Time taken = 0:0:0.57
   [junit4]   2> 9198 T11 C4 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={synchronous=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-513ECE12E95D7320-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"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&command=delta-import&commit=true&indent=true&clean=false}
 {delete=[11, 16],add=[1010, TripleThreat-1-1010, TripleThreat-2-1010, 15, 
TripleThreat-1-15, TripleThreat-2-15, 17, TripleThreat-1-17, TripleThreat-2-17, 
1, ... (18 adds)],commit=} 0 66
   [junit4]   2> 9203 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={q=*:*&rows=57&sort=id+asc} hits=61 status=0 QTime=2 
   [junit4]   2> 9245 T11 C4 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={qt=standard&version=2.2&start=0&q=id:TripleThreat-1-*&rows=20} hits=21 
status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C5 name=collection1 
org.apache.solr.core.SolrCore@6e45faff
   [junit4]   2> 9267 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={qt=standard&version=2.2&start=0&q=id:TripleThreat-2-*&rows=20} hits=21 
status=0 QTime=2 
   [junit4]   2> 9277 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={qt=standard&version=2.2&start=0&q=%2Bid:TripleThreat-1-3+%2BNAME_mult_s:Michael+%2BNAME_mult_s:leahciM++%2BCOUNTRY_CODES_mult_s:NR+%2BCOUNTRY_CODES_mult_s:RN&rows=20}
 hits=1 status=0 QTime=1 
   [junit4]   2> 9281 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=AddAColumn_s:Added&rows=20} 
hits=21 status=0 QTime=0 
   [junit4]   2> 9291 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 9292 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 9294 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:1010&rows=20} hits=1 
status=0 QTime=1 
   [junit4]   2> 9298 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:16&rows=20} hits=0 
status=0 QTime=1 
   [junit4]   2> 9302 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:11&rows=20} hits=0 
status=0 QTime=1 
   [junit4]   2> 9306 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:20&rows=20} hits=1 
status=0 QTime=1 
   [junit4]   2> 9313 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:6&rows=20} hits=1 
status=0 QTime=2 
   [junit4]   2> 9318 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:17&rows=20} hits=1 
status=0 QTime=1 
   [junit4]   2> 9322 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:1&rows=20} hits=1 
status=0 QTime=0 
   [junit4]   2> 9326 T11 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:15&rows=20} hits=1 
status=0 QTime=0 
   [junit4]   2> 9330 T11 oas.SolrTestCaseJ4.tearDown ###Ending 
testWithComplexTransformer
   [junit4]   2> 9352 T11 oas.SolrTestCaseJ4.setUp ###Starting testChildEntities
   [junit4]   2> 9354 T11 C5 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 9355 T11 C5 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 2
   [junit4]   2> 9357 T11 C5 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 9358 T11 C5 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_9,generation=9}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_a,generation=10}
   [junit4]   2> 9359 T11 C5 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 10
   [junit4]   2> 9359 T11 C5 oass.SolrIndexSearcher.<init> Opening 
Searcher@89d1e34[collection1] main
   [junit4]   2> 9360 T11 C5 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 9361 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@89d1e34[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 9361 T14 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 9362 T14 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 9363 T14 C5 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 9363 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 9364 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@89d1e34[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 9365 T11 C5 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 8
   [junit4]   2> 9814 T11 C5 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 9814 T11 C5 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 9815 T11 C5 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 9816 T11 C5 oashd.SimplePropertiesWriter.readIndexerProperties 
WARN Unable to read: the.properties
   [junit4]   2> ASYNC  NEW_CORE C6 name=collection1 
org.apache.solr.core.SolrCore@6e45faff
   [junit4]   2> 9829 T11 C6 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 9831 T11 C6 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 9833 T11 C6 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 1
   [junit4]   2> 9863 T11 C6 oashd.JdbcDataSource$1.call Creating a connection 
for entity Countries with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 9866 T11 C6 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 1
   [junit4]   2> 9979 T11 C6 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 9979 T11 C6 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 9987 T11 C6 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_a,generation=10}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_b,generation=11}
   [junit4]   2> 9988 T11 C6 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 11
   [junit4]   2> 9990 T11 C6 oass.SolrIndexSearcher.<init> Opening 
Searcher@e25df95[collection1] main
   [junit4]   2> 9991 T11 C6 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 9996 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@e25df95[collection1] 
main{UninvertingDirectoryReader(Uninverting(_5(5.0):C20))}
   [junit4]   2> 9997 T14 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 9998 T14 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 9999 T14 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=0 
   [junit4]   2> 9999 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10000 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@e25df95[collection1] 
main{UninvertingDirectoryReader(Uninverting(_5(5.0):C20))}
   [junit4]   2> 10000 T11 C6 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 10001 T11 C6 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 10002 T11 C6 oashd.DocBuilder.execute Time taken = 0:0:0.184
   [junit4]   2> 10002 T11 C6 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={synchronous=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-513ECE12E95D7320-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.People.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.People.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.People.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a<entity+name%3D"Countries"+pk%3D"Countries.CODE"+dataSource%3D"derby"+transformer%3D"AddAColumnTransformer"+newColumnName%3D"countryAdded_s"+newColumnValue%3D"country_added"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+CODE,+COUNTRY_NAME+FROM+COUNTRIES+WHERE+DELETED+!%3D+'Y'+AND+CODE%3D'${People.COUNTRY_CODE}'+"+>+%0a<field+column%3D"CODE"+name%3D"COUNTRY_CODE_s"+/>+%0a<field+column%3D"COUNTRY_NAME"+name%3D"COUNTRY_NAME_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=full-import&commit=true&indent=true&clean=true}
 {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 
0 196
   [junit4]   2> 10007 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=*:*&rows=20} hits=20 
status=0 QTime=2 
   [junit4]   2> 10023 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=AddAColumn_s:Added&rows=20} 
hits=20 status=0 QTime=2 
   [junit4]   2> 10050 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=NAME_mult_s:Jayden&rows=20} 
hits=1 status=0 QTime=1 
   [junit4]   2> 10080 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={qt=standard&version=2.2&start=0&q=COUNTRY_CODES_mult_s:NR&rows=20} 
hits=2 status=0 QTime=1 
   [junit4]   2> 10087 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={qt=standard&version=2.2&start=0&q=countryAdded_s:country_added&rows=20} 
hits=20 status=0 QTime=3 
   [junit4]   2> 10096 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=1 
   [junit4]   2> 10096 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 10097 T11 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 10141 T11 C6 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 10142 T11 C6 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 10142 T11 C6 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 10143 T11 C6 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 10144 T11 C6 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 10145 T11 C6 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: Countries
   [junit4]   2> 10145 T11 C6 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: Countries rows obtained : 0
   [junit4]   2> 10145 T11 C6 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: Countries rows obtained : 0
   [junit4]   2> 10146 T11 C6 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: Countries
   [junit4]   2> 10146 T11 C6 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 10147 T11 C6 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 10148 T11 C6 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 1
   [junit4]   2> 10168 T11 C6 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 10176 T11 C6 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 6
   [junit4]   2> 10176 T11 C6 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 5
   [junit4]   2> 10177 T11 C6 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 10177 T11 C6 oashd.DocBuilder.deleteAll Deleting stale 
documents 
   [junit4]   2> 10178 T11 C6 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 10178 T11 C6 oashd.SolrWriter.deleteDoc Deleting document: 12
   [junit4]   2> 10178 T11 C6 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 10179 T11 C6 oashd.SolrWriter.deleteDoc Deleting document: 11
   [junit4]   2> 10179 T11 C6 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 10180 T11 C6 oashd.SolrWriter.deleteDoc Deleting document: 6
   [junit4]   2> 10180 T11 C6 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 10181 T11 C6 oashd.SolrWriter.deleteDoc Deleting document: 5
   [junit4]   2> 10181 T11 C6 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 10181 T11 C6 oashd.SolrWriter.deleteDoc Deleting document: 17
   [junit4]   2> 10188 T11 C6 oashd.JdbcDataSource$1.call Creating a connection 
for entity Countries with URL: jdbc:derby:memory:derbyDB;territory=en_US
   [junit4]   2> 10190 T11 C6 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 1
   [junit4]   2> 10233 T11 C6 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 10234 T11 C6 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 10234 T11 C6 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10241 T11 C6 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_b,generation=11}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_c,generation=12}
   [junit4]   2> 10241 T11 C6 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 12
   [junit4]   2> 10244 T11 C6 oass.SolrIndexSearcher.<init> Opening 
Searcher@3474f6b0[collection1] main
   [junit4]   2> 10244 T11 C6 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 10252 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@3474f6b0[collection1] 
main{UninvertingDirectoryReader(Uninverting(_5(5.0):C20/10:delGen=1) 
Uninverting(_6(5.0):C6))}
   [junit4]   2> 10253 T14 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 10253 T14 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 10254 T14 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=0 
   [junit4]   2> 10255 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10255 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3474f6b0[collection1] 
main{UninvertingDirectoryReader(Uninverting(_5(5.0):C20/10:delGen=1) 
Uninverting(_6(5.0):C6))}
   [junit4]   2> 10256 T11 C6 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 10256 T11 C6 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 10257 T11 C6 oashd.DocBuilder.execute Time taken = 0:0:0.113
   [junit4]   2> 10257 T11 C6 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={synchronous=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-513ECE12E95D7320-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+name%3D"Countries"+pk%3D"Countries.CODE"+dataSource%3D"derby"+transformer%3D"AddAColumnTransformer"+newColumnName%3D"countryAdded_s"+newColumnValue%3D"country_added"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+CODE,+COUNTRY_NAME+FROM+COUNTRIES+WHERE+DELETED+!%3D+'Y'+AND+CODE%3D'${People.COUNTRY_CODE}'+"+>+%0a<field+column%3D"CODE"+name%3D"COUNTRY_CODE_s"+/>+%0a<field+column%3D"COUNTRY_NAME"+name%3D"COUNTRY_NAME_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=delta-import&commit=true&indent=true&clean=false}
 {delete=[12, 11, 6, 5, 17],add=[10, 8, 7, 1002, 19, 2],commit=} 0 119
   [junit4]   2> 10262 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=*:*&rows=20} hits=16 
status=0 QTime=2 
   [junit4]   2> 10275 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=NAME_mult_s:Jayden&rows=20} 
hits=1 status=0 QTime=0 
   [junit4]   2> 10284 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={qt=standard&version=2.2&start=0&q=COUNTRY_CODES_mult_s:NR&rows=20} 
hits=2 status=0 QTime=0 
   [junit4]   2> 10289 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:1002&rows=20} hits=1 
status=0 QTime=1 
   [junit4]   2> 10292 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:11&rows=20} hits=0 
status=0 QTime=1 
   [junit4]   2> 10294 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:12&rows=20} hits=0 
status=0 QTime=0 
   [junit4]   2> 10297 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:17&rows=20} hits=0 
status=0 QTime=1 
   [junit4]   2> 10299 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:5&rows=20} hits=0 
status=0 QTime=0 
   [junit4]   2> 10302 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:6&rows=20} hits=0 
status=0 QTime=1 
   [junit4]   2> 10304 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:7&rows=20} hits=1 
status=0 QTime=0 
   [junit4]   2> 10308 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:19&rows=20} hits=1 
status=0 QTime=1 
   [junit4]   2> 10312 T11 C6 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:2&rows=20} hits=1 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C7 name=collection1 
org.apache.solr.core.SolrCore@6e45faff
   [junit4]   2> 10326 T11 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:10&rows=20} hits=1 
status=0 QTime=1 
   [junit4]   2> 10330 T11 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=id:8&rows=20} hits=1 
status=0 QTime=0 
   [junit4]   2> 10841 T11 oas.SolrTestCaseJ4.tearDown ###Ending 
testChildEntities
   [junit4]   2> 10855 T11 oas.SolrTestCaseJ4.setUp ###Starting 
testWithSimpleTransformer
   [junit4]   2> 10856 T11 C7 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 10857 T11 C7 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 10859 T11 C7 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10860 T11 C7 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_c,generation=12}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_d,generation=13}
   [junit4]   2> 10861 T11 C7 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 13
   [junit4]   2> 10861 T11 C7 oass.SolrIndexSearcher.<init> Opening 
Searcher@2dbe2bb7[collection1] main
   [junit4]   2> 10862 T11 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 10863 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@2dbe2bb7[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 10864 T14 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 10864 T14 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 10865 T14 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=0 
   [junit4]   2> 10866 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10866 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@2dbe2bb7[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 10868 T11 C7 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> 10888 T11 C7 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 10889 T11 C7 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 10889 T11 C7 oashd.DataImporter.doFullImport Starting Full 
Import
   [junit4]   2> 10890 T11 C7 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 10891 T11 C7 oasu.DirectUpdateHandler2.deleteAll [collection1] 
REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 10892 T11 C7 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 10892 T11 C7 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 10902 T11 C7 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 10903 T11 C7 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10909 T11 C7 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_d,generation=13}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@43877d40 
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./temp/solr.handler.dataimport.TestSqlEntityProcessorDelta-513ECE12E95D7320-001/init-core-data-001/index),segFN=segments_e,generation=14}
   [junit4]   2> 10909 T11 C7 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 14
   [junit4]   2> 10911 T11 C7 oass.SolrIndexSearcher.<init> Opening 
Searcher@26b7933f[collection1] main
   [junit4]   2> 10912 T11 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 10918 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@26b7933f[collection1] 
main{UninvertingDirectoryReader(Uninverting(_7(5.0):C20))}
   [junit4]   2> 10918 T14 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=solr&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 10919 T14 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={event=newSearcher&start=0&distrib=false&q=rocks&rows=10} 
hits=0 status=0 QTime=0 
   [junit4]   2> 10920 T14 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 10920 T14 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10920 T14 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@26b7933f[collection1] 
main{UninvertingDirectoryReader(Uninverting(_7(5.0):C20))}
   [junit4]   2> 10921 T11 C7 
oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: 
the.properties
   [junit4]   2> 10922 T11 C7 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 10922 T11 C7 oashd.DocBuilder.execute Time taken = 0:0:0.31
   [junit4]   2> 10923 T11 C7 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={synchronous=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-513ECE12E95D7320-001/tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.People.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.People.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.People.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=full-import&commit=true&indent=true&clean=true}
 {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 
0 39
   [junit4]   2> 10925 T11 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={qt=standard&version=2.2&start=0&q=AddAColumn_s:Added&rows=20} 
hits=20 status=0 QTime=1 
   [junit4]   2> 10933 T11 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 10934 T11 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={indent=true} status=0 QTime=0 
   [junit4]   2> 10934 T11 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 10939 T11 C7 oashdc.DIHConfiguration.verifyWithSchema id is a 
required field in SolrSchema . But not found in DataConfig
   [junit4]   2> 10940 T11 C7 oashd.DataImporter.loadDataConfig Data 
Configuration loaded successfully
   [junit4]   2> 10940 T11 C7 oashd.DataImporter.doDeltaImport Starting Delta 
Import
   [junit4]   2> 10940 T11 C7 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 10941 T11 C7 oashd.DocBuilder.doDelta Starting delta 
collection.
   [junit4]   2> 10942 T11 C7 oashd.DocBuilder.collectDelta Running 
ModifiedRowKey() for Entity: People
   [junit4]   2> 10942 T11 C7 oashd.JdbcDataSource$1.call Creating a connection 
for entity People with URL: jdbc:hsqldb:mem:.
   [junit4]   2> 10943 T11 C7 oashd.JdbcDataSource$1.call Time taken for 
getConnection(): 0
   [junit4]   2> 10945 T11 C7 oashd.DocBuilder.findMatchingPkColumn Resolving 
deltaQuery column 'ID' to match entity's declared pk 'People.ID'
   [junit4]   2> 10947 T11 C7 oashd.DocBuilder.collectDelta Completed 
ModifiedRowKey for Entity: People rows obtained : 6
   [junit4]   2> 10947 T11 C7 oashd.DocBuilder.collectDelta Completed 
DeletedRowKey for Entity: People rows obtained : 0
   [junit4]   2> 10948 T11 C7 oashd.DocBuilder.collectDelta Completed 
parentDeltaQuery for Entity: People
   [junit4]   2> 10953 T11 C7 oashd.DocBuilder.doDelta Delta Import completed 
successfully
   [junit4]   2> 10954 T11 C7 oashd.DocBuilder.finish Import completed 
successfully
   [junit4]   2> 10954 T11 C7 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false

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

ll params={start=0&distrib=false&q=rocks&event=newSearcher&rows=10} hits=0 
status=0 QTime=0 
   [junit4]   2> 14546 T43 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml&event=newSearcher}
 hits=0 status=0 QTime=0 
   [junit4]   2> 14546 T43 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 14547 T43 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@4e3c3e59[collection1] 
main{UninvertingDirectoryReader(Uninverting(_3(5.0):C1))}
   [junit4]   2> 14548 T40 C7 
oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
   [junit4]   2> 14549 T40 C7 oashd.SimplePropertiesWriter.persist Wrote last 
indexed time to the.properties
   [junit4]   2> 14549 T40 C7 oashd.DocBuilder.execute Time taken = 0:0:0.73
   [junit4]   2> 14549 T40 C7 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null 
params={command=full-import&commit=true&clean=true&indent=true&dataConfig=<dataConfig>+%0a<propertyWriter+dateFormat%3D"yyyy-MM-dd+HH:mm:ss.SSSSSS"+type%3D"SimplePropertiesWriter"+directory%3D"/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./temp/solr.handler.dataimport.TestSimplePropertiesWriter-513ECE12E95D7320-001/tempDir-001"+filename%3D"the.properties"+/>%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"TestSimplePropertiesWriter">+%0a<entity+name%3D"SomeDates"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+query%3D"select+1+as+id,+YEAR('${dih.last_index_time}')+as+AYEAR_S+from+sysibm.sysdummy1+"+>%0a<field+column%3D"AYEAR_S"+name%3D"ayear_s"+/>+%0a</entity>%0a</document>+%0a</dataConfig>+%0a&synchronous=true}
 {deleteByQuery=*:*,add=[1],commit=} 0 77
   [junit4]   2> 14550 T40 oashd.SimplePropertiesWriter.readIndexerProperties 
Read the.properties
   [junit4]   2> 14553 T40 C7 oasc.SolrCore.execute [collection1] webapp=null 
path=null params={start=0&q=*:*&qt=standard&rows=20&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> 15083 T40 oas.SolrTestCaseJ4.tearDown ###Ending 
testSimplePropertiesWriter
   [junit4]   2> 15106 T40 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 15106 T40 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=521691242
   [junit4]   2> 15107 T40 oasc.SolrCore.close [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@34205bb8
   [junit4]   2> 15107 T40 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=5,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=5,cumulative_errors=0}
   [junit4]   2> 15107 T40 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 15108 T40 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 15108 T40 oasu.DefaultSolrCoreState.closeIndexWriter closing 
IndexWriter with IndexWriterCloser
   [junit4]   2> 15109 T40 oasc.SolrCore.closeSearcher [collection1] Closing 
main searcher on request.
   [junit4]   2> 15109 T40 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 15110 T40 oasc.CachingDirectoryFactory.closeCacheValue looking 
to close 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./temp/solr.handler.dataimport.TestSimplePropertiesWriter-513ECE12E95D7320-001/init-core-data-001
 
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./temp/solr.handler.dataimport.TestSimplePropertiesWriter-513ECE12E95D7320-001/init-core-data-001;done=false>>]
   [junit4]   2> 15110 T40 oasc.CachingDirectoryFactory.close Closing 
directory: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./temp/solr.handler.dataimport.TestSimplePropertiesWriter-513ECE12E95D7320-001/init-core-data-001
   [junit4]   2> 15110 T40 oasc.CachingDirectoryFactory.closeCacheValue looking 
to close 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./temp/solr.handler.dataimport.TestSimplePropertiesWriter-513ECE12E95D7320-001/init-core-data-001/index
 
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./temp/solr.handler.dataimport.TestSimplePropertiesWriter-513ECE12E95D7320-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 15111 T40 oasc.CachingDirectoryFactory.close Closing 
directory: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./temp/solr.handler.dataimport.TestSimplePropertiesWriter-513ECE12E95D7320-001/init-core-data-001/index
   [junit4]   2> 3225 T39 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will 
linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> 23286 T39 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 
thread leaked from SUITE scope at 
org.apache.solr.handler.dataimport.TestSimplePropertiesWriter: 
   [junit4]   2>           1) Thread[id=41, name=Timer-0, state=WAITING, 
group=TGRP-TestSimplePropertiesWriter]
   [junit4]   2>                at java.lang.Object.wait(Native Method)
   [junit4]   2>                at java.lang.Object.wait(Object.java:503)
   [junit4]   2>                at 
java.util.TimerThread.mainLoop(Timer.java:526)
   [junit4]   2>                at java.util.TimerThread.run(Timer.java:505)
   [junit4]   2> 23287 T39 ccr.ThreadLeakControl.tryToInterruptAll Starting to 
interrupt leaked threads:
   [junit4]   2>           1) Thread[id=41, name=Timer-0, state=WAITING, 
group=TGRP-TestSimplePropertiesWriter]
   [junit4]   2> 26289 T39 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There 
are still zombie threads that couldn't be terminated:
   [junit4]   2>           1) Thread[id=41, name=Timer-0, state=WAITING, 
group=TGRP-TestSimplePropertiesWriter]
   [junit4]   2>                at java.lang.Object.wait(Native Method)
   [junit4]   2>                at java.lang.Object.wait(Object.java:503)
   [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=Lucene45, sim=DefaultSimilarity, 
locale=fi_FI, timezone=Asia/Urumqi
   [junit4]   2> NOTE: Linux 3.13.0-32-generic amd64/Oracle Corporation 
1.7.0_65 (64-bit)/cpus=8,threads=2,free=99332000,total=126484480
   [junit4]   2> NOTE: All tests run in this JVM: [TestWriterImpl, 
TestZKPropertiesWriter, TestSimplePropertiesWriter]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSimplePropertiesWriter -Dtests.seed=513ECE12E95D7320 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fi_FI 
-Dtests.timezone=Asia/Urumqi -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J0 | TestSimplePropertiesWriter (suite) <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.handler.dataimport.TestSimplePropertiesWriter: 
   [junit4]    >    1) Thread[id=41, name=Timer-0, state=WAITING, 
group=TGRP-TestSimplePropertiesWriter]
   [junit4]    >         at java.lang.Object.wait(Native Method)
   [junit4]    >         at java.lang.Object.wait(Object.java:503)
   [junit4]    >         at java.util.TimerThread.mainLoop(Timer.java:526)
   [junit4]    >         at java.util.TimerThread.run(Timer.java:505)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([513ECE12E95D7320]:0)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=41, name=Timer-0, state=WAITING, 
group=TGRP-TestSimplePropertiesWriter]
   [junit4]    >         at java.lang.Object.wait(Native Method)
   [junit4]    >         at java.lang.Object.wait(Object.java:503)
   [junit4]    >         at java.util.TimerThread.mainLoop(Timer.java:526)
   [junit4]    >         at java.util.TimerThread.run(Timer.java:505)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([513ECE12E95D7320]:0)
   [junit4] Completed on J0 in 26.43s, 1 test, 2 errors <<< FAILURES!

[...truncated 26 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:1307:
 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, 4 suite-level errors, 136 
ignored

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


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

Reply via email to