lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b58) - Build # 2987 - Still Failing!
Date Fri, 30 Nov 2012 23:18:54 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/2987/
Java: 32bit/jdk1.8.0-ea-b58 -server -XX:+UseSerialGC

1 tests failed.
REGRESSION:  org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testWithComplexTransformer

Error Message:
Expecting 8 database calls, but DIH reported 15

Stack Trace:
junit.framework.AssertionFailedError: Expecting 8 database calls, but DIH reported 15
	at __randomizedtesting.SeedInfo.seed([FDC319251BF17845:97C968AE700776F8]:0)
	at junit.framework.Assert.fail(Assert.java:50)
	at junit.framework.Assert.assertTrue(Assert.java:20)
	at org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.complexTransform(AbstractSqlEntityProcessorTestCase.java:111)
	at org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testWithComplexTransformer(TestSqlEntityProcessorDelta.java:58)
	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$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	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 11946 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta
[junit4:junit4]   2> Sat Dec 01 02:18:45 EAT 2012 Thread[SUITE-TestSqlEntityProcessorDelta-seed#[FDC319251BF17845]-worker,5,TGRP-TestSqlEntityProcessorDelta] java.lang.ClassNotFoundException: DerbyUtil
[junit4:junit4]   2> 15 T106 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-1354317525988
[junit4:junit4]   2> 16 T106 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> 59 T106 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 96 T106 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4:junit4]   2> 96 T106 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 99 T106 oass.IndexSchema.readSchema Schema name=dih_test
[junit4:junit4]   2> 138 T106 oass.IndexSchema.readSchema default search field in schema is desc
[junit4:junit4]   2> 139 T106 oass.IndexSchema.readSchema query parser default operator is OR
[junit4:junit4]   2> 140 T106 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 141 T106 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 142 T106 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> 142 T106 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> 154 T106 oasc.CoreContainer.<init> New CoreContainer 7499964
[junit4:junit4]   2> 155 T106 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-1354317525988/
[junit4:junit4]   2> 155 T106 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 155 T106 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> 155 T106 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   2> 156 T106 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 157 T106 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1478050 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1393a2f; maxCacheMB=0.6103515625 maxMergeSizeMB=0.5302734375)) assuming 'simple'
[junit4:junit4]   2> 157 T106 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988 forceNew:false
[junit4:junit4]   2> 157 T106 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 158 T106 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-1354317525988/index/
[junit4:junit4]   2> 158 T106 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-1354317525988/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 158 T106 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index forceNew:false
[junit4:junit4]   2> 160 T106 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 160 T106 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 160 T106 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 161 T106 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
[junit4:junit4]   2> 162 T106 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 162 T106 oasc.RequestHandlers.initHandlersFromConfig created /dataimport: org.apache.solr.handler.dataimport.DataImportHandler
[junit4:junit4]   2> 163 T106 oasc.RequestHandlers.initHandlersFromConfig created /search: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 164 T106 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 166 T106 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 168 T106 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 169 T106 oass.SolrIndexSearcher.<init> Opening Searcher@859a9a main
[junit4:junit4]   2> 169 T106 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 169 T106 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 170 T106 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 170 T106 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 170 T106 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 171 T106 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 171 T106 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 171 T106 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 172 T106 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 172 T106 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 172 T106 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 172 T106 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 176 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@859a9a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 176 T106 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 176 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 181 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@859a9a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 183 T106 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 188 T106 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
[junit4:junit4]   2> ASYNC  NEW_CORE C13 name=collection1 org.apache.solr.core.SolrCore@893099
[junit4:junit4]   2> 192 T106 C13 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 192 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 194 T106 C13 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 195 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 195 T106 C13 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 4
[junit4:junit4]   2> 205 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 206 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 206 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 207 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 208 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@13a8074 main
[junit4:junit4]   2> 208 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 208 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@13a8074 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 209 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 210 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 210 T108 C13 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> 212 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 212 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13a8074 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 213 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 213 T106 C13 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4:junit4]   2> 233 T106 C13 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 233 T106 C13 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 234 T106 C13 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 234 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 235 T106 C13 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 235 T106 C13 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 236 T106 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 245 T106 C13 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 245 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 251 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_3,generation=3,filenames=[_0.fdx, _0_Lucene40_0.frq, _0.si, _0.fnm, _0_Lucene40_0.tip, segments_3, _0_Lucene40_0.tim, _0.fdt]
[junit4:junit4]   2> 251 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0.fdx, _0_Lucene40_0.frq, _0.si, _0.fnm, _0_Lucene40_0.tip, segments_3, _0_Lucene40_0.tim, _0.fdt]
[junit4:junit4]   2> 251 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 256 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@fd4b55 main
[junit4:junit4]   2> 257 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 257 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@fd4b55 main{StandardDirectoryReader(segments_3:5 _0(5.0):C20)}
[junit4:junit4]   2> 258 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 258 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 259 T108 C13 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> 259 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 259 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@fd4b55 main{StandardDirectoryReader(segments_3:5 _0(5.0):C20)}
[junit4:junit4]   2> 259 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 260 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 261 T106 C13 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 261 T106 C13 oashd.DocBuilder.execute Time taken = 0:0:0.26
[junit4:junit4]   2> 262 T106 C13 UPDATE [collection1] webapp=null path=null params={synchronous=true&indent=true&command=full-import&commit=true&dataConfig=<dataConfig>+%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"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.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&clean=true} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 30
[junit4:junit4]   2> 266 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=*:*} hits=20 status=0 QTime=1 
[junit4:junit4]   2> 270 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 270 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 276 T106 C13 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 277 T106 C13 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 277 T106 C13 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 278 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 278 T106 C13 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 279 T106 C13 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 279 T106 C13 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 279 T106 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 290 T106 C13 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 5
[junit4:junit4]   2> 291 T106 C13 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 3
[junit4:junit4]   2> 291 T106 C13 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 292 T106 C13 oashd.DocBuilder.deleteAll Deleting stale documents 
[junit4:junit4]   2> 293 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 13
[junit4:junit4]   2> 293 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 5
[junit4:junit4]   2> 294 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 19
[junit4:junit4]   2> 300 T106 C13 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 300 T106 C13 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 300 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 323 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_3,generation=3,filenames=[_0.fdx, _0_Lucene40_0.frq, _0.si, _0.fnm, _0_Lucene40_0.tip, segments_3, _0_Lucene40_0.tim, _0.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_4,generation=4,filenames=[_1.fdx, _0_Lucene40_0.frq, _1_Lucene40_0.tim, segments_4, _1_Lucene40_0.tip, _0.si, _1_Lucene40_0.frq, _0_1.del, _0_Lucene40_0.tip, _1.fnm, _1.si, _1.fdt, _0.fdt, _0.fdx, _0.fnm, _0_Lucene40_0.tim]
[junit4:junit4]   2> 324 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[_1.fdx, _0_Lucene40_0.frq, _1_Lucene40_0.tim, segments_4, _1_Lucene40_0.tip, _0.si, _1_Lucene40_0.frq, _0_1.del, _0_Lucene40_0.tip, _1.fnm, _1.si, _1.fdt, _0.fdt, _0.fdx, _0.fnm, _0_Lucene40_0.tim]
[junit4:junit4]   2> 324 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 327 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@4ecd5c main
[junit4:junit4]   2> 328 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 328 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4ecd5c main{StandardDirectoryReader(segments_4:8 _0(5.0):C20/8 _1(5.0):C5)}
[junit4:junit4]   2> 329 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 329 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 330 T108 C13 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> 330 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 330 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ecd5c main{StandardDirectoryReader(segments_4:8 _0(5.0):C20/8 _1(5.0):C5)}
[junit4:junit4]   2> 331 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 331 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 332 T106 C13 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 332 T106 C13 oashd.DocBuilder.execute Time taken = 0:0:0.54
[junit4:junit4]   2> 332 T106 C13 UPDATE [collection1] webapp=null path=null params={synchronous=true&indent=true&command=delta-import&commit=true&dataConfig=<dataConfig>+%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&clean=false} {delete=[13, 5, 19],add=[11, 7, 20, 18, 17],commit=} 0 57
[junit4:junit4]   2> 334 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=*:*} hits=17 status=0 QTime=1 
[junit4:junit4]   2> 337 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 337 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 338 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:5} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 340 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:19} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 353 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:13} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 356 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:11} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 359 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:20} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 361 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:7} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 363 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:17} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 367 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:18} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 370 T106 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> 371 T106 oas.SolrTestCaseJ4.tearDown ###Ending testSingleEntity
[junit4:junit4]   2> 376 T106 oas.SolrTestCaseJ4.setUp ###Starting testWithSimpleTransformer
[junit4:junit4]   2> 380 T106 C13 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 381 T106 C13 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 386 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 387 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_4,generation=4,filenames=[_1.fdx, _0_Lucene40_0.frq, _1_Lucene40_0.tim, segments_4, _1_Lucene40_0.tip, _0.si, _1_Lucene40_0.frq, _0_1.del, _0_Lucene40_0.tip, _1.fnm, _1.si, _1.fdt, _0.fdt, _0.fdx, _0.fnm, _0_Lucene40_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 387 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 5[segments_5]
[junit4:junit4]   2> 389 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 390 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@55bc92 main
[junit4:junit4]   2> 391 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 391 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@55bc92 main{StandardDirectoryReader(segments_5:9)}
[junit4:junit4]   2> 391 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 392 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 392 T108 C13 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> 392 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 392 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@55bc92 main{StandardDirectoryReader(segments_5:9)}
[junit4:junit4]   2> 393 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 394 T106 C13 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4:junit4]   2> ----------------------------------------------------------------
[junit4:junit4]   2> Sat Dec 01 02:18:46 EAT 2012:
[junit4:junit4]   2> Booting Derby version The Apache Software Foundation - Apache Derby - 10.9.1.0 - (1344872): instance a816c00e-013b-539d-73e0-0000025ecaf8 
[junit4:junit4]   2> on database directory memory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/derbyDB  with class loader sun.misc.Launcher$AppClassLoader@c78e02 
[junit4:junit4]   2> Loaded from file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar
[junit4:junit4]   2> java.vendor=Oracle Corporation
[junit4:junit4]   2> java.runtime.version=1.8.0-ea-b58
[junit4:junit4]   2> user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0
[junit4:junit4]   2> derby.system.home=null
[junit4:junit4]   2> derby.stream.error.field=DerbyUtil.DEV_NULL
[junit4:junit4]   2> Database Class Loader started - derby.database.classpath=''
[junit4:junit4]   2> 1770 T106 C13 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1770 T106 C13 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1770 T106 C13 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 1771 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1772 T106 C13 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1772 T106 C13 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:derby:memory:derbyDB;
[junit4:junit4]   2> 1773 T106 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 1866 T106 C13 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 1867 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1870 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_6,generation=6,filenames=[_2.fdx, _2_Lucene40_0.tip, segments_6, _2.si, _2.fdt, _2.fnm, _2_Lucene40_0.frq, _2_Lucene40_0.tim]
[junit4:junit4]   2> 1870 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 6[_2.fdx, _2_Lucene40_0.tip, segments_6, _2.si, _2.fdt, _2.fnm, _2_Lucene40_0.frq, _2_Lucene40_0.tim]
[junit4:junit4]   2> 1871 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 1872 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@130a9c main
[junit4:junit4]   2> 1875 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@130a9c main{StandardDirectoryReader(segments_6:12 _2(5.0):C20)}
[junit4:junit4]   2> 1875 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1875 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1875 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1876 T108 C13 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> 1876 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1876 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@130a9c main{StandardDirectoryReader(segments_6:12 _2(5.0):C20)}
[junit4:junit4]   2> 1880 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 1881 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1881 T106 C13 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 1882 T106 C13 oashd.DocBuilder.execute Time taken = 0:0:0.111
[junit4:junit4]   2> 1882 T106 C13 UPDATE [collection1] webapp=null path=null params={synchronous=true&indent=true&command=full-import&commit=true&dataConfig=<dataConfig>+%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"derby"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.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&clean=true} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 115
[junit4:junit4]   2> 1923 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=AddAColumn_s:Added} hits=20 status=0 QTime=2 
[junit4:junit4]   2> 1926 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1927 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1954 T106 C13 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 1955 T106 C13 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 1955 T106 C13 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 1955 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 1956 T106 C13 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 1957 T106 C13 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 1958 T106 C13 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:derby:memory:derbyDB;
[junit4:junit4]   2> 1959 T106 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4:junit4]   2> 2032 T106 C13 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 2
[junit4:junit4]   2> 2033 T106 C13 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 0
[junit4:junit4]   2> 2033 T106 C13 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 2049 T106 C13 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 2050 T106 C13 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 2050 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2052 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_6,generation=6,filenames=[_2.fdx, _2_Lucene40_0.tip, segments_6, _2.si, _2.fdt, _2.fnm, _2_Lucene40_0.frq, _2_Lucene40_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_7,generation=7,filenames=[_3.fdt, _3_Lucene40_0.tip, _2_Lucene40_0.tip, _2_Lucene40_0.frq, _2.fdx, _3_Lucene40_0.tim, _2.si, _3.fdx, _2.fdt, _2.fnm, _3.fnm, segments_7, _3.si, _3_Lucene40_0.frq, _2_Lucene40_0.tim]
[junit4:junit4]   2> 2053 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 7[_3.fdt, _3_Lucene40_0.tip, _2_Lucene40_0.tip, _2_Lucene40_0.frq, _2.fdx, _3_Lucene40_0.tim, _2.si, _3.fdx, _2.fdt, _2.fnm, _3.fnm, segments_7, _3.si, _3_Lucene40_0.frq, _2_Lucene40_0.tim]
[junit4:junit4]   2> 2053 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 2054 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@9be45 main
[junit4:junit4]   2> 2055 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2057 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@9be45 main{StandardDirectoryReader(segments_7:14 _2(5.0):C20 _3(5.0):C2)}
[junit4:junit4]   2> 2057 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2058 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 2058 T108 C13 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> 2058 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2058 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9be45 main{StandardDirectoryReader(segments_7:14 _2(5.0):C20 _3(5.0):C2)}
[junit4:junit4]   2> 2059 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 2059 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 2060 T106 C13 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 2060 T106 C13 oashd.DocBuilder.execute Time taken = 0:0:0.104
[junit4:junit4]   2> 2061 T106 C13 UPDATE [collection1] webapp=null path=null params={synchronous=true&indent=true&command=delta-import&commit=true&dataConfig=<dataConfig>+%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"derby"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.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&clean=false} {add=[1017, 1008],commit=} 0 109
[junit4:junit4]   2> 2065 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=AddAColumn_s:Added} hits=22 status=0 QTime=2 
[junit4:junit4]   2> 2069 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 2070 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 2071 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:1017} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2074 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:1008} hits=1 status=0 QTime=0 
[junit4:junit4]   2> ----------------------------------------------------------------
[junit4:junit4]   2> Sat Dec 01 02:18:48 EAT 2012:
[junit4:junit4]   2> Shutting down instance a816c00e-013b-539d-73e0-0000025ecaf8 on database directory memory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/derbyDB with class loader sun.misc.Launcher$AppClassLoader@c78e02 
[junit4:junit4]   2> 2614 T106 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> 2615 T106 oas.SolrTestCaseJ4.tearDown ###Ending testWithSimpleTransformer
[junit4:junit4]   2> 2636 T106 oas.SolrTestCaseJ4.setUp ###Starting testChildEntities
[junit4:junit4]   2> 2639 T106 C13 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 2640 T106 C13 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 2644 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2644 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_7,generation=7,filenames=[_3.fdt, _3_Lucene40_0.tip, _2_Lucene40_0.tip, _2_Lucene40_0.frq, _2.fdx, _3_Lucene40_0.tim, _2.si, _3.fdx, _2.fdt, _2.fnm, _3.fnm, segments_7, _3.si, _3_Lucene40_0.frq, _2_Lucene40_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4]   2> 2645 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 8[segments_8]
[junit4:junit4]   2> 2646 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 2646 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@da3e4c main
[junit4:junit4]   2> 2647 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2647 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@da3e4c main{StandardDirectoryReader(segments_8:15)}
[junit4:junit4]   2> 2648 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 2648 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2648 T108 C13 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> 2648 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2649 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@da3e4c main{StandardDirectoryReader(segments_8:15)}
[junit4:junit4]   2> 2652 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 2652 T106 C13 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4:junit4]   2> 2686 T106 C13 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 2686 T106 C13 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 2687 T106 C13 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 2687 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 2689 T106 C13 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 2690 T106 C13 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 2691 T106 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 2693 T106 C13 oashd.JdbcDataSource$1.call Creating a connection for entity Sports with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 2694 T106 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 2727 T106 C13 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 2727 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2737 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_9,generation=9,filenames=[_4.fnm, segments_9, _4.fdx, _4_Lucene40_0.tim, _4.si, _4_Lucene40_0.frq, _4_Lucene40_0.tip, _4.fdt]
[junit4:junit4]   2> 2738 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 9[_4.fnm, segments_9, _4.fdx, _4_Lucene40_0.tim, _4.si, _4_Lucene40_0.frq, _4_Lucene40_0.tip, _4.fdt]
[junit4:junit4]   2> 2738 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 2740 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@1eb584f main
[junit4:junit4]   2> 2741 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2742 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1eb584f main{StandardDirectoryReader(segments_9:18 _4(5.0):C20)}
[junit4:junit4]   2> 2743 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2743 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2744 T108 C13 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> 2744 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2744 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1eb584f main{StandardDirectoryReader(segments_9:18 _4(5.0):C20)}
[junit4:junit4]   2> 2745 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 2745 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 2746 T106 C13 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 2747 T106 C13 oashd.DocBuilder.execute Time taken = 0:0:0.59
[junit4:junit4]   2> 2747 T106 C13 UPDATE [collection1] webapp=null path=null params={synchronous=true&indent=true&command=full-import&commit=true&dataConfig=<dataConfig>+%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+name%3D"Sports"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+newColumnName%3D"sportsAdded_s"+newColumnValue%3D"sport_added"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+ID,+SPORT_NAME+FROM+PEOPLE_SPORTS+WHERE+DELETED+!%3D+'Y'+AND+PERSON_ID%3D${People.ID}+ORDER+BY+ID"+>+%0a<field+column%3D"SPORT_NAME"+name%3D"SPORT_NAME_mult_s"+/>+%0a<field+column%3D"id"+name%3D"SPORT_ID_mult_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&clean=true} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 65
[junit4:junit4]   2> 2758 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=*:*} hits=20 status=0 QTime=9 
[junit4:junit4]   2> 2763 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=SPORT_NAME_mult_s:Sailing} hits=2 status=0 QTime=1 
[junit4:junit4]   2> 2767 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=NAME_mult_s:Michael} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2775 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=sportsAdded_s:sport_added} hits=20 status=0 QTime=1 
[junit4:junit4]   2> 2778 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 2779 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 2789 T106 C13 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 2789 T106 C13 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 2790 T106 C13 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 2790 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 2791 T106 C13 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 2792 T106 C13 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: Sports
[junit4:junit4]   2> 2792 T106 C13 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: Sports rows obtained : 0
[junit4:junit4]   2> 2793 T106 C13 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: Sports rows obtained : 0
[junit4:junit4]   2> 2793 T106 C13 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: Sports
[junit4:junit4]   2> 2794 T106 C13 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 2794 T106 C13 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 2795 T106 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 2800 T106 C13 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 6
[junit4:junit4]   2> 2801 T106 C13 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 2
[junit4:junit4]   2> 2801 T106 C13 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 2802 T106 C13 oashd.DocBuilder.deleteAll Deleting stale documents 
[junit4:junit4]   2> 2803 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 4
[junit4:junit4]   2> 2803 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 9
[junit4:junit4]   2> 2804 T106 C13 oashd.JdbcDataSource$1.call Creating a connection for entity Sports with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 2806 T106 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 2825 T106 C13 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 2826 T106 C13 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 2826 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2839 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_9,generation=9,filenames=[_4.fnm, segments_9, _4.fdx, _4_Lucene40_0.tim, _4.si, _4_Lucene40_0.frq, _4_Lucene40_0.tip, _4.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_a,generation=10,filenames=[segments_a, _5_Lucene40_0.frq, _5.fnm, _4_Lucene40_0.tip, _5.si, _5_Lucene40_0.tip, _4.fdt, _5.fdx, _4.fnm, _4.fdx, _4_Lucene40_0.tim, _4_Lucene40_0.frq, _4.si, _4_1.del, _5.fdt, _5_Lucene40_0.tim]
[junit4:junit4]   2> 2840 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 10[segments_a, _5_Lucene40_0.frq, _5.fnm, _4_Lucene40_0.tip, _5.si, _5_Lucene40_0.tip, _4.fdt, _5.fdx, _4.fnm, _4.fdx, _4_Lucene40_0.tim, _4_Lucene40_0.frq, _4.si, _4_1.del, _5.fdt, _5_Lucene40_0.tim]
[junit4:junit4]   2> 2840 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 2842 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@1192459 main
[junit4:junit4]   2> 2843 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2846 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1192459 main{StandardDirectoryReader(segments_a:21 _4(5.0):C20/6 _5(5.0):C6)}
[junit4:junit4]   2> 2847 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2848 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 2848 T108 C13 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> 2848 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2849 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1192459 main{StandardDirectoryReader(segments_a:21 _4(5.0):C20/6 _5(5.0):C6)}
[junit4:junit4]   2> 2849 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 2850 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 2851 T106 C13 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 2855 T106 C13 oashd.DocBuilder.execute Time taken = 0:0:0.64
[junit4:junit4]   2> 2856 T106 C13 UPDATE [collection1] webapp=null path=null params={synchronous=true&indent=true&command=delta-import&commit=true&dataConfig=<dataConfig>+%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+name%3D"Sports"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+newColumnName%3D"sportsAdded_s"+newColumnValue%3D"sport_added"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+ID,+SPORT_NAME+FROM+PEOPLE_SPORTS+WHERE+DELETED+!%3D+'Y'+AND+PERSON_ID%3D${People.ID}+ORDER+BY+ID"+>+%0a<field+column%3D"SPORT_NAME"+name%3D"SPORT_NAME_mult_s"+/>+%0a<field+column%3D"id"+name%3D"SPORT_ID_mult_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&clean=false} {delete=[4, 9],add=[16, 1, 7, 1010, 1009, 14],commit=} 0 69
[junit4:junit4]   2> 2863 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=*:*} hits=20 status=0 QTime=0 
[junit4:junit4]   2> 2873 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=NAME_mult_s:Michael} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2880 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:1010} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2884 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:1009} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2887 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:4} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 2907 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:9} hits=0 status=0 QTime=18 
[junit4:junit4]   2> 2910 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:14} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2919 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:7} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2923 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:16} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2928 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:1} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2934 T106 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> 2934 T106 oas.SolrTestCaseJ4.tearDown ###Ending testChildEntities
[junit4:junit4]   2> 2940 T106 oas.SolrTestCaseJ4.setUp ###Starting testWithComplexTransformer
[junit4:junit4]   2> 2943 T106 C13 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 2944 T106 C13 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 2947 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2948 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_a,generation=10,filenames=[segments_a, _5_Lucene40_0.frq, _5.fnm, _4_Lucene40_0.tip, _5.si, _5_Lucene40_0.tip, _4.fdt, _5.fdx, _4.fnm, _4.fdx, _4_Lucene40_0.tim, _4_Lucene40_0.frq, _4.si, _4_1.del, _5.fdt, _5_Lucene40_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_b,generation=11,filenames=[segments_b]
[junit4:junit4]   2> 2949 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 11[segments_b]
[junit4:junit4]   2> 2950 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 2950 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@1855502 main
[junit4:junit4]   2> 2952 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1855502 main{StandardDirectoryReader(segments_b:22)}
[junit4:junit4]   2> 2953 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 2953 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2953 T108 C13 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> 2954 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2954 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1855502 main{StandardDirectoryReader(segments_b:22)}
[junit4:junit4]   2> 2954 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 2955 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2955 T106 C13 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4:junit4]   2> 2980 T106 C13 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 2980 T106 C13 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 2981 T106 C13 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4]   2> 2982 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 2984 T106 C13 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 2985 T106 C13 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 2986 T106 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 3062 T106 C13 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 3062 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 3067 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_b,generation=11,filenames=[segments_b]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_c,generation=12,filenames=[_6.fnm, _6_Lucene40_0.frq, _6.si, _6.fdt, _6_Lucene40_0.tim, _6_Lucene40_0.tip, _6.fdx, segments_c]
[junit4:junit4]   2> 3068 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 12[_6.fnm, _6_Lucene40_0.frq, _6.si, _6.fdt, _6_Lucene40_0.tim, _6_Lucene40_0.tip, _6.fdx, segments_c]
[junit4:junit4]   2> 3068 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 3070 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@188e99 main
[junit4:junit4]   2> 3070 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3074 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@188e99 main{StandardDirectoryReader(segments_c:25 _6(5.0):C60)}
[junit4:junit4]   2> 3074 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3075 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3075 T108 C13 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> 3075 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3076 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@188e99 main{StandardDirectoryReader(segments_c:25 _6(5.0):C60)}
[junit4:junit4]   2> 3076 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 3076 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 3077 T106 C13 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 3077 T106 C13 oashd.DocBuilder.execute Time taken = 0:0:0.95
[junit4:junit4]   2> 3078 T106 C13 UPDATE [collection1] webapp=null path=null params={synchronous=true&indent=true&command=full-import&commit=true&dataConfig=<dataConfig>+%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"TripleThreatTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.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&clean=true} {deleteByQuery=*:*,add=[1, TripleThreat-1-1, TripleThreat-2-1, 2, TripleThreat-1-2, TripleThreat-2-2, 3, TripleThreat-1-3, TripleThreat-2-3, 4, ... (60 adds)],commit=} 0 100
[junit4:junit4]   2> 3147 T106 C13 REQ [collection1] webapp=null path=null params={sort=id+asc&rows=60&q=*:*} hits=60 status=0 QTime=67 
[junit4:junit4]   2> 3162 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:TripleThreat-1-*} hits=20 status=0 QTime=1 
[junit4:junit4]   2> 3166 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:TripleThreat-2-*} hits=20 status=0 QTime=1 
[junit4:junit4]   2> 3173 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=%2Bid:TripleThreat-1-3+%2BNAME_mult_s:Michael+%2BNAME_mult_s:leahciM++%2BCOUNTRY_CODES_mult_s:NR+%2BCOUNTRY_CODES_mult_s:RN} hits=1 status=0 QTime=4 
[junit4:junit4]   2> 3176 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=AddAColumn_s:Added} hits=20 status=0 QTime=0 
[junit4:junit4]   2> 3179 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 3180 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=1 
[junit4:junit4]   2> 3204 T106 C13 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4]   2> 3204 T106 C13 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4]   2> 3205 T106 C13 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4]   2> 3205 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 3206 T106 C13 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4]   2> 3206 T106 C13 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4]   2> 3206 T106 C13 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4]   2> 3207 T106 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4]   2> 3209 T106 C13 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 13
[junit4:junit4]   2> 3210 T106 C13 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 8
[junit4:junit4]   2> 3210 T106 C13 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4]   2> 3210 T106 C13 oashd.DocBuilder.deleteAll Deleting stale documents 
[junit4:junit4]   2> 3211 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 15
[junit4:junit4]   2> 3211 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 10
[junit4:junit4]   2> 3211 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 11
[junit4:junit4]   2> 3212 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 4
[junit4:junit4]   2> 3212 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 6
[junit4:junit4]   2> 3212 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 1
[junit4:junit4]   2> 3213 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 16
[junit4:junit4]   2> 3213 T106 C13 oashd.SolrWriter.deleteDoc Deleting document: 3
[junit4:junit4]   2> 3227 T106 C13 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4]   2> 3228 T106 C13 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4]   2> 3228 T106 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 3233 T106 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_c,generation=12,filenames=[_6.fnm, _6_Lucene40_0.frq, _6.si, _6.fdt, _6_Lucene40_0.tim, _6_Lucene40_0.tip, _6.fdx, segments_c]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@da5dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@17c8bb9),segFN=segments_d,generation=13,filenames=[_7_Lucene40_0.tim, _7.si, _6_Lucene40_0.frq, _7.fdt, _7_Lucene40_0.frq, _6_Lucene40_0.tim, _6.fnm, _6_1.del, _7_Lucene40_0.tip, _7.fnm, _6.fdt, _6.si, _7.fdx, segments_d, _6_Lucene40_0.tip, _6.fdx]
[junit4:junit4]   2> 3234 T106 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 13[_7_Lucene40_0.tim, _7.si, _6_Lucene40_0.frq, _7.fdt, _7_Lucene40_0.frq, _6_Lucene40_0.tim, _6.fnm, _6_1.del, _7_Lucene40_0.tip, _7.fnm, _6.fdt, _6.si, _7.fdx, segments_d, _6_Lucene40_0.tip, _6.fdx]
[junit4:junit4]   2> 3234 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988
[junit4:junit4]   2> 3236 T106 C13 oass.SolrIndexSearcher.<init> Opening Searcher@1ede6c3 main
[junit4:junit4]   2> 3236 T106 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3244 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ede6c3 main{StandardDirectoryReader(segments_d:28 _6(5.0):C60/44 _7(5.0):C39)}
[junit4:junit4]   2> 3244 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=solr&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3245 T108 C13 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&rows=10&q=rocks&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3245 T108 C13 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> 3246 T108 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3246 T108 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ede6c3 main{StandardDirectoryReader(segments_d:28 _6(5.0):C60/44 _7(5.0):C39)}
[junit4:junit4]   2> 3246 T108 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 3247 T106 C13 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4]   2> 3247 T106 C13 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4]   2> 3248 T106 C13 oashd.DocBuilder.execute Time taken = 0:0:0.42
[junit4:junit4]   2> 3248 T106 C13 UPDATE [collection1] webapp=null path=null params={synchronous=true&indent=true&command=delta-import&commit=true&dataConfig=<dataConfig>+%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"TripleThreatTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.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&clean=false} {delete=[15, 10, 11, 4, 6, 1, 16, 3],add=[12, TripleThreat-1-12, TripleThreat-2-12, 13, TripleThreat-1-13, TripleThreat-2-13, 14, TripleThreat-1-14, TripleThreat-2-14, 1015, ... (39 adds)],commit=} 0 54
[junit4:junit4]   2> 3251 T106 C13 REQ [collection1] webapp=null path=null params={sort=id+asc&rows=39&q=*:*} hits=55 status=0 QTime=0 
[junit4:junit4]   2> 3256 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:TripleThreat-1-*} hits=21 status=0 QTime=0 
[junit4:junit4]   2> 3260 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=id:TripleThreat-2-*} hits=21 status=0 QTime=0 
[junit4:junit4]   2> 3263 T106 C13 REQ [collection1] webapp=null path=null params={start=0&rows=20&qt=standard&version=2.2&q=AddAColumn_s:Added} hits=21 status=0 QTime=0 
[junit4:junit4]   2> 3266 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 3267 T106 C13 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0 
[junit4:junit4]   2> 3268 T106 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> 3268 T106 oas.SolrTestCaseJ4.tearDown ###Ending testWithComplexTransformer
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSqlEntityProcessorDelta -Dtests.method=testWithComplexTransformer -Dtests.seed=FDC319251BF17845 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt_LT -Dtests.timezone=Africa/Khartoum -Dtests.file.encoding=UTF-8
[junit4:junit4] FAILURE 0.38s J0 | TestSqlEntityProcessorDelta.testWithComplexTransformer <<<
[junit4:junit4]    > Throwable #1: junit.framework.AssertionFailedError: Expecting 8 database calls, but DIH reported 15
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([FDC319251BF17845:97C968AE700776F8]:0)
[junit4:junit4]    > 	at junit.framework.Assert.fail(Assert.java:50)
[junit4:junit4]    > 	at junit.framework.Assert.assertTrue(Assert.java:20)
[junit4:junit4]    > 	at org.apache.solr.handler.dataimport.AbstractSqlEntityProcessorTestCase.complexTransform(AbstractSqlEntityProcessorTestCase.java:111)
[junit4:junit4]    > 	at org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testWithComplexTransformer(TestSqlEntityProcessorDelta.java:58)
[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$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[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> ----------------------------------------------------------------
[junit4:junit4]   2> Sat Dec 01 02:18:49 EAT 2012: Shutting down Derby engine
[junit4:junit4]   2> ----------------------------------------------------------------
[junit4:junit4]   2> 3321 T106 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 3322 T106 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=7499964
[junit4:junit4]   2> 3322 T106 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@893099
[junit4:junit4]   2> 3322 T106 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=12,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=172,cumulative_deletesById=13,cumulative_deletesByQuery=8,cumulative_errors=0}
[junit4:junit4]   2> 3322 T106 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 3323 T106 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 3323 T106 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 3323 T106 C13 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> 3324 T106 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 3324 T106 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/contrib/solr-dataimporthandler/test/J0/./solrtest-TestSqlEntityProcessorDelta-1354317525988/index
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=lt_LT, timezone=Africa/Khartoum
[junit4:junit4]   2> NOTE: Linux 3.2.0-34-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=33778528,total=64946176
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestFieldReader, TestContextImpl, TestVariableResolverEndToEnd, TestFileListWithLineEntityProcessor, TestScriptTransformer, TestSolrEntityProcessorUnit, TestSolrEntityProcessorEndToEnd, TestSqlEntityProcessorDelta]
[junit4:junit4] Completed on J0 in 3.40s, 4 tests, 1 failure <<< FAILURES!

[...truncated 43 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:1163: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:827: There were test failures: 34 suites, 140 tests, 1 failure, 3 ignored

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



Mime
View raw message