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 22:19:02 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_65) - Build #
4241 - Still Failing!
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
[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