You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@sd-datasolutions.de> on 2012/11/07 13:26:29 UTC
[JENKINS] Lucene-Solr-4.x-Linux
(32bit/jrockit-jdk1.6.0_33-R28.2.4-4.1.0) - Build # 2246 - Failure!
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/2246/
Java: 32bit/jrockit-jdk1.6.0_33-R28.2.4-4.1.0 -XnoOpt
1 tests failed.
REGRESSION: org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta.testWithComplexTransformer
Error Message:
Stack Trace:
junit.framework.AssertionFailedError
at __randomizedtesting.SeedInfo.seed([21286DB5AB8C9BB3:4B221C3EC07A950E]:0)
at junit.framework.Assert.fail(Assert.java:48)
at junit.framework.Assert.assertTrue(Assert.java:20)
at junit.framework.Assert.assertTrue(Assert.java:27)
at org.apache.solr.handler.dataimport.AbstractDIHJdbcTestCase.complexTransform(AbstractDIHJdbcTestCase.java:129)
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:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
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:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:774)
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:683)
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:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:44)
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:56)
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:662)
Build Log:
[...truncated 10730 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.dataimport.TestSqlEntityProcessorDelta
[junit4:junit4] 2> 2 T64 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352291182888
[junit4:junit4] 2> 3 T64 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/'
[junit4:junit4] 2> 46 T64 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4] 2> 98 T64 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4:junit4] 2> 98 T64 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 101 T64 oass.IndexSchema.readSchema Schema name=dih_test
[junit4:junit4] 2> 162 T64 oass.IndexSchema.readSchema default search field in schema is desc
[junit4:junit4] 2> 163 T64 oass.IndexSchema.readSchema query parser default operator is OR
[junit4:junit4] 2> 165 T64 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 166 T64 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 166 T64 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr
[junit4:junit4] 2> 166 T64 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/'
[junit4:junit4] 2> 176 T64 oasc.CoreContainer.<init> New CoreContainer 450741201
[junit4:junit4] 2> 176 T64 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test-files/dih/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352291182888/
[junit4:junit4] 2> 177 T64 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 177 T64 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> 177 T64 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4] 2> 178 T64 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352291182888/index/
[junit4:junit4] 2> 178 T64 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-TestSqlEntityProcessorDelta-1352291182888/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 179 T64 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/./solrtest-TestSqlEntityProcessorDelta-1352291182888/index forceNew:false
[junit4:junit4] 2> 182 T64 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 182 T64 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4] 2> 183 T64 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
[junit4:junit4] 2> 183 T64 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 184 T64 oasc.RequestHandlers.initHandlersFromConfig created /dataimport: org.apache.solr.handler.dataimport.DataImportHandler
[junit4:junit4] 2> 184 T64 oasc.RequestHandlers.initHandlersFromConfig created /search: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 184 T64 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 186 T64 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 190 T64 oass.SolrIndexSearcher.<init> Opening Searcher@1ac0f419 main
[junit4:junit4] 2> 190 T64 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 190 T64 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 191 T64 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 191 T64 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 191 T64 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 192 T64 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 192 T64 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 192 T64 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 193 T64 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 193 T64 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 194 T64 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 194 T64 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 195 T64 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 198 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ac0f419 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 198 T64 oasc.CoreContainer.register registering core: collection1
[junit4:junit4] 2> 198 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 199 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ac0f419 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 200 T64 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4] 2> 214 T64 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
[junit4:junit4] 2> ASYNC NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@1ac1f69a
[junit4:junit4] 2> 218 T64 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 219 T64 C3 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 219 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4] 2> 220 T64 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 2
[junit4:junit4] 2> 228 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 229 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> 229 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4] 2> 230 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1acf3d64 main
[junit4:junit4] 2> 230 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 230 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1acf3d64 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4] 2> 230 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 231 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 231 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 231 T65 C3 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> 231 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 232 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1acf3d64 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4] 2> 232 T64 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4:junit4] 2> 661 T64 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4] 2> 661 T64 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4] 2> 662 T64 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4] 2> 662 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 674 T64 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 680 T64 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4] 2> 681 T64 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4] 2> 738 T64 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4] 2> 739 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 753 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_3,generation=3,filenames=[_0.fnm, _0.tim, _0.fdx, _0.si, _0.doc, _0.tip, _0.fdt, segments_3]
[junit4:junit4] 2> 759 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4] 2> 764 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1ac16992 main
[junit4:junit4] 2> 764 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 764 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 765 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ac16992 main{StandardDirectoryReader(segments_3:5 _0(4.1):C20)}
[junit4:junit4] 2> 765 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 765 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 765 T65 C3 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> 766 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 766 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ac16992 main{StandardDirectoryReader(segments_3:5 _0(4.1):C20)}
[junit4:junit4] 2> 766 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 767 T64 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4] 2> 767 T64 C3 oashd.DocBuilder.execute Time taken = 0:0:0.104
[junit4:junit4] 2> 767 T64 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=true&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%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&command=full-import&synchronous=true} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 108
[junit4:junit4] 2> 777 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=*:*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=9
[junit4:junit4] 2> 780 T64 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4] 2> 799 T64 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4] 2> 799 T64 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4] 2> 800 T64 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4] 2> 801 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 802 T64 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4] 2> 804 T64 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4] 2> 805 T64 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4] 2> 805 T64 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4] 2> 832 T64 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 9
[junit4:junit4] 2> 833 T64 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 3
[junit4:junit4] 2> 833 T64 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4] 2> 834 T64 C3 oashd.DocBuilder.deleteAll Deleting stale documents
[junit4:junit4] 2> 835 T64 C3 oashd.SolrWriter.deleteDoc Deleting document: 12
[junit4:junit4] 2> 841 T64 C3 oashd.SolrWriter.deleteDoc Deleting document: 14
[junit4:junit4] 2> 841 T64 C3 oashd.SolrWriter.deleteDoc Deleting document: 5
[junit4:junit4] 2> 845 T64 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4] 2> 846 T64 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4] 2> 846 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 901 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_3,generation=3,filenames=[_0.fnm, _0.tim, _0.fdx, _0.si, _0.doc, _0.tip, _0.fdt, segments_3]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_4,generation=4,filenames=[_1.doc, _0.fnm, _0_1.del, _1.fnm, _1.fdx, _1.fdt, _1.si, _0.tim, _0.fdx, _0.si, _1.tip, _0.tip, _0.doc, _1.tim, _0.fdt, segments_4]
[junit4:junit4] 2> 902 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4] 2> 908 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1ac64e37 main
[junit4:junit4] 2> 908 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 909 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 910 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ac64e37 main{StandardDirectoryReader(segments_4:8 _0(4.1):C20/11 _1(4.1):C9)}
[junit4:junit4] 2> 911 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=1
[junit4:junit4] 2> 911 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 911 T65 C3 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> 911 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 912 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ac64e37 main{StandardDirectoryReader(segments_4:8 _0(4.1):C20/11 _1(4.1):C9)}
[junit4:junit4] 2> 912 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 913 T64 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4] 2> 913 T64 C3 oashd.DocBuilder.execute Time taken = 0:0:0.112
[junit4:junit4] 2> 914 T64 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=false&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%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&command=delta-import&synchronous=true} {delete=[12, 14, 5],add=[11, 13, 17, 2, 18, 1002, 3, 6, 7],commit=} 0 117
[junit4:junit4] 2> 915 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=*:*&qt=standard&rows=20&version=2.2} hits=18 status=0 QTime=1
[junit4:junit4] 2> 917 T64 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4] 2> 925 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:1002&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=6
[junit4:junit4] 2> 928 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:5&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 931 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:12&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 934 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:14&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 936 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:17&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 942 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:2&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 960 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:18&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 963 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:3&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 966 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:6&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 969 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:7&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 972 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:11&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 975 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:13&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 978 T64 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/collection1/conf/dataimport.properties
[junit4:junit4] 2> 978 T64 oas.SolrTestCaseJ4.tearDown ###Ending testSingleEntity
[junit4:junit4] 2> 993 T64 oas.SolrTestCaseJ4.setUp ###Starting testWithSimpleTransformer
[junit4:junit4] 2> 997 T64 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 1000 T64 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 2
[junit4:junit4] 2> 1005 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 1006 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_4,generation=4,filenames=[_1.doc, _0.fnm, _0_1.del, _1.fnm, _1.fdx, _1.fdt, _1.si, _0.tim, _0.fdx, _0.si, _1.tip, _0.tip, _0.doc, _1.tim, _0.fdt, segments_4]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4] 2> 1006 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4] 2> 1009 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1ad71a38 main
[junit4:junit4] 2> 1010 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 1011 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 1011 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ad71a38 main{StandardDirectoryReader(segments_5:9)}
[junit4:junit4] 2> 1011 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1012 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=1
[junit4:junit4] 2> 1012 T65 C3 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> 1012 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 1012 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ad71a38 main{StandardDirectoryReader(segments_5:9)}
[junit4:junit4] 2> 1013 T64 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 9
[junit4:junit4] 2> 1026 T64 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4] 2> 1027 T64 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4] 2> 1027 T64 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4] 2> 1027 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1028 T64 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 1029 T64 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4] 2> 1029 T64 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4] 2> 1045 T64 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4] 2> 1045 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 1051 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_6,generation=6,filenames=[_2.si, _2.tip, _2.fdt, _2.fdx, segments_6, _2.doc, _2.tim, _2.fnm]
[junit4:junit4] 2> 1051 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4] 2> 1053 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1ae9da76 main
[junit4:junit4] 2> 1053 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 1054 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 1054 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ae9da76 main{StandardDirectoryReader(segments_6:12 _2(4.1):C20)}
[junit4:junit4] 2> 1054 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1054 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1055 T65 C3 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> 1055 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 1055 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ae9da76 main{StandardDirectoryReader(segments_6:12 _2(4.1):C20)}
[junit4:junit4] 2> 1055 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1056 T64 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4] 2> 1056 T64 C3 oashd.DocBuilder.execute Time taken = 0:0:0.28
[junit4:junit4] 2> 1056 T64 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=true&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=full-import&synchronous=true} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 31
[junit4:junit4] 2> 1057 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=AddAColumn_s:Added&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=0
[junit4:junit4] 2> 1060 T64 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4] 2> 1062 T64 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4] 2> 1063 T64 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4] 2> 1063 T64 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4] 2> 1063 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1064 T64 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4] 2> 1064 T64 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4] 2> 1064 T64 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4] 2> 1064 T64 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4] 2> 1065 T64 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 9
[junit4:junit4] 2> 1065 T64 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 2
[junit4:junit4] 2> 1066 T64 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4] 2> 1066 T64 C3 oashd.DocBuilder.deleteAll Deleting stale documents
[junit4:junit4] 2> 1066 T64 C3 oashd.SolrWriter.deleteDoc Deleting document: 18
[junit4:junit4] 2> 1067 T64 C3 oashd.SolrWriter.deleteDoc Deleting document: 2
[junit4:junit4] 2> 1069 T64 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4] 2> 1069 T64 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4] 2> 1069 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 1088 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_6,generation=6,filenames=[_2.si, _2.tip, _2.fdt, _2.fdx, segments_6, _2.doc, _2.tim, _2.fnm]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_7,generation=7,filenames=[_2.si, _3.tim, _2.fdt, _3.doc, _3.fdt, _3.fnm, _2.fdx, _2.doc, _2.fnm, _2.tim, _3.fdx, _2.tip, segments_7, _3.si, _3.tip, _2_1.del]
[junit4:junit4] 2> 1088 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 7
[junit4:junit4] 2> 1092 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1af33397 main
[junit4:junit4] 2> 1092 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 1092 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 1094 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1af33397 main{StandardDirectoryReader(segments_7:15 _2(4.1):C20/9 _3(4.1):C9)}
[junit4:junit4] 2> 1094 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1095 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1095 T65 C3 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> 1096 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 1096 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1af33397 main{StandardDirectoryReader(segments_7:15 _2(4.1):C20/9 _3(4.1):C9)}
[junit4:junit4] 2> 1097 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1097 T64 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4] 2> 1098 T64 C3 oashd.DocBuilder.execute Time taken = 0:0:0.34
[junit4:junit4] 2> 1098 T64 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=false&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=delta-import&synchronous=true} {delete=[18, 2],add=[1001, 7, 12, 13, 1013, 11, 10, 20, 5],commit=} 0 37
[junit4:junit4] 2> 1099 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=AddAColumn_s:Added&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=0
[junit4:junit4] 2> 1101 T64 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4] 2> 1102 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:1001&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1103 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:1013&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1112 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:2&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1114 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:18&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1117 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:5&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1121 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:20&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1125 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:7&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1128 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:10&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1
[junit4:junit4] 2> 1130 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:11&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1133 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:12&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1135 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:13&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1139 T64 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/collection1/conf/dataimport.properties
[junit4:junit4] 2> 1139 T64 oas.SolrTestCaseJ4.tearDown ###Ending testWithSimpleTransformer
[junit4:junit4] 2> 1154 T64 oas.SolrTestCaseJ4.setUp ###Starting testChildEntities
[junit4:junit4] 2> 1157 T64 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 1160 T64 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 3
[junit4:junit4] 2> 1165 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 1166 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_7,generation=7,filenames=[_2.si, _3.tim, _2.fdt, _3.doc, _3.fdt, _3.fnm, _2.fdx, _2.doc, _2.fnm, _2.tim, _3.fdx, _2.tip, segments_7, _3.si, _3.tip, _2_1.del]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4] 2> 1167 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 8
[junit4:junit4] 2> 1170 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1adc725c main
[junit4:junit4] 2> 1170 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 1171 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 1171 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1adc725c main{StandardDirectoryReader(segments_8:16)}
[junit4:junit4] 2> 1172 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1172 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1172 T65 C3 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> 1173 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 1173 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1adc725c main{StandardDirectoryReader(segments_8:16)}
[junit4:junit4] 2> 1173 T64 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4:junit4] 2> 1181 T64 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4] 2> 1181 T64 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4] 2> 1181 T64 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4] 2> 1182 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1182 T64 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 1183 T64 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4] 2> 1184 T64 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4] 2> 1184 T64 C3 oashd.JdbcDataSource$1.call Creating a connection for entity Countries with URL: jdbc:hsqldb:mem:.
[junit4:junit4] 2> 1184 T64 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4] 2> 1189 T64 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4] 2> 1190 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 1195 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_9,generation=9,filenames=[_4.fdx, _4.doc, segments_9, _4.tim, _4.fnm, _4.si, _4.fdt, _4.tip]
[junit4:junit4] 2> 1196 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 9
[junit4:junit4] 2> 1197 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1ae3f307 main
[junit4:junit4] 2> 1198 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 1198 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 1199 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ae3f307 main{StandardDirectoryReader(segments_9:19 _4(4.1):C20)}
[junit4:junit4] 2> 1199 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1200 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1200 T65 C3 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> 1200 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 1200 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ae3f307 main{StandardDirectoryReader(segments_9:19 _4(4.1):C20)}
[junit4:junit4] 2> 1201 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1201 T64 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4] 2> 1202 T64 C3 oashd.DocBuilder.execute Time taken = 0:0:0.20
[junit4:junit4] 2> 1202 T64 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=true&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+"+deltaImportQuery%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+where+ID%3D${dih.delta.ID}+"+deltaQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+last_modified+%26gt;%3D'${dih.last_index_time}'+UNION+DISTINCT+SELECT+ID+FROM+PEOPLE+WHERE+DELETED!%3D'Y'+AND+COUNTRY_CODE+IN+(SELECT+CODE+FROM+COUNTRIES+WHERE+last_modified+%26gt;%3D'${dih.last_index_time}')+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a<entity+name%3D"Countries"+pk%3D"Countries.CODE"+dataSource%3D"hsqldb"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+CODE,+COUNTRY_NAME+FROM+COUNTRIES+WHERE+DELETED+!%3D+'Y'+AND+CODE%3D'${People.COUNTRY_CODE}'+"+>+%0a<field+column%3D"CODE"+name%3D"COUNTRY_CODE_s"+/>+%0a<field+column%3D"COUNTRY_NAME"+name%3D"COUNTRY_NAME_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=full-import&synchronous=true} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 23
[junit4:junit4] 2> 1203 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=*:*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=0
[junit4:junit4] 2> 1205 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=AddAColumn_s:Added&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=0
[junit4:junit4] 2> 1209 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=NAME_mult_s:Jayden&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1216 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=COUNTRY_CODES_mult_s:NR&qt=standard&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4:junit4] 2> 1225 T64 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4] 2> 1225 T64 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4] 2> 1230 T64 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4] 2> 1230 T64 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4] 2> 1231 T64 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4] 2> 1232 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1233 T64 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4] 2> 1233 T64 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: Countries
[junit4:junit4] 2> 1233 T64 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: Countries rows obtained : 0
[junit4:junit4] 2> 1234 T64 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: Countries rows obtained : 0
[junit4:junit4] 2> 1234 T64 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: Countries
[junit4:junit4] 2> 1235 T64 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4] 2> 1236 T64 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4] 2> 1237 T64 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4] 2> 1238 T64 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 8
[junit4:junit4] 2> 1238 T64 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 0
[junit4:junit4] 2> 1238 T64 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4] 2> 1239 T64 C3 oashd.JdbcDataSource$1.call Creating a connection for entity Countries with URL: jdbc:hsqldb:mem:.
[junit4:junit4] 2> 1239 T64 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4] 2> 1242 T64 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4] 2> 1242 T64 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4] 2> 1242 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 1261 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_9,generation=9,filenames=[_4.fdx, _4.doc, segments_9, _4.tim, _4.fnm, _4.si, _4.fdt, _4.tip]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_a,generation=10,filenames=[_5.fdt, _5.fnm, segments_a, _4.tim, _4.fnm, _5.doc, _4.fdt, _4.si, _4.doc, _4.fdx, _5.si, _5.tip, _5.tim, _5.fdx, _4_1.del, _4.tip]
[junit4:junit4] 2> 1261 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 10
[junit4:junit4] 2> 1265 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1ac07fc6 main
[junit4:junit4] 2> 1265 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 1265 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 1267 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ac07fc6 main{StandardDirectoryReader(segments_a:22 _4(4.1):C20/8 _5(4.1):C8)}
[junit4:junit4] 2> 1268 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1268 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1268 T65 C3 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> 1269 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 1269 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ac07fc6 main{StandardDirectoryReader(segments_a:22 _4(4.1):C20/8 _5(4.1):C8)}
[junit4:junit4] 2> 1270 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1270 T64 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4] 2> 1270 T64 C3 oashd.DocBuilder.execute Time taken = 0:0:0.38
[junit4:junit4] 2> 1271 T64 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=false&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%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"Countries"+pk%3D"CODE"+dataSource%3D"hsqldb"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+CODE,+COUNTRY_NAME+FROM+COUNTRIES+WHERE+DELETED+!%3D+'Y'+AND+CODE%3D'${People.COUNTRY_CODE}'+"+>+%0a<field+column%3D"CODE"+name%3D"COUNTRY_CODE_s"+/>+%0a<field+column%3D"COUNTRY_NAME"+name%3D"COUNTRY_NAME_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&command=delta-import&synchronous=true} {add=[8, 9, 13, 14, 19, 2, 4, 5],commit=} 0 43
[junit4:junit4] 2> 1272 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=*:*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=1
[junit4:junit4] 2> 1274 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=NAME_mult_s:Jayden&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1277 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=COUNTRY_CODES_mult_s:NR&qt=standard&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4:junit4] 2> 1280 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=COUNTRY_CODE_s:NF&qt=standard&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4:junit4] 2> 1283 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=COUNTRY_CODE_s:NG&qt=standard&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4:junit4] 2> 1287 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=COUNTRY_CODE_s:NU&qt=standard&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4:junit4] 2> 1290 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=COUNTRY_CODE_s:NP&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1293 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=COUNTRY_CODE_s:NO&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 1295 T64 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/collection1/conf/dataimport.properties
[junit4:junit4] 2> 1296 T64 oas.SolrTestCaseJ4.tearDown ###Ending testChildEntities
[junit4:junit4] 2> 1318 T64 oas.SolrTestCaseJ4.setUp ###Starting testWithComplexTransformer
[junit4:junit4] 2> 1321 T64 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 1324 T64 C3 UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 3
[junit4:junit4] 2> 1329 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 1330 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_a,generation=10,filenames=[_5.fdt, _5.fnm, segments_a, _4.tim, _4.fnm, _5.doc, _4.fdt, _4.si, _4.doc, _4.fdx, _5.si, _5.tip, _5.tim, _5.fdx, _4_1.del, _4.tip]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_b,generation=11,filenames=[segments_b]
[junit4:junit4] 2> 1330 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 11
[junit4:junit4] 2> 1333 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1ac839a4 main
[junit4:junit4] 2> 1333 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 1334 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 1334 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ac839a4 main{StandardDirectoryReader(segments_b:23)}
[junit4:junit4] 2> 1334 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1335 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1335 T65 C3 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> 1335 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 1335 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ac839a4 main{StandardDirectoryReader(segments_b:23)}
[junit4:junit4] 2> 1336 T64 C3 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4:junit4] 2> 1340 T64 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4] 2> 1341 T64 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4] 2> 1341 T64 C3 oashd.DataImporter.doFullImport Starting Full Import
[junit4:junit4] 2> 1341 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1342 T64 C3 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 1343 T64 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4] 2> 1343 T64 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4] 2> 1369 T64 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4] 2> 1369 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 1385 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_b,generation=11,filenames=[segments_b]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_c,generation=12,filenames=[segments_c, _6.tip, _6.doc, _6.tim, _6.fdx, _6.si, _6.fnm, _6.fdt]
[junit4:junit4] 2> 1385 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 12
[junit4:junit4] 2> 1387 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1acf28b5 main
[junit4:junit4] 2> 1387 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 1388 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 1390 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1acf28b5 main{StandardDirectoryReader(segments_c:26 _6(4.1):C60)}
[junit4:junit4] 2> 1390 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1390 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1390 T65 C3 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> 1391 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 1391 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1acf28b5 main{StandardDirectoryReader(segments_c:26 _6(4.1):C60)}
[junit4:junit4] 2> 1391 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1392 T64 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4] 2> 1392 T64 C3 oashd.DocBuilder.execute Time taken = 0:0:0.51
[junit4:junit4] 2> 1392 T64 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=true&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"TripleThreatTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+deletedPkQuery%3D"SELECT+ID+FROM+PEOPLE+WHERE+DELETED%3D'Y'+AND+last_modified+%26gt;%3D'${dih.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&command=full-import&synchronous=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 53
[junit4:junit4] 2> 1471 T64 C3 REQ [collection1] webapp=null path=null params={sort=id+asc&q=*:*&rows=60} hits=60 status=0 QTime=78
[junit4:junit4] 2> 1482 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:TripleThreat-1-*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=6
[junit4:junit4] 2> 1488 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:TripleThreat-2-*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=1
[junit4:junit4] 2> 1491 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=AddAColumn_s:Added&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=0
[junit4:junit4] 2> 1493 T64 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4] 2> 1496 T64 C3 oashdc.ConfigParseUtil.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4:junit4] 2> 1496 T64 C3 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4:junit4] 2> 1496 T64 C3 oashd.DataImporter.doDeltaImport Starting Delta Import
[junit4:junit4] 2> 1497 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1497 T64 C3 oashd.DocBuilder.doDelta Starting delta collection.
[junit4:junit4] 2> 1497 T64 C3 oashd.DocBuilder.collectDelta Running ModifiedRowKey() for Entity: People
[junit4:junit4] 2> 1498 T64 C3 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4:junit4] 2> 1498 T64 C3 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4:junit4] 2> 1499 T64 C3 oashd.DocBuilder.collectDelta Completed ModifiedRowKey for Entity: People rows obtained : 18
[junit4:junit4] 2> 1499 T64 C3 oashd.DocBuilder.collectDelta Completed DeletedRowKey for Entity: People rows obtained : 2
[junit4:junit4] 2> 1499 T64 C3 oashd.DocBuilder.collectDelta Completed parentDeltaQuery for Entity: People
[junit4:junit4] 2> 1500 T64 C3 oashd.DocBuilder.deleteAll Deleting stale documents
[junit4:junit4] 2> 1500 T64 C3 oashd.SolrWriter.deleteDoc Deleting document: 12
[junit4:junit4] 2> 1500 T64 C3 oashd.SolrWriter.deleteDoc Deleting document: 14
[junit4:junit4] 2> 1510 T64 C3 oashd.DocBuilder.doDelta Delta Import completed successfully
[junit4:junit4] 2> 1510 T64 C3 oashd.DocBuilder.finish Import completed successfully
[junit4:junit4] 2> 1511 T64 C3 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 1534 T64 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_c,generation=12,filenames=[segments_c, _6.tip, _6.doc, _6.tim, _6.fdx, _6.si, _6.fnm, _6.fdt]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ac211ae lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abcc2eb),segFN=segments_d,generation=13,filenames=[segments_d, _6.fdx, _7.si, _7.fdx, _6_1.del, _7.fdt, _7.fnm, _6.tip, _6.doc, _6.tim, _7.tim, _6.fnm, _6.si, _6.fdt, _7.doc, _7.tip]
[junit4:junit4] 2> 1534 T64 C3 oasc.SolrDeletionPolicy.updateCommits newest commit = 13
[junit4:junit4] 2> 1537 T64 C3 oass.SolrIndexSearcher.<init> Opening Searcher@1acc1d91 main
[junit4:junit4] 2> 1538 T64 C3 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4] 2> 1538 T64 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 1542 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1acc1d91 main{StandardDirectoryReader(segments_d:29 _6(4.1):C60/56 _7(4.1):C54)}
[junit4:junit4] 2> 1542 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=solr&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1542 T65 C3 REQ [collection1] webapp=null path=null params={start=0&event=newSearcher&q=rocks&distrib=false&rows=10} hits=0 status=0 QTime=0
[junit4:junit4] 2> 1542 T65 C3 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> 1542 T65 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4] 2> 1543 T65 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1acc1d91 main{StandardDirectoryReader(segments_d:29 _6(4.1):C60/56 _7(4.1):C54)}
[junit4:junit4] 2> 1543 T64 C3 oashd.SimplePropertiesWriter.readIndexerProperties Read dataimport.properties
[junit4:junit4] 2> 1544 T64 C3 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4:junit4] 2> 1544 T64 C3 oashd.DocBuilder.execute Time taken = 0:0:0.47
[junit4:junit4] 2> 1544 T64 C3 UPDATE [collection1] webapp=null path=null params={indent=true&clean=false&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%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&command=delta-import&synchronous=true} {delete=[12, 14],add=[17, TripleThreat-1-17, TripleThreat-2-17, 16, TripleThreat-1-16, TripleThreat-2-16, 19, TripleThreat-1-19, TripleThreat-2-19, 18, ... (54 adds)],commit=} 0 50
[junit4:junit4] 2> 1546 T64 C3 REQ [collection1] webapp=null path=null params={sort=id+asc&q=*:*&rows=54} hits=58 status=0 QTime=1
[junit4:junit4] 2> 1550 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:TripleThreat-1-*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=1
[junit4:junit4] 2> 1552 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=id:TripleThreat-2-*&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=0
[junit4:junit4] 2> 1554 T64 C3 REQ [collection1] webapp=null path=null params={start=0&q=AddAColumn_s:Added&qt=standard&rows=20&version=2.2} hits=20 status=0 QTime=0
[junit4:junit4] 2> 1556 T64 C3 REQ [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4:junit4] 2> 1557 T64 oashd.AbstractDataImportHandlerTestCase.tearDown Looking for dataimport.properties at: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-dataimporthandler/test/J1/solr/collection1/conf/dataimport.properties
[junit4:junit4] 2> 1557 T64 oas.SolrTestCaseJ4.tearDown ###Ending testWithComplexTransformer
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSqlEntityProcessorDelta -Dtests.method=testWithComplexTransformer -Dtests.seed=21286DB5AB8C9BB3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=el -Dtests.timezone=Africa/Bangui -Dtests.file.encoding=US-ASCII
[junit4:junit4] FAILURE 0.34s J1 | TestSqlEntityProcessorDelta.testWithComplexTransformer <<<
[junit4:junit4] > Throwable #1: junit.framework.AssertionFailedError
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([21286DB5AB8C9BB3:4B221C3EC07A950E]:0)
[junit4:junit4] > at junit.framework.Assert.fail(Assert.java:48)
[junit4:junit4] > at junit.framework.Assert.assertTrue(Assert.java:20)
[junit4:junit4] > at junit.framework.Assert.assertTrue(Assert.java:27)
[junit4:junit4] > at org.apache.solr.handler.dataimport.AbstractDIHJdbcTestCase.complexTransform(AbstractDIHJdbcTestCase.java:129)
[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:39)
[junit4:junit4] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4] > at java.lang.reflect.Method.invoke(Method.java:597)
[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:738)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:774)
[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:683)
[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:40)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:44)
[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:56)
[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:662)
[junit4:junit4] 2> 1642 T64 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> 1642 T64 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=450741201
[junit4:junit4] 2> 1642 T64 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1ac1f69a
[junit4:junit4] 2> 1643 T64 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=200,cumulative_deletesById=7,cumulative_deletesByQuery=8,cumulative_errors=0}
[junit4:junit4] 2> 1643 T64 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 1643 T64 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 1643 T64 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 1647 T64 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> NOTE: test params are: codec=Asserting, sim=DefaultSimilarity, locale=el, timezone=Africa/Bangui
[junit4:junit4] 2> NOTE: Linux 3.2.0-32-generic i386/Oracle Corporation 1.6.0_33 (32-bit)/cpus=8,threads=1,free=39952648,total=67108864
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestFileListEntityProcessor, TestXPathEntityProcessor, TestJdbcDataSource, TestLineEntityProcessor, TestFieldReader, TestTemplateString, TestTemplateTransformer, TestScriptTransformer, TestContentStreamDataSource, TestErrorHandling, TestSqlEntityProcessorDelta]
[junit4:junit4] Completed on J1 in 1.73s, 4 tests, 1 failure <<< FAILURES!
[...truncated 55 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:335: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:192: The following 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:1163: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:827: There were test failures: 33 suites, 138 tests, 1 failure, 5 ignored
Total time: 41 minutes 53 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jrockit-jdk1.6.0_33-R28.2.4-4.1.0 -XnoOpt
Email was triggered for: Failure
Sending email for trigger: Failure