You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@sd-datasolutions.de> on 2012/12/07 20:27:14 UTC

[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.6.0_37) - Build # 3105 - Still Failing!

Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3105/
Java: 64bit/jdk1.6.0_37 -XX:+UseParallelGC

All tests passed

Build Log:
[...truncated 12953 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:88: The following files contain @author tags, tabs or nocommits:
* solr/solrj/src/java/org/apache/solr/common/cloud/CompositeIdRouter.java

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



[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.6.0_37) - Build # 3116 - Still Failing!

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3116/
Java: 32bit/jdk1.6.0_37 -server -XX:+UseParallelGC

All tests passed

Build Log:
[...truncated 12953 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:88: The following files contain @author tags, tabs or nocommits:
* solr/solrj/src/java/org/apache/solr/common/cloud/CompositeIdRouter.java

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



[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.6.0_37) - Build # 3115 - Still Failing!

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3115/
Java: 64bit/jdk1.6.0_37 -XX:+UseConcMarkSweepGC

All tests passed

Build Log:
[...truncated 12944 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:88: The following files contain @author tags, tabs or nocommits:
* solr/solrj/src/java/org/apache/solr/common/cloud/CompositeIdRouter.java

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



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

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3114/
Java: 64bit/jdk1.8.0-ea-b65 -XX:+UseParallelGC

1 tests failed.
REGRESSION:  org.apache.solr.handler.dataimport.TestSimplePropertiesWriter.testSimplePropertiesWriter

Error Message:


Stack Trace:
java.lang.NullPointerException
	at java.io.File.<init>(File.java:251)
	at org.apache.solr.handler.dataimport.TestSimplePropertiesWriter.spwAfter(TestSimplePropertiesWriter.java:54)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:474)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 11953 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.dataimport.TestSimplePropertiesWriter
[junit4:junit4]   2> 7 T90 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461
[junit4:junit4]   2> 8 T90 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/'
[junit4:junit4]   2> 45 T90 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 95 T90 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4:junit4]   2> 95 T90 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 99 T90 oass.IndexSchema.readSchema Schema name=dih_test
[junit4:junit4]   2> 148 T90 oass.IndexSchema.readSchema default search field in schema is desc
[junit4:junit4]   2> 149 T90 oass.IndexSchema.readSchema query parser default operator is OR
[junit4:junit4]   2> 149 T90 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 150 T90 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 150 T90 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr
[junit4:junit4]   2> 151 T90 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/'
[junit4:junit4]   2> 157 T90 oasc.CoreContainer.<init> New CoreContainer 654124278
[junit4:junit4]   2> 158 T90 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461/
[junit4:junit4]   2> 158 T90 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 158 T90 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=solr,start=0,rows=10}, {q=rocks,start=0,rows=10}, {q=static newSearcher warming query from solrconfig.xml}]}
[junit4:junit4]   2> 159 T90 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   2> 159 T90 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 160 T90 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@6ab96acf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@331372c6) assuming 'simple'
[junit4:junit4]   2> 160 T90 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461 forceNew:false
[junit4:junit4]   2> 161 T90 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461
[junit4:junit4]   2> 161 T90 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461/index/
[junit4:junit4]   2> 161 T90 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 162 T90 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461/index forceNew:false
[junit4:junit4]   2> 163 T90 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6b3a24e4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@42cb1dd6),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 163 T90 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 164 T90 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461/index
[junit4:junit4]   2> 165 T90 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
[junit4:junit4]   2> 165 T90 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 166 T90 oasc.RequestHandlers.initHandlersFromConfig created /dataimport: org.apache.solr.handler.dataimport.DataImportHandler
[junit4:junit4]   2> 168 T90 oasc.RequestHandlers.initHandlersFromConfig created /search: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 168 T90 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 171 T90 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 173 T90 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461
[junit4:junit4]   2> 174 T90 oass.SolrIndexSearcher.<init> Opening Searcher@337da81e main
[junit4:junit4]   2> 174 T90 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 175 T90 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 175 T90 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 175 T90 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 176 T90 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 176 T90 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 176 T90 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 176 T90 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 176 T90 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 177 T90 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 177 T90 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 177 T90 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 180 T92 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@337da81e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 180 T90 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 181 T92 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 181 T92 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@337da81e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 182 T90 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 186 T90 oas.SolrTestCaseJ4.setUp ###Starting testSimplePropertiesWriter
[junit4:junit4]   2> ASYNC  NEW_CORE C9 name=collection1 org.apache.solr.core.SolrCore@60e669a5
[junit4:junit4]   2> 190 T90 C9 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 190 T90 C9 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461
[junit4:junit4]   2> 192 T90 C9 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6b3a24e4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@42cb1dd6),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 193 T90 C9 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 193 T90 C9 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 4
[junit4:junit4]   2> 203 T90 C9 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 204 T90 C9 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6b3a24e4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@42cb1dd6),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6b3a24e4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@42cb1dd6),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 205 T90 C9 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 205 T90 C9 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461
[junit4:junit4]   2> 206 T90 C9 oass.SolrIndexSearcher.<init> Opening Searcher@34e5a52c main
[junit4:junit4]   2> 206 T92 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@34e5a52c main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 206 T90 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207 T92 C9 REQ [collection1] webapp=null path=null params={event=newSearcher&distrib=false&rows=10&q=solr&start=0} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 207 T92 C9 REQ [collection1] webapp=null path=null params={event=newSearcher&distrib=false&rows=10&q=rocks&start=0} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 208 T92 C9 REQ [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:junit4]   2> 208 T92 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 208 T92 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34e5a52c main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 208 T92 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461/index
[junit4:junit4]   2> 209 T90 C9 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4:junit4]   2> 245 T90 oashd.AbstractDIHJdbcTestCase.buildDatabase WARNING Skipping test because Database DERBY does not support the locale sr__#Latn
[junit4:junit4]   2> 259 T90 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 259 T90 oas.SolrTestCaseJ4.tearDown ###Ending testSimplePropertiesWriter
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSimplePropertiesWriter -Dtests.method=testSimplePropertiesWriter -Dtests.seed=73D0A0D7ABC2AE44 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr__#Latn -Dtests.timezone=Asia/Brunei -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   0.14s | TestSimplePropertiesWriter.testSimplePropertiesWriter <<<
[junit4:junit4]    > Assumption #1: got: <java.sql.SQLException: Failed to create database 'memory:derbyDB', see the next exception for details.>, expected: nullThrowable #2: java.lang.NullPointerException
[junit4:junit4]    > 	at java.io.File.<init>(File.java:251)
[junit4:junit4]    > 	at org.apache.solr.handler.dataimport.TestSimplePropertiesWriter.spwAfter(TestSimplePropertiesWriter.java:54)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 327 T90 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 327 T90 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=654124278
[junit4:junit4]   2> 328 T90 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@60e669a5
[junit4:junit4]   2> 328 T90 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 328 T90 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 328 T90 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 328 T90 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 329 T90 C9 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461/index
[junit4:junit4]   2> 329 T90 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 329 T90 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSimplePropertiesWriter-1354944827461/index
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=sr__#Latn, timezone=Asia/Brunei
[junit4:junit4]   2> NOTE: Linux 3.2.0-34-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=174881448,total=252510208
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestLineEntityProcessor, TestFileListEntityProcessor, TestContentStreamDataSource, TestVariableResolverEndToEnd, TestDocBuilder2, TestErrorHandling, TestNestedChildren, TestVariableResolver, TestXPathEntityProcessor, TestSqlEntityProcessorDelta, TestDataConfig, TestFieldReader, TestClobTransformer, TestNonWritablePersistFile, TestScriptTransformer, TestTemplateTransformer, TestSimplePropertiesWriter]
[junit4:junit4] Completed in 0.35s, 1 test, 1 error <<< FAILURES!

[...truncated 60 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:335: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:192: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:410: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:438: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1167: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:831: There were test failures: 34 suites, 140 tests, 1 error, 3 ignored

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



[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.7.0_09) - Build # 3113 - Still Failing!

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3113/
Java: 64bit/jdk1.7.0_09 -XX:+UseConcMarkSweepGC

All tests passed

Build Log:
[...truncated 13628 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:88: The following files contain @author tags, tabs or nocommits:
* solr/solrj/src/java/org/apache/solr/common/cloud/CompositeIdRouter.java

Total time: 28 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.7.0_09 -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure



[JENKINS] Lucene-Solr-trunk-Linux (64bit/ibm-j9-jdk7) - Build # 3112 - Still Failing!

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3112/
Java: 64bit/ibm-j9-jdk7 

All tests passed

Build Log:
[...truncated 13625 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:88: The following files contain @author tags, tabs or nocommits:
* solr/solrj/src/java/org/apache/solr/common/cloud/CompositeIdRouter.java

Total time: 27 minutes 27 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/ibm-j9-jdk7 
Email was triggered for: Failure
Sending email for trigger: Failure



[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b65) - Build # 3111 - Still Failing!

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3111/
Java: 32bit/jdk1.8.0-ea-b65 -client -XX:+UseG1GC

All tests passed

Build Log:
[...truncated 13636 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:88: The following files contain @author tags, tabs or nocommits:
* solr/solrj/src/java/org/apache/solr/common/cloud/CompositeIdRouter.java

Total time: 31 minutes 21 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b65 -client -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure



[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.6.0_37) - Build # 3110 - Still Failing!

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3110/
Java: 64bit/jdk1.6.0_37 -XX:+UseSerialGC

All tests passed

Build Log:
[...truncated 12948 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:88: The following files contain @author tags, tabs or nocommits:
* solr/solrj/src/java/org/apache/solr/common/cloud/CompositeIdRouter.java

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



[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.7.0_09) - Build # 3109 - Still Failing!

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3109/
Java: 64bit/jdk1.7.0_09 -XX:+UseSerialGC

All tests passed

Build Log:
[...truncated 13628 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:88: The following files contain @author tags, tabs or nocommits:
* solr/solrj/src/java/org/apache/solr/common/cloud/CompositeIdRouter.java

Total time: 27 minutes 14 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.7.0_09 -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.6.0_37) - Build # 3108 - Still Failing!

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3108/
Java: 32bit/jdk1.6.0_37 -server -XX:+UseConcMarkSweepGC

All tests passed

Build Log:
[...truncated 12937 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:88: The following files contain @author tags, tabs or nocommits:
* solr/solrj/src/java/org/apache/solr/common/cloud/CompositeIdRouter.java

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



RE: [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b65) - Build # 3107 - Still Failing!

Posted by "Dyer, James" <Ja...@ingramcontent.com>.
This is a test bug.  The test hit an Assume while setting up the db so the temp file never got created, hence NPE trying to delete.  (The test selected Derby as the db and sr__#Latn as the Locale, but Derby doesn't support locales with no middle name.)

I committed a fix.

James Dyer
E-Commerce Systems
Ingram Content Group
(615) 213-4311


-----Original Message-----
From: Policeman Jenkins Server [mailto:jenkins@sd-datasolutions.de]
Sent: Friday, December 07, 2012 3:39 PM
To: dev@lucene.apache.org
Subject: [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b65) - Build # 3107 - Still Failing!

Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3107/
Java: 32bit/jdk1.8.0-ea-b65 -server -XX:+UseG1GC

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

Error Message:


Stack Trace:
java.lang.NullPointerException
        at java.io.File.<init>(File.java:251)
        at org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.afterSqlEntitiyProcessorTestCase(AbstractSqlEntityProcessorTestCase.java:76)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:474)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
        at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
        at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
        at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
        at java.lang.Thread.run(Thread.java:722)


REGRESSION:  org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testWithComplexTransformer

Error Message:


Stack Trace:
java.lang.NullPointerException
        at java.io.File.<init>(File.java:251)
        at org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.afterSqlEntitiyProcessorTestCase(AbstractSqlEntityProcessorTestCase.java:76)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:474)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
        at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
        at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
        at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
        at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 11962 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta
[junit4:junit4]   2> 5 T157 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 6 T157 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/'
[junit4:junit4]   2> 35 T157 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 78 T157 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4:junit4]   2> 78 T157 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 82 T157 oass.IndexSchema.readSchema Schema name=dih_test
[junit4:junit4]   2> 126 T157 oass.IndexSchema.readSchema default search field in schema is desc
[junit4:junit4]   2> 127 T157 oass.IndexSchema.readSchema query parser default operator is OR
[junit4:junit4]   2> 128 T157 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 129 T157 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 129 T157 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr
[junit4:junit4]   2> 129 T157 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/'
[junit4:junit4]   2> 135 T157 oasc.CoreContainer.<init> New CoreContainer 14971289
[junit4:junit4]   2> 135 T157 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/
[junit4:junit4]   2> 135 T157 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 135 T157 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=solr,start=0,rows=10}, {q=rocks,start=0,rows=10}, {q=static newSearcher warming query from solrconfig.xml}]}
[junit4:junit4]   2> 136 T157 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   2> 136 T157 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 136 T157 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1de0ff6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ea0870)) assuming 'simple'
[junit4:junit4]   2> 137 T157 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214 forceNew:false
[junit4:junit4]   2> 137 T157 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 137 T157 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index/
[junit4:junit4]   2> 138 T157 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 138 T157 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index forceNew:false
[junit4:junit4]   2> 139 T157 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 140 T157 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 140 T157 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 141 T157 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
[junit4:junit4]   2> 142 T157 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 142 T157 oasc.RequestHandlers.initHandlersFromConfig created /dataimport: org.apache.solr.handler.dataimport.DataImportHandler
[junit4:junit4]   2> 143 T157 oasc.RequestHandlers.initHandlersFromConfig created /search: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 144 T157 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 146 T157 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 149 T157 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 149 T157 oass.SolrIndexSearcher.<init> Opening Searcher@372d8b main
[junit4:junit4]   2> 149 T157 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 150 T157 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 150 T157 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 150 T157 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 150 T157 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 151 T157 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 151 T157 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 151 T157 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 152 T157 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 152 T157 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 152 T157 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 152 T157 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 155 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@372d8b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 155 T157 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 156 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 157 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@372d8b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 158 T157 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 161 T157 oas.SolrTestCaseJ4.setUp ###Starting testChildEntities
[junit4:junit4]   2> ASYNC  NEW_CORE C17 name=collection1 org.apache.solr.core.SolrCore@1c234ef
[junit4:junit4]   2> 163 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 164 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 165 T157 C17 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 165 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 166 T157 C17 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 3
[junit4:junit4]   2> 173 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 174 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 174 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 175 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 175 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@163dfdf main
[junit4:junit4]   2> 176 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@163dfdf main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 176 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 176 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 177 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=1
[junit4:junit4]   2> 177 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0
[junit4:junit4]   2> 178 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 178 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@163dfdf main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 178 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 178 T157 C17 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4:junit4]   2> 206 T157 oashd.AbstractDIHJdbcTestCase.buildDatabase WARNING Skipping test because Database DERBY does not support the locale sr__#Latn
[junit4:junit4]   2> 219 T157 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 220 T157 oas.SolrTestCaseJ4.tearDown ###Ending testChildEntities
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSqlEntityProcessorDelta -Dtests.method=testChildEntities -Dtests.seed=8ECB6DABEBBE982F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr__#Latn -Dtests.timezone=Asia/Tokyo -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   0.12s | TestSqlEntityProcessorDelta.testChildEntities <<<
[junit4:junit4]    > Assumption #1: got: <java.sql.SQLException: Failed to create database 'memory:derbyDB', see the next exception for details.>, expected: nullThrowable #2: java.lang.NullPointerException
[junit4:junit4]    >    at java.io.File.<init>(File.java:251)
[junit4:junit4]    >    at org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.afterSqlEntitiyProcessorTestCase(AbstractSqlEntityProcessorTestCase.java:76)
[junit4:junit4]    >    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    >    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    >    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    >    at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    >    at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    >    at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    >    at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 279 T157 oas.SolrTestCaseJ4.setUp ###Starting testWithComplexTransformer
[junit4:junit4]   2> 283 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 283 T157 C17 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 286 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 287 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 287 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 288 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 288 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@caf2d4 main
[junit4:junit4]   2> 289 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 289 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@caf2d4 main{StandardDirectoryReader(segments_3:3)}
[junit4:junit4]   2> 289 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 290 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 290 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0
[junit4:junit4]   2> 290 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 290 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@caf2d4 main{StandardDirectoryReader(segments_3:3)}
[junit4:junit4]   2> 291 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 291 T157 C17 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4:junit4]   2> 296 T157 oashd.AbstractDIHJdbcTestCase.buildDatabase WARNING Skipping test because Database DERBY does not support the locale sr__#Latn
[junit4:junit4]   2> 298 T157 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 298 T157 oas.SolrTestCaseJ4.tearDown ###Ending testWithComplexTransformer
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSqlEntityProcessorDelta -Dtests.method=testWithComplexTransformer -Dtests.seed=8ECB6DABEBBE982F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr__#Latn -Dtests.timezone=Asia/Tokyo -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   0.03s | TestSqlEntityProcessorDelta.testWithComplexTransformer <<<
[junit4:junit4]    > Assumption #1: got: <java.sql.SQLException: Failed to create database 'memory:derbyDB', see the next exception for details.>, expected: nullThrowable #2: java.lang.NullPointerException
[junit4:junit4]    >    at java.io.File.<init>(File.java:251)
[junit4:junit4]    >    at org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.afterSqlEntitiyProcessorTestCase(AbstractSqlEntityProcessorTestCase.java:76)
[junit4:junit4]    >    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    >    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    >    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    >    at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    >    at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    >    at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    >    at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    >    at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 313 T157 oas.SolrTestCaseJ4.setUp ###Starting testWithSimpleTransformer
[junit4:junit4]   2> 318 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 319 T157 C17 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 324 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 325 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 326 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[segments_4]
[junit4:junit4]   2> 326 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 327 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@db45f2 main
[junit4:junit4]   2> 328 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 328 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@db45f2 main{StandardDirectoryReader(segments_4:4)}
[junit4:junit4]   2> 329 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 329 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 329 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0
[junit4:junit4]   2> 329 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 330 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@db45f2 main{StandardDirectoryReader(segments_4:4)}
[junit4:junit4]   2> 330 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 330 T157 C17 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4:junit4]   2> 522 T157 C17 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 522 T157 C17 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 522 T157 C17 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 523 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties WARNING Unable to read: the.properties
[junit4:junit4]   2> 524 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 524 T157 C17 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 525 T157 C17 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 556 T157 C17 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 557 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 560 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_5,generation=5,filenames=[_0_Pulsing41_0.doc, _0_Pulsing41_0.tim, _0_Direct_0.doc, _0.si, _0.fdt, _0_Pulsing41_0.tip, _0.fdx, _0_Direct_0.tim, _0_SimpleText_0.pst, _0.fnm, _0_Direct_0.tip, segments_5]
[junit4:junit4]   2> 560 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 5[_0_Pulsing41_0.doc, _0_Pulsing41_0.tim, _0_Direct_0.doc, _0.si, _0.fdt, _0_Pulsing41_0.tip, _0.fdx, _0_Direct_0.tim, _0_SimpleText_0.pst, _0.fnm, _0_Direct_0.tip, segments_5]
[junit4:junit4]   2> 561 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 568 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@1bfa778 main
[junit4:junit4]   2> 569 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 569 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1bfa778 main{StandardDirectoryReader(segments_5:7 _0(5.0):C20)}
[junit4:junit4]   2> 569 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 570 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 570 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0
[junit4:junit4]   2> 570 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 571 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1bfa778 main{StandardDirectoryReader(segments_5:7 _0(5.0):C20)}
[junit4:junit4]   2> 571 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 571 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties WARNING Unable to read: the.properties
[junit4:junit4]   2> 572 T157 C17 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4:junit4]   2> 572 T157 C17 oashd.DocBuilder.execute Time taken = 0:0:0.49
[junit4:junit4]   2> 573 T157 C17 UPDATE [collection1] webapp=null path=null params={dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"./test7617929663601582937tmp"+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;"+/>+%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.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&indent=true&synchronous=true&commit=true&clean=true&command=full-import} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 54
[junit4:junit4]   2> 578 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=AddAColumn_s:Added&rows=20&qt=standard} hits=20 status=0 QTime=2
[junit4:junit4]   2> 582 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4]   2> 583 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4]   2> 583 T157 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 589 T157 C17 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 590 T157 C17 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 590 T157 C17 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 590 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 591 T157 C17 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 592 T157 C17 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 592 T157 C17 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 593 T157 C17 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 598 T157 C17 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 7
[junit4:junit4]   2> 599 T157 C17 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 8
[junit4:junit4]   2> 599 T157 C17 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 600 T157 C17 oashd.DocBuilder.deleteAll Deleting stale documents
[junit4:junit4]   2> 600 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 8
[junit4:junit4]   2> 601 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 9
[junit4:junit4]   2> 601 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 14
[junit4:junit4]   2> 602 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 7
[junit4:junit4]   2> 602 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 18
[junit4:junit4]   2> 602 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 19
[junit4:junit4]   2> 603 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 13
[junit4:junit4]   2> 603 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 12
[junit4:junit4]   2> 610 T157 C17 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 611 T157 C17 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 611 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 618 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_5,generation=5,filenames=[_0_Pulsing41_0.doc, _0_Pulsing41_0.tim, _0_Direct_0.doc, _0.si, _0.fdt, _0_Pulsing41_0.tip, _0.fdx, _0_Direct_0.tim, _0_SimpleText_0.pst, _0.fnm, _0_Direct_0.tip, segments_5]
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_6,generation=6,filenames=[_0_Pulsing41_0.doc, _1_Pulsing41_0.tim, _1_SimpleText_0.pst, _0_1.del, _0_Pulsing41_0.tip, _0_Direct_0.tim, _1_Direct_0.doc, _0.fnm, _0_Direct_0.tip, _1.fnm, segments_6, _0_Pulsing41_0.tim, _1.si, _0_Direct_0.doc, _1.fdx, _1_Direct_0.tip, _0.si, _0.fdt, _1.fdt, _1_Pulsing41_0.doc, _0.fdx, _1_Direct_0.tim, _0_SimpleText_0.pst, _1_Pulsing41_0.tip]
[junit4:junit4]   2> 619 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 6[_0_Pulsing41_0.doc, _1_Pulsing41_0.tim, _1_SimpleText_0.pst, _0_1.del, _0_Pulsing41_0.tip, _0_Direct_0.tim, _1_Direct_0.doc, _0.fnm, _0_Direct_0.tip, _1.fnm, segments_6, _0_Pulsing41_0.tim, _1.si, _0_Direct_0.doc, _1.fdx, _1_Direct_0.tip, _0.si, _0.fdt, _1.fdt, _1_Pulsing41_0.doc, _0.fdx, _1_Direct_0.tim, _0_SimpleText_0.pst, _1_Pulsing41_0.tip]
[junit4:junit4]   2> 619 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 625 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@12dc291 main
[junit4:junit4]   2> 626 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 627 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@12dc291 main{StandardDirectoryReader(segments_6:10 _0(5.0):C20/14 _1(5.0):C7)}
[junit4:junit4]   2> 627 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 627 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 628 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1
[junit4:junit4]   2> 628 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 628 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12dc291 main{StandardDirectoryReader(segments_6:10 _0(5.0):C20/14 _1(5.0):C7)}
[junit4:junit4]   2> 628 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 628 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 629 T157 C17 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4:junit4]   2> 630 T157 C17 oashd.DocBuilder.execute Time taken = 0:0:0.39
[junit4:junit4]   2> 630 T157 C17 UPDATE [collection1] webapp=null path=null params={dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"./test7617929663601582937tmp"+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;"+/>+%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.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&indent=true&synchronous=true&commit=true&clean=false&command=delta-import} {delete=[8, 9, 14, 7, 18, 19, 13, 12],add=[15, 20, 11, 1008, 2, 17, 6],commit=} 0 43
[junit4:junit4]   2> 632 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=AddAColumn_s:Added&rows=20&qt=standard} hits=13 status=0 QTime=1
[junit4:junit4]   2> 634 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4]   2> 635 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4]   2> 637 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:1008&rows=20&qt=standard} hits=1 status=0 QTime=2
[junit4:junit4]   2> 640 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:19&rows=20&qt=standard} hits=0 status=0 QTime=1
[junit4:junit4]   2> 642 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:18&rows=20&qt=standard} hits=0 status=0 QTime=0
[junit4:junit4]   2> 643 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:7&rows=20&qt=standard} hits=0 status=0 QTime=0
[junit4:junit4]   2> 645 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:8&rows=20&qt=standard} hits=0 status=0 QTime=0
[junit4:junit4]   2> 647 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:9&rows=20&qt=standard} hits=0 status=0 QTime=1
[junit4:junit4]   2> 648 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:12&rows=20&qt=standard} hits=0 status=0 QTime=0
[junit4:junit4]   2> 650 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:13&rows=20&qt=standard} hits=0 status=0 QTime=1
[junit4:junit4]   2> 652 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:14&rows=20&qt=standard} hits=0 status=0 QTime=1
[junit4:junit4]   2> 666 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:17&rows=20&qt=standard} hits=1 status=0 QTime=4
[junit4:junit4]   2> 671 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:2&rows=20&qt=standard} hits=1 status=0 QTime=1
[junit4:junit4]   2> 675 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:20&rows=20&qt=standard} hits=1 status=0 QTime=1
[junit4:junit4]   2> 679 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:6&rows=20&qt=standard} hits=1 status=0 QTime=1
[junit4:junit4]   2> 683 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:11&rows=20&qt=standard} hits=1 status=0 QTime=1
[junit4:junit4]   2> 686 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:15&rows=20&qt=standard} hits=1 status=0 QTime=0
[junit4:junit4]   2> 693 T157 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 693 T157 oas.SolrTestCaseJ4.tearDown ###Ending testWithSimpleTransformer
[junit4:junit4]   2> 697 T157 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
[junit4:junit4]   2> 700 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 701 T157 C17 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 704 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 705 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_6,generation=6,filenames=[_0_Pulsing41_0.doc, _1_Pulsing41_0.tim, _1_SimpleText_0.pst, _0_1.del, _0_Pulsing41_0.tip, _0_Direct_0.tim, _1_Direct_0.doc, _0.fnm, _0_Direct_0.tip, _1.fnm, segments_6, _0_Pulsing41_0.tim, _1.si, _0_Direct_0.doc, _1.fdx, _1_Direct_0.tip, _0.si, _0.fdt, _1.fdt, _1_Pulsing41_0.doc, _0.fdx, _1_Direct_0.tim, _0_SimpleText_0.pst, _1_Pulsing41_0.tip]
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 705 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 7[segments_7]
[junit4:junit4]   2> 706 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 707 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@7df12d main
[junit4:junit4]   2> 707 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 708 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7df12d main{StandardDirectoryReader(segments_7:11)}
[junit4:junit4]   2> 708 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 708 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 709 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0
[junit4:junit4]   2> 709 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 709 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7df12d main{StandardDirectoryReader(segments_7:11)}
[junit4:junit4]   2> 710 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 710 T157 C17 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4:junit4]   2> 725 T157 C17 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 726 T157 C17 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 726 T157 C17 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 727 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties WARNING Unable to read: the.properties
[junit4:junit4]   2> 728 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 729 T157 C17 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 730 T157 C17 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4:junit4]   2> 737 T157 C17 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 737 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 741 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_8,generation=8,filenames=[_2.si, _2.fnm, segments_8, _2_Direct_0.doc, _2_Direct_0.tim, _2.fdt, _2_Pulsing41_0.tip, _2_Pulsing41_0.doc, _2_Pulsing41_0.tim, _2_SimpleText_0.pst, _2.fdx, _2_Direct_0.tip]
[junit4:junit4]   2> 742 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 8[_2.si, _2.fnm, segments_8, _2_Direct_0.doc, _2_Direct_0.tim, _2.fdt, _2_Pulsing41_0.tip, _2_Pulsing41_0.doc, _2_Pulsing41_0.tim, _2_SimpleText_0.pst, _2.fdx, _2_Direct_0.tip]
[junit4:junit4]   2> 742 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 744 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@126e223 main
[junit4:junit4]   2> 745 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 749 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@126e223 main{StandardDirectoryReader(segments_8:14 _2(5.0):C20)}
[junit4:junit4]   2> 749 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 750 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 750 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0
[junit4:junit4]   2> 750 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 751 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@126e223 main{StandardDirectoryReader(segments_8:14 _2(5.0):C20)}
[junit4:junit4]   2> 751 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 752 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties WARNING Unable to read: the.properties
[junit4:junit4]   2> 752 T157 C17 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4:junit4]   2> 753 T157 C17 oashd.DocBuilder.execute Time taken = 0:0:0.25
[junit4:junit4]   2> 753 T157 C17 UPDATE [collection1] webapp=null path=null params={dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"./test3364518985196256828tmp"+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;"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&indent=true&synchronous=true&commit=true&clean=true&command=full-import} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 31
[junit4:junit4]   2> 756 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=*:*&rows=20&qt=standard} hits=20 status=0 QTime=1
[junit4:junit4]   2> 761 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4]   2> 762 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4]   2> 762 T157 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 769 T157 C17 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 769 T157 C17 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 770 T157 C17 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 770 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 771 T157 C17 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 771 T157 C17 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 771 T157 C17 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 772 T157 C17 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 775 T157 C17 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 7
[junit4:junit4]   2> 775 T157 C17 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 5
[junit4:junit4]   2> 775 T157 C17 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 775 T157 C17 oashd.DocBuilder.deleteAll Deleting stale documents
[junit4:junit4]   2> 776 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 17
[junit4:junit4]   2> 776 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 19
[junit4:junit4]   2> 776 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 3
[junit4:junit4]   2> 777 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 9
[junit4:junit4]   2> 777 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 14
[junit4:junit4]   2> 782 T157 C17 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 782 T157 C17 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 783 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 787 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_8,generation=8,filenames=[_2.si, _2.fnm, segments_8, _2_Direct_0.doc, _2_Direct_0.tim, _2.fdt, _2_Pulsing41_0.tip, _2_Pulsing41_0.doc, _2_Pulsing41_0.tim, _2_SimpleText_0.pst, _2.fdx, _2_Direct_0.tip]
[junit4:junit4]   2>            commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_9,generation=9,filenames=[_3.fnm, _2_Direct_0.tim, _2_Direct_0.doc, _2_1.del, _2_Pulsing41_0.tip, _3.fdx, _2_SimpleText_0.pst, _2_Pulsing41_0.tim, _3_Direct_0.tip, _2_Direct_0.tip, _2.fnm, _2.si, _3_Pulsing41_0.tim, _3.fdt, _3_Pulsing41_0.tip, _2.fdt, _3.si, _2_Pulsing41_0.doc, _2.fdx, _3_Direct_0.doc, _3_Direct_0.tim, _3_SimpleText_0.pst, _3_Pulsing41_0.doc, segments_9]
[junit4:junit4]   2> 787 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 9[_3.fnm, _2_Direct_0.tim, _2_Direct_0.doc, _2_1.del, _2_Pulsing41_0.tip, _3.fdx, _2_SimpleText_0.pst, _2_Pulsing41_0.tim, _3_Direct_0.tip, _2_Direct_0.tip, _2.fnm, _2.si, _3_Pulsing41_0.tim, _3.fdt, _3_Pulsing41_0.tip, _2.fdt, _3.si, _2_Pulsing41_0.doc, _2.fdx, _3_Direct_0.doc, _3_Direct_0.tim, _3_SimpleText_0.pst, _3_Pulsing41_0.doc, segments_9]
[junit4:junit4]   2> 788 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 789 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@9bcb7e main
[junit4:junit4]   2> 790 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 793 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@9bcb7e main{StandardDirectoryReader(segments_9:17 _2(5.0):C20/10 _3(5.0):C7)}
[junit4:junit4]   2> 793 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 793 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4]   2> 794 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0
[junit4:junit4]   2> 794 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 794 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9bcb7e main{StandardDirectoryReader(segments_9:17 _2(5.0):C20/10 _3(5.0):C7)}
[junit4:junit4]   2> 794 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 795 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 795 T157 C17 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4:junit4]   2> 795 T157 C17 oashd.DocBuilder.execute Time taken = 0:0:0.25
[junit4:junit4]   2> 796 T157 C17 UPDATE [collection1] webapp=null path=null params={dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"./test3364518985196256828tmp"+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;"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&indent=true&synchronous=true&commit=true&clean=false&command=delta-import} {delete=[17, 19, 3, 9, 14],add=[8, 15, 1001, 1, 1017, 2, 4],commit=} 0 28
[junit4:junit4]   2> 797 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=*:*&rows=20&qt=standard} hits=17 status=0 QTime=0
[junit4:junit4]   2> 800 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4]   2> 801 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4]   2> 802 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:1001&rows=20&qt=standard} hits=1 status=0 QTime=1
[junit4:junit4]   2> 803 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:1017&rows=20&qt=standard} hits=1 status=0 QTime=0
[junit4:junit4]   2> 805 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:3&rows=20&qt=standard} hits=0 status=0 QTime=1
[junit4:junit4]   2> 806 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:19&rows=20&qt=standard} hits=0 status=0 QTime=0
[junit4:junit4]   2> 808 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:17&rows=20&qt=standard} hits=0 status=0 QTime=1
[junit4:junit4]   2> 809 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:9&rows=20&qt=standard} hits=0 status=0 QTime=0
[junit4:junit4]   2> 810 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:14&rows=20&qt=standard} hits=0 status=0 QTime=0
[junit4:junit4]   2> 812 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:15&rows=20&qt=standard} hits=1 status=0 QTime=1
[junit4:junit4]   2> 814 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:8&rows=20&qt=standard} hits=1 status=0 QTime=0
[junit4:junit4]   2> 816 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:4&rows=20&qt=standard} hits=1 status=0 QTime=0
[junit4:junit4]   2> 819 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:1&rows=20&qt=standard} hits=1 status=0 QTime=0
[junit4:junit4]   2> 821 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:2&rows=20&qt=standard} hits=1 status=0 QTime=0
[junit4:junit4]   2> 824 T157 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 824 T157 oas.SolrTestCaseJ4.tearDown ###Ending testSingleEntity
[junit4:junit4]   2> 830 T157 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 830 T157 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=14971289
[junit4:junit4]   2> 830 T157 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1c234ef
[junit4:junit4]   2> 830 T157 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=8,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=54,cumulative_deletesById=13,cumulative_deletesByQuery=6,cumulative_errors=0}
[junit4:junit4]   2> 830 T157 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 831 T157 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 831 T157 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 831 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 832 T157 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 832 T157 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {id=PostingsFormat(name=SimpleText), COUNTRY_CODES_mult_s=PostingsFormat(name=Direct), NAME_mult_s=Pulsing41(freqCutoff=12 minBlockSize=60 maxBlockSize=195), timestamp=PostingsFormat(name=Direct), AddAColumn_s=PostingsFormat(name=Direct)}, sim=DefaultSimilarity, locale=sr__#Latn, timezone=Asia/Tokyo
[junit4:junit4]   2> NOTE: Linux 3.2.0-34-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=22311328,total=72351744
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestJdbcDataSource, TestLineEntityProcessor, TestEntityProcessorBase, TestSimplePropertiesWriter, TestContextImpl, TestPlainTextEntityProcessor, TestDocBuilder2, TestFileListEntityProcessor, TestClobTransformer, TestContentStreamDataSource, TestDateFormatTransformer, TestVariableResolver, TestNestedChildren, TestBuiltInEvaluators, TestFileListWithLineEntityProcessor, TestScriptTransformer, TestSolrEntityProcessorUnit, TestSolrEntityProcessorEndToEnd, TestSqlEntityProcessorDelta]
[junit4:junit4] Completed in 0.85s, 4 tests, 2 errors <<< FAILURES!

