Error while re-indexing the DateOfFirstResponseCFType in the JIRA Charting Plugin

Summary

• After upgrading from JIRA 5.2.111 to 6.4.8 there are Errors in the logs during the Lock JIRA and rebuild re-index with the JIRA Charting Plugin enabled.
• There are no visible errors on the JIRA UI.
• The re-index finishes successfully bu there are errors in the logs
• Here is the errors from the logs :

  2015-07-22 10:47:43,844 IssueIndexer:thread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 94% complete. Current index: Issue
  2015-07-22 10:47:44,522 IssueIndexer:thread-6 ERROR xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [core.entity.jdbc.SQLProcessor] !!! ABANDONED xxxxxx DETECTED !!!
  	This probably means that somebody forgot to close an EntityListIterator.
  	Connection: DelegatingConnectionImpl[connectionPoolInfo=ConnectionPoolInfo{maxSize=30, minSize=2, initialSize=null, maxIdle=30, maxWait=60000, sleepTime=300000, lifeTime=600000, deadLockMaxWait=600000, deadLockRetryWait=10000, validationQuery='select 1', minEvictableTimeMillis=null, timeBetweenEvictionRunsMillis=null, poolPreparedStatements=null, testOnBorrow=null, testOnReturn=null, testWhileIdle=null, maxOpenPreparedStatements=null, numTestsPerEvictionRun=null, removeAbandonedTimeout=null, validationQueryTimeout=null, defaultCatalog=null},sqlConnectionInterceptor=org.ofbiz.core.entity.jdbc.interceptors.connection.SafeDelegatingSqlConnectionInterceptor@29d49e9a]
  	SQL: select min( act.CREATED) from jiraaction act , jiraissue it where act.issueid = ?  and act.issueid = it.ID and act.actiontype = 'comment'  and it.REPORTER != act.AUTHOR
  2015-07-22 10:47:44,524 IssueIndexer:thread-6 ERROR xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [jdbc.interceptors.connection.SafeDelegatingSqlConnectionInterceptor] Unexpected exception from SQL connection interceptor callback: onConnectionReplaced([DelegatingConnectionImpl[connectionPoolInfo=ConnectionPoolInfo{maxSize=30, minSize=2, initialSize=null, maxIdle=30, maxWait=60000, sleepTime=300000, lifeTime=600000, deadLockMaxWait=600000, deadLockRetryWait=10000, validationQuery='select 1', minEvictableTimeMillis=null, timeBetweenEvictionRunsMillis=null, poolPreparedStatements=null, testOnBorrow=null, testOnReturn=null, testWhileIdle=null, maxOpenPreparedStatements=null, numTestsPerEvictionRun=null, removeAbandonedTimeout=null, validationQueryTimeout=null, defaultCatalog=null},sqlConnectionInterceptor=org.ofbiz.core.entity.jdbc.interceptors.connection.SafeDelegatingSqlConnectionInterceptor@29d49e9a], ConnectionPoolStateImpl[timeToBorrowNanos=0,borrowCount=2,connectionPoolInfo=ConnectionPoolInfo{maxSize=30, minSize=2, initialSize=null, maxIdle=30, maxWait=60000, sleepTime=300000, lifeTime=600000, deadLockMaxWait=600000, deadLockRetryWait=10000, validationQuery='select 1', minEvictableTimeMillis=null, timeBetweenEvictionRunsMillis=null, poolPreparedStatements=null, testOnBorrow=null, testOnReturn=null, testWhileIdle=null, maxOpenPreparedStatements=null, numTestsPerEvictionRun=null, removeAbandonedTimeout=null, validationQueryTimeout=null, defaultCatalog=null}]])
  java.lang.IllegalStateException: The OpTimer has been re-used.  end() can only be called once!
  	at com.atlassian.instrumentation.operations.SimpleOpTimer.end(SimpleOpTimer.java:142)
  	at com.atlassian.instrumentation.operations.SimpleOpTimer.end(SimpleOpTimer.java:99)
  	at com.atlassian.instrumentation.operations.SimpleOpTimer.end(SimpleOpTimer.java:117)
  	at com.atlassian.jira.ofbiz.InstrumentedSQLInterceptor.onConnectionReplaced(InstrumentedSQLInterceptor.java:39)
  	at com.atlassian.jira.ofbiz.ChainedSQLInterceptor.onConnectionReplaced(ChainedSQLInterceptor.java:75)
  	at org.ofbiz.core.entity.jdbc.interceptors.connection.SafeDelegatingSqlConnectionInterceptor.onConnectionReplaced(SafeDelegatingSqlConnectionInterceptor.java:47)
  	at org.ofbiz.core.entity.jdbc.interceptors.connection.ConnectionTracker$DelegatingConnectionImpl.close(ConnectionTracker.java:99)
  	at org.ofbiz.core.entity.jdbc.ConnectionGuard.closeAbandonedConnection(ConnectionGuard.java:124)
  	at org.ofbiz.core.entity.jdbc.ConnectionGuard.closeAbandonedProcessor(ConnectionGuard.java:112)
  	at org.ofbiz.core.entity.jdbc.ConnectionGuard.closeAbandonedProcessors(ConnectionGuard.java:150)
  	at org.ofbiz.core.entity.jdbc.SQLProcessor.getConnection(SQLProcessor.java:390)
  	at org.ofbiz.core.entity.jdbc.SQLProcessor.prepareStatement(SQLProcessor.java:544)
  	at org.ofbiz.core.entity.GenericDAO.createEntityListIterator(GenericDAO.java:960)
  	at org.ofbiz.core.entity.GenericDAO.selectListIteratorByCondition(GenericDAO.java:883)
  	at org.ofbiz.core.entity.GenericDAO.selectByAnd(GenericDAO.java:730)
  	at org.ofbiz.core.entity.GenericHelperDAO.findByAnd(GenericHelperDAO.java:150)
  	at org.ofbiz.core.entity.GenericDelegator.findByAnd(GenericDelegator.java:901)
  	at org.ofbiz.core.entity.GenericDelegator.findByAnd(GenericDelegator.java:879)
  	at com.atlassian.jira.ofbiz.DefaultOfBizDelegator.findByAnd(DefaultOfBizDelegator.java:110)
  	at com.atlassian.jira.ofbiz.WrappingOfBizDelegator.findByAnd(WrappingOfBizDelegator.java:91)
  	at com.atlassian.jira.issue.worklog.OfBizWorklogStore.getByIssue(OfBizWorklogStore.java:125)
  	at com.atlassian.jira.issue.worklog.DefaultWorklogManager.getByIssue(DefaultWorklogManager.java:94)  <+2> (DelegatingMethodAccessorImpl.java:43)
  	at java.lang.reflect.Method.invoke(Method.java:497)
  	at com.atlassian.plugin.osgi.hostcomponents.impl.DefaultComponentRegistrar$ContextClassLoaderSettingInvocationHandler.invoke(DefaultComponentRegistrar.java:134)
  	at com.sun.proxy.$Proxy304.getByIssue(Unknown Source)  <+2> (DelegatingMethodAccessorImpl.java:43)
  	at java.lang.reflect.Method.invoke(Method.java:497)
  	at com.atlassian.plugin.osgi.bridge.external.HostComponentFactoryBean$DynamicServiceInvocationHandler.invoke(HostComponentFactoryBean.java:154)
  	at com.sun.proxy.$Proxy304.getByIssue(Unknown Source)
  	at com.atlassian.jira.issue.worklog.WorklogManager$getByIssue$6.call(Unknown Source)
  	at com.onresolve.jira.groovy.jql.ScriptFunctionSearchIndexer.addIndex(ScriptFunctionSearchIndexer.groovy:84)
  	at com.atlassian.jira.issue.index.DefaultIssueDocumentFactory$Builder.add(DefaultIssueDocumentFactory.java:102)
  	at com.atlassian.jira.issue.index.DefaultIssueDocumentFactory$Builder.addAll(DefaultIssueDocumentFactory.java:91)
  	at com.atlassian.jira.issue.index.DefaultIssueDocumentFactory.apply(DefaultIssueDocumentFactory.java:60)
  	at com.atlassian.jira.issue.index.DefaultIssueDocumentFactory.apply(DefaultIssueDocumentFactory.java:37)
  	at com.atlassian.jira.issue.index.DefaultIssueIndexer$DefaultDocumentCreationStrategy.get(DefaultIssueIndexer.java:644)
  	at com.atlassian.jira.issue.index.DefaultIssueIndexer$IndexIssuesOperation.perform(DefaultIssueIndexer.java:427)
  	at com.atlassian.jira.issue.index.DefaultIssueIndexer$IndexIssuesOperation.perform(DefaultIssueIndexer.java:412)
  	at com.atlassian.jira.issue.index.DefaultIssueIndexer$3$1.get(DefaultIssueIndexer.java:334)
  	at com.atlassian.jira.issue.index.DefaultIssueIndexer$3$1.get(DefaultIssueIndexer.java:330)
  	at com.atlassian.jira.index.SimpleIndexingStrategy.get(SimpleIndexingStrategy.java:9)
  	at com.atlassian.jira.index.SimpleIndexingStrategy.get(SimpleIndexingStrategy.java:5)
  	at com.atlassian.jira.index.MultiThreadedIndexingStrategy$1.call(MultiThreadedIndexingStrategy.java:38)
  	at com.atlassian.jira.index.MultiThreadedIndexingStrategy$1.call(MultiThreadedIndexingStrategy.java:35)
  	at com.atlassian.jira.util.concurrent.BoundedExecutor$2.call(BoundedExecutor.java:85)
  	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  	at java.lang.Thread.run(Thread.java:745)
  2015-07-22 10:47:51,235 IssueIndexer:thread-10 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 95% complete. Current index: Issue
  2015-07-22 10:48:00,402 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [jira.util.index.CompositeIndexLifecycleManager] Reindex took: 675507ms. Indexer: DefaultIndexManager: paths: [/data/home/xxxxx/xxxxx/home/caches/indexes/comments,/data/home/xxxxx/xxxxx/home/caches/indexes/issues, /data/home/xxxxx/xxxxx/home/caches/indexes/changes, /data/home/xxxxx/xxxxx/home/caches/indexes/worklogs, //data/home/xxxxx/xxxxx/home/caches/indexes/plugins/atlassian-subversion-revisions]
  2015-07-22 10:48:00,413 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 95% complete. Current index: SearchRequest
  2015-07-22 10:48:01,867 SharedEntityIndexer:thread-5 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 96% complete. Current index: SearchRequest
  2015-07-22 10:48:04,189 SharedEntityIndexer:thread-6 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 97% complete. Current index: SearchRequest
  2015-07-22 10:48:06,463 SharedEntityIndexer:thread-10 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 98% complete. Current index: SearchRequest
  2015-07-22 10:48:08,809 SharedEntityIndexer:thread-9 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 99% complete. Current index: SearchRequest
  2015-07-22 10:48:09,415 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 99% complete. Current index: PortalPage
  2015-07-22 10:48:11,004 SharedEntityIndexer:thread-5 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 100% complete. Current index: PortalPage
  2015-07-22 10:48:11,006 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [jira.util.index.CompositeIndexLifecycleManager] Reindex took: 10590ms. Indexer: SharedEntityIndexManager: paths: []
  2015-07-22 10:48:11,008 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 100% complete. Current index: 
  2015-07-22 10:48:11,008 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx/secure/admin/IndexReIndex.jspa [jira.util.index.CompositeIndexLifecycleManager] Reindex All complete. Total time: 686097ms. Reindex run: 1
  2015-07-22 10:48:11,009 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing finished
  

Environment

• JIRA version 6.4.8
• JIRA Charting Plugin version 1.26.1

Steps to Reproduce

1. Upgrade from JIRA 5.2.11 to 6.4.8
2. Install JIRA Charting Plugin version 1.26.1
3. Perform a full re-index

Expected Results

1. Logs should not show any errors

Actual Results

The below exception is thrown in the atlassian-jira.log file:

2015-07-22 10:47:43,844 IssueIndexer:thread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 94% complete. Current index: Issue
2015-07-22 10:47:44,522 IssueIndexer:thread-6 ERROR xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [core.entity.jdbc.SQLProcessor] !!! ABANDONED SQLProcessor DETECTED !!!
	This probably means that somebody forgot to close an EntityListIterator.
	Connection: DelegatingConnectionImpl[connectionPoolInfo=ConnectionPoolInfo{maxSize=30, minSize=2, initialSize=null, maxIdle=30, maxWait=60000, sleepTime=300000, lifeTime=600000, deadLockMaxWait=600000, deadLockRetryWait=10000, validationQuery='select 1', minEvictableTimeMillis=null, timeBetweenEvictionRunsMillis=null, poolPreparedStatements=null, testOnBorrow=null, testOnReturn=null, testWhileIdle=null, maxOpenPreparedStatements=null, numTestsPerEvictionRun=null, removeAbandonedTimeout=null, validationQueryTimeout=null, defaultCatalog=null},sqlConnectionInterceptor=org.ofbiz.core.entity.jdbc.interceptors.connection.SafeDelegatingSqlConnectionInterceptor@29d49e9a]
	SQL: select min( act.CREATED) from jiraaction act , jiraissue it where act.issueid = ?  and act.issueid = it.ID and act.actiontype = 'comment'  and it.REPORTER != act.AUTHOR
2015-07-22 10:47:44,524 IssueIndexer:thread-6 ERROR xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [jdbc.interceptors.connection.SafeDelegatingSqlConnectionInterceptor] Unexpected exception from SQL connection interceptor callback: onConnectionReplaced([DelegatingConnectionImpl[connectionPoolInfo=ConnectionPoolInfo{maxSize=30, minSize=2, initialSize=null, maxIdle=30, maxWait=60000, sleepTime=300000, lifeTime=600000, deadLockMaxWait=600000, deadLockRetryWait=10000, validationQuery='select 1', minEvictableTimeMillis=null, timeBetweenEvictionRunsMillis=null, poolPreparedStatements=null, testOnBorrow=null, testOnReturn=null, testWhileIdle=null, maxOpenPreparedStatements=null, numTestsPerEvictionRun=null, removeAbandonedTimeout=null, validationQueryTimeout=null, defaultCatalog=null},sqlConnectionInterceptor=org.ofbiz.core.entity.jdbc.interceptors.connection.SafeDelegatingSqlConnectionInterceptor@29d49e9a], ConnectionPoolStateImpl[timeToBorrowNanos=0,borrowCount=2,connectionPoolInfo=ConnectionPoolInfo{maxSize=30, minSize=2, initialSize=null, maxIdle=30, maxWait=60000, sleepTime=300000, lifeTime=600000, deadLockMaxWait=600000, deadLockRetryWait=10000, validationQuery='select 1', minEvictableTimeMillis=null, timeBetweenEvictionRunsMillis=null, poolPreparedStatements=null, testOnBorrow=null, testOnReturn=null, testWhileIdle=null, maxOpenPreparedStatements=null, numTestsPerEvictionRun=null, removeAbandonedTimeout=null, validationQueryTimeout=null, defaultCatalog=null}]])
java.lang.IllegalStateException: The OpTimer has been re-used.  end() can only be called once!
	at com.atlassian.instrumentation.operations.SimpleOpTimer.end(SimpleOpTimer.java:142)
	at com.atlassian.instrumentation.operations.SimpleOpTimer.end(SimpleOpTimer.java:99)
	at com.atlassian.instrumentation.operations.SimpleOpTimer.end(SimpleOpTimer.java:117)
	at com.atlassian.jira.ofbiz.InstrumentedSQLInterceptor.onConnectionReplaced(InstrumentedSQLInterceptor.java:39)
	at com.atlassian.jira.ofbiz.ChainedSQLInterceptor.onConnectionReplaced(ChainedSQLInterceptor.java:75)
	at org.ofbiz.core.entity.jdbc.interceptors.connection.SafeDelegatingSqlConnectionInterceptor.onConnectionReplaced(SafeDelegatingSqlConnectionInterceptor.java:47)
	at org.ofbiz.core.entity.jdbc.interceptors.connection.ConnectionTracker$DelegatingConnectionImpl.close(ConnectionTracker.java:99)
	at org.ofbiz.core.entity.jdbc.ConnectionGuard.closeAbandonedConnection(ConnectionGuard.java:124)
	at org.ofbiz.core.entity.jdbc.ConnectionGuard.closeAbandonedProcessor(ConnectionGuard.java:112)
	at org.ofbiz.core.entity.jdbc.ConnectionGuard.closeAbandonedProcessors(ConnectionGuard.java:150)
	at org.ofbiz.core.entity.jdbc.SQLProcessor.getConnection(SQLProcessor.java:390)
	at org.ofbiz.core.entity.jdbc.SQLProcessor.prepareStatement(SQLProcessor.java:544)
	at org.ofbiz.core.entity.GenericDAO.createEntityListIterator(GenericDAO.java:960)
	at org.ofbiz.core.entity.GenericDAO.selectListIteratorByCondition(GenericDAO.java:883)
	at org.ofbiz.core.entity.GenericDAO.selectByAnd(GenericDAO.java:730)
	at org.ofbiz.core.entity.GenericHelperDAO.findByAnd(GenericHelperDAO.java:150)
	at org.ofbiz.core.entity.GenericDelegator.findByAnd(GenericDelegator.java:901)
	at org.ofbiz.core.entity.GenericDelegator.findByAnd(GenericDelegator.java:879)
	at com.atlassian.jira.ofbiz.DefaultOfBizDelegator.findByAnd(DefaultOfBizDelegator.java:110)
	at com.atlassian.jira.ofbiz.WrappingOfBizDelegator.findByAnd(WrappingOfBizDelegator.java:91)
	at com.atlassian.jira.issue.worklog.OfBizWorklogStore.getByIssue(OfBizWorklogStore.java:125)
	at com.atlassian.jira.issue.worklog.DefaultWorklogManager.getByIssue(DefaultWorklogManager.java:94)  <+2> (DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:497)
	at com.atlassian.plugin.osgi.hostcomponents.impl.DefaultComponentRegistrar$ContextClassLoaderSettingInvocationHandler.invoke(DefaultComponentRegistrar.java:134)
	at com.sun.proxy.$Proxy304.getByIssue(Unknown Source)  <+2> (DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:497)
	at com.atlassian.plugin.osgi.bridge.external.HostComponentFactoryBean$DynamicServiceInvocationHandler.invoke(HostComponentFactoryBean.java:154)
	at com.sun.proxy.$Proxy304.getByIssue(Unknown Source)
	at com.atlassian.jira.issue.worklog.WorklogManager$getByIssue$6.call(Unknown Source)
	at com.onresolve.jira.groovy.jql.ScriptFunctionSearchIndexer.addIndex(ScriptFunctionSearchIndexer.groovy:84)
	at com.atlassian.jira.issue.index.DefaultIssueDocumentFactory$Builder.add(DefaultIssueDocumentFactory.java:102)
	at com.atlassian.jira.issue.index.DefaultIssueDocumentFactory$Builder.addAll(DefaultIssueDocumentFactory.java:91)
	at com.atlassian.jira.issue.index.DefaultIssueDocumentFactory.apply(DefaultIssueDocumentFactory.java:60)
	at com.atlassian.jira.issue.index.DefaultIssueDocumentFactory.apply(DefaultIssueDocumentFactory.java:37)
	at com.atlassian.jira.issue.index.DefaultIssueIndexer$DefaultDocumentCreationStrategy.get(DefaultIssueIndexer.java:644)
	at com.atlassian.jira.issue.index.DefaultIssueIndexer$IndexIssuesOperation.perform(DefaultIssueIndexer.java:427)
	at com.atlassian.jira.issue.index.DefaultIssueIndexer$IndexIssuesOperation.perform(DefaultIssueIndexer.java:412)
	at com.atlassian.jira.issue.index.DefaultIssueIndexer$3$1.get(DefaultIssueIndexer.java:334)
	at com.atlassian.jira.issue.index.DefaultIssueIndexer$3$1.get(DefaultIssueIndexer.java:330)
	at com.atlassian.jira.index.SimpleIndexingStrategy.get(SimpleIndexingStrategy.java:9)
	at com.atlassian.jira.index.SimpleIndexingStrategy.get(SimpleIndexingStrategy.java:5)
	at com.atlassian.jira.index.MultiThreadedIndexingStrategy$1.call(MultiThreadedIndexingStrategy.java:38)
	at com.atlassian.jira.index.MultiThreadedIndexingStrategy$1.call(MultiThreadedIndexingStrategy.java:35)
	at com.atlassian.jira.util.concurrent.BoundedExecutor$2.call(BoundedExecutor.java:85)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)
2015-07-22 10:47:51,235 IssueIndexer:thread-10 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 95% complete. Current index: Issue
2015-07-22 10:48:00,402 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx/secure/admin/IndexReIndex.jspa [jira.util.index.CompositeIndexLifecycleManager] Reindex took: 675507ms. Indexer: DefaultIndexManager: paths: [/data/home/xxxxx/xxxxx/home/caches/indexes/comments, /data/home/xxxxx/xxxxx/home/home/caches/indexes/issues, /data/home/xxxxx/xxxxx/home/caches/indexes/changes, /data/home/xxxxx/xxxxx/home/caches/indexes/worklogs, /data/home/xxxxx/xxxxx/home/caches/indexes/plugins/atlassian-subversion-revisions]
2015-07-22 10:48:00,413 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 95% complete. Current index: SearchRequest
2015-07-22 10:48:01,867 SharedEntityIndexer:thread-5 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 96% complete. Current index: SearchRequest
2015-07-22 10:48:04,189 SharedEntityIndexer:thread-6 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 97% complete. Current index: SearchRequest
2015-07-22 10:48:06,463 SharedEntityIndexer:thread-10 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 98% complete. Current index: SearchRequest
2015-07-22 10:48:08,809 SharedEntityIndexer:thread-9 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 99% complete. Current index: SearchRequest
2015-07-22 10:48:09,415 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 99% complete. Current index: PortalPage
2015-07-22 10:48:11,004 SharedEntityIndexer:thread-5 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 100% complete. Current index: PortalPage
2015-07-22 10:48:11,006 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [jira.util.index.CompositeIndexLifecycleManager] Reindex took: 10590ms. Indexer: SharedEntityIndexManager: paths: []
2015-07-22 10:48:11,008 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing is 100% complete. Current index: 
2015-07-22 10:48:11,008 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [jira.util.index.CompositeIndexLifecycleManager] Reindex All complete. Total time: 686097ms. Reindex run: 1
2015-07-22 10:48:11,009 JiraTaskExectionThread-1 INFO xxxxxx 636x147x1 xhcmf7 10.x.xxx.xxx /secure/admin/IndexReIndex.jspa [action.admin.index.IndexAdminImpl] Re-indexing finished

Notes

JCHART-462 is used to track the fix for this issue.