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...@thetaphi.de> on 2014/08/10 09:57:09 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_11) - Build #
4240 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4240/
Java: 32bit/jdk1.8.0_11 -server -XX:+UseSerialGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestSqlEntityProcessor
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSqlEntityProcessor: 1) Thread[id=18, name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessor] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:502) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSqlEntityProcessor:
1) Thread[id=18, name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessor]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:502)
at java.util.TimerThread.mainLoop(Timer.java:526)
at java.util.TimerThread.run(Timer.java:505)
at __randomizedtesting.SeedInfo.seed([164D5C7DA1C92A86]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestSqlEntityProcessor
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=18, name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessor] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:502) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=18, name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessor]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:502)
at java.util.TimerThread.mainLoop(Timer.java:526)
at java.util.TimerThread.run(Timer.java:505)
at __randomizedtesting.SeedInfo.seed([164D5C7DA1C92A86]:0)
Build Log:
[...truncated 15136 lines...]
[junit4] Suite: org.apache.solr.handler.dataimport.TestSqlEntityProcessor
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001
[junit4] 2> 12037 T17 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true)
[junit4] 2> 14238 T17 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 14238 T17 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001\collection1\'
[junit4] 2> 14305 T17 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 14338 T17 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4] 2> 14339 T17 oass.IndexSchema.readSchema Reading Solr Schema from dataimport-schema.xml
[junit4] 2> 14347 T17 oass.IndexSchema.readSchema [null] Schema name=dih_test
[junit4] 2> 14370 T17 oass.IndexSchema.readSchema default search field in schema is desc
[junit4] 2> 14373 T17 oass.IndexSchema.readSchema query parser default operator is OR
[junit4] 2> 14374 T17 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 14374 T17 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 14377 T17 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001
[junit4] 2> 14377 T17 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001\'
[junit4] 2> 14427 T17 oasc.CoreContainer.<init> New CoreContainer 26977015
[junit4] 2> 14427 T17 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001\]
[junit4] 2> 14428 T17 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 14428 T17 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 14428 T17 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 14428 T17 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 14429 T17 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 14430 T17 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 14430 T17 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 14430 T17 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 14431 T17 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 14431 T17 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 14431 T17 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 14432 T17 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 14433 T17 oasc.CoreContainer.load Host Name:
[junit4] 2> 14437 T19 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001\collection1/'
[junit4] 2> 14479 T19 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 14501 T19 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4] 2> 14502 T19 oass.IndexSchema.readSchema Reading Solr Schema from dataimport-schema.xml
[junit4] 2> 14513 T19 oass.IndexSchema.readSchema [collection1] Schema name=dih_test
[junit4] 2> 14537 T19 oass.IndexSchema.readSchema default search field in schema is desc
[junit4] 2> 14538 T19 oass.IndexSchema.readSchema query parser default operator is OR
[junit4] 2> 14538 T19 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 14539 T19 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001\collection1/
[junit4] 2> 14540 T19 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 14541 T19 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001\collection1/, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\
[junit4] 2> 14542 T19 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 14542 T19 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=solr,start=0,rows=10}, {q=rocks,start=0,rows=10}, {q=static newSearcher warming query from solrconfig.xml}]}
[junit4] 2> 14543 T19 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 14550 T19 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\
[junit4] 2> 14551 T19 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index/
[junit4] 2> 14551 T19 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index' doesn't exist. Creating new index...
[junit4] 2> 14552 T19 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index
[junit4] 2> 14557 T19 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_1,generation=1}
[junit4] 2> 14557 T19 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 14558 T19 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "" (default)
[junit4] 2> 14559 T19 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
[junit4] 2> 14560 T19 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 14560 T19 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 14560 T19 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 14561 T19 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 14561 T19 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 14561 T19 oasc.RequestHandlers.initHandlersFromConfig created /dataimport: org.apache.solr.handler.dataimport.DataImportHandler
[junit4] 2> 14563 T19 oasc.RequestHandlers.initHandlersFromConfig created /search: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 14563 T19 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 14563 T19 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 14569 T19 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 14571 T19 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 14573 T19 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 14576 T19 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 14578 T19 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 14579 T19 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 14579 T19 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 14581 T19 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_1,generation=1}
[junit4] 2> 14581 T19 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 14582 T19 oass.SolrIndexSearcher.<init> Opening Searcher@e6006a[collection1] main
[junit4] 2> 14583 T19 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001\collection1\conf
[junit4] 2> 14584 T19 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001\collection1\conf}
[junit4] 2> 14584 T19 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001\collection1\conf
[junit4] 2> 14586 T19 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 14587 T19 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\core-home-001\collection1\conf
[junit4] 2> 14587 T19 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 14588 T19 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 14590 T17 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 14598 T17 oas.SolrTestCaseJ4.setUp ###Starting testCachedChildEntities
[junit4] 2> 14599 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@e6006a[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 14599 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 14600 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e6006a[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> ASYNC NEW_CORE C5 name=collection1 org.apache.solr.core.SolrCore@14f3105
[junit4] 2> 14804 T17 C5 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 14809 T17 C5 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 204
[junit4] 2> 14818 T17 C5 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 14820 T17 C5 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_2,generation=2}
[junit4] 2> 14821 T17 C5 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 14821 T17 C5 oass.SolrIndexSearcher.<init> Opening Searcher@db22e8[collection1] main
[junit4] 2> 14821 T17 C5 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 14822 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@db22e8[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 14827 T20 C5 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=4
[junit4] 2> 14828 T20 C5 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 14829 T20 C5 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 14830 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 14830 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@db22e8[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 14831 T17 C5 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4] 2> ASYNC NEW_CORE C6 name=collection1 org.apache.solr.core.SolrCore@14f3105
[junit4] 2> 18186 T17 C6 oashdc.DIHConfiguration.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4] 2> 18186 T17 C6 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4] 2> 18187 T17 C6 oashd.DataImporter.doFullImport Starting Full Import
[junit4] 2> 18189 T17 C6 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 18317 T17 C6 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [CachedSqlEntityProcessor]. Please consult documentation how to replace it accordingly.
[junit4] 2> 18318 T17 C6 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 18328 T17 C6 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 18331 T17 C6 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4] 2> ASYNC NEW_CORE C7 name=collection1 org.apache.solr.core.SolrCore@14f3105
[junit4] 2> 18500 T17 C7 oashd.JdbcDataSource$1.call Creating a connection for entity Countries with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 18502 T17 C7 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4] 2> 18674 T17 C7 oashd.DocBuilder.finish Import completed successfully
[junit4] 2> 18675 T17 C7 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18684 T17 C7 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_3,generation=3}
[junit4] 2> 18684 T17 C7 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 18687 T17 C7 oass.SolrIndexSearcher.<init> Opening Searcher@1750042[collection1] main
[junit4] 2> 18687 T17 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 18689 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1750042[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):c20))}
[junit4] 2> 18690 T20 C7 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 18691 T20 C7 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 18691 T20 C7 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 18693 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 18693 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1750042[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):c20))}
[junit4] 2> 18695 T17 C7 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 18697 T17 C7 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 18697 T17 C7 oashd.DocBuilder.execute Time taken = 0:0:0.549
[junit4] 2> 18698 T17 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+>+%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"derby"+transformer%3D"AddAColumnTransformer"+newColumnName%3D"countryAdded_s"+newColumnValue%3D"country_added"+processor%3D"CachedSqlEntityProcessor"+where%3D"CODE%3DPeople.COUNTRY_CODE"+query%3D"SELECT+CODE,+COUNTRY_NAME+FROM+COUNTRIES"+>+%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&clean=true&command=full-import} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 516
[junit4] 2> ASYNC NEW_CORE C8 name=collection1 org.apache.solr.core.SolrCore@14f3105
[junit4] 2> 18799 T17 C8 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=20 status=0 QTime=2
[junit4] 2> 18873 T17 C8 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=NAME_mult_s:Jayden&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=3
[junit4] 2> 18924 T17 C8 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=COUNTRY_CODES_mult_s:NR&qt=standard&start=0&rows=20&version=2.2} hits=2 status=0 QTime=1
[junit4] 2> 18934 T17 C8 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=countryAdded_s:country_added&qt=standard&start=0&rows=20&version=2.2} hits=20 status=0 QTime=5
[junit4] 2> 18949 T17 C8 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4] 2> 18950 T17 C8 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4] 2> 19513 T17 oas.SolrTestCaseJ4.tearDown ###Ending testCachedChildEntities
[junit4] 2> 19522 T17 oas.SolrTestCaseJ4.setUp ###Starting testChildEntities
[junit4] 2> 19525 T17 C8 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 19526 T17 C8 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 19528 T17 C8 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 19529 T17 C8 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_4,generation=4}
[junit4] 2> 19530 T17 C8 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 19530 T17 C8 oass.SolrIndexSearcher.<init> Opening Searcher@1115819[collection1] main
[junit4] 2> 19532 T17 C8 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 19533 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1115819[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 19533 T20 C8 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 19534 T20 C8 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 19534 T20 C8 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 19534 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 19535 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1115819[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 19537 T17 C8 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
[junit4] 2> ASYNC NEW_CORE C9 name=collection1 org.apache.solr.core.SolrCore@14f3105
[junit4] 2> 20197 T17 C9 oashdc.DIHConfiguration.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4] 2> 20197 T17 C9 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4] 2> 20200 T17 C9 oashd.DataImporter.doFullImport Starting Full Import
[junit4] 2> 20202 T17 C9 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 20203 T17 C9 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 20204 T17 C9 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:hsqldb:mem:.
[junit4] 2> 20204 T17 C9 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4] 2> 20213 T17 C9 oashd.JdbcDataSource$1.call Creating a connection for entity Countries with URL: jdbc:hsqldb:mem:.
[junit4] 2> 20213 T17 C9 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4] 2> 20238 T17 C9 oashd.DocBuilder.finish Import completed successfully
[junit4] 2> 20239 T17 C9 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 20247 T17 C9 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_5,generation=5}
[junit4] 2> 20248 T17 C9 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 20252 T17 C9 oass.SolrIndexSearcher.<init> Opening Searcher@1341a7f[collection1] main
[junit4] 2> 20252 T17 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 20254 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1341a7f[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0):c20))}
[junit4] 2> 20255 T20 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 20255 T20 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 20256 T20 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 20257 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 20257 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1341a7f[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0):c20))}
[junit4] 2> 20259 T17 C9 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 20259 T17 C9 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 20260 T17 C9 oashd.DocBuilder.execute Time taken = 0:0:0.63
[junit4] 2> 20261 T17 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"hsqldb"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+>+%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"+transformer%3D"AddAColumnTransformer"+newColumnName%3D"countryAdded_s"+newColumnValue%3D"country_added"+processor%3D"SqlEntityProcessor"+query%3D"SELECT+CODE,+COUNTRY_NAME+FROM+COUNTRIES+WHERE+DELETED+!%3D+'Y'+AND+CODE%3D'${People.COUNTRY_CODE}'+"+>+%0a<field+column%3D"CODE"+name%3D"COUNTRY_CODE_s"+/>+%0a<field+column%3D"COUNTRY_NAME"+name%3D"COUNTRY_NAME_s"+/>+%0a</entity>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&clean=true&command=full-import} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 70
[junit4] 2> 20278 T17 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=20 status=0 QTime=3
[junit4] 2> 20314 T17 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=AddAColumn_s:Added&qt=standard&start=0&rows=20&version=2.2} hits=20 status=0 QTime=6
[junit4] 2> 20321 T17 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=NAME_mult_s:Jayden&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 20328 T17 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=COUNTRY_CODES_mult_s:NR&qt=standard&start=0&rows=20&version=2.2} hits=2 status=0 QTime=1
[junit4] 2> 20339 T17 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=countryAdded_s:country_added&qt=standard&start=0&rows=20&version=2.2} hits=20 status=0 QTime=4
[junit4] 2> 20348 T17 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4] 2> 20349 T17 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4] 2> 20363 T17 oas.SolrTestCaseJ4.tearDown ###Ending testChildEntities
[junit4] 2> 20386 T17 oas.SolrTestCaseJ4.setUp ###Starting testWithSimpleTransformer
[junit4] 2> 20390 T17 C9 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 20390 T17 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 20393 T17 C9 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 20394 T17 C9 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_6,generation=6}
[junit4] 2> 20395 T17 C9 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 20395 T17 C9 oass.SolrIndexSearcher.<init> Opening Searcher@30d684[collection1] main
[junit4] 2> 20396 T17 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 20396 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@30d684[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 20397 T20 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 20398 T20 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 20398 T20 C9 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 20399 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 20399 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@30d684[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 20401 T17 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4] 2> ASYNC NEW_CORE C10 name=collection1 org.apache.solr.core.SolrCore@14f3105
[junit4] 2> 20877 T17 C10 oashdc.DIHConfiguration.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4] 2> 20878 T17 C10 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4] 2> 20879 T17 C10 oashd.DataImporter.doFullImport Starting Full Import
[junit4] 2> 20882 T17 C10 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 20882 T17 C10 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 20884 T17 C10 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 20884 T17 C10 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4] 2> 20915 T17 C10 oashd.DocBuilder.finish Import completed successfully
[junit4] 2> 20915 T17 C10 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 20930 T17 C10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_7,generation=7}
[junit4] 2> 20930 T17 C10 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 20936 T17 C10 oass.SolrIndexSearcher.<init> Opening Searcher@68bd2[collection1] main
[junit4] 2> 20937 T17 C10 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 20941 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@68bd2[collection1] main{UninvertingDirectoryReader(Uninverting(_2(5.0):c20))}
[junit4] 2> 20941 T20 C10 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 20942 T20 C10 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 20943 T20 C10 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 20943 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 20943 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@68bd2[collection1] main{UninvertingDirectoryReader(Uninverting(_2(5.0):c20))}
[junit4] 2> 20943 T17 C10 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 20943 T17 C10 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 20945 T17 C10 oashd.DocBuilder.execute Time taken = 0:0:0.67
[junit4] 2> 20945 T17 C10 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+transformer%3D"AddAColumnTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&clean=true&command=full-import} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 74
[junit4] 2> 20961 T17 C10 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=AddAColumn_s:Added&qt=standard&start=0&rows=20&version=2.2} hits=20 status=0 QTime=5
[junit4] 2> 20968 T17 C10 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true} status=0 QTime=1
[junit4] 2> 20969 T17 C10 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4] 2> 21450 T17 oas.SolrTestCaseJ4.tearDown ###Ending testWithSimpleTransformer
[junit4] 2> 21492 T17 oas.SolrTestCaseJ4.setUp ###Starting testWithComplexTransformer
[junit4] 2> 21498 T17 C10 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 21498 T17 C10 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 2
[junit4] 2> 21499 T17 C10 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 21501 T17 C10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_8,generation=8}
[junit4] 2> 21501 T17 C10 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 21501 T17 C10 oass.SolrIndexSearcher.<init> Opening Searcher@170f4ab[collection1] main
[junit4] 2> 21502 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@170f4ab[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 21503 T20 C10 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 21524 T20 C10 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=21
[junit4] 2> 21527 T20 C10 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 21527 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 21528 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@170f4ab[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 21530 T17 C10 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 21530 T17 C10 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 31
[junit4] 2> ASYNC NEW_CORE C11 name=collection1 org.apache.solr.core.SolrCore@14f3105
[junit4] 2> 21765 T17 C11 oashdc.DIHConfiguration.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4] 2> 21766 T17 C11 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4] 2> 21766 T17 C11 oashd.DataImporter.doFullImport Starting Full Import
[junit4] 2> 21768 T17 C11 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 21769 T17 C11 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 21770 T17 C11 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 21770 T17 C11 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4] 2> 21893 T17 C11 oashd.DocBuilder.finish Import completed successfully
[junit4] 2> 21893 T17 C11 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 21910 T17 C11 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_9,generation=9}
[junit4] 2> 21911 T17 C11 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 21914 T17 C11 oass.SolrIndexSearcher.<init> Opening Searcher@eedbaa[collection1] main
[junit4] 2> 21920 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@eedbaa[collection1] main{UninvertingDirectoryReader(Uninverting(_3(5.0):c60))}
[junit4] 2> 21921 T20 C11 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 21922 T20 C11 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 21923 T20 C11 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 21923 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 21923 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@eedbaa[collection1] main{UninvertingDirectoryReader(Uninverting(_3(5.0):c60))}
[junit4] 2> 21924 T17 C11 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 21924 T17 C11 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 21924 T17 C11 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 21925 T17 C11 oashd.DocBuilder.execute Time taken = 0:0:0.167
[junit4] 2> 21925 T17 C11 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"People.ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+transformer%3D"TripleThreatTransformer"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&clean=true&command=full-import} {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 168
[junit4] 2> 22275 T17 C11 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&sort=id+asc&rows=60} hits=60 status=0 QTime=323
[junit4] 2> 22291 T17 C11 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:TripleThreat-1-*&qt=standard&start=0&rows=20&version=2.2} hits=20 status=0 QTime=2
[junit4] 2> 22302 T17 C11 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:TripleThreat-2-*&qt=standard&start=0&rows=20&version=2.2} hits=20 status=0 QTime=2
[junit4] 2> ASYNC NEW_CORE C12 name=collection1 org.apache.solr.core.SolrCore@14f3105
[junit4] 2> 22357 T17 C12 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=%2Bid:TripleThreat-1-3+%2BNAME_mult_s:Michael+%2BNAME_mult_s:leahciM++%2BCOUNTRY_CODES_mult_s:NR+%2BCOUNTRY_CODES_mult_s:RN&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=12
[junit4] 2> 22359 T17 C12 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=AddAColumn_s:Added&qt=standard&start=0&rows=20&version=2.2} hits=20 status=0 QTime=0
[junit4] 2> 22372 T17 C12 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4] 2> 22372 T17 C12 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4] 2> 22856 T17 oas.SolrTestCaseJ4.tearDown ###Ending testWithComplexTransformer
[junit4] 2> 22883 T17 oas.SolrTestCaseJ4.setUp ###Starting testSingleEntity
[junit4] 2> 22890 T17 C12 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 22890 T17 C12 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 22891 T17 C12 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 22892 T17 C12 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_a,generation=10}
[junit4] 2> 22892 T17 C12 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 22893 T17 C12 oass.SolrIndexSearcher.<init> Opening Searcher@217905[collection1] main
[junit4] 2> 22894 T17 C12 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 22898 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@217905[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 22898 T20 C12 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 22899 T20 C12 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 22900 T20 C12 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 22900 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 22900 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@217905[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 22904 T17 C12 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4] 2> ASYNC NEW_CORE C13 name=collection1 org.apache.solr.core.SolrCore@14f3105
[junit4] 2> 23065 T17 C13 oashdc.DIHConfiguration.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4] 2> 23065 T17 C13 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4] 2> 23065 T17 C13 oashd.DataImporter.doFullImport Starting Full Import
[junit4] 2> 23069 T17 C13 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 23069 T17 C13 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 23070 T17 C13 oashd.JdbcDataSource$1.call Creating a connection for entity People with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 23072 T17 C13 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4] 2> 23087 T17 C13 oashd.DocBuilder.finish Import completed successfully
[junit4] 2> 23087 T17 C13 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 23089 T17 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@156b7de lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index),segFN=segments_b,generation=11}
[junit4] 2> 23089 T17 C13 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 23091 T17 C13 oass.SolrIndexSearcher.<init> Opening Searcher@4c72ed[collection1] main
[junit4] 2> 23091 T17 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 23098 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4c72ed[collection1] main{UninvertingDirectoryReader(Uninverting(_4(5.0):c20))}
[junit4] 2> 23099 T20 C13 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 23099 T20 C13 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 23100 T20 C13 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 23100 T20 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 23100 T20 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4c72ed[collection1] main{UninvertingDirectoryReader(Uninverting(_4(5.0):c20))}
[junit4] 2> 23101 T17 C13 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 23101 T17 C13 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 23102 T17 C13 oashd.DocBuilder.execute Time taken = 0:0:0.32
[junit4] 2> 23103 T17 C13 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={indent=true&synchronous=true&commit=true&dataConfig=%0a<dataConfig>+%0a<propertyWriter+type%3D"SimplePropertiesWriter"+directory%3D"C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"hsqldb"+driver%3D"org.hsqldb.jdbcDriver"+url%3D"jdbc:hsqldb:mem:."+/>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSqlEntityProcessor">+%0a<entity+name%3D"People"+pk%3D"ID"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+query%3D"SELECT+ID,+NAME,+COUNTRY_CODE+FROM+PEOPLE+WHERE+DELETED+!%3D+'Y'+"+>+%0a<field+column%3D"NAME"+name%3D"NAME_mult_s"+/>+%0a<field+column%3D"COUNTRY_CODE"+name%3D"COUNTRY_CODES_mult_s"+/>+%0a</entity>+%0a</document>+%0a</dataConfig>+%0a&clean=true&command=full-import} {deleteByQuery=*:*,add=[1, 2, 3, 4, 5, 6, 7, 8, 9, 10, ... (20 adds)],commit=} 0 45
[junit4] 2> 23113 T17 C13 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=20 status=0 QTime=3
[junit4] 2> 23117 T17 C13 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4] 2> 23118 T17 C13 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true} status=0 QTime=0
[junit4] 2> 23636 T17 oas.SolrTestCaseJ4.tearDown ###Ending testSingleEntity
[junit4] 2> 23735 T17 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 23735 T17 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=26977015
[junit4] 2> 23736 T17 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@14f3105
[junit4] 2> 23736 T17 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=10,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=140,cumulative_deletesById=0,cumulative_deletesByQuery=10,cumulative_errors=0}
[junit4] 2> 23736 T17 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 23736 T17 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 23736 T17 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 23740 T17 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 23742 T17 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 23742 T17 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index;done=false>>]
[junit4] 2> 23743 T17 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\index
[junit4] 2> 23743 T17 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\;done=false>>]
[junit4] 2> 23744 T17 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSqlEntityProcessor-164D5C7DA1C92A86-001\init-core-data-001\
[junit4] 2> 11937 T16 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> 32006 T16 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSqlEntityProcessor:
[junit4] 2> 1) Thread[id=18, name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessor]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] 2> at java.util.TimerThread.run(Timer.java:505)
[junit4] 2> 32007 T16 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=18, name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessor]
[junit4] 2> 35007 T16 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=18, name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessor]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] 2> at java.util.TimerThread.run(Timer.java:505)
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=it_CH, timezone=Asia/Jakarta
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_11 (32-bit)/cpus=2,threads=2,free=16221456,total=47816704
[junit4] 2> NOTE: All tests run in this JVM: [TestFileListWithLineEntityProcessor, TestSqlEntityProcessor]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSqlEntityProcessor -Dtests.seed=164D5C7DA1C92A86 -Dtests.slow=true -Dtests.locale=it_CH -Dtests.timezone=Asia/Jakarta -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s | TestSqlEntityProcessor (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSqlEntityProcessor:
[junit4] > 1) Thread[id=18, name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessor]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Object.wait(Object.java:502)
[junit4] > at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] > at java.util.TimerThread.run(Timer.java:505)
[junit4] > at __randomizedtesting.SeedInfo.seed([164D5C7DA1C92A86]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=18, name=Timer-0, state=WAITING, group=TGRP-TestSqlEntityProcessor]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Object.wait(Object.java:502)
[junit4] > at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] > at java.util.TimerThread.run(Timer.java:505)
[junit4] > at __randomizedtesting.SeedInfo.seed([164D5C7DA1C92A86]:0)
[junit4] Completed in 35.41s, 6 tests, 2 errors, 1 skipped <<< FAILURES!
[...truncated 114 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:474: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:454: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:209: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:440: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1298: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:922: There were test failures: 37 suites, 148 tests, 2 suite-level errors, 138 ignored
Total time: 157 minutes 41 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_11 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_20-ea-b23) -
Build # 4242 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4242/
Java: 32bit/jdk1.8.0_20-ea-b23 -client -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestNestedChildren
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestNestedChildren: 1) Thread[id=77, name=Timer-0, state=WAITING, group=TGRP-TestNestedChildren] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:502) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestNestedChildren:
1) Thread[id=77, name=Timer-0, state=WAITING, group=TGRP-TestNestedChildren]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:502)
at java.util.TimerThread.mainLoop(Timer.java:526)
at java.util.TimerThread.run(Timer.java:505)
at __randomizedtesting.SeedInfo.seed([73B1D5EA76876E2F]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestNestedChildren
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=77, name=Timer-0, state=WAITING, group=TGRP-TestNestedChildren] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:502) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=77, name=Timer-0, state=WAITING, group=TGRP-TestNestedChildren]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:502)
at java.util.TimerThread.mainLoop(Timer.java:526)
at java.util.TimerThread.run(Timer.java:505)
at __randomizedtesting.SeedInfo.seed([73B1D5EA76876E2F]:0)
Build Log:
[...truncated 15020 lines...]
[junit4] Suite: org.apache.solr.handler.dataimport.TestNestedChildren
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001
[junit4] 2> 38430 T76 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 40581 T76 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 40581 T76 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001\collection1\'
[junit4] 2> 40671 T76 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 40700 T76 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4] 2> 40702 T76 oass.IndexSchema.readSchema Reading Solr Schema from dataimport-schema.xml
[junit4] 2> 40711 T76 oass.IndexSchema.readSchema [null] Schema name=dih_test
[junit4] 2> 40751 T76 oass.IndexSchema.readSchema default search field in schema is desc
[junit4] 2> 40753 T76 oass.IndexSchema.readSchema query parser default operator is OR
[junit4] 2> 40757 T76 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 40759 T76 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 40759 T76 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001
[junit4] 2> 40759 T76 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001\'
[junit4] 2> 40843 T3 oashd.JdbcDataSource.finalize ERROR JdbcDataSource was not closed prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!!
[junit4] 2> 40843 T3 oashd.JdbcDataSource.finalize ERROR JdbcDataSource was not closed prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!!
[junit4] 2> 40861 T76 oasc.CoreContainer.<init> New CoreContainer 23558811
[junit4] 2> 40861 T76 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001\]
[junit4] 2> 40863 T76 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 40863 T76 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 40863 T76 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 40864 T76 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 40864 T76 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 40864 T76 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 40864 T76 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 40864 T76 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 40865 T76 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 40879 T76 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 40884 T76 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 40884 T76 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 40884 T76 oasc.CoreContainer.load Host Name:
[junit4] 2> 40892 T78 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001\collection1/'
[junit4] 2> 40941 T78 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 40964 T78 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4] 2> 40964 T78 oass.IndexSchema.readSchema Reading Solr Schema from dataimport-schema.xml
[junit4] 2> 40973 T78 oass.IndexSchema.readSchema [collection1] Schema name=dih_test
[junit4] 2> 40994 T78 oass.IndexSchema.readSchema default search field in schema is desc
[junit4] 2> 40994 T78 oass.IndexSchema.readSchema query parser default operator is OR
[junit4] 2> 40996 T78 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 40996 T78 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001\collection1/
[junit4] 2> 40997 T78 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 40998 T78 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001\collection1/, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\
[junit4] 2> 40998 T78 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 41000 T78 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=solr,start=0,rows=10}, {q=rocks,start=0,rows=10}, {q=static newSearcher warming query from solrconfig.xml}]}
[junit4] 2> 41000 T78 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 41001 T78 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\
[junit4] 2> 41002 T78 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index/
[junit4] 2> 41002 T78 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index' doesn't exist. Creating new index...
[junit4] 2> 41003 T78 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index
[junit4] 2> 41007 T78 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ff4e2a lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index),segFN=segments_1,generation=1}
[junit4] 2> 41007 T78 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 41009 T78 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "" (default)
[junit4] 2> 41009 T78 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
[junit4] 2> 41010 T78 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 41010 T78 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 41010 T78 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 41012 T78 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 41012 T78 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 41013 T78 oasc.RequestHandlers.initHandlersFromConfig created /dataimport: org.apache.solr.handler.dataimport.DataImportHandler
[junit4] 2> 41013 T78 oasc.RequestHandlers.initHandlersFromConfig created /search: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 41013 T78 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 41014 T78 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 41020 T78 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 41022 T78 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 41024 T78 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 41025 T78 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 41027 T78 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 41030 T78 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 41031 T78 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 41031 T78 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ff4e2a lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index),segFN=segments_1,generation=1}
[junit4] 2> 41032 T78 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 41032 T78 oass.SolrIndexSearcher.<init> Opening Searcher@12c63d9[collection1] main
[junit4] 2> 41033 T78 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001\collection1\conf
[junit4] 2> 41034 T78 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001\collection1\conf}
[junit4] 2> 41034 T78 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001\collection1\conf
[junit4] 2> 41038 T78 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 41039 T78 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\core-home-001\collection1\conf
[junit4] 2> 41040 T78 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 41041 T79 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@12c63d9[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 41042 T79 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 41042 T79 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12c63d9[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 41042 T78 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 41047 T76 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 41054 T76 oas.SolrTestCaseJ4.setUp ###Starting test
[junit4] 2> ASYNC NEW_CORE C30 name=collection1 org.apache.solr.core.SolrCore@53c66
[junit4] 2> 41088 T76 C30 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 41090 T76 C30 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 28
[junit4] 2> 41097 T76 C30 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 41098 T76 C30 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ff4e2a lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ff4e2a lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index),segFN=segments_2,generation=2}
[junit4] 2> 41100 T76 C30 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 41101 T76 C30 oass.SolrIndexSearcher.<init> Opening Searcher@1b53254[collection1] main
[junit4] 2> 41101 T76 C30 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 41102 T79 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1b53254[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 41103 T79 C30 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 41106 T79 C30 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 41111 T79 C30 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=4
[junit4] 2> 41111 T79 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 41111 T79 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b53254[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 41112 T76 C30 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 16
[junit4] 2> ASYNC NEW_CORE C31 name=collection1 org.apache.solr.core.SolrCore@53c66
[junit4] 2> 43026 T76 C31 oashdc.DIHConfiguration.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4] 2> 43027 T76 C31 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4] 2> 43027 T76 C31 oashd.DataImporter.doFullImport Starting Full Import
[junit4] 2> 43031 T76 C31 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: dataimport.properties
[junit4] 2> 43032 T76 C31 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 43043 T76 C31 oashd.JdbcDataSource$1.call Creating a connection for entity FIRST with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 43045 T76 C31 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4] 2> ASYNC NEW_CORE C32 name=collection1 org.apache.solr.core.SolrCore@53c66
[junit4] 2> 43940 T76 C32 oashd.JdbcDataSource$1.call Creating a connection for entity SECOND with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 43943 T76 C32 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4] 2> 43976 T76 C32 oashd.JdbcDataSource$1.call Creating a connection for entity THIRD with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 43976 T76 C32 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4] 2> 43984 T76 C32 oashd.DocBuilder.finish Import completed successfully
[junit4] 2> 43986 T76 C32 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 43988 T76 C32 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ff4e2a lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ff4e2a lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index),segFN=segments_3,generation=3}
[junit4] 2> 43989 T76 C32 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 43990 T76 C32 oass.SolrIndexSearcher.<init> Opening Searcher@53ba81[collection1] main
[junit4] 2> 43992 T76 C32 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 43992 T79 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@53ba81[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C1))}
[junit4] 2> 43993 T79 C32 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=solr&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=1
[junit4] 2> 43993 T79 C32 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=rocks&distrib=false&start=0&rows=10&event=newSearcher} hits=0 status=0 QTime=0
[junit4] 2> 43995 T79 C32 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=static+newSearcher+warming+query+from+solrconfig.xml&distrib=false&event=newSearcher} hits=0 status=0 QTime=2
[junit4] 2> 43995 T79 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 43995 T79 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@53ba81[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C1))}
[junit4] 2> 43996 T76 C32 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: dataimport.properties
[junit4] 2> 43997 T76 C32 oashd.SimplePropertiesWriter.persist Wrote last indexed time to dataimport.properties
[junit4] 2> 43997 T76 C32 oashd.DocBuilder.execute Time taken = 0:0:1.96
[junit4] 2> 43998 T76 C32 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={indent=true&synchronous=true&commit=true&dataConfig=<dataConfig>+%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSimplePropertiesWriter">+%0a<entity+name%3D"FIRST"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"++query%3D"select+1+as+id,+'PORK'+as+FIRST_S+from+sysibm.sysdummy1+"+>%0a++<field+column%3D"FIRST_S"+name%3D"first_s"+/>+%0a++<entity+name%3D"SECOND"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"++++query%3D"select+1+as+id,+2+as+SECOND_ID,+'BEEF'+as+SECOND_S+from+sysibm.sysdummy1+WHERE+1%3D${FIRST.ID}"+>%0a+++<field+column%3D"SECOND_S"+name%3D"second_s"+/>+%0a+++<entity+name%3D"THIRD"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+++++query%3D"select+1+as+id,+'CHICKEN'+as+THIRD_S+from+sysibm.sysdummy1+WHERE+2%3D${SECOND.SECOND_ID}"+>%0a++++<field+column%3D"THIRD_S"+name%3D"third_s"+/>+%0a+++</entity>%0a++</entity>%0a</entity>%0a</document>+%0a</dataConfig>+%0a&clean=true&command=full-import} {deleteByQuery=*:*,add=[1],commit=} 0 977
[junit4] 2> 44000 T76 C32 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=1
[junit4] 2> 44002 T76 C32 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=third_s:CHICKEN&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=1
[junit4] 2> 44544 T76 oas.SolrTestCaseJ4.tearDown ###Ending test
[junit4] 2> 44620 T76 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 44620 T76 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=23558811
[junit4] 2> 44622 T76 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@53c66
[junit4] 2> 44623 T76 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=0}
[junit4] 2> 44623 T76 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 44623 T76 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 44623 T76 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 44627 T76 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 44627 T76 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 44627 T76 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\;done=false>>]
[junit4] 2> 44627 T76 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\
[junit4] 2> 44627 T76 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index;done=false>>]
[junit4] 2> 44629 T76 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestNestedChildren-73B1D5EA76876E2F-001\init-core-data-001\index
[junit4] 2> 6404 T75 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> 26549 T75 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestNestedChildren:
[junit4] 2> 1) Thread[id=77, name=Timer-0, state=WAITING, group=TGRP-TestNestedChildren]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] 2> at java.util.TimerThread.run(Timer.java:505)
[junit4] 2> 26549 T75 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=77, name=Timer-0, state=WAITING, group=TGRP-TestNestedChildren]
[junit4] 2> 29552 T75 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=77, name=Timer-0, state=WAITING, group=TGRP-TestNestedChildren]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] 2> at java.util.TimerThread.run(Timer.java:505)
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=sr__#Latn, timezone=America/Scoresbysund
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_20-ea (32-bit)/cpus=2,threads=2,free=3173952,total=28839936
[junit4] 2> NOTE: All tests run in this JVM: [TestContextImpl, TestSolrEntityProcessorUnit, TestContentStreamDataSource, TestEphemeralCache, TestClobTransformer, TestVariableResolver, TestEntityProcessorBase, TestDocBuilder2, TestFileListWithLineEntityProcessor, TestDocBuilder, TestJdbcDataSource, TestURLDataSource, TestFieldReader, TestBuiltInEvaluators, TestNestedChildren]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestNestedChildren -Dtests.seed=73B1D5EA76876E2F -Dtests.slow=true -Dtests.locale=sr__#Latn -Dtests.timezone=America/Scoresbysund -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s | TestNestedChildren (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestNestedChildren:
[junit4] > 1) Thread[id=77, name=Timer-0, state=WAITING, group=TGRP-TestNestedChildren]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Object.wait(Object.java:502)
[junit4] > at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] > at java.util.TimerThread.run(Timer.java:505)
[junit4] > at __randomizedtesting.SeedInfo.seed([73B1D5EA76876E2F]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=77, name=Timer-0, state=WAITING, group=TGRP-TestNestedChildren]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Object.wait(Object.java:502)
[junit4] > at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] > at java.util.TimerThread.run(Timer.java:505)
[junit4] > at __randomizedtesting.SeedInfo.seed([73B1D5EA76876E2F]:0)
[junit4] Completed in 29.67s, 1 test, 2 errors <<< FAILURES!
[...truncated 72 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:474: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:454: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:209: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:440: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1307: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:922: There were test failures: 37 suites, 148 tests, 2 suite-level errors, 92 ignored
Total time: 169 minutes 16 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_20-ea-b23 -client -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_65) - Build #
4241 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4241/
Java: 32bit/jdk1.7.0_65 -server -XX:+UseParallelGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestSimplePropertiesWriter
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSimplePropertiesWriter: 1) Thread[id=20, name=Timer-0, state=WAITING, group=TGRP-TestSimplePropertiesWriter] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:503) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSimplePropertiesWriter:
1) Thread[id=20, name=Timer-0, state=WAITING, group=TGRP-TestSimplePropertiesWriter]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at java.util.TimerThread.mainLoop(Timer.java:526)
at java.util.TimerThread.run(Timer.java:505)
at __randomizedtesting.SeedInfo.seed([14A22E60A8C4F43]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestSimplePropertiesWriter
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=20, name=Timer-0, state=WAITING, group=TGRP-TestSimplePropertiesWriter] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:503) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=20, name=Timer-0, state=WAITING, group=TGRP-TestSimplePropertiesWriter]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at java.util.TimerThread.mainLoop(Timer.java:526)
at java.util.TimerThread.run(Timer.java:505)
at __randomizedtesting.SeedInfo.seed([14A22E60A8C4F43]:0)
Build Log:
[...truncated 14940 lines...]
[junit4] Suite: org.apache.solr.handler.dataimport.TestSimplePropertiesWriter
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001
[junit4] 2> 12208 T19 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
[junit4] 2> 14531 T19 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 14531 T19 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001\collection1\'
[junit4] 2> 14796 T19 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 14864 T19 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4] 2> 14865 T19 oass.IndexSchema.readSchema Reading Solr Schema from dataimport-schema.xml
[junit4] 2> 14888 T19 oass.IndexSchema.readSchema [null] Schema name=dih_test
[junit4] 2> 14961 T19 oass.IndexSchema.readSchema default search field in schema is desc
[junit4] 2> 14964 T19 oass.IndexSchema.readSchema query parser default operator is OR
[junit4] 2> 14965 T19 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 14968 T19 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 14968 T19 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001
[junit4] 2> 14969 T19 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001\'
[junit4] 2> 15063 T19 oasc.CoreContainer.<init> New CoreContainer 33477971
[junit4] 2> 15065 T19 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001\]
[junit4] 2> 15066 T19 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 15067 T19 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 15067 T19 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 15067 T19 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 15068 T19 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 15068 T19 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 15069 T19 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 15069 T19 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 15069 T19 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 15080 T19 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 15084 T19 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 15085 T19 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 15085 T19 oasc.CoreContainer.load Host Name:
[junit4] 2> 15099 T21 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001\collection1/'
[junit4] 2> 15199 T21 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 15277 T21 oasc.SolrConfig.<init> Loaded SolrConfig: dataimport-solrconfig.xml
[junit4] 2> 15277 T21 oass.IndexSchema.readSchema Reading Solr Schema from dataimport-schema.xml
[junit4] 2> 15304 T21 oass.IndexSchema.readSchema [collection1] Schema name=dih_test
[junit4] 2> 15385 T21 oass.IndexSchema.readSchema default search field in schema is desc
[junit4] 2> 15386 T21 oass.IndexSchema.readSchema query parser default operator is OR
[junit4] 2> 15388 T21 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 15389 T21 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001\collection1/
[junit4] 2> 15390 T21 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 15391 T21 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001\collection1/, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\
[junit4] 2> 15391 T21 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 15392 T21 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=solr,start=0,rows=10}, {q=rocks,start=0,rows=10}, {q=static newSearcher warming query from solrconfig.xml}]}
[junit4] 2> 15392 T21 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 15400 T21 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\
[junit4] 2> 15401 T21 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index/
[junit4] 2> 15401 T21 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index' doesn't exist. Creating new index...
[junit4] 2> 15402 T21 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index
[junit4] 2> 15410 T21 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_1,generation=1}
[junit4] 2> 15412 T21 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 15416 T21 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "" (default)
[junit4] 2> 15529 T21 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "" (default)
[junit4] 2> 15529 T21 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 15531 T21 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 15531 T21 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 15532 T21 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 15532 T21 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 15533 T21 oasc.RequestHandlers.initHandlersFromConfig created /dataimport: org.apache.solr.handler.dataimport.DataImportHandler
[junit4] 2> 15533 T21 oasc.RequestHandlers.initHandlersFromConfig created /search: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 15534 T21 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 15534 T21 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 15546 T21 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 15549 T21 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 15553 T21 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 15557 T21 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 15559 T21 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 15565 T21 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 15566 T21 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 15569 T21 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_1,generation=1}
[junit4] 2> 15571 T21 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 15572 T21 oass.SolrIndexSearcher.<init> Opening Searcher@15a1d31[collection1] main
[junit4] 2> 15573 T21 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001\collection1\conf
[junit4] 2> 15574 T21 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001\collection1\conf}
[junit4] 2> 15575 T21 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001\collection1\conf
[junit4] 2> 15578 T21 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 15587 T21 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\core-home-001\collection1\conf
[junit4] 2> 15587 T21 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 15587 T21 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 15592 T19 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 15601 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@15a1d31[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 15603 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 15603 T22 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15a1d31[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 15602 T19 oas.SolrTestCaseJ4.setUp ###Starting testSimplePropertiesWriter
[junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@9c485c
[junit4] 2> 15637 T19 C1 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 15638 T19 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 25
[junit4] 2> 15655 T19 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 15658 T19 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_2,generation=2}
[junit4] 2> 15659 T19 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 15660 T19 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1676c8e[collection1] main
[junit4] 2> 15661 T19 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 15661 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1676c8e[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 15666 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=10&event=newSearcher&distrib=false&start=0&q=solr} hits=0 status=0 QTime=5
[junit4] 2> 15667 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=10&event=newSearcher&distrib=false&start=0&q=rocks} hits=0 status=0 QTime=1
[junit4] 2> 15667 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml} hits=0 status=0 QTime=0
[junit4] 2> 15667 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 15667 T22 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1676c8e[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 15669 T19 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
[junit4] 2> 17692 T19 oashd.SimplePropertiesWriter.readIndexerProperties WARN Unable to read: the.properties
[junit4] 2> 17694 T19 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 17710 T19 C1 oashdc.DIHConfiguration.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4] 2> 17711 T19 C1 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4] 2> 17716 T19 C1 oashd.DataImporter.doFullImport Starting Full Import
[junit4] 2> 17717 T19 C1 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 17783 T19 C1 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 17793 T19 C1 oashd.JdbcDataSource$1.call Creating a connection for entity SomeDates with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 17796 T19 C1 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4] 2> 18813 T19 C1 oashd.DocBuilder.finish Import completed successfully
[junit4] 2> 18814 T19 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18818 T19 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_3,generation=3}
[junit4] 2> 18819 T19 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 18821 T19 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1eafae1[collection1] main
[junit4] 2> 18822 T19 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 18823 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1eafae1[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C1))}
[junit4] 2> 18823 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=10&event=newSearcher&distrib=false&start=0&q=solr} hits=0 status=0 QTime=0
[junit4] 2> 18825 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=10&event=newSearcher&distrib=false&start=0&q=rocks} hits=0 status=0 QTime=2
[junit4] 2> 18826 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml} hits=0 status=0 QTime=1
[junit4] 2> 18826 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 18829 T22 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1eafae1[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C1))}
[junit4] 2> 18831 T19 C1 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 18831 T19 C1 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 18832 T19 C1 oashd.DocBuilder.execute Time taken = 0:0:1.135
[junit4] 2> 18833 T19 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={synchronous=true&dataConfig=<dataConfig>+%0a<propertyWriter+dateFormat%3D"{'ts'+''yyyy-MM-dd+HH:mm:ss.SSSSSS''}"+type%3D"SimplePropertiesWriter"+directory%3D"C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSimplePropertiesWriter">+%0a<entity+name%3D"SomeDates"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+query%3D"select+1+as+id,+YEAR(${dih.last_index_time})+as+AYEAR_S+from+sysibm.sysdummy1+"+>%0a<field+column%3D"AYEAR_S"+name%3D"ayear_s"+/>+%0a</entity>%0a</document>+%0a</dataConfig>+%0a&clean=true&indent=true&commit=true&command=full-import} {deleteByQuery=*:*,add=[1],commit=} 0 1136
[junit4] 2> 18836 T19 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 18862 T19 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&version=2.2&start=0&qt=standard&q=*:*} hits=1 status=0 QTime=4
[junit4] 2> 18883 T19 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 18884 T19 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 18894 T19 C1 oashdc.DIHConfiguration.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4] 2> 18895 T19 C1 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4] 2> 18895 T19 C1 oashd.DataImporter.doFullImport Starting Full Import
[junit4] 2> 18896 T19 C1 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 18898 T19 C1 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 18899 T19 C1 oashd.JdbcDataSource$1.call Creating a connection for entity SomeDates with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 18901 T19 C1 oashd.JdbcDataSource$1.call Time taken for getConnection(): 1
[junit4] 2> 18911 T19 C1 oashd.DocBuilder.finish Import completed successfully
[junit4] 2> 18912 T19 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18916 T19 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_4,generation=4}
[junit4] 2> 18916 T19 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 18968 T19 C1 oass.SolrIndexSearcher.<init> Opening Searcher@2e240a[collection1] main
[junit4] 2> 18977 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2e240a[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0):C1))}
[junit4] 2> 18978 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=10&event=newSearcher&distrib=false&start=0&q=solr} hits=0 status=0 QTime=1
[junit4] 2> 18980 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=10&event=newSearcher&distrib=false&start=0&q=rocks} hits=0 status=0 QTime=2
[junit4] 2> 18982 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml} hits=0 status=0 QTime=2
[junit4] 2> 18983 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 18982 T19 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 18984 T22 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2e240a[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0):C1))}
[junit4] 2> 18988 T19 C1 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 18988 T19 C1 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 18988 T19 C1 oashd.DocBuilder.execute Time taken = 0:0:0.93
[junit4] 2> 18991 T19 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={synchronous=true&dataConfig=<dataConfig>+%0a<propertyWriter+dateFormat%3D"{'ts'+''yyyy-MM-dd+HH:mm:ss''}"+type%3D"SimplePropertiesWriter"+directory%3D"C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSimplePropertiesWriter">+%0a<entity+name%3D"SomeDates"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+query%3D"select+1+as+id,+YEAR(${dih.last_index_time})+as+AYEAR_S+from+sysibm.sysdummy1+"+>%0a<field+column%3D"AYEAR_S"+name%3D"ayear_s"+/>+%0a</entity>%0a</document>+%0a</dataConfig>+%0a&clean=true&indent=true&commit=true&command=full-import} {deleteByQuery=*:*,add=[1],commit=} 0 107
[junit4] 2> 18992 T19 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 18996 T19 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&version=2.2&start=0&qt=standard&q=*:*} hits=1 status=0 QTime=1
[junit4] 2> 19002 T19 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 19002 T19 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 19017 T19 C1 oashdc.DIHConfiguration.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4] 2> 19017 T19 C1 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4] 2> 19017 T19 C1 oashd.DataImporter.doFullImport Starting Full Import
[junit4] 2> 19019 T19 C1 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 19021 T19 C1 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 19026 T19 C1 oashd.JdbcDataSource$1.call Creating a connection for entity SomeDates with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 19027 T19 C1 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4] 2> 19048 T19 C1 oashd.DocBuilder.finish Import completed successfully
[junit4] 2> 19048 T19 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 19051 T19 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_5,generation=5}
[junit4] 2> 19054 T19 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 19054 T19 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1f0d83f[collection1] main
[junit4] 2> 19055 T19 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 19057 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1f0d83f[collection1] main{UninvertingDirectoryReader(Uninverting(_2(5.0):C1))}
[junit4] 2> 19058 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=10&event=newSearcher&distrib=false&start=0&q=solr} hits=0 status=0 QTime=0
[junit4] 2> 19059 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=10&event=newSearcher&distrib=false&start=0&q=rocks} hits=0 status=0 QTime=1
[junit4] 2> 19059 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml} hits=0 status=0 QTime=0
[junit4] 2> 19059 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 19059 T22 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f0d83f[collection1] main{UninvertingDirectoryReader(Uninverting(_2(5.0):C1))}
[junit4] 2> 19062 T19 C1 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 19063 T19 C1 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 19063 T19 C1 oashd.DocBuilder.execute Time taken = 0:0:0.43
[junit4] 2> 19063 T19 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={synchronous=true&dataConfig=<dataConfig>+%0a<propertyWriter+dateFormat%3D"yyyy-MM-dd+HH:mm:ss"+type%3D"SimplePropertiesWriter"+directory%3D"C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSimplePropertiesWriter">+%0a<entity+name%3D"SomeDates"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+query%3D"select+1+as+id,+YEAR('${dih.last_index_time}')+as+AYEAR_S+from+sysibm.sysdummy1+"+>%0a<field+column%3D"AYEAR_S"+name%3D"ayear_s"+/>+%0a</entity>%0a</document>+%0a</dataConfig>+%0a&clean=true&indent=true&commit=true&command=full-import} {deleteByQuery=*:*,add=[1],commit=} 0 61
[junit4] 2> 19064 T19 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 19067 T19 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&version=2.2&start=0&qt=standard&q=*:*} hits=1 status=0 QTime=2
[junit4] 2> 19071 T19 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 19071 T19 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 19075 T19 C1 oashdc.DIHConfiguration.verifyWithSchema id is a required field in SolrSchema . But not found in DataConfig
[junit4] 2> 19075 T19 C1 oashd.DataImporter.loadDataConfig Data Configuration loaded successfully
[junit4] 2> 19075 T19 C1 oashd.DataImporter.doFullImport Starting Full Import
[junit4] 2> 19077 T19 C1 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 19078 T19 C1 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 19080 T19 C1 oashd.JdbcDataSource$1.call Creating a connection for entity SomeDates with URL: jdbc:derby:memory:derbyDB;territory=en_US
[junit4] 2> 19081 T19 C1 oashd.JdbcDataSource$1.call Time taken for getConnection(): 0
[junit4] 2> 19090 T19 C1 oashd.DocBuilder.finish Import completed successfully
[junit4] 2> 19091 T19 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 19094 T19 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@980278 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index),segFN=segments_6,generation=6}
[junit4] 2> 19094 T19 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 19097 T19 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5196be[collection1] main
[junit4] 2> 19097 T19 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 19098 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5196be[collection1] main{UninvertingDirectoryReader(Uninverting(_3(5.0):C1))}
[junit4] 2> 19099 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=10&event=newSearcher&distrib=false&start=0&q=solr} hits=0 status=0 QTime=1
[junit4] 2> 19100 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=10&event=newSearcher&distrib=false&start=0&q=rocks} hits=0 status=0 QTime=1
[junit4] 2> 19101 T22 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={event=newSearcher&distrib=false&q=static+newSearcher+warming+query+from+solrconfig.xml} hits=0 status=0 QTime=1
[junit4] 2> 19101 T22 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 19101 T22 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5196be[collection1] main{UninvertingDirectoryReader(Uninverting(_3(5.0):C1))}
[junit4] 2> 19105 T19 C1 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 19106 T19 C1 oashd.SimplePropertiesWriter.persist Wrote last indexed time to the.properties
[junit4] 2> 19106 T19 C1 oashd.DocBuilder.execute Time taken = 0:0:0.29
[junit4] 2> 19107 T19 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={synchronous=true&dataConfig=<dataConfig>+%0a<propertyWriter+dateFormat%3D"yyyy-MM-dd+HH:mm:ss.SSSSSS"+type%3D"SimplePropertiesWriter"+directory%3D"C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\tempDir-001"+filename%3D"the.properties"+/>%0a<dataSource+name%3D"derby"+driver%3D"org.apache.derby.jdbc.EmbeddedDriver"+url%3D"jdbc:derby:memory:derbyDB;territory%3Den_US"+/>+%0a<document+name%3D"TestSimplePropertiesWriter">+%0a<entity+name%3D"SomeDates"+processor%3D"SqlEntityProcessor"+dataSource%3D"derby"+query%3D"select+1+as+id,+YEAR('${dih.last_index_time}')+as+AYEAR_S+from+sysibm.sysdummy1+"+>%0a<field+column%3D"AYEAR_S"+name%3D"ayear_s"+/>+%0a</entity>%0a</document>+%0a</dataConfig>+%0a&clean=true&indent=true&commit=true&command=full-import} {deleteByQuery=*:*,add=[1],commit=} 0 36
[junit4] 2> 19109 T19 oashd.SimplePropertiesWriter.readIndexerProperties Read the.properties
[junit4] 2> 19113 T19 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&version=2.2&start=0&qt=standard&q=*:*} hits=1 status=0 QTime=1
[junit4] 2> 19715 T19 oas.SolrTestCaseJ4.tearDown ###Ending testSimplePropertiesWriter
[junit4] 2> 19793 T19 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 19794 T19 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=33477971
[junit4] 2> 19795 T19 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@9c485c
[junit4] 2> 19795 T19 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=5,cumulative_errors=0}
[junit4] 2> 19796 T19 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 19796 T19 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 19796 T19 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 19800 T19 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 19801 T19 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 19801 T19 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index;done=false>>]
[junit4] 2> 19802 T19 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\index
[junit4] 2> 19802 T19 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\;done=false>>]
[junit4] 2> 19803 T19 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\contrib\solr-dataimporthandler\test\J0\.\temp\solr.handler.dataimport.TestSimplePropertiesWriter-14A22E60A8C4F43-001\init-core-data-001\
[junit4] 2> 7801 T18 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> 27924 T18 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSimplePropertiesWriter:
[junit4] 2> 1) Thread[id=20, name=Timer-0, state=WAITING, group=TGRP-TestSimplePropertiesWriter]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:503)
[junit4] 2> at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] 2> at java.util.TimerThread.run(Timer.java:505)
[junit4] 2> 27926 T18 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=20, name=Timer-0, state=WAITING, group=TGRP-TestSimplePropertiesWriter]
[junit4] 2> 30932 T18 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=20, name=Timer-0, state=WAITING, group=TGRP-TestSimplePropertiesWriter]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:503)
[junit4] 2> at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] 2> at java.util.TimerThread.run(Timer.java:505)
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=fr_LU, timezone=America/Argentina/La_Rioja
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_65 (32-bit)/cpus=2,threads=2,free=66723016,total=112984064
[junit4] 2> NOTE: All tests run in this JVM: [TestXPathRecordReader, TestWriterImpl, TestSimplePropertiesWriter]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSimplePropertiesWriter -Dtests.seed=14A22E60A8C4F43 -Dtests.slow=true -Dtests.locale=fr_LU -Dtests.timezone=America/Argentina/La_Rioja -Dtests.file.encoding=Cp1252
[junit4] ERROR 0.00s | TestSimplePropertiesWriter (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSimplePropertiesWriter:
[junit4] > 1) Thread[id=20, name=Timer-0, state=WAITING, group=TGRP-TestSimplePropertiesWriter]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Object.wait(Object.java:503)
[junit4] > at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] > at java.util.TimerThread.run(Timer.java:505)
[junit4] > at __randomizedtesting.SeedInfo.seed([14A22E60A8C4F43]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=20, name=Timer-0, state=WAITING, group=TGRP-TestSimplePropertiesWriter]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Object.wait(Object.java:503)
[junit4] > at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] > at java.util.TimerThread.run(Timer.java:505)
[junit4] > at __randomizedtesting.SeedInfo.seed([14A22E60A8C4F43]:0)
[junit4] Completed in 31.06s, 1 test, 2 errors <<< FAILURES!
[...truncated 111 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:474: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:454: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:209: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:440: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1307: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:922: There were test failures: 37 suites, 148 tests, 2 suite-level errors, 120 ignored
Total time: 168 minutes 23 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.7.0_65 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any