Another thing I have noted is that the exception always follows a commit
operation.Log excerpt below:
INFO: SolrDeletionPolicy.onCommit: commits:num=2
commit{dir=/opt/solr/cores/calls/data/index,segFN=segments_2qt,version=1347458723267,generation=3557,filenames=[_3z9.tii,
_3z3.fnm, _3z9.nrm, _3za.prx, _3z9.fdt, _3z9.fnm, _3z9.fdx, _3z3.frq,
_3za.nrm, segments_2qt, _3z3.fdx, _3z9.prx, _3z3.fdt, _3za.fdx, _3z9.frq,
_3z3.prx, _3za.fdt, _3z3.tii, _3za.tis, _3za.fnm, _3z3.nrm, _3z9.tis,
_3za.tii, _3za.frq, _3z3.tis]
commit{dir=/opt/solr/cores/calls/data/index,segFN=segments_2qu,version=1347458723269,generation=3558,filenames=[_3zb.fdt,
_3z9.tii, _3z3.fnm, _3z9.nrm, _3zb.tii, _3zb.tis, _3zb.fdx, _3za.prx,
_3z9.fdt, _3z9.fnm, _3z9.fdx, _3zb.frq, _3z3.frq, _3za.nrm, segments_2qu,
_3z3.fdx, _3zb.prx, _3z9.prx, _3zb.fnm, _3z3.fdt, _3za.fdx, _3z9.frq,
_3z3.prx, _3za.fdt, _3zb.nrm, _3z3.tii, _3za.tis, _3za.fnm, _3z3.nrm,
_3z9.tis, _3za.tii, _3za.frq, _3z3.tis]
May 24, 2014 5:49:05 AM org.apache.solr.core.SolrDeletionPolicy
updateCommits
INFO: newest commit = 1347458723269
May 24, 2014 5:49:05 AM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@423dbcca main
May 24, 2014 5:49:05 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@423dbcca main from Searcher@19c19869 main
fieldValueCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
May 24, 2014 5:49:05 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@423dbcca main
fieldValueCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
May 24, 2014 5:49:05 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@423dbcca main from Searcher@19c19869 main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
May 24, 2014 5:49:05 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@423dbcca main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
May 24, 2014 5:49:05 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@423dbcca main from Searcher@19c19869 main
queryResultCache{lookups=1,hits=1,hitratio=1.00,inserts=3,evictions=0,size=3,warmupTime=2,cumulative_lookups=47,cumulative_hits=46,cumulative_hitratio=0.97,cumulative_inserts=1,cumulative_evictions=0}
May 24, 2014 5:49:05 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@423dbcca main
queryResultCache{lookups=0,hits=0,hitratio=0.00,inserts=3,evictions=0,size=3,warmupTime=2,cumulative_lookups=47,cumulative_hits=46,cumulative_hitratio=0.97,cumulative_inserts=1,cumulative_evictions=0}
May 24, 2014 5:49:05 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@423dbcca main from Searcher@19c19869 main
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=40,evictions=0,size=40,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
May 24, 2014 5:49:05 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@423dbcca main
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
May 24, 2014 5:49:05 AM org.apache.solr.core.QuerySenderListener newSearcher
INFO: QuerySenderListener sending requests to Searcher@423dbcca main
May 24, 2014 5:49:05 AM org.apache.solr.core.SolrCore execute
INFO: [calls] webapp=null path=null
params={start=0&event=newSearcher&q=*:*&rows=20} hits=40028 status=0
QTime=2
May 24, 2014 5:49:05 AM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: end_commit_flush
May 24, 2014 5:49:05 AM org.apache.solr.core.SolrCore execute
INFO: [calls] webapp=null path=null
params={start=0&event=newSearcher&q=banking&rows=20} hits=636 status=0
QTime=3
May 24, 2014 5:49:05 AM org.apache.solr.core.QuerySenderListener newSearcher
INFO: QuerySenderListener done.
May 24, 2014 5:49:05 AM
org.apache.solr.handler.component.SpellCheckComponent$SpellCheckerListener
newSearcher
INFO: Index is not optimized therefore skipping building spell check index
for: default
May 24, 2014 5:49:05 AM org.apache.solr.core.SolrCore registerSearcher
INFO: [calls] Registered new searcher Searcher@423dbcca main
May 24, 2014 5:49:05 AM org.apache.solr.search.SolrIndexSearcher close
INFO: Closing Searcher@19c19869 main
fieldValueCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
queryResultCache{lookups=1,hits=1,hitratio=1.00,inserts=3,evictions=0,size=3,warmupTime=2,cumulative_lookups=47,cumulative_hits=46,cumulative_hitratio=0.97,cumulative_inserts=1,cumulative_evictions=0}
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=40,evictions=0,size=40,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
May 24, 2014 5:49:05 AM org.apache.solr.core.SolrDeletionPolicy onInit
INFO: SolrDeletionPolicy.onInit: commits:num=1
commit{dir=/opt/solr/cores/calls/data/index,segFN=segments_2qu,version=1347458723269,generation=3558,filenames=[_3zb.fdt,
_3z9.tii, _3z3.fnm, _3z9.nrm, _3zb.tii, _3zb.tis, _3zb.fdx, _3za.prx,
_3z9.fdt, _3z9.fnm, _3z9.fdx, _3zb.frq, _3z3.frq, _3za.nrm, segments_2qu,
_3z3.fdx, _3zb.prx, _3z9.prx, _3zb.fnm, _3z3.fdt, _3za.fdx, _3z9.frq,
_3z3.prx, _3za.fdt, _3zb.nrm, _3z3.tii, _3za.tis, _3za.fnm, _3z3.nrm,
_3z9.tis, _3za.tii, _3za.frq, _3z3.tis]
May 24, 2014 5:49:05 AM org.apache.solr.core.SolrDeletionPolicy
updateCommits
INFO: newest commit = 1347458723269
May 24, 2014 5:49:05 AM org.apache.solr.update.SolrIndexWriter <init>
FINE: Opened Writer DirectUpdateHandler2
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner runAThread
INFO: Generating record for Unique ID :AH_291731 attachment Ref:291731 parent
ref :192076 executed by thread:156
May 24, 2014 5:49:05 AM org.apache.solr.handler.dataimport.JdbcDataSource$1
call
INFO: Creating a connection for entity attachment_text with URL: null
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner runAThread
INFO: Generating record for Unique ID :AH_291749 attachment Ref:291749 parent
ref :192508 executed by thread:158
May 24, 2014 5:49:05 AM org.apache.solr.handler.dataimport.JdbcDataSource$1
call
INFO: Creating a connection for entity attachment_text with URL: null
May 24, 2014 5:49:05 AM org.apache.solr.handler.dataimport.JdbcDataSource$1
call
INFO: Time taken for getConnection(): 7
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator <init>
FINE: Executing SQL: select ref, object_title, CASE WHEN (hds_size >
20000000 OR hds_image IS NULL) THEN convert(varbinary(10),'') ELSE
hds_image END hds_image from hd_object with (nolock) where ref = '291731'
AND status = 'A'
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner runAThread
INFO: Generating record for Unique ID :AH_291752 attachment Ref:291752 parent
ref :192512 executed by thread:155
May 24, 2014 5:49:05 AM org.apache.solr.handler.dataimport.JdbcDataSource$1
call
INFO: Creating a connection for entity attachment_text with URL: null
May 24, 2014 5:49:05 AM org.apache.solr.handler.dataimport.JdbcDataSource$1
call
INFO: Time taken for getConnection(): 6
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator <init>
FINE: Executing SQL: select ref, object_title, CASE WHEN (hds_size >
20000000 OR hds_image IS NULL) THEN convert(varbinary(10),'') ELSE
hds_image END hds_image from hd_object with (nolock) where ref = '291749'
AND status = 'A'
May 24, 2014 5:49:05 AM org.apache.solr.handler.dataimport.JdbcDataSource$1
call
INFO: Time taken for getConnection(): 3
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator <init>
FINE: Executing SQL: select ref, object_title, CASE WHEN (hds_size >
20000000 OR hds_image IS NULL) THEN convert(varbinary(10),'') ELSE
hds_image END hds_image from hd_object with (nolock) where ref = '291752'
AND status = 'A'
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner runAThread
INFO: Generating record for Unique ID :AH_291776 attachment Ref:291776 parent
ref :192359 executed by thread:157
May 24, 2014 5:49:05 AM org.apache.solr.handler.dataimport.JdbcDataSource$1
call
INFO: Creating a connection for entity attachment_text with URL: null
May 24, 2014 5:49:05 AM
com.mckinsey.vsm.dataimport.transformer.AttachmentExclusionTransformer
transformRow
INFO: In AttachmentExclusionTransformer transformRow REF:291731
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner runAThread
INFO: Generating record for Unique ID :null attachment Ref:291731 parent
ref :null executed by thread:156
May 24, 2014 5:49:05 AM
com.mckinsey.vsm.dataimport.transformer.AttachmentExclusionTransformer
transformRow
INFO: In AttachmentExclusionTransformer transformRow REF:291752
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner runAThread
INFO: Generating record for Unique ID :null attachment Ref:291752 parent
ref :null executed by thread:155
May 24, 2014 5:49:05 AM
com.mckinsey.vsm.dataimport.transformer.AttachmentExclusionTransformer
transformRow
INFO: In AttachmentExclusionTransformer transformRow REF:291749
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner runAThread
INFO: Generating record for Unique ID :null attachment Ref:291749 parent
ref :null executed by thread:158
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner runAThread
INFO: Generating record for Unique ID :null attachment Ref:null parent ref
:null executed by thread:156
May 24, 2014 5:49:05 AM
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner runAThread
INFO: Generating record for Unique ID :null attachment Ref:null parent ref
:null executed by thread:155
May 24, 2014 5:49:06 AM
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner runAThread
INFO: Generating record for Unique ID :null attachment Ref:null parent ref
:null executed by thread:158
May 24, 2014 5:49:06 AM org.apache.solr.handler.dataimport.JdbcDataSource$1
call
INFO: Time taken for getConnection(): 909
May 24, 2014 5:49:06 AM
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator <init>
FINE: Executing SQL: select ref, object_title, CASE WHEN (hds_size >
20000000 OR hds_image IS NULL) THEN convert(varbinary(10),'') ELSE
hds_image END hds_image from hd_object with (nolock) where ref = '291776'
AND status = 'A'
May 24, 2014 5:49:06 AM org.apache.solr.update.processor.LogUpdateProcessor
processAdd
FINE: add AH_291749
May 24, 2014 5:49:06 AM
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator
closeResources
SEVERE: Can not close connection
java.sql.SQLException: Already closed.
at
org.apache.tomcat.dbcp.dbcp.PoolableConnection.close(PoolableConnection.java:84)
at
org.apache.tomcat.dbcp.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.close(PoolingDataSource.java:189)
at
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator.closeResources(Unknown
Source)
at
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator.hasnext(Unknown
Source)
at
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator.access$500(Unknown
Source)
at
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator$1.hasNext(Unknown
Source)
at org.apache.solr.handler.dataimport.EntityProcessorBase.getNext(Unknown
Source)
at org.apache.solr.handler.dataimport.SqlEntityProcessor.nextRow(Unknown
Source)
at
org.apache.solr.handler.dataimport.ThreadedEntityProcessorWrapper.nextRow(Unknown
Source)
at
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner.runAThread(Unknown
Source)
at
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner.access$000(Unknown
Source)
at org.apache.solr.handler.dataimport.DocBuilder$EntityRunner$1.run(Unknown
Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
May 24, 2014 5:49:06 AM org.apache.solr.common.SolrException log
SEVERE: getNext() failed for query 'select obj_type, unique_id, ref,
CAST(parent_ref AS varchar(30)) parent_ref, object_title, object_abstract,
customer_ref, customer, officer_ref, officer, charge_code_name,
charge_code_description, created_date, display_date, time_changed,
current_group_ref, current_group, pager_number, pager_number, unit,
service_line, confidential_flag, stakeholder_ref_list, parent_title,
hds_table,officer_role, object_security_profile from srch_attachment_v
where hds_table IN ('CL_CALL_LOGGING','KB_ENTRY','CR_REQUEST') and (ref %
3) = 2':org.apache.solr.handler.dataimport.DataImportHandlerException:
com.microsoft.sqlserver.jdbc.SQLServerException: The result set is closed.
at
org.apache.solr.handler.dataimport.DataImportHandlerException.wrapAndThrow(Unknown
Source)
at
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator.hasnext(Unknown
Source)
at
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator.access$500(Unknown
Source)
at
org.apache.solr.handler.dataimport.JdbcDataSource$ResultSetIterator$1.hasNext(Unknown
Source)
at org.apache.solr.handler.dataimport.EntityProcessorBase.getNext(Unknown
Source)
at org.apache.solr.handler.dataimport.SqlEntityProcessor.nextRow(Unknown
Source)
at
org.apache.solr.handler.dataimport.ThreadedEntityProcessorWrapper.nextRow(Unknown
Source)
at
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner.runAThread(Unknown
Source)
at
org.apache.solr.handler.dataimport.DocBuilder$EntityRunner.access$000(Unknown
Source)
at org.apache.solr.handler.dataimport.DocBuilder$EntityRunner$1.run(Unknown
Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: com.microsoft.sqlserver.jdbc.SQLServerException: The result set
is closed.
at
com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDriverError(SQLServerException.java:170)
at
com.microsoft.sqlserver.jdbc.SQLServerResultSet.checkClosed(SQLServerResultSet.java:346)
at
com.microsoft.sqlserver.jdbc.SQLServerResultSet.next(SQLServerResultSet.java:916)
at
org.apache.tomcat.dbcp.dbcp.DelegatingResultSet.next(DelegatingResultSet.java:174)
... 12 more