[...truncated 55 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:335: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:192: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:410: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:438: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1167: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:831: There were test failures: 34 suites, 140 tests, 2 errors, 3 ignored

Total time: 28 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b65 -server -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure


[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b65) - Build # 3107 - Still Failing!

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3107/
Java: 32bit/jdk1.8.0-ea-b65 -server -XX:+UseG1GC

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

Error Message:


Stack Trace:
java.lang.NullPointerException
	at java.io.File.<init>(File.java:251)
	at org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.afterSqlEntitiyProcessorTestCase(AbstractSqlEntityProcessorTestCase.java:76)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:474)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


REGRESSION:  org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testWithComplexTransformer

Error Message:


Stack Trace:
java.lang.NullPointerException
	at java.io.File.<init>(File.java:251)
	at org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.afterSqlEntitiyProcessorTestCase(AbstractSqlEntityProcessorTestCase.java:76)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:474)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 11962 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta
[junit4:junit4]   2> 5 T157 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 6 T157 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/'
[junit4:junit4]   2> 35 T157 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 78 T157 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4:junit4]   2> 78 T157 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 82 T157 oass.IndexSchema.readSchema Schema name=dih_test
[junit4:junit4]   2> 126 T157 oass.IndexSchema.readSchema default search field in schema is desc
[junit4:junit4]   2> 127 T157 oass.IndexSchema.readSchema query parser default operator is OR
[junit4:junit4]   2> 128 T157 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 129 T157 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 129 T157 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr
[junit4:junit4]   2> 129 T157 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/'
[junit4:junit4]   2> 135 T157 oasc.CoreContainer.<init> New CoreContainer 14971289
[junit4:junit4]   2> 135 T157 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/
[junit4:junit4]   2> 135 T157 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 135 T157 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=solr,start=0,rows=10}, {q=rocks,start=0,rows=10}, {q=static newSearcher warming query from solrconfig.xml}]}
[junit4:junit4]   2> 136 T157 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   2> 136 T157 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 136 T157 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1de0ff6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ea0870)) assuming 'simple'
[junit4:junit4]   2> 137 T157 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214 forceNew:false
[junit4:junit4]   2> 137 T157 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 137 T157 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index/
[junit4:junit4]   2> 138 T157 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 138 T157 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index forceNew:false
[junit4:junit4]   2> 139 T157 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 140 T157 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 140 T157 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 141 T157 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
[junit4:junit4]   2> 142 T157 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 142 T157 oasc.RequestHandlers.initHandlersFromConfig created /dataimport: org.apache.solr.handler.dataimport.DataImportHandler
[junit4:junit4]   2> 143 T157 oasc.RequestHandlers.initHandlersFromConfig created /search: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 144 T157 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 146 T157 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 149 T157 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 149 T157 oass.SolrIndexSearcher.<init> Opening Searcher@372d8b main
[junit4:junit4]   2> 149 T157 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 150 T157 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 150 T157 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 150 T157 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 150 T157 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 151 T157 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 151 T157 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 151 T157 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 152 T157 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 152 T157 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 152 T157 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 152 T157 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 155 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@372d8b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 155 T157 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 156 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 157 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@372d8b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 158 T157 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 161 T157 oas.SolrTestCaseJ4.setUp ###Starting testChildEntities
[junit4:junit4]   2> ASYNC  NEW_CORE C17 name=collection1 org.apache.solr.core.SolrCore@1c234ef
[junit4:junit4]   2> 163 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 164 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 165 T157 C17 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 165 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 166 T157 C17 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 3
[junit4:junit4]   2> 173 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 174 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 174 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 175 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 175 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@163dfdf main
[junit4:junit4]   2> 176 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@163dfdf main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 176 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 176 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 177 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 177 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 178 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 178 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@163dfdf main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 178 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 178 T157 C17 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4:junit4]   2> 206 T157 oashd.AbstractDIHJdbcTestCase.buildDatabase WARNING Skipping test because Database DERBY does not support the locale sr__#Latn
[junit4:junit4]   2> 219 T157 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 220 T157 oas.SolrTestCaseJ4.tearDown ###Ending testChildEntities
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSqlEntityProcessorDelta -Dtests.method=testChildEntities -Dtests.seed=8ECB6DABEBBE982F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr__#Latn -Dtests.timezone=Asia/Tokyo -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   0.12s | TestSqlEntityProcessorDelta.testChildEntities <<<
[junit4:junit4]    > Assumption #1: got: <java.sql.SQLException: Failed to create database 'memory:derbyDB', see the next exception for details.>, expected: nullThrowable #2: java.lang.NullPointerException
[junit4:junit4]    > 	at java.io.File.<init>(File.java:251)
[junit4:junit4]    > 	at org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.afterSqlEntitiyProcessorTestCase(AbstractSqlEntityProcessorTestCase.java:76)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 279 T157 oas.SolrTestCaseJ4.setUp ###Starting testWithComplexTransformer
[junit4:junit4]   2> 283 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 283 T157 C17 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 286 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 287 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 287 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 288 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 288 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@caf2d4 main
[junit4:junit4]   2> 289 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 289 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@caf2d4 main{StandardDirectoryReader(segments_3:3)}
[junit4:junit4]   2> 289 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 290 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 290 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 290 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 290 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@caf2d4 main{StandardDirectoryReader(segments_3:3)}
[junit4:junit4]   2> 291 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 291 T157 C17 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4:junit4]   2> 296 T157 oashd.AbstractDIHJdbcTestCase.buildDatabase WARNING Skipping test because Database DERBY does not support the locale sr__#Latn
[junit4:junit4]   2> 298 T157 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 298 T157 oas.SolrTestCaseJ4.tearDown ###Ending testWithComplexTransformer
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSqlEntityProcessorDelta -Dtests.method=testWithComplexTransformer -Dtests.seed=8ECB6DABEBBE982F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr__#Latn -Dtests.timezone=Asia/Tokyo -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   0.03s | TestSqlEntityProcessorDelta.testWithComplexTransformer <<<
[junit4:junit4]    > Assumption #1: got: <java.sql.SQLException: Failed to create database 'memory:derbyDB', see the next exception for details.>, expected: nullThrowable #2: java.lang.NullPointerException
[junit4:junit4]    > 	at java.io.File.<init>(File.java:251)
[junit4:junit4]    > 	at org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.afterSqlEntitiyProcessorTestCase(AbstractSqlEntityProcessorTestCase.java:76)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 313 T157 oas.SolrTestCaseJ4.setUp ###Starting testWithSimpleTransformer
[junit4:junit4]   2> 318 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 319 T157 C17 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 324 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 325 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 326 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[segments_4]
[junit4:junit4]   2> 326 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 327 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@db45f2 main
[junit4:junit4]   2> 328 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 328 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@db45f2 main{StandardDirectoryReader(segments_4:4)}
[junit4:junit4]   2> 329 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 329 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 329 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 329 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 330 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@db45f2 main{StandardDirectoryReader(segments_4:4)}
[junit4:junit4]   2> 330 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 330 T157 C17 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4:junit4]   2> 522 T157 C17 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 522 T157 C17 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 522 T157 C17 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 523 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties WARNING Unable to read: the.properties
[junit4:junit4]   2> 524 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 524 T157 C17 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 525 T157 C17 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 556 T157 C17 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 557 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 560 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_5,generation=5,filenames=[_0_Pulsing41_0.doc, _0_Pulsing41_0.tim, _0_Direct_0.doc, _0.si, _0.fdt, _0_Pulsing41_0.tip, _0.fdx, _0_Direct_0.tim, _0_SimpleText_0.pst, _0.fnm, _0_Direct_0.tip, segments_5]
[junit4:junit4]   2> 560 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 5[_0_Pulsing41_0.doc, _0_Pulsing41_0.tim, _0_Direct_0.doc, _0.si, _0.fdt, _0_Pulsing41_0.tip, _0.fdx, _0_Direct_0.tim, _0_SimpleText_0.pst, _0.fnm, _0_Direct_0.tip, segments_5]
[junit4:junit4]   2> 561 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 568 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@1bfa778 main
[junit4:junit4]   2> 569 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 569 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1bfa778 main{StandardDirectoryReader(segments_5:7 _0(5.0):C20)}
[junit4:junit4]   2> 569 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 570 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 570 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 570 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 571 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1bfa778 main{StandardDirectoryReader(segments_5:7 _0(5.0):C20)}
[junit4:junit4]   2> 571 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 571 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties WARNING Unable to read: the.properties
[junit4:junit4]   2> 572 T157 C17 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4:junit4]   2> 572 T157 C17 oashd.DocBuilder.execute Time taken = 0:0:0.49
[junit4:junit4]   2> 573 T157 C17 UPDATE [collection1] webapp=null path=null params={dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"./test7617929663601582937tmp"+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;"+/>+%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.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&indent=true&synchronous=true&commit=true&clean=true&command=full-import} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 54
[junit4:junit4]   2> 578 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=AddAColumn_s:Added&rows=20&qt=standard} hits=20 status=0 QTime=2 
[junit4:junit4]   2> 582 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 583 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 583 T157 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 589 T157 C17 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 590 T157 C17 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 590 T157 C17 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 590 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 591 T157 C17 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 592 T157 C17 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 592 T157 C17 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 593 T157 C17 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 598 T157 C17 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 7
[junit4:junit4]   2> 599 T157 C17 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 8
[junit4:junit4]   2> 599 T157 C17 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 600 T157 C17 oashd.DocBuilder.deleteAll Deleting stale documents 
[junit4:junit4]   2> 600 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 8
[junit4:junit4]   2> 601 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 9
[junit4:junit4]   2> 601 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 14
[junit4:junit4]   2> 602 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 7
[junit4:junit4]   2> 602 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 18
[junit4:junit4]   2> 602 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 19
[junit4:junit4]   2> 603 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 13
[junit4:junit4]   2> 603 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 12
[junit4:junit4]   2> 610 T157 C17 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 611 T157 C17 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 611 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 618 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_5,generation=5,filenames=[_0_Pulsing41_0.doc, _0_Pulsing41_0.tim, _0_Direct_0.doc, _0.si, _0.fdt, _0_Pulsing41_0.tip, _0.fdx, _0_Direct_0.tim, _0_SimpleText_0.pst, _0.fnm, _0_Direct_0.tip, segments_5]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_6,generation=6,filenames=[_0_Pulsing41_0.doc, _1_Pulsing41_0.tim, _1_SimpleText_0.pst, _0_1.del, _0_Pulsing41_0.tip, _0_Direct_0.tim, _1_Direct_0.doc, _0.fnm, _0_Direct_0.tip, _1.fnm, segments_6, _0_Pulsing41_0.tim, _1.si, _0_Direct_0.doc, _1.fdx, _1_Direct_0.tip, _0.si, _0.fdt, _1.fdt, _1_Pulsing41_0.doc, _0.fdx, _1_Direct_0.tim, _0_SimpleText_0.pst, _1_Pulsing41_0.tip]
[junit4:junit4]   2> 619 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 6[_0_Pulsing41_0.doc, _1_Pulsing41_0.tim, _1_SimpleText_0.pst, _0_1.del, _0_Pulsing41_0.tip, _0_Direct_0.tim, _1_Direct_0.doc, _0.fnm, _0_Direct_0.tip, _1.fnm, segments_6, _0_Pulsing41_0.tim, _1.si, _0_Direct_0.doc, _1.fdx, _1_Direct_0.tip, _0.si, _0.fdt, _1.fdt, _1_Pulsing41_0.doc, _0.fdx, _1_Direct_0.tim, _0_SimpleText_0.pst, _1_Pulsing41_0.tip]
[junit4:junit4]   2> 619 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 625 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@12dc291 main
[junit4:junit4]   2> 626 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 627 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@12dc291 main{StandardDirectoryReader(segments_6:10 _0(5.0):C20/14 _1(5.0):C7)}
[junit4:junit4]   2> 627 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 627 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 628 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 628 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 628 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12dc291 main{StandardDirectoryReader(segments_6:10 _0(5.0):C20/14 _1(5.0):C7)}
[junit4:junit4]   2> 628 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 628 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 629 T157 C17 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4:junit4]   2> 630 T157 C17 oashd.DocBuilder.execute Time taken = 0:0:0.39
[junit4:junit4]   2> 630 T157 C17 UPDATE [collection1] webapp=null path=null params={dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"./test7617929663601582937tmp"+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;"+/>+%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.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&indent=true&synchronous=true&commit=true&clean=false&command=delta-import} {delete=[8, 9, 14, 7, 18, 19, 13, 12],add=[15, 20, 11, 1008, 2, 17, 6],commit=} 0 43
[junit4:junit4]   2> 632 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=AddAColumn_s:Added&rows=20&qt=standard} hits=13 status=0 QTime=1 
[junit4:junit4]   2> 634 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 635 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 637 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:1008&rows=20&qt=standard} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 640 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:19&rows=20&qt=standard} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 642 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:18&rows=20&qt=standard} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 643 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:7&rows=20&qt=standard} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 645 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:8&rows=20&qt=standard} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 647 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:9&rows=20&qt=standard} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 648 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:12&rows=20&qt=standard} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 650 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:13&rows=20&qt=standard} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 652 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:14&rows=20&qt=standard} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 666 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:17&rows=20&qt=standard} hits=1 status=0 QTime=4 
[junit4:junit4]   2> 671 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:2&rows=20&qt=standard} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 675 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:20&rows=20&qt=standard} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 679 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:6&rows=20&qt=standard} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 683 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:11&rows=20&qt=standard} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 686 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:15&rows=20&qt=standard} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 693 T157 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 693 T157 oas.SolrTestCaseJ4.tearDown ###Ending testWithSimpleTransformer
[junit4:junit4]   2> 697 T157 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
[junit4:junit4]   2> 700 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 701 T157 C17 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 704 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 705 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_6,generation=6,filenames=[_0_Pulsing41_0.doc, _1_Pulsing41_0.tim, _1_SimpleText_0.pst, _0_1.del, _0_Pulsing41_0.tip, _0_Direct_0.tim, _1_Direct_0.doc, _0.fnm, _0_Direct_0.tip, _1.fnm, segments_6, _0_Pulsing41_0.tim, _1.si, _0_Direct_0.doc, _1.fdx, _1_Direct_0.tip, _0.si, _0.fdt, _1.fdt, _1_Pulsing41_0.doc, _0.fdx, _1_Direct_0.tim, _0_SimpleText_0.pst, _1_Pulsing41_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 705 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 7[segments_7]
[junit4:junit4]   2> 706 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 707 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@7df12d main
[junit4:junit4]   2> 707 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 708 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7df12d main{StandardDirectoryReader(segments_7:11)}
[junit4:junit4]   2> 708 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 708 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 709 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 709 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 709 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7df12d main{StandardDirectoryReader(segments_7:11)}
[junit4:junit4]   2> 710 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 710 T157 C17 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4:junit4]   2> 725 T157 C17 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 726 T157 C17 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 726 T157 C17 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 727 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties WARNING Unable to read: the.properties
[junit4:junit4]   2> 728 T157 C17 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 729 T157 C17 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 730 T157 C17 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4:junit4]   2> 737 T157 C17 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 737 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 741 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_8,generation=8,filenames=[_2.si, _2.fnm, segments_8, _2_Direct_0.doc, _2_Direct_0.tim, _2.fdt, _2_Pulsing41_0.tip, _2_Pulsing41_0.doc, _2_Pulsing41_0.tim, _2_SimpleText_0.pst, _2.fdx, _2_Direct_0.tip]
[junit4:junit4]   2> 742 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 8[_2.si, _2.fnm, segments_8, _2_Direct_0.doc, _2_Direct_0.tim, _2.fdt, _2_Pulsing41_0.tip, _2_Pulsing41_0.doc, _2_Pulsing41_0.tim, _2_SimpleText_0.pst, _2.fdx, _2_Direct_0.tip]
[junit4:junit4]   2> 742 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 744 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@126e223 main
[junit4:junit4]   2> 745 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 749 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@126e223 main{StandardDirectoryReader(segments_8:14 _2(5.0):C20)}
[junit4:junit4]   2> 749 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 750 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 750 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 750 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 751 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@126e223 main{StandardDirectoryReader(segments_8:14 _2(5.0):C20)}
[junit4:junit4]   2> 751 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 752 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties WARNING Unable to read: the.properties
[junit4:junit4]   2> 752 T157 C17 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4:junit4]   2> 753 T157 C17 oashd.DocBuilder.execute Time taken = 0:0:0.25
[junit4:junit4]   2> 753 T157 C17 UPDATE [collection1] webapp=null path=null params={dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"./test3364518985196256828tmp"+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;"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&indent=true&synchronous=true&commit=true&clean=true&command=full-import} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 31
[junit4:junit4]   2> 756 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=*:*&rows=20&qt=standard} hits=20 status=0 QTime=1 
[junit4:junit4]   2> 761 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 762 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 762 T157 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 769 T157 C17 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 769 T157 C17 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 770 T157 C17 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 770 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 771 T157 C17 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 771 T157 C17 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 771 T157 C17 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 772 T157 C17 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 775 T157 C17 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 7
[junit4:junit4]   2> 775 T157 C17 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 5
[junit4:junit4]   2> 775 T157 C17 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 775 T157 C17 oashd.DocBuilder.deleteAll Deleting stale documents 
[junit4:junit4]   2> 776 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 17
[junit4:junit4]   2> 776 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 19
[junit4:junit4]   2> 776 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 3
[junit4:junit4]   2> 777 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 9
[junit4:junit4]   2> 777 T157 C17 oashd.SolrWriter.deleteDoc Deleting document: 14
[junit4:junit4]   2> 782 T157 C17 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 782 T157 C17 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 783 T157 C17 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 787 T157 C17 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_8,generation=8,filenames=[_2.si, _2.fnm, segments_8, _2_Direct_0.doc, _2_Direct_0.tim, _2.fdt, _2_Pulsing41_0.tip, _2_Pulsing41_0.doc, _2_Pulsing41_0.tim, _2_SimpleText_0.pst, _2.fdx, _2_Direct_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@bdbd31 lockFactory=org.apache.lucene.store.NativeFSLockFactory@54ce99)),segFN=segments_9,generation=9,filenames=[_3.fnm, _2_Direct_0.tim, _2_Direct_0.doc, _2_1.del, _2_Pulsing41_0.tip, _3.fdx, _2_SimpleText_0.pst, _2_Pulsing41_0.tim, _3_Direct_0.tip, _2_Direct_0.tip, _2.fnm, _2.si, _3_Pulsing41_0.tim, _3.fdt, _3_Pulsing41_0.tip, _2.fdt, _3.si, _2_Pulsing41_0.doc, _2.fdx, _3_Direct_0.doc, _3_Direct_0.tim, _3_SimpleText_0.pst, _3_Pulsing41_0.doc, segments_9]
[junit4:junit4]   2> 787 T157 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 9[_3.fnm, _2_Direct_0.tim, _2_Direct_0.doc, _2_1.del, _2_Pulsing41_0.tip, _3.fdx, _2_SimpleText_0.pst, _2_Pulsing41_0.tim, _3_Direct_0.tip, _2_Direct_0.tip, _2.fnm, _2.si, _3_Pulsing41_0.tim, _3.fdt, _3_Pulsing41_0.tip, _2.fdt, _3.si, _2_Pulsing41_0.doc, _2.fdx, _3_Direct_0.doc, _3_Direct_0.tim, _3_SimpleText_0.pst, _3_Pulsing41_0.doc, segments_9]
[junit4:junit4]   2> 788 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214
[junit4:junit4]   2> 789 T157 C17 oass.SolrIndexSearcher.<init> Opening Searcher@9bcb7e main
[junit4:junit4]   2> 790 T157 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 793 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@9bcb7e main{StandardDirectoryReader(segments_9:17 _2(5.0):C20/10 _3(5.0):C7)}
[junit4:junit4]   2> 793 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 793 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&start=0&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 794 T159 C17 REQ [collection1] webapp=null path=null params={event=newSearcher&q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 794 T159 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 794 T159 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9bcb7e main{StandardDirectoryReader(segments_9:17 _2(5.0):C20/10 _3(5.0):C7)}
[junit4:junit4]   2> 794 T159 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 795 T157 C17 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4:junit4]   2> 795 T157 C17 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4:junit4]   2> 795 T157 C17 oashd.DocBuilder.execute Time taken = 0:0:0.25
[junit4:junit4]   2> 796 T157 C17 UPDATE [collection1] webapp=null path=null params={dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"./test3364518985196256828tmp"+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;"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&indent=true&synchronous=true&commit=true&clean=false&command=delta-import} {delete=[17, 19, 3, 9, 14],add=[8, 15, 1001, 1, 1017, 2, 4],commit=} 0 28
[junit4:junit4]   2> 797 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=*:*&rows=20&qt=standard} hits=17 status=0 QTime=0 
[junit4:junit4]   2> 800 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 801 T157 C17 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 802 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:1001&rows=20&qt=standard} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 803 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:1017&rows=20&qt=standard} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 805 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:3&rows=20&qt=standard} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 806 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:19&rows=20&qt=standard} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 808 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:17&rows=20&qt=standard} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 809 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:9&rows=20&qt=standard} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 810 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:14&rows=20&qt=standard} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 812 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:15&rows=20&qt=standard} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 814 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:8&rows=20&qt=standard} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 816 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:4&rows=20&qt=standard} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 819 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:1&rows=20&qt=standard} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 821 T157 C17 REQ [collection1] webapp=null path=null params={start=0&version=2.2&q=id:2&rows=20&qt=standard} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 824 T157 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/solr/collection1/conf/dataimport.properties
[junit4:junit4]   2> 824 T157 oas.SolrTestCaseJ4.tearDown ###Ending testSingleEntity
[junit4:junit4]   2> 830 T157 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 830 T157 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=14971289
[junit4:junit4]   2> 830 T157 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1c234ef
[junit4:junit4]   2> 830 T157 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=8,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=54,cumulative_deletesById=13,cumulative_deletesByQuery=6,cumulative_errors=0}
[junit4:junit4]   2> 830 T157 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 831 T157 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 831 T157 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 831 T157 C17 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> 832 T157 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 832 T157 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354916289214/index
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {id=PostingsFormat(name=SimpleText), COUNTRY_CODES_mult_s=PostingsFormat(name=Direct), NAME_mult_s=Pulsing41(freqCutoff=12 minBlockSize=60 maxBlockSize=195), timestamp=PostingsFormat(name=Direct), AddAColumn_s=PostingsFormat(name=Direct)}, sim=DefaultSimilarity, locale=sr__#Latn, timezone=Asia/Tokyo
[junit4:junit4]   2> NOTE: Linux 3.2.0-34-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=22311328,total=72351744
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestJdbcDataSource, TestLineEntityProcessor, TestEntityProcessorBase, TestSimplePropertiesWriter, TestContextImpl, TestPlainTextEntityProcessor, TestDocBuilder2, TestFileListEntityProcessor, TestClobTransformer, TestContentStreamDataSource, TestDateFormatTransformer, TestVariableResolver, TestNestedChildren, TestBuiltInEvaluators, TestFileListWithLineEntityProcessor, TestScriptTransformer, TestSolrEntityProcessorUnit, TestSolrEntityProcessorEndToEnd, TestSqlEntityProcessorDelta]
[junit4:junit4] Completed in 0.85s, 4 tests, 2 errors <<< FAILURES!

[...truncated 55 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:335: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:192: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:410: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:438: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1167: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:831: There were test failures: 34 suites, 140 tests, 2 errors, 3 ignored

Total time: 28 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b65 -server -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure



[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b65) - Build # 3106 - Still Failing!

Posted by Policeman Jenkins Server <je...@sd-datasolutions.de>.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3106/
Java: 32bit/jdk1.8.0-ea-b65 -server -XX:+UseParallelGC

All tests passed

Build Log:
[...truncated 13632 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:88: The following files contain @author tags, tabs or nocommits:
* solr/solrj/src/java/org/apache/solr/common/cloud/CompositeIdRouter.java

Total time: 33 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b65 -server -XX:+UseParallelGC
Email was triggered for: Failure
Sending email for trigger: Failure