On Mon, May 26, 2014 at 4:14 PM, Aniket Bhoi <aniket.b...@gmail.com> wrote:
> 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
>
Any help with this??.
