Return-Path: X-Original-To: apmail-lucene-dev-archive@www.apache.org Delivered-To: apmail-lucene-dev-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id F1844DF3F for ; Thu, 8 Nov 2012 15:34:03 +0000 (UTC) Received: (qmail 19039 invoked by uid 500); 8 Nov 2012 15:34:02 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 18877 invoked by uid 500); 8 Nov 2012 15:34:02 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 18858 invoked by uid 99); 8 Nov 2012 15:34:01 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 08 Nov 2012 15:34:01 +0000 X-ASF-Spam-Status: No, hits=-0.0 required=5.0 tests=SPF_PASS X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: local policy) Received: from [188.138.97.18] (HELO mail.sd-datasolutions.de) (188.138.97.18) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 08 Nov 2012 15:33:50 +0000 Received: from serv1 (localhost.localdomain [127.0.0.1]) by mail.sd-datasolutions.de (Postfix) with ESMTP id 2587814AA06A; Thu, 8 Nov 2012 15:33:30 +0000 (UTC) Date: Thu, 8 Nov 2012 15:33:03 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org, mikemccand@apache.org, yonik@apache.org, rmuir@apache.org Message-ID: <283828838.100.1352388810144.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.6.0_37) - Build # 2270 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_99_1513126391.1352388783397" X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_99_1513126391.1352388783397 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/2270/ Java: 64bit/jdk1.6.0_37 -XX:+UseConcMarkSweepGC 1 tests failed. REGRESSION: org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta= .testSingleEntity Error Message: Stack Trace: junit.framework.AssertionFailedError =09at __randomizedtesting.SeedInfo.seed([7ABC0EB25D0EA317:1403722E32E34C94]= :0) =09at junit.framework.Assert.fail(Assert.java:48) =09at junit.framework.Assert.assertTrue(Assert.java:20) =09at junit.framework.Assert.assertTrue(Assert.java:27) =09at org.apache.solr.handler.dataimport.AbstractDIHJdbcTestCase.singleEnti= ty(AbstractDIHJdbcTestCase.java:103) =09at org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testSi= ngleEntity(TestSqlEntityProcessorDelta.java:44) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:39) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:25) =09at java.lang.reflect.Method.invoke(Method.java:597) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:782) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:442) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:746) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:648) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:682) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:693) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:43) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at java.lang.Thread.run(Thread.java:662) Build Log: [...truncated 10800 lines...] [junit4:junit4] Suite: org.apache.solr.handler.dataimport.TestSqlEntityProc= essorDelta [junit4:junit4] 2> 2 T57 oas.SolrTestCaseJ4.initCore ####initCore [junit4:junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-So= lr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-T= estSqlEntityProcessorDelta-1352388776022 [junit4:junit4] 2> 2 T57 oasc.SolrResourceLoader. new SolrResourceL= oader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr= /build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/' [junit4:junit4] 2> 44 T57 oasc.SolrConfig. Using Lucene MatchVersio= n: LUCENE_41 [junit4:junit4] 2> 114 T57 oasc.SolrConfig. Loaded SolrConfig: data= import-solrconfig.xml [junit4:junit4] 2> 115 T57 oass.IndexSchema.readSchema Reading Solr Schem= a [junit4:junit4] 2> 129 T57 oass.IndexSchema.readSchema Schema name=3Ddih_= test [junit4:junit4] 2> 212 T57 oass.IndexSchema.readSchema default search fie= ld in schema is desc [junit4:junit4] 2> 213 T57 oass.IndexSchema.readSchema query parser defau= lt operator is OR [junit4:junit4] 2> 214 T57 oass.IndexSchema.readSchema unique key field: = id [junit4:junit4] 2> 216 T57 oasc.SolrResourceLoader.locateSolrHome JNDI no= t configured for solr (NoInitialContextEx) [junit4:junit4] 2> 216 T57 oasc.SolrResourceLoader.locateSolrHome using s= ystem property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-L= inux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr [junit4:junit4] 2> 216 T57 oasc.SolrResourceLoader. new SolrResourc= eLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/so= lr/build/contrib/solr-dataimporthandler/test-files/dih/solr/' [junit4:junit4] 2> 224 T57 oasc.CoreContainer. New CoreContainer 11= 02757339 [junit4:junit4] 2> 224 T57 oasc.SolrCore. [collection1] Opening new= SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/co= ntrib/solr-dataimporthandler/test-files/dih/solr/collection1/, dataDir=3D/m= nt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-data= importhandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352388776022/ [junit4:junit4] 2> 225 T57 oasc.SolrCore. JMX monitoring not detect= ed for core: collection1 [junit4:junit4] 2> 225 T57 oasc.SolrCore.initListeners [collection1] Adde= d SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListen= er{queries=3D[{q=3Dsolr,start=3D0,rows=3D10}, {q=3Drocks,start=3D0,rows=3D1= 0}, {q=3Dstatic newSearcher warming query from solrconfig.xml}]} [junit4:junit4] 2> 225 T57 oasc.SolrCore.initListeners [collection1] Adde= d SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderList= ener{queries=3D[]} [junit4:junit4] 2> 226 T57 oasc.SolrCore.getNewIndexDir New index directo= ry detected: old=3Dnull new=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Li= nux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEnt= ityProcessorDelta-1352388776022/index/ [junit4:junit4] 2> 226 T57 oasc.SolrCore.initIndex WARNING [collection1] = Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr= /build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProce= ssorDelta-1352388776022/index' doesn't exist. Creating new index... [junit4:junit4] 2> 227 T57 oasc.CachingDirectoryFactory.get return new di= rectory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/con= trib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-= 1352388776022/index forceNew:false [junit4:junit4] 2> 229 T57 oasc.SolrDeletionPolicy.onCommit SolrDeletionP= olicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_= 1] [junit4:junit4] 2> 229 T57 oasc.SolrDeletionPolicy.updateCommits newest c= ommit =3D 1 [junit4:junit4] 2> 230 T57 oasup.UpdateRequestProcessorChain.init inserti= ng DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (d= efault) [junit4:junit4] 2> 231 T57 oasc.RequestHandlers.initHandlersFromConfig cr= eated standard: solr.StandardRequestHandler [junit4:junit4] 2> 231 T57 oasc.RequestHandlers.initHandlersFromConfig cr= eated /dataimport: org.apache.solr.handler.dataimport.DataImportHandler [junit4:junit4] 2> 232 T57 oasc.RequestHandlers.initHandlersFromConfig cr= eated /search: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 232 T57 oasc.RequestHandlers.initHandlersFromConfig cr= eated /update: solr.UpdateRequestHandler [junit4:junit4] 2> 234 T57 oashl.XMLLoader.init xsltCacheLifetimeSeconds= =3D60 [junit4:junit4] 2> 237 T57 oass.SolrIndexSearcher. Opening Searcher= @6da21389 main [junit4:junit4] 2> 238 T57 oass.SolrIndexSearcher.getIndexDir WARNING WAR= NING: Directory impl does not support setting indexDir: org.apache.lucene.s= tore.MockDirectoryWrapper [junit4:junit4] 2> 238 T57 oasu.CommitTracker. Hard AutoCommit: dis= abled [junit4:junit4] 2> 239 T57 oasu.CommitTracker. Soft AutoCommit: dis= abled [junit4:junit4] 2> 239 T57 oashc.HttpShardHandlerFactory.getParameter Set= ting socketTimeout to: 0 [junit4:junit4] 2> 240 T57 oashc.HttpShardHandlerFactory.getParameter Set= ting urlScheme to: http:// [junit4:junit4] 2> 240 T57 oashc.HttpShardHandlerFactory.getParameter Set= ting connTimeout to: 0 [junit4:junit4] 2> 240 T57 oashc.HttpShardHandlerFactory.getParameter Set= ting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 241 T57 oashc.HttpShardHandlerFactory.getParameter Set= ting corePoolSize to: 0 [junit4:junit4] 2> 241 T57 oashc.HttpShardHandlerFactory.getParameter Set= ting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 241 T57 oashc.HttpShardHandlerFactory.getParameter Set= ting maxThreadIdleTime to: 5 [junit4:junit4] 2> 242 T57 oashc.HttpShardHandlerFactory.getParameter Set= ting sizeOfQueue to: -1 [junit4:junit4] 2> 242 T57 oashc.HttpShardHandlerFactory.getParameter Set= ting fairnessPolicy to: false [junit4:junit4] 2> 242 T57 oascsi.HttpClientUtil.createClient Creating ne= w http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&soc= ketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 248 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener sending requests to Searcher@6da21389 main{StandardDirectoryRead= er(segments_1:1)} [junit4:junit4] 2> 248 T57 oasc.CoreContainer.register registering core: = collection1 [junit4:junit4] 2> 250 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener done. [junit4:junit4] 2> 251 T58 oasc.SolrCore.registerSearcher [collection1] R= egistered new searcher Searcher@6da21389 main{StandardDirectoryReader(segme= nts_1:1)} [junit4:junit4] 2> 252 T57 oas.SolrTestCaseJ4.initCore ####initCore end [junit4:junit4] 2> 258 T57 oas.SolrTestCaseJ4.setUp ###Starting testWithS= impleTransformer [junit4:junit4] 2> ASYNC NEW_CORE C3 name=3Dcollection1 org.apache.solr.= core.SolrCore@46c9220 [junit4:junit4] 2> 264 T57 C3 oasu.DirectUpdateHandler2.deleteAll [collec= tion1] REMOVING ALL DOCUMENTS FROM INDEX [junit4:junit4] 2> 266 T57 C3 oasc.SolrDeletionPolicy.onInit SolrDeletion= Policy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_= 1] [junit4:junit4] 2> 267 T57 C3 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 268 T57 C3 UPDATE [collection1] webapp=3Dnull path=3Dn= ull params=3D{} {deleteByQuery=3D*:*} 0 5 [junit4:junit4] 2> 281 T57 C3 oasu.DirectUpdateHandler2.commit start comm= it{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearche= r=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 282 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_= 1] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_= 2] [junit4:junit4] 2> 283 T57 C3 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 2 [junit4:junit4] 2> 284 T57 C3 oass.SolrIndexSearcher. Opening Searc= her@57cae5b7 main [junit4:junit4] 2> 285 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 286 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener sending requests to Searcher@57cae5b7 main{StandardDirectoryRead= er(segments_2:2)} [junit4:junit4] 2> 286 T57 C3 oasu.DirectUpdateHandler2.commit end_commit= _flush [junit4:junit4] 2> 287 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D10= } hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 287 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 288 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+so= lrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 289 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener done. [junit4:junit4] 2> 289 T58 oasc.SolrCore.registerSearcher [collection1] R= egistered new searcher Searcher@57cae5b7 main{StandardDirectoryReader(segme= nts_2:2)} [junit4:junit4] 2> 290 T57 C3 UPDATE [collection1] webapp=3Dnull path=3Dn= ull params=3D{} {commit=3D} 0 9 [junit4:junit4] 2> 485 T57 C3 oashdc.ConfigParseUtil.verifyWithSchema id = is a required field in SolrSchema . But not found in DataConfig [junit4:junit4] 2> 486 T57 C3 oashd.DataImporter.loadDataConfig Data Conf= iguration loaded successfully [junit4:junit4] 2> 486 T57 C3 oashd.DataImporter.doFullImport Starting Fu= ll Import [junit4:junit4] 2> 487 T57 C3 oashd.SimplePropertiesWriter.readIndexerPro= perties Read dataimport.properties [junit4:junit4] 2> 498 T57 C3 oasu.DirectUpdateHandler2.deleteAll [collec= tion1] REMOVING ALL DOCUMENTS FROM INDEX [junit4:junit4] 2> 501 T57 C3 oashd.JdbcDataSource$1.call Creating a conn= ection for entity People with URL: jdbc:hsqldb:mem:. [junit4:junit4] 2> 502 T57 C3 oashd.JdbcDataSource$1.call Time taken for = getConnection(): 1 [junit4:junit4] 2> 540 T57 C3 oashd.DocBuilder.finish Import completed su= ccessfully [junit4:junit4] 2> 541 T57 C3 oasu.DirectUpdateHandler2.commit start comm= it{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearche= r=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 549 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_= 2] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_3,generation=3D3,filenames=3D[_0_Lucene= 40_0.tim, _0.fnm, _0_Lucene40_0.frq, _0.fdx, _0.si, _0.fdt, segments_3, _0_= Lucene40_0.tip] [junit4:junit4] 2> 549 T57 C3 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 3 [junit4:junit4] 2> 555 T57 C3 oass.SolrIndexSearcher. Opening Searc= her@4283df14 main [junit4:junit4] 2> 555 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 556 T57 C3 oasu.DirectUpdateHandler2.commit end_commit= _flush [junit4:junit4] 2> 556 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener sending requests to Searcher@4283df14 main{StandardDirectoryRead= er(segments_3:5 _0(4.1):C20)} [junit4:junit4] 2> 557 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D10= } hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 557 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 558 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+so= lrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 558 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener done. [junit4:junit4] 2> 559 T58 oasc.SolrCore.registerSearcher [collection1] R= egistered new searcher Searcher@4283df14 main{StandardDirectoryReader(segme= nts_3:5 _0(4.1):C20)} [junit4:junit4] 2> 559 T57 C3 oashd.SimplePropertiesWriter.readIndexerPro= perties Read dataimport.properties [junit4:junit4] 2> 560 T57 C3 oashd.SimplePropertiesWriter.persist Wrote = last indexed time to dataimport.properties [junit4:junit4] 2> 561 T57 C3 oashd.DocBuilder.execute Time taken =3D 0:0= :0.74 [junit4:junit4] 2> 561 T57 C3 UPDATE [collection1] webapp=3Dnull path=3Dn= ull params=3D{indent=3Dtrue&clean=3Dtrue&commit=3Dtrue&dataConfig=3D+%0a+%0a+%0a+%0a+%0a+%0a+%0a+%0a+%0a&command=3Dfull-import&synchronous=3Dtrue} {dele= teByQuery=3D*:*,add=3D[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit= =3D} 0 79 [junit4:junit4] 2> 568 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3DAddAColumn_s:Added&qt=3Dstandard&rows=3D20&version= =3D2.2} hits=3D20 status=3D0 QTime=3D6=20 [junit4:junit4] 2> 578 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{indent=3Dtrue} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 587 T57 C3 oashdc.ConfigParseUtil.verifyWithSchema id = is a required field in SolrSchema . But not found in DataConfig [junit4:junit4] 2> 587 T57 C3 oashd.DataImporter.loadDataConfig Data Conf= iguration loaded successfully [junit4:junit4] 2> 588 T57 C3 oashd.DataImporter.doDeltaImport Starting D= elta Import [junit4:junit4] 2> 589 T57 C3 oashd.SimplePropertiesWriter.readIndexerPro= perties Read dataimport.properties [junit4:junit4] 2> 590 T57 C3 oashd.DocBuilder.doDelta Starting delta col= lection. [junit4:junit4] 2> 590 T57 C3 oashd.DocBuilder.collectDelta Running Modif= iedRowKey() for Entity: People [junit4:junit4] 2> 591 T57 C3 oashd.JdbcDataSource$1.call Creating a conn= ection for entity People with URL: jdbc:hsqldb:mem:. [junit4:junit4] 2> 592 T57 C3 oashd.JdbcDataSource$1.call Time taken for = getConnection(): 0 [junit4:junit4] 2> 602 T57 C3 oashd.DocBuilder.collectDelta Completed Mod= ifiedRowKey for Entity: People rows obtained : 7 [junit4:junit4] 2> 602 T57 C3 oashd.DocBuilder.collectDelta Completed Del= etedRowKey for Entity: People rows obtained : 3 [junit4:junit4] 2> 603 T57 C3 oashd.DocBuilder.collectDelta Completed par= entDeltaQuery for Entity: People [junit4:junit4] 2> 603 T57 C3 oashd.DocBuilder.deleteAll Deleting stale d= ocuments=20 [junit4:junit4] 2> 604 T57 C3 oashd.SolrWriter.deleteDoc Deleting documen= t: 18 [junit4:junit4] 2> 604 T57 C3 oashd.SolrWriter.deleteDoc Deleting documen= t: 11 [junit4:junit4] 2> 605 T57 C3 oashd.SolrWriter.deleteDoc Deleting documen= t: 4 [junit4:junit4] 2> 612 T57 C3 oashd.DocBuilder.doDelta Delta Import compl= eted successfully [junit4:junit4] 2> 613 T57 C3 oashd.DocBuilder.finish Import completed su= ccessfully [junit4:junit4] 2> 613 T57 C3 oasu.DirectUpdateHandler2.commit start comm= it{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearche= r=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 634 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_3,generation=3D3,filenames=3D[_0_Lucene= 40_0.tim, _0.fnm, _0_Lucene40_0.frq, _0.fdx, _0.si, _0.fdt, segments_3, _0_= Lucene40_0.tip] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_4,generation=3D4,filenames=3D[_1_Lucene= 40_0.tim, _0_Lucene40_0.tim, _0.fnm, _1_Lucene40_0.frq, _0_1.del, _1.fnm, _= 1_Lucene40_0.tip, _0_Lucene40_0.tip, _1.fdx, _0_Lucene40_0.frq, _1.fdt, _1.= si, _0.fdx, _0.si, _0.fdt, segments_4] [junit4:junit4] 2> 634 T57 C3 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 4 [junit4:junit4] 2> 640 T57 C3 oass.SolrIndexSearcher. Opening Searc= her@203ba002 main [junit4:junit4] 2> 641 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 642 T57 C3 oasu.DirectUpdateHandler2.commit end_commit= _flush [junit4:junit4] 2> 644 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener sending requests to Searcher@203ba002 main{StandardDirectoryRead= er(segments_4:8 _0(4.1):C20/9 _1(4.1):C7)} [junit4:junit4] 2> 644 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D10= } hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 645 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 645 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+so= lrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 646 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener done. [junit4:junit4] 2> 646 T58 oasc.SolrCore.registerSearcher [collection1] R= egistered new searcher Searcher@203ba002 main{StandardDirectoryReader(segme= nts_4:8 _0(4.1):C20/9 _1(4.1):C7)} [junit4:junit4] 2> 647 T57 C3 oashd.SimplePropertiesWriter.readIndexerPro= perties Read dataimport.properties [junit4:junit4] 2> 648 T57 C3 oashd.SimplePropertiesWriter.persist Wrote = last indexed time to dataimport.properties [junit4:junit4] 2> 648 T57 C3 oashd.DocBuilder.execute Time taken =3D 0:0= :0.59 [junit4:junit4] 2> 649 T57 C3 UPDATE [collection1] webapp=3Dnull path=3Dn= ull params=3D{indent=3Dtrue&clean=3Dfalse&commit=3Dtrue&dataConfig=3D+%0a+%0a+%0a<= entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSourc= e%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME= ,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELEC= T+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last= _index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOP= LE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+D= ELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DIS= TINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELEC= T+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}'= )+"+>+%0a+%0a+%0a+%0a= +%0a+%0a&command=3Ddelta-import&synchronous=3Dtrue} {delete=3D= [18, 11, 4],add=3D[19, 6, 12, 2, 16, 1003, 10],commit=3D} 0 66 [junit4:junit4] 2> 652 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3DAddAColumn_s:Added&qt=3Dstandard&rows=3D20&version= =3D2.2} hits=3D18 status=3D0 QTime=3D2=20 [junit4:junit4] 2> 665 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{indent=3Dtrue} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 667 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:1003&qt=3Dstandard&rows=3D20&version=3D2.2} hit= s=3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 673 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:18&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 678 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:4&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 683 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:11&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 688 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:16&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 696 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:2&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 701 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:19&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 706 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:6&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 711 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:10&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 716 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:12&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 720 T57 oashd.AbstractDataImportHandlerTestCase.tearDo= wn Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-= Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/colle= ction1/conf/dataimport.properties [junit4:junit4] 2> 721 T57 oas.SolrTestCaseJ4.tearDown ###Ending testWith= SimpleTransformer [junit4:junit4] 2> 726 T57 oas.SolrTestCaseJ4.setUp ###Starting testChild= Entities [junit4:junit4] 2> 731 T57 C3 oasu.DirectUpdateHandler2.deleteAll [collec= tion1] REMOVING ALL DOCUMENTS FROM INDEX [junit4:junit4] 2> 732 T57 C3 UPDATE [collection1] webapp=3Dnull path=3Dn= ull params=3D{} {deleteByQuery=3D*:*} 0 1 [junit4:junit4] 2> 740 T57 C3 oasu.DirectUpdateHandler2.commit start comm= it{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearche= r=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 742 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_4,generation=3D4,filenames=3D[_1_Lucene= 40_0.tim, _0_Lucene40_0.tim, _0.fnm, _1_Lucene40_0.frq, _0_1.del, _1.fnm, _= 1_Lucene40_0.tip, _0_Lucene40_0.tip, _1.fdx, _0_Lucene40_0.frq, _1.fdt, _1.= si, _0.fdx, _0.si, _0.fdt, segments_4] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_5,generation=3D5,filenames=3D[segments_= 5] [junit4:junit4] 2> 744 T57 C3 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 5 [junit4:junit4] 2> 746 T57 C3 oass.SolrIndexSearcher. Opening Searc= her@5999ae9c main [junit4:junit4] 2> 746 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 747 T57 C3 oasu.DirectUpdateHandler2.commit end_commit= _flush [junit4:junit4] 2> 747 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener sending requests to Searcher@5999ae9c main{StandardDirectoryRead= er(segments_5:9)} [junit4:junit4] 2> 748 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D10= } hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 749 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 750 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+so= lrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 750 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener done. [junit4:junit4] 2> 751 T58 oasc.SolrCore.registerSearcher [collection1] R= egistered new searcher Searcher@5999ae9c main{StandardDirectoryReader(segme= nts_5:9)} [junit4:junit4] 2> 751 T57 C3 UPDATE [collection1] webapp=3Dnull path=3Dn= ull params=3D{} {commit=3D} 0 11 [junit4:junit4] 2> 765 T57 C3 oashdc.ConfigParseUtil.verifyWithSchema id = is a required field in SolrSchema . But not found in DataConfig [junit4:junit4] 2> 766 T57 C3 oashd.DataImporter.loadDataConfig Data Conf= iguration loaded successfully [junit4:junit4] 2> 766 T57 C3 oashd.DataImporter.doFullImport Starting Fu= ll Import [junit4:junit4] 2> 767 T57 C3 oashd.SimplePropertiesWriter.readIndexerPro= perties Read dataimport.properties [junit4:junit4] 2> 767 T57 C3 oasu.DirectUpdateHandler2.deleteAll [collec= tion1] REMOVING ALL DOCUMENTS FROM INDEX [junit4:junit4] 2> 768 T57 C3 oashd.JdbcDataSource$1.call Creating a conn= ection for entity People with URL: jdbc:hsqldb:mem:. [junit4:junit4] 2> 769 T57 C3 oashd.JdbcDataSource$1.call Time taken for = getConnection(): 0 [junit4:junit4] 2> 770 T57 C3 oashd.JdbcDataSource$1.call Creating a conn= ection for entity Sports with URL: jdbc:hsqldb:mem:. [junit4:junit4] 2> 770 T57 C3 oashd.JdbcDataSource$1.call Time taken for = getConnection(): 0 [junit4:junit4] 2> 805 T57 C3 oashd.DocBuilder.finish Import completed su= ccessfully [junit4:junit4] 2> 806 T57 C3 oasu.DirectUpdateHandler2.commit start comm= it{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearche= r=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 812 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_5,generation=3D5,filenames=3D[segments_= 5] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_6,generation=3D6,filenames=3D[_2.si, _2= _Lucene40_0.tip, _2_Lucene40_0.frq, _2.fdt, _2_Lucene40_0.tim, _2.fdx, segm= ents_6, _2.fnm] [junit4:junit4] 2> 813 T57 C3 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 6 [junit4:junit4] 2> 821 T57 C3 oass.SolrIndexSearcher. Opening Searc= her@7a0d637d main [junit4:junit4] 2> 822 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 823 T57 C3 oasu.DirectUpdateHandler2.commit end_commit= _flush [junit4:junit4] 2> 827 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener sending requests to Searcher@7a0d637d main{StandardDirectoryRead= er(segments_6:12 _2(4.1):C20)} [junit4:junit4] 2> 828 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D10= } hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 828 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 829 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+so= lrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 830 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener done. [junit4:junit4] 2> 830 T58 oasc.SolrCore.registerSearcher [collection1] R= egistered new searcher Searcher@7a0d637d main{StandardDirectoryReader(segme= nts_6:12 _2(4.1):C20)} [junit4:junit4] 2> 831 T57 C3 oashd.SimplePropertiesWriter.readIndexerPro= perties Read dataimport.properties [junit4:junit4] 2> 832 T57 C3 oashd.SimplePropertiesWriter.persist Wrote = last indexed time to dataimport.properties [junit4:junit4] 2> 832 T57 C3 oashd.DocBuilder.execute Time taken =3D 0:0= :0.65 [junit4:junit4] 2> 833 T57 C3 UPDATE [collection1] webapp=3Dnull path=3Dn= ull params=3D{indent=3Dtrue&clean=3Dtrue&commit=3Dtrue&dataConfig=3D+%0a+%0a+%0a= +%0a+%0a+%0a+%0a+%0a+%0a+%0a+%0a+%0a+%0a&comm= and=3Dfull-import&synchronous=3Dtrue} {deleteByQuery=3D*:*,add=3D[1, 2, 3, = 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=3D} 0 71 [junit4:junit4] 2> 836 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3D*:*&qt=3Dstandard&rows=3D20&version=3D2.2} hits=3D= 20 status=3D0 QTime=3D2=20 [junit4:junit4] 2> 851 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3DAddAColumn_s:Added&qt=3Dstandard&rows=3D20&version= =3D2.2} hits=3D20 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 864 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3DSPORT_NAME_mult_s:Sailing&qt=3Dstandard&rows=3D20&= version=3D2.2} hits=3D2 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 870 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3DNAME_mult_s:Michael&qt=3Dstandard&rows=3D20&versio= n=3D2.2} hits=3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 881 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3DsportsAdded_s:sport_added&qt=3Dstandard&rows=3D20&= version=3D2.2} hits=3D20 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 893 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{indent=3Dtrue} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 895 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{indent=3Dtrue} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 903 T57 C3 oashdc.ConfigParseUtil.verifyWithSchema id = is a required field in SolrSchema . But not found in DataConfig [junit4:junit4] 2> 904 T57 C3 oashd.DataImporter.loadDataConfig Data Conf= iguration loaded successfully [junit4:junit4] 2> 905 T57 C3 oashd.DataImporter.doDeltaImport Starting D= elta Import [junit4:junit4] 2> 905 T57 C3 oashd.SimplePropertiesWriter.readIndexerPro= perties Read dataimport.properties [junit4:junit4] 2> 906 T57 C3 oashd.DocBuilder.doDelta Starting delta col= lection. [junit4:junit4] 2> 907 T57 C3 oashd.DocBuilder.collectDelta Running Modif= iedRowKey() for Entity: Sports [junit4:junit4] 2> 908 T57 C3 oashd.DocBuilder.collectDelta Completed Mod= ifiedRowKey for Entity: Sports rows obtained : 0 [junit4:junit4] 2> 908 T57 C3 oashd.DocBuilder.collectDelta Completed Del= etedRowKey for Entity: Sports rows obtained : 0 [junit4:junit4] 2> 909 T57 C3 oashd.DocBuilder.collectDelta Completed par= entDeltaQuery for Entity: Sports [junit4:junit4] 2> 909 T57 C3 oashd.DocBuilder.collectDelta Running Modif= iedRowKey() for Entity: People [junit4:junit4] 2> 910 T57 C3 oashd.JdbcDataSource$1.call Creating a conn= ection for entity People with URL: jdbc:hsqldb:mem:. [junit4:junit4] 2> 911 T57 C3 oashd.JdbcDataSource$1.call Time taken for = getConnection(): 0 [junit4:junit4] 2> 914 T57 C3 oashd.DocBuilder.findMatchingPkColumn Resol= ving deltaQuery column 'ID' to match entity's declared pk 'People.ID' [junit4:junit4] 2> 917 T57 C3 oashd.DocBuilder.collectDelta Completed Mod= ifiedRowKey for Entity: People rows obtained : 6 [junit4:junit4] 2> 917 T57 C3 oashd.DocBuilder.collectDelta Completed Del= etedRowKey for Entity: People rows obtained : 4 [junit4:junit4] 2> 918 T57 C3 oashd.DocBuilder.collectDelta Completed par= entDeltaQuery for Entity: People [junit4:junit4] 2> 919 T57 C3 oashd.DocBuilder.deleteAll Deleting stale d= ocuments=20 [junit4:junit4] 2> 919 T57 C3 oashd.DocBuilder.findMatchingPkColumn Resol= ving deltaQuery column 'ID' to match entity's declared pk 'People.ID' [junit4:junit4] 2> 920 T57 C3 oashd.SolrWriter.deleteDoc Deleting documen= t: 8 [junit4:junit4] 2> 920 T57 C3 oashd.DocBuilder.findMatchingPkColumn Resol= ving deltaQuery column 'ID' to match entity's declared pk 'People.ID' [junit4:junit4] 2> 921 T57 C3 oashd.SolrWriter.deleteDoc Deleting documen= t: 9 [junit4:junit4] 2> 922 T57 C3 oashd.DocBuilder.findMatchingPkColumn Resol= ving deltaQuery column 'ID' to match entity's declared pk 'People.ID' [junit4:junit4] 2> 922 T57 C3 oashd.SolrWriter.deleteDoc Deleting documen= t: 12 [junit4:junit4] 2> 923 T57 C3 oashd.DocBuilder.findMatchingPkColumn Resol= ving deltaQuery column 'ID' to match entity's declared pk 'People.ID' [junit4:junit4] 2> 924 T57 C3 oashd.SolrWriter.deleteDoc Deleting documen= t: 4 [junit4:junit4] 2> 925 T57 C3 oashd.JdbcDataSource$1.call Creating a conn= ection for entity Sports with URL: jdbc:hsqldb:mem:. [junit4:junit4] 2> 926 T57 C3 oashd.JdbcDataSource$1.call Time taken for = getConnection(): 0 [junit4:junit4] 2> 940 T57 C3 oashd.DocBuilder.doDelta Delta Import compl= eted successfully [junit4:junit4] 2> 941 T57 C3 oashd.DocBuilder.finish Import completed su= ccessfully [junit4:junit4] 2> 941 T57 C3 oasu.DirectUpdateHandler2.commit start comm= it{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearche= r=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 947 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_6,generation=3D6,filenames=3D[_2.si, _2= _Lucene40_0.tip, _2_Lucene40_0.frq, _2.fdt, _2_Lucene40_0.tim, _2.fdx, segm= ents_6, _2.fnm] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_7,generation=3D7,filenames=3D[_2.si, _3= _Lucene40_0.tip, _3_Lucene40_0.tim, _2.fdt, _3.fdt, _3.fnm, _2.fdx, _2.fnm,= _3.fdx, _2_Lucene40_0.tip, segments_7, _3_Lucene40_0.frq, _2_Lucene40_0.fr= q, _3.si, _2_Lucene40_0.tim, _2_1.del] [junit4:junit4] 2> 947 T57 C3 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 7 [junit4:junit4] 2> 950 T57 C3 oass.SolrIndexSearcher. Opening Searc= her@77b31c23 main [junit4:junit4] 2> 950 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 951 T57 C3 oasu.DirectUpdateHandler2.commit end_commit= _flush [junit4:junit4] 2> 957 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener sending requests to Searcher@77b31c23 main{StandardDirectoryRead= er(segments_7:15 _2(4.1):C20/9 _3(4.1):C6)} [junit4:junit4] 2> 957 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D10= } hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 958 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 959 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+so= lrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 959 T58 oasc.QuerySenderListener.newSearcher QuerySend= erListener done. [junit4:junit4] 2> 959 T58 oasc.SolrCore.registerSearcher [collection1] R= egistered new searcher Searcher@77b31c23 main{StandardDirectoryReader(segme= nts_7:15 _2(4.1):C20/9 _3(4.1):C6)} [junit4:junit4] 2> 960 T57 C3 oashd.SimplePropertiesWriter.readIndexerPro= perties Read dataimport.properties [junit4:junit4] 2> 960 T57 C3 oashd.SimplePropertiesWriter.persist Wrote = last indexed time to dataimport.properties [junit4:junit4] 2> 961 T57 C3 oashd.DocBuilder.execute Time taken =3D 0:0= :0.55 [junit4:junit4] 2> 961 T57 C3 UPDATE [collection1] webapp=3Dnull path=3Dn= ull params=3D{indent=3Dtrue&clean=3Dfalse&commit=3Dtrue&dataConfig=3D+%0a+%0a+%0a<= entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+da= taSource%3D"hsqldb"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHER= E+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%= 3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuer= y%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}'+"+>+%0a+%0a+%0a+%0a+%0a+%0a+%0a+%0= a+%0a+%0a&command=3Ddelta-import&synchronous=3Dtrue= } {delete=3D[8, 9, 12, 4],add=3D[1012, 13, 17, 2, 5, 6],commit=3D} 0 62 [junit4:junit4] 2> 963 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3D*:*&qt=3Dstandard&rows=3D20&version=3D2.2} hits=3D= 17 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 972 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3DNAME_mult_s:Michael&qt=3Dstandard&rows=3D20&versio= n=3D2.2} hits=3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 979 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:1012&qt=3Dstandard&rows=3D20&version=3D2.2} hit= s=3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 982 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:4&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 985 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:8&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 988 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:9&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 990 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:12&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 993 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:17&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 997 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnull= params=3D{start=3D0&q=3Did:2&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1002 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:5&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1010 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:6&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1015 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:13&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1019 T57 oashd.AbstractDataImportHandlerTestCase.tearD= own Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene= -Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/coll= ection1/conf/dataimport.properties [junit4:junit4] 2> 1019 T57 oas.SolrTestCaseJ4.tearDown ###Ending testChi= ldEntities [junit4:junit4] 2> 1024 T57 oas.SolrTestCaseJ4.setUp ###Starting testWith= ComplexTransformer [junit4:junit4] 2> 1028 T57 C3 oasu.DirectUpdateHandler2.deleteAll [colle= ction1] REMOVING ALL DOCUMENTS FROM INDEX [junit4:junit4] 2> 1029 T57 C3 UPDATE [collection1] webapp=3Dnull path=3D= null params=3D{} {deleteByQuery=3D*:*} 0 1 [junit4:junit4] 2> 1036 T57 C3 oasu.DirectUpdateHandler2.commit start com= mit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearch= er=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 1037 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_7,generation=3D7,filenames=3D[_2.si, _3= _Lucene40_0.tip, _3_Lucene40_0.tim, _2.fdt, _3.fdt, _3.fnm, _2.fdx, _2.fnm,= _3.fdx, _2_Lucene40_0.tip, segments_7, _3_Lucene40_0.frq, _2_Lucene40_0.fr= q, _3.si, _2_Lucene40_0.tim, _2_1.del] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_8,generation=3D8,filenames=3D[segments_= 8] [junit4:junit4] 2> 1037 T57 C3 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 8 [junit4:junit4] 2> 1038 T57 C3 oass.SolrIndexSearcher. Opening Sear= cher@682f8c99 main [junit4:junit4] 2> 1039 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING= WARNING: Directory impl does not support setting indexDir: org.apache.luce= ne.store.MockDirectoryWrapper [junit4:junit4] 2> 1039 T57 C3 oasu.DirectUpdateHandler2.commit end_commi= t_flush [junit4:junit4] 2> 1040 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener sending requests to Searcher@682f8c99 main{StandardDirectoryRea= der(segments_8:16)} [junit4:junit4] 2> 1041 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1043 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D= 10} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1044 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+s= olrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1045 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener done. [junit4:junit4] 2> 1045 T58 oasc.SolrCore.registerSearcher [collection1] = Registered new searcher Searcher@682f8c99 main{StandardDirectoryReader(segm= ents_8:16)} [junit4:junit4] 2> 1046 T57 C3 UPDATE [collection1] webapp=3Dnull path=3D= null params=3D{} {commit=3D} 0 11 [junit4:junit4] 2> 1054 T57 C3 oashdc.ConfigParseUtil.verifyWithSchema id= is a required field in SolrSchema . But not found in DataConfig [junit4:junit4] 2> 1055 T57 C3 oashd.DataImporter.loadDataConfig Data Con= figuration loaded successfully [junit4:junit4] 2> 1055 T57 C3 oashd.DataImporter.doFullImport Starting F= ull Import [junit4:junit4] 2> 1055 T57 C3 oashd.SimplePropertiesWriter.readIndexerPr= operties Read dataimport.properties [junit4:junit4] 2> 1056 T57 C3 oasu.DirectUpdateHandler2.deleteAll [colle= ction1] REMOVING ALL DOCUMENTS FROM INDEX [junit4:junit4] 2> 1057 T57 C3 oashd.JdbcDataSource$1.call Creating a con= nection for entity People with URL: jdbc:hsqldb:mem:. [junit4:junit4] 2> 1058 T57 C3 oashd.JdbcDataSource$1.call Time taken for= getConnection(): 0 [junit4:junit4] 2> 1118 T57 C3 oashd.DocBuilder.finish Import completed s= uccessfully [junit4:junit4] 2> 1119 T57 C3 oasu.DirectUpdateHandler2.commit start com= mit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearch= er=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 1127 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_8,generation=3D8,filenames=3D[segments_= 8] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_9,generation=3D9,filenames=3D[_4.fdx, s= egments_9, _4_Lucene40_0.frq, _4_Lucene40_0.tip, _4.fnm, _4.si, _4.fdt, _4_= Lucene40_0.tim] [junit4:junit4] 2> 1128 T57 C3 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 9 [junit4:junit4] 2> 1180 T57 C3 oass.SolrIndexSearcher. Opening Sear= cher@141d19 main [junit4:junit4] 2> 1181 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING= WARNING: Directory impl does not support setting indexDir: org.apache.luce= ne.store.MockDirectoryWrapper [junit4:junit4] 2> 1181 T57 C3 oasu.DirectUpdateHandler2.commit end_commi= t_flush [junit4:junit4] 2> 1186 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener sending requests to Searcher@141d19 main{StandardDirectoryReade= r(segments_9:19 _4(4.1):C60)} [junit4:junit4] 2> 1187 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1188 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D= 10} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1189 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+s= olrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1189 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener done. [junit4:junit4] 2> 1190 T58 oasc.SolrCore.registerSearcher [collection1] = Registered new searcher Searcher@141d19 main{StandardDirectoryReader(segmen= ts_9:19 _4(4.1):C60)} [junit4:junit4] 2> 1190 T57 C3 oashd.SimplePropertiesWriter.readIndexerPr= operties Read dataimport.properties [junit4:junit4] 2> 1191 T57 C3 oashd.SimplePropertiesWriter.persist Wrote= last indexed time to dataimport.properties [junit4:junit4] 2> 1192 T57 C3 oashd.DocBuilder.execute Time taken =3D 0:= 0:0.136 [junit4:junit4] 2> 1192 T57 C3 UPDATE [collection1] webapp=3Dnull path=3D= null params=3D{indent=3Dtrue&clean=3Dtrue&commit=3Dtrue&dataConfig=3D+%0a+%0a+%0a<= entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSourc= e%3D"hsqldb"+transformer%3D"TripleThreatTransformer"+query%3D"SELECT+ID,+NA= ME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SEL= ECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.la= st_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PE= OPLE+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+D= ISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SEL= ECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time= }')+"+>+%0a+%0a+%0a+%0a+%0a+%0a&command=3Dfull-import&synchronous=3Dtrue} {deleteBy= Query=3D*:*,add=3D[1, TripleThreat-1-1, TripleThreat-2-1, 2, TripleThreat-1= -2, TripleThreat-2-2, 3, TripleThreat-1-3, TripleThreat-2-3, 4, ... (60 add= s)],commit=3D} 0 140 [junit4:junit4] 2> 1247 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{sort=3Did+asc&q=3D*:*&rows=3D60} hits=3D60 status=3D0 QTime=3D5= 4=20 [junit4:junit4] 2> 1271 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:TripleThreat-1-*&qt=3Dstandard&rows=3D20&versi= on=3D2.2} hits=3D20 status=3D0 QTime=3D5=20 [junit4:junit4] 2> 1278 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:TripleThreat-2-*&qt=3Dstandard&rows=3D20&versi= on=3D2.2} hits=3D20 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1285 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3DAddAColumn_s:Added&qt=3Dstandard&rows=3D20&versio= n=3D2.2} hits=3D20 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1290 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{indent=3Dtrue} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1291 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{indent=3Dtrue} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1298 T57 C3 oashdc.ConfigParseUtil.verifyWithSchema id= is a required field in SolrSchema . But not found in DataConfig [junit4:junit4] 2> 1299 T57 C3 oashd.DataImporter.loadDataConfig Data Con= figuration loaded successfully [junit4:junit4] 2> 1299 T57 C3 oashd.DataImporter.doDeltaImport Starting = Delta Import [junit4:junit4] 2> 1300 T57 C3 oashd.SimplePropertiesWriter.readIndexerPr= operties Read dataimport.properties [junit4:junit4] 2> 1300 T57 C3 oashd.DocBuilder.doDelta Starting delta co= llection. [junit4:junit4] 2> 1301 T57 C3 oashd.DocBuilder.collectDelta Running Modi= fiedRowKey() for Entity: People [junit4:junit4] 2> 1301 T57 C3 oashd.JdbcDataSource$1.call Creating a con= nection for entity People with URL: jdbc:hsqldb:mem:. [junit4:junit4] 2> 1302 T57 C3 oashd.JdbcDataSource$1.call Time taken for= getConnection(): 0 [junit4:junit4] 2> 1305 T57 C3 oashd.DocBuilder.collectDelta Completed Mo= difiedRowKey for Entity: People rows obtained : 4 [junit4:junit4] 2> 1306 T57 C3 oashd.DocBuilder.collectDelta Completed De= letedRowKey for Entity: People rows obtained : 6 [junit4:junit4] 2> 1306 T57 C3 oashd.DocBuilder.collectDelta Completed pa= rentDeltaQuery for Entity: People [junit4:junit4] 2> 1307 T57 C3 oashd.DocBuilder.deleteAll Deleting stale = documents=20 [junit4:junit4] 2> 1307 T57 C3 oashd.SolrWriter.deleteDoc Deleting docume= nt: 8 [junit4:junit4] 2> 1307 T57 C3 oashd.SolrWriter.deleteDoc Deleting docume= nt: 10 [junit4:junit4] 2> 1308 T57 C3 oashd.SolrWriter.deleteDoc Deleting docume= nt: 12 [junit4:junit4] 2> 1308 T57 C3 oashd.SolrWriter.deleteDoc Deleting docume= nt: 17 [junit4:junit4] 2> 1309 T57 C3 oashd.SolrWriter.deleteDoc Deleting docume= nt: 3 [junit4:junit4] 2> 1309 T57 C3 oashd.SolrWriter.deleteDoc Deleting docume= nt: 4 [junit4:junit4] 2> 1319 T57 C3 oashd.DocBuilder.doDelta Delta Import comp= leted successfully [junit4:junit4] 2> 1320 T57 C3 oashd.DocBuilder.finish Import completed s= uccessfully [junit4:junit4] 2> 1320 T57 C3 oasu.DirectUpdateHandler2.commit start com= mit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearch= er=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 1326 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_9,generation=3D9,filenames=3D[_4.fdx, s= egments_9, _4_Lucene40_0.frq, _4_Lucene40_0.tip, _4.fnm, _4.si, _4.fdt, _4_= Lucene40_0.tim] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_a,generation=3D10,filenames=3D[_5.fnm, = _5_Lucene40_0.frq, _5.fdt, segments_a, _4.fnm, _4.fdt, _4.si, _4.fdx, _5.si= , _5_Lucene40_0.tim, _5_Lucene40_0.tip, _4_Lucene40_0.frq, _5.fdx, _4_Lucen= e40_0.tip, _4_1.del, _4_Lucene40_0.tim] [junit4:junit4] 2> 1326 T57 C3 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 10 [junit4:junit4] 2> 1328 T57 C3 oass.SolrIndexSearcher. Opening Sear= cher@780324ff main [junit4:junit4] 2> 1329 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING= WARNING: Directory impl does not support setting indexDir: org.apache.luce= ne.store.MockDirectoryWrapper [junit4:junit4] 2> 1329 T57 C3 oasu.DirectUpdateHandler2.commit end_commi= t_flush [junit4:junit4] 2> 1340 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener sending requests to Searcher@780324ff main{StandardDirectoryRea= der(segments_a:22 _4(4.1):C60/15 _5(4.1):C12)} [junit4:junit4] 2> 1341 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1341 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D= 10} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1342 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+s= olrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1343 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener done. [junit4:junit4] 2> 1343 T58 oasc.SolrCore.registerSearcher [collection1] = Registered new searcher Searcher@780324ff main{StandardDirectoryReader(segm= ents_a:22 _4(4.1):C60/15 _5(4.1):C12)} [junit4:junit4] 2> 1344 T57 C3 oashd.SimplePropertiesWriter.readIndexerPr= operties Read dataimport.properties [junit4:junit4] 2> 1345 T57 C3 oashd.SimplePropertiesWriter.persist Wrote= last indexed time to dataimport.properties [junit4:junit4] 2> 1345 T57 C3 oashd.DocBuilder.execute Time taken =3D 0:= 0:0.45 [junit4:junit4] 2> 1346 T57 C3 UPDATE [collection1] webapp=3Dnull path=3D= null params=3D{indent=3Dtrue&clean=3Dfalse&commit=3Dtrue&dataConfig=3D+%0a+%0a+%0a= +%0a+%0a+%0a+%0a+%0a+%0a&command=3Ddelta-import&synchronous=3Dtrue} {delete= =3D[8, 10, 12, 17, 3, 4],add=3D[1016, TripleThreat-1-1016, TripleThreat-2-1= 016, 18, TripleThreat-1-18, TripleThreat-2-18, 6, TripleThreat-1-6, TripleT= hreat-2-6, 7, ... (12 adds)],commit=3D} 0 53 [junit4:junit4] 2> 1348 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{sort=3Did+asc&q=3D*:*&rows=3D45} hits=3D57 status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 1363 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:TripleThreat-1-*&qt=3Dstandard&rows=3D20&versi= on=3D2.2} hits=3D21 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1371 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:TripleThreat-2-*&qt=3Dstandard&rows=3D20&versi= on=3D2.2} hits=3D21 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1381 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3DAddAColumn_s:Added&qt=3Dstandard&rows=3D20&versio= n=3D2.2} hits=3D21 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1388 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{indent=3Dtrue} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1389 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{indent=3Dtrue} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1391 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:1016&qt=3Dstandard&rows=3D20&version=3D2.2} hi= ts=3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1395 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:17&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1399 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:3&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1402 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:4&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1406 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:8&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1409 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:10&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1413 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:12&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1416 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:18&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1421 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:6&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1425 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3Did:7&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1428 T57 oashd.AbstractDataImportHandlerTestCase.tearD= own Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene= -Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/coll= ection1/conf/dataimport.properties [junit4:junit4] 2> 1429 T57 oas.SolrTestCaseJ4.tearDown ###Ending testWit= hComplexTransformer [junit4:junit4] 2> 1439 T57 oas.SolrTestCaseJ4.setUp ###Starting testSing= leEntity [junit4:junit4] 2> 1442 T57 C3 oasu.DirectUpdateHandler2.deleteAll [colle= ction1] REMOVING ALL DOCUMENTS FROM INDEX [junit4:junit4] 2> 1443 T57 C3 UPDATE [collection1] webapp=3Dnull path=3D= null params=3D{} {deleteByQuery=3D*:*} 0 1 [junit4:junit4] 2> 1448 T57 C3 oasu.DirectUpdateHandler2.commit start com= mit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearch= er=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 1448 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_a,generation=3D10,filenames=3D[_5.fnm, = _5_Lucene40_0.frq, _5.fdt, segments_a, _4.fnm, _4.fdt, _4.si, _4.fdx, _5.si= , _5_Lucene40_0.tim, _5_Lucene40_0.tip, _4_Lucene40_0.frq, _5.fdx, _4_Lucen= e40_0.tip, _4_1.del, _4_Lucene40_0.tim] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_b,generation=3D11,filenames=3D[segments= _b] [junit4:junit4] 2> 1449 T57 C3 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 11 [junit4:junit4] 2> 1450 T57 C3 oass.SolrIndexSearcher. Opening Sear= cher@64f007ad main [junit4:junit4] 2> 1450 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING= WARNING: Directory impl does not support setting indexDir: org.apache.luce= ne.store.MockDirectoryWrapper [junit4:junit4] 2> 1453 T57 C3 oasu.DirectUpdateHandler2.commit end_commi= t_flush [junit4:junit4] 2> 1454 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener sending requests to Searcher@64f007ad main{StandardDirectoryRea= der(segments_b:23)} [junit4:junit4] 2> 1455 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1455 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D= 10} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1456 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+s= olrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1456 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener done. [junit4:junit4] 2> 1456 T58 oasc.SolrCore.registerSearcher [collection1] = Registered new searcher Searcher@64f007ad main{StandardDirectoryReader(segm= ents_b:23)} [junit4:junit4] 2> 1457 T57 C3 UPDATE [collection1] webapp=3Dnull path=3D= null params=3D{} {commit=3D} 0 10 [junit4:junit4] 2> 1465 T57 C3 oashdc.ConfigParseUtil.verifyWithSchema id= is a required field in SolrSchema . But not found in DataConfig [junit4:junit4] 2> 1466 T57 C3 oashd.DataImporter.loadDataConfig Data Con= figuration loaded successfully [junit4:junit4] 2> 1466 T57 C3 oashd.DataImporter.doFullImport Starting F= ull Import [junit4:junit4] 2> 1467 T57 C3 oashd.SimplePropertiesWriter.readIndexerPr= operties Read dataimport.properties [junit4:junit4] 2> 1467 T57 C3 oasu.DirectUpdateHandler2.deleteAll [colle= ction1] REMOVING ALL DOCUMENTS FROM INDEX [junit4:junit4] 2> 1468 T57 C3 oashd.JdbcDataSource$1.call Creating a con= nection for entity People with URL: jdbc:hsqldb:mem:. [junit4:junit4] 2> 1469 T57 C3 oashd.JdbcDataSource$1.call Time taken for= getConnection(): 0 [junit4:junit4] 2> 1481 T57 C3 oashd.DocBuilder.finish Import completed s= uccessfully [junit4:junit4] 2> 1481 T57 C3 oasu.DirectUpdateHandler2.commit start com= mit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearch= er=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 1485 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_b,generation=3D11,filenames=3D[segments= _b] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_c,generation=3D12,filenames=3D[segments= _c, _6_Lucene40_0.tim, _6_Lucene40_0.tip, _6.fdx, _6.si, _6.fnm, _6.fdt, _6= _Lucene40_0.frq] [junit4:junit4] 2> 1485 T57 C3 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 12 [junit4:junit4] 2> 1487 T57 C3 oass.SolrIndexSearcher. Opening Sear= cher@442b7fc2 main [junit4:junit4] 2> 1488 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING= WARNING: Directory impl does not support setting indexDir: org.apache.luce= ne.store.MockDirectoryWrapper [junit4:junit4] 2> 1488 T57 C3 oasu.DirectUpdateHandler2.commit end_commi= t_flush [junit4:junit4] 2> 1496 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener sending requests to Searcher@442b7fc2 main{StandardDirectoryRea= der(segments_c:26 _6(4.1):C20)} [junit4:junit4] 2> 1497 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1498 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D= 10} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1499 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+s= olrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1499 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener done. [junit4:junit4] 2> 1499 T58 oasc.SolrCore.registerSearcher [collection1] = Registered new searcher Searcher@442b7fc2 main{StandardDirectoryReader(segm= ents_c:26 _6(4.1):C20)} [junit4:junit4] 2> 1500 T57 C3 oashd.SimplePropertiesWriter.readIndexerPr= operties Read dataimport.properties [junit4:junit4] 2> 1501 T57 C3 oashd.SimplePropertiesWriter.persist Wrote= last indexed time to dataimport.properties [junit4:junit4] 2> 1501 T57 C3 oashd.DocBuilder.execute Time taken =3D 0:= 0:0.34 [junit4:junit4] 2> 1502 T57 C3 UPDATE [collection1] webapp=3Dnull path=3D= null params=3D{indent=3Dtrue&clean=3Dtrue&commit=3Dtrue&dataConfig=3D+%0a+%0a+%0a<= entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSourc= e%3D"hsqldb"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELET= ED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+A= ND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SE= LECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQ= uery%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+DELE= TED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modif= ied+%26gt;%3D'${dih.last_index_time}')+"+>+%0a+%0a+%0a+%0a+%0a+%0a&command=3Dfull-i= mport&synchronous=3Dtrue} {deleteByQuery=3D*:*,add=3D[1, 2, 3, 4, 5, 6, 7, = 8, 9, 10, ... (20 adds)],commit=3D} 0 39 [junit4:junit4] 2> 1503 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3D*:*&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D20 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1509 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{indent=3Dtrue} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1513 T57 C3 oashdc.ConfigParseUtil.verifyWithSchema id= is a required field in SolrSchema . But not found in DataConfig [junit4:junit4] 2> 1513 T57 C3 oashd.DataImporter.loadDataConfig Data Con= figuration loaded successfully [junit4:junit4] 2> 1513 T57 C3 oashd.DataImporter.doDeltaImport Starting = Delta Import [junit4:junit4] 2> 1514 T57 C3 oashd.SimplePropertiesWriter.readIndexerPr= operties Read dataimport.properties [junit4:junit4] 2> 1515 T57 C3 oashd.DocBuilder.doDelta Starting delta co= llection. [junit4:junit4] 2> 1516 T57 C3 oashd.DocBuilder.collectDelta Running Modi= fiedRowKey() for Entity: People [junit4:junit4] 2> 1517 T57 C3 oashd.JdbcDataSource$1.call Creating a con= nection for entity People with URL: jdbc:hsqldb:mem:. [junit4:junit4] 2> 1517 T57 C3 oashd.JdbcDataSource$1.call Time taken for= getConnection(): 0 [junit4:junit4] 2> 1522 T57 C3 oashd.DocBuilder.findMatchingPkColumn Reso= lving deltaQuery column 'ID' to match entity's declared pk 'People.ID' [junit4:junit4] 2> 1524 T57 C3 oashd.DocBuilder.collectDelta Completed Mo= difiedRowKey for Entity: People rows obtained : 19 [junit4:junit4] 2> 1524 T57 C3 oashd.DocBuilder.collectDelta Completed De= letedRowKey for Entity: People rows obtained : 2 [junit4:junit4] 2> 1525 T57 C3 oashd.DocBuilder.collectDelta Completed pa= rentDeltaQuery for Entity: People [junit4:junit4] 2> 1526 T57 C3 oashd.DocBuilder.deleteAll Deleting stale = documents=20 [junit4:junit4] 2> 1526 T57 C3 oashd.DocBuilder.findMatchingPkColumn Reso= lving deltaQuery column 'ID' to match entity's declared pk 'People.ID' [junit4:junit4] 2> 1527 T57 C3 oashd.SolrWriter.deleteDoc Deleting docume= nt: 10 [junit4:junit4] 2> 1528 T57 C3 oashd.DocBuilder.findMatchingPkColumn Reso= lving deltaQuery column 'ID' to match entity's declared pk 'People.ID' [junit4:junit4] 2> 1528 T57 C3 oashd.SolrWriter.deleteDoc Deleting docume= nt: 7 [junit4:junit4] 2> 1545 T57 C3 oashd.DocBuilder.doDelta Delta Import comp= leted successfully [junit4:junit4] 2> 1545 T57 C3 oashd.DocBuilder.finish Import completed s= uccessfully [junit4:junit4] 2> 1545 T57 C3 oasu.DirectUpdateHandler2.commit start com= mit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,waitSearch= er=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 1551 T57 C3 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_c,generation=3D12,filenames=3D[segments= _c, _6_Lucene40_0.tim, _6_Lucene40_0.tip, _6.fdx, _6.si, _6.fnm, _6.fdt, _6= _Lucene40_0.frq] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4b069693 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@381172c5),segFN=3Dsegments_d,generation=3D13,filenames=3D[_7.fnm, = segments_d, _7_Lucene40_0.frq, _7.si, _7.fdx, _7_Lucene40_0.tim, _7.fdt, _7= _Lucene40_0.tip] [junit4:junit4] 2> 1551 T57 C3 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 13 [junit4:junit4] 2> 1553 T57 C3 oass.SolrIndexSearcher. Opening Sear= cher@121a2cc7 main [junit4:junit4] 2> 1554 T57 C3 oass.SolrIndexSearcher.getIndexDir WARNING= WARNING: Directory impl does not support setting indexDir: org.apache.luce= ne.store.MockDirectoryWrapper [junit4:junit4] 2> 1554 T57 C3 oasu.DirectUpdateHandler2.commit end_commi= t_flush [junit4:junit4] 2> 1562 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener sending requests to Searcher@121a2cc7 main{StandardDirectoryRea= der(segments_d:30 _7(4.1):C19)} [junit4:junit4] 2> 1563 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Dsolr&distrib=3Dfalse&rows=3D1= 0} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1564 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&event=3DnewSearcher&q=3Drocks&distrib=3Dfalse&rows=3D= 10} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1565 T58 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{event=3DnewSearcher&q=3Dstatic+newSearcher+warming+query+from+s= olrconfig.xml&distrib=3Dfalse} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1565 T58 oasc.QuerySenderListener.newSearcher QuerySen= derListener done. [junit4:junit4] 2> 1565 T58 oasc.SolrCore.registerSearcher [collection1] = Registered new searcher Searcher@121a2cc7 main{StandardDirectoryReader(segm= ents_d:30 _7(4.1):C19)} [junit4:junit4] 2> 1566 T57 C3 oashd.SimplePropertiesWriter.readIndexerPr= operties Read dataimport.properties [junit4:junit4] 2> 1567 T57 C3 oashd.SimplePropertiesWriter.persist Wrote= last indexed time to dataimport.properties [junit4:junit4] 2> 1568 T57 C3 oashd.DocBuilder.execute Time taken =3D 0:= 0:0.53 [junit4:junit4] 2> 1568 T57 C3 UPDATE [collection1] webapp=3Dnull path=3D= null params=3D{indent=3Dtrue&clean=3Dfalse&commit=3Dtrue&dataConfig=3D+%0a+%0a+%0a= +%0a+%0a+%0a+%0a+%0a+%0a&command= =3Ddelta-import&synchronous=3Dtrue} {delete=3D[10, 7],add=3D[17, 16, 19, 18= , 20, 1007, 8, 9, 11, 12, ... (19 adds)],commit=3D} 0 57 [junit4:junit4] 2> 1570 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{start=3D0&q=3D*:*&qt=3Dstandard&rows=3D20&version=3D2.2} hits= =3D19 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1575 T57 C3 REQ [collection1] webapp=3Dnull path=3Dnul= l params=3D{indent=3Dtrue} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1576 T57 oashd.AbstractDataImportHandlerTestCase.tearD= own Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene= -Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/coll= ection1/conf/dataimport.properties [junit4:junit4] 2> 1577 T57 oas.SolrTestCaseJ4.tearDown ###Ending testSin= gleEntity [junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestSqlEn= tityProcessorDelta -Dtests.method=3DtestSingleEntity -Dtests.seed=3D7ABC0EB= 25D0EA317 -Dtests.multiplier=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Dhi_IN= -Dtests.timezone=3DAsia/Katmandu -Dtests.file.encoding=3DUTF-8 [junit4:junit4] FAILURE 0.19s J1 | TestSqlEntityProcessorDelta.testSingleEn= tity <<< [junit4:junit4] > Throwable #1: junit.framework.AssertionFailedError [junit4:junit4] > =09at __randomizedtesting.SeedInfo.seed([7ABC0EB25D0EA= 317:1403722E32E34C94]:0) [junit4:junit4] > =09at junit.framework.Assert.fail(Assert.java:48) [junit4:junit4] > =09at junit.framework.Assert.assertTrue(Assert.java:20= ) [junit4:junit4] > =09at junit.framework.Assert.assertTrue(Assert.java:27= ) [junit4:junit4] > =09at org.apache.solr.handler.dataimport.AbstractDIHJd= bcTestCase.singleEntity(AbstractDIHJdbcTestCase.java:103) [junit4:junit4] > =09at org.apache.solr.handler.dataimport.TestSqlEntity= ProcessorDelta.testSingleEntity(TestSqlEntityProcessorDelta.java:44) [junit4:junit4] > =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Nat= ive Method) [junit4:junit4] > =09at sun.reflect.NativeMethodAccessorImpl.invoke(Nati= veMethodAccessorImpl.java:39) [junit4:junit4] > =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(= DelegatingMethodAccessorImpl.java:25) [junit4:junit4] > =09at java.lang.reflect.Method.invoke(Method.java:597) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.invoke(RandomizedRunner.java:1559) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.access$600(RandomizedRunner.java:79) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$6.evaluate(RandomizedRunner.java:737) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$7.evaluate(RandomizedRunner.java:773) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$8.evaluate(RandomizedRunner.java:787) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleSetupTeardownChai= ned$1.evaluate(TestRuleSetupTeardownChained.java:50) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleFieldCacheSanity$= 1.evaluate(TestRuleFieldCacheSanity.java:51) [junit4:junit4] > =09at org.apache.lucene.util.AbstractBeforeAfterRule$1= .evaluate(AbstractBeforeAfterRule.java:45) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleThreadAndTestName= $1.evaluate(TestRuleThreadAndTestName.java:48) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFai= lures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleMarkFailure$1.eva= luate(TestRuleMarkFailure.java:48) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol.forkTimeoutingTask(ThreadLeakControl.java:782) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$3.evaluate(ThreadLeakControl.java:442) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.runSingleTest(RandomizedRunner.java:746) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$3.evaluate(RandomizedRunner.java:648) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$4.evaluate(RandomizedRunner.java:682) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$5.evaluate(RandomizedRunner.java:693) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] > =09at org.apache.lucene.util.AbstractBeforeAfterRule$1= .evaluate(AbstractBeforeAfterRule.java:45) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleStoreClassName$1.= evaluate(TestRuleStoreClassName.java:42) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.NoShado= wingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule= .java:39) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.NoShado= wingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule= .java:39) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleAssertionsRequire= d$1.evaluate(TestRuleAssertionsRequired.java:43) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleMarkFailure$1.eva= luate(TestRuleMarkFailure.java:48) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFai= lures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$= 1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] > =09at java.lang.Thread.run(Thread.java:662) [junit4:junit4] 2> 1624 T57 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4:junit4] 2> 1625 T57 oasc.CoreContainer.shutdown Shutting down Cor= eContainer instance=3D1102757339 [junit4:junit4] 2> 1625 T57 oasc.SolrCore.close [collection1] CLOSING So= lrCore org.apache.solr.core.SolrCore@46c9220 [junit4:junit4] 2> 1625 T57 oasu.DirectUpdateHandler2.close closing Direc= tUpdateHandler2{commits=3D12,autocommits=3D0,soft autocommits=3D0,optimizes= =3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesById= =3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D164,cumulative_deletes= ById=3D15,cumulative_deletesByQuery=3D8,cumulative_errors=3D0} [junit4:junit4] 2> 1626 T57 oasc.SolrCore.decrefSolrCoreState Closing Sol= rCoreState [junit4:junit4] 2> 1626 T57 oasu.DefaultSolrCoreState.closeIndexWriter So= lrCoreState ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> 1626 T57 oasu.DefaultSolrCoreState.closeIndexWriter cl= osing IndexWriter with IndexWriterCloser [junit4:junit4] 2> 1627 T57 oasc.SolrCore.closeSearcher [collection1] Clo= sing main searcher on request. [junit4:junit4] 2> NOTE: test params are: codec=3DLucene40, sim=3DDefault= Similarity, locale=3Dhi_IN, timezone=3DAsia/Katmandu [junit4:junit4] 2> NOTE: Linux 3.2.0-32-generic amd64/Sun Microsystems In= c. 1.6.0_37 (64-bit)/cpus=3D8,threads=3D1,free=3D67651712,total=3D85000192 [junit4:junit4] 2> NOTE: All tests run in this JVM: [TestErrorHandling, T= estXPathEntityProcessor, TestContentStreamDataSource, TestLineEntityProcess= or, TestJdbcDataSource, TestScriptTransformer, TestPlainTextEntityProcessor= , TestDocBuilder, TestXPathRecordReader, TestSqlEntityProcessorDelta] [junit4:junit4] Completed on J1 in 1.66s, 4 tests, 1 failure <<< FAILURES! [...truncated 67 lines...] BUILD FAILED /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:335: The followi= ng error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The followin= g error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:192: The fo= llowing error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:410:= The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/module-build.xml:61= : The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:11= 63: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:82= 7: There were test failures: 33 suites, 138 tests, 1 failure, 5 ignored Total time: 30 minutes 18 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Description set: Java: 64bit/jdk1.6.0_37 -XX:+UseConcMarkSweepGC Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_99_1513126391.1352388783397 Content-Type: text/plain; charset=us-ascii --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org For additional commands, e-mail: dev-help@lucene.apache.org ------=_Part_99_1513126391.1352388783397--