Issues getting duplicated after upgrading Jira 8.5.17 to 9.12.7

[Sandesh TM]

After upgrading Jira 8.5.17 to 9.12.7, when we try to create a issue, the issue is getting created but it is getting duplicated and two issues are getting created with different ID.

[Pavel Junek]

Hi Sandesh,

More information is needed to identify the problem:

• Does this behavior happen to you only in one Jira project or is it in all of them?
• Does it happen only for a specific Issue Type?
• What does the workflow look like where it happens to you (is there any Postfunction for Create transition?)?
• What is in the Jira log file when this behavior shows up?

Pavel

[Sandesh TM]

@Pavel Junek 

Thank you for the response.

• It is happening for all our team members and for all the projects.
• It is happening for all the issue types.
• No extra PostFunction created there for Create transition.
• Nothing much we are getting out of Jira logs.

[Sandesh TM]

@Pavel Junek  Please check the below jira logs generated upon clicking the submit button of creating the issue.

2024-05-28 13:24:53,073+0000 http-nio-8080-exec-11 url: /rest/rim-shell-extensions/1.0/study-roles/10400/is-in-role; user: rbm INFO rbm 804x17296x1 1gzjdih 172.28.0.1 /rest/rim-shell-extensions/1.0/study-roles/10400/is-in-role [c.a.j.util.stats.JiraStats] [JIRA-STATS] [GROUP-DAO-STATS] snapshot stats: duration=PT5M13.464S, statsOverhead=n/a, data={"_statsName":"GROUP-DAO-STATS","_statsType":"snapshot","_time":"2024-05-28T13:24:53.072Z","_timestamp":1716902693072,"_duration":"PT5M13.464S","_invocations":1506,"_statsOverhead":"n/a","eager":true,"findByNameOrNullSkipRefreshOnNull":false,"findByNameOrNull":502,"findByNameOrNullCacheHit":502,"findByNameOrNullCacheMiss":0,"findByNameOrNullDBHitInMilliseconds":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"50":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0}},"findByNameOrNullDBMissInMilliseconds":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"50":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0}},"findGroupsGenericValueInMilliseconds":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"50":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0}},"groupAddFailed":0,"groupAddFailedUpdateSuccessful":0,"groupAddFailedUpdateFailed":0}
2024-05-28 13:24:53,077+0000 http-nio-8080-exec-11 url: /rest/rim-shell-extensions/1.0/study-roles/10400/is-in-role; user: rbm INFO rbm 804x17296x1 1gzjdih 172.28.0.1 /rest/rim-shell-extensions/1.0/study-roles/10400/is-in-role [c.a.j.util.stats.JiraStats] [JIRA-STATS] [GROUP-DAO-STATS] total stats: duration=PT7H1M32.964S, statsOverhead=n/a, data={"_statsName":"GROUP-DAO-STATS","_statsType":"total","_time":"2024-05-28T13:24:53.072Z","_timestamp":1716902693072,"_duration":"PT7H1M32.964S","_invocations":6125,"_statsOverhead":"n/a","eager":true,"findByNameOrNullSkipRefreshOnNull":false,"findByNameOrNull":2041,"findByNameOrNullCacheHit":2041,"findByNameOrNullCacheMiss":0,"findByNameOrNullDBHitInMilliseconds":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"50":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0}},"findByNameOrNullDBMissInMilliseconds":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"50":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0}},"findGroupsGenericValueInMilliseconds":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"50":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0}},"groupAddFailed":0,"groupAddFailedUpdateSuccessful":0,"groupAddFailedUpdateFailed":0}
2024-05-28 13:24:55,783+0000 http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm INFO rbm 804x17306x2 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.a.jira.index.QueueingIndexStats] [JIRA-STATS] [INDEXING-QUEUE] index:ISSUE, total primary queue stats: {"maxQueueSize":1,"putCounter":13,"queueFullOnPut":0,"getCounter":12,"timeInQueueMillis":{"count":12,"min":0,"max":23,"sum":39,"avg":3,"distributionCounter":{"0":7,"1":1,"10":3,"100":1,"1000":0,"10000":0,"20000":0,"30000":0}},"timeToUpdateIndexMillis":{"count":12,"min":2,"max":964,"sum":1141,"avg":95,"distributionCounter":{"0":0,"1":0,"10":6,"50":4,"100":1,"500":0,"1000":1}},"totalTimeMillis":{"count":12,"min":2,"max":996,"sum":1196,"avg":100,"distributionCounter":{"0":0,"1":0,"10":6,"100":5,"1000":1,"10000":0,"20000":0,"30000":0}},"totalTimeFailedMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"totalTimeTimedOutMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"putCounterByThreadTopN":{"http-nio-8080-exec-6 url: /secure/QuickEditIssue.jspa; user: rbm":4,"http-nio-8080-exec-1 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-2 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-4 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-7 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm":1},"indexerNotRunningCounter":0}
2024-05-28 13:24:55,788+0000 http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm INFO rbm 804x17306x2 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.a.jira.index.QueueingIndexStats] [JIRA-STATS] [INDEXING-QUEUE] index:ISSUE, total secondary queue stats: {"maxQueueSize":1,"putCounter":2,"queueFullOnPut":0,"getCounter":2,"timeInQueueMillis":{"count":2,"min":1,"max":15,"sum":16,"avg":8,"distributionCounter":{"0":0,"1":1,"10":0,"100":1,"1000":0,"10000":0,"20000":0,"30000":0}},"timeToUpdateIndexMillis":{"count":2,"min":0,"max":67,"sum":67,"avg":34,"distributionCounter":{"0":1,"1":0,"10":0,"50":0,"100":1,"500":0,"1000":0}},"totalTimeMillis":{"count":2,"min":3,"max":84,"sum":87,"avg":44,"distributionCounter":{"0":0,"1":0,"10":1,"100":1,"1000":0,"10000":0,"20000":0,"30000":0}},"totalTimeFailedMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"totalTimeTimedOutMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"putCounterByThreadTopN":{"Caesium-1-4":2},"indexerNotRunningCounter":0}
2024-05-28 13:24:55,792+0000 http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm INFO rbm 804x17306x2 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.a.jira.index.QueueingIndexStats] [JIRA-STATS] [INDEXING-QUEUE] index:ISSUE, snapshot primary queue stats: {"maxQueueSize":1,"putCounter":10,"queueFullOnPut":0,"getCounter":10,"timeInQueueMillis":{"count":10,"min":0,"max":9,"sum":13,"avg":1,"distributionCounter":{"0":7,"1":1,"10":2,"100":0,"1000":0,"10000":0,"20000":0,"30000":0}},"timeToUpdateIndexMillis":{"count":10,"min":2,"max":55,"sum":173,"avg":17,"distributionCounter":{"0":0,"1":0,"10":5,"50":4,"100":1,"500":0,"1000":0}},"totalTimeMillis":{"count":10,"min":2,"max":58,"sum":192,"avg":19,"distributionCounter":{"0":0,"1":0,"10":5,"100":5,"1000":0,"10000":0,"20000":0,"30000":0}},"totalTimeFailedMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"totalTimeTimedOutMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"putCounterByThreadTopN":{"http-nio-8080-exec-2 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-4 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-6 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-7 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-1 url: /secure/QuickEditIssue.jspa; user: rbm":1,"http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm":1},"indexerNotRunningCounter":0}
2024-05-28 13:24:55,794+0000 http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm INFO rbm 804x17306x2 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.a.jira.index.QueueingIndexStats] [JIRA-STATS] [INDEXING-QUEUE] index:ISSUE, snapshot secondary queue stats: {"maxQueueSize":0,"putCounter":0,"queueFullOnPut":0,"getCounter":0,"timeInQueueMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"0":0,"1":0,"10":0,"100":0,"1000":0,"10000":0,"20000":0,"30000":0}},"timeToUpdateIndexMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"0":0,"1":0,"10":0,"50":0,"100":0,"500":0,"1000":0}},"totalTimeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"0":0,"1":0,"10":0,"100":0,"1000":0,"10000":0,"20000":0,"30000":0}},"totalTimeFailedMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"totalTimeTimedOutMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"putCounterByThreadTopN":{},"indexerNotRunningCounter":0}
2024-05-28 13:24:55,797+0000 http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm INFO rbm 804x17306x2 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.a.jira.index.QueueingIndexStats] [JIRA-STATS] [INDEXING-QUEUE] index:CHANGE_HISTORY, total primary queue stats: {"maxQueueSize":1,"putCounter":13,"queueFullOnPut":0,"getCounter":12,"timeInQueueMillis":{"count":12,"min":0,"max":9,"sum":27,"avg":2,"distributionCounter":{"0":7,"1":1,"10":4,"100":0,"1000":0,"10000":0,"20000":0,"30000":0}},"timeToUpdateIndexMillis":{"count":12,"min":0,"max":162,"sum":284,"avg":24,"distributionCounter":{"0":5,"1":0,"10":1,"50":5,"100":0,"500":1,"1000":0}},"totalTimeMillis":{"count":12,"min":2,"max":950,"sum":1142,"avg":95,"distributionCounter":{"0":0,"1":0,"10":6,"100":5,"1000":1,"10000":0,"20000":0,"30000":0}},"totalTimeFailedMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"totalTimeTimedOutMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"putCounterByThreadTopN":{"http-nio-8080-exec-6 url: /secure/QuickEditIssue.jspa; user: rbm":4,"http-nio-8080-exec-1 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-2 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-4 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-7 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm":1},"indexerNotRunningCounter":0}
2024-05-28 13:24:55,804+0000 http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm INFO rbm 804x17306x2 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.a.jira.index.QueueingIndexStats] [JIRA-STATS] [INDEXING-QUEUE] index:CHANGE_HISTORY, total secondary queue stats: {"maxQueueSize":1,"putCounter":2,"queueFullOnPut":0,"getCounter":2,"timeInQueueMillis":{"count":2,"min":0,"max":7,"sum":7,"avg":4,"distributionCounter":{"0":1,"1":0,"10":1,"100":0,"1000":0,"10000":0,"20000":0,"30000":0}},"timeToUpdateIndexMillis":{"count":2,"min":0,"max":1,"sum":1,"avg":1,"distributionCounter":{"0":1,"1":1,"10":0,"50":0,"100":0,"500":0,"1000":0}},"totalTimeMillis":{"count":2,"min":0,"max":9,"sum":9,"avg":5,"distributionCounter":{"0":1,"1":0,"10":1,"100":0,"1000":0,"10000":0,"20000":0,"30000":0}},"totalTimeFailedMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"totalTimeTimedOutMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"putCounterByThreadTopN":{"Caesium-1-4":2},"indexerNotRunningCounter":0}
2024-05-28 13:24:55,807+0000 http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm INFO rbm 804x17306x2 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.a.jira.index.QueueingIndexStats] [JIRA-STATS] [INDEXING-QUEUE] index:CHANGE_HISTORY, snapshot primary queue stats: {"maxQueueSize":1,"putCounter":10,"queueFullOnPut":0,"getCounter":10,"timeInQueueMillis":{"count":10,"min":0,"max":9,"sum":13,"avg":1,"distributionCounter":{"0":6,"1":2,"10":2,"100":0,"1000":0,"10000":0,"20000":0,"30000":0}},"timeToUpdateIndexMillis":{"count":10,"min":0,"max":46,"sum":122,"avg":12,"distributionCounter":{"0":4,"1":0,"10":1,"50":5,"100":0,"500":0,"1000":0}},"totalTimeMillis":{"count":10,"min":2,"max":58,"sum":183,"avg":18,"distributionCounter":{"0":0,"1":0,"10":5,"100":5,"1000":0,"10000":0,"20000":0,"30000":0}},"totalTimeFailedMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"totalTimeTimedOutMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"putCounterByThreadTopN":{"http-nio-8080-exec-2 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-4 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-6 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-7 url: /secure/QuickEditIssue.jspa; user: rbm":2,"http-nio-8080-exec-1 url: /secure/QuickEditIssue.jspa; user: rbm":1,"http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm":1},"indexerNotRunningCounter":0}
2024-05-28 13:24:55,808+0000 http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm INFO rbm 804x17306x2 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.a.jira.index.QueueingIndexStats] [JIRA-STATS] [INDEXING-QUEUE] index:CHANGE_HISTORY, snapshot secondary queue stats: {"maxQueueSize":0,"putCounter":0,"queueFullOnPut":0,"getCounter":0,"timeInQueueMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"0":0,"1":0,"10":0,"100":0,"1000":0,"10000":0,"20000":0,"30000":0}},"timeToUpdateIndexMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"0":0,"1":0,"10":0,"50":0,"100":0,"500":0,"1000":0}},"totalTimeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"0":0,"1":0,"10":0,"100":0,"1000":0,"10000":0,"20000":0,"30000":0}},"totalTimeFailedMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"totalTimeTimedOutMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"putCounterByThreadTopN":{},"indexerNotRunningCounter":0}
2024-05-28 13:24:56,001+0000 http-nio-8080-exec-4 url: /secure/QuickCreateIssue.jspa; user: rbm ERROR rbm 804x17301x1 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.c.r.irms.jms.MsgProducer] null
2024-05-28 13:24:56,006+0000 http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm ERROR rbm 804x17306x2 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.c.r.irms.jms.MsgProducer] null
2024-05-28 13:24:56,009+0000 http-nio-8080-exec-4 url: /secure/QuickCreateIssue.jspa; user: rbm ERROR rbm 804x17301x1 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.c.r.irms.jms.MsgProducer] Fail to send the message due to it was not received or rejected.
2024-05-28 13:24:56,014+0000 http-nio-8080-exec-11 url: /secure/QuickCreateIssue.jspa; user: rbm ERROR rbm 804x17306x2 1gzjdih 172.28.0.1 /secure/QuickCreateIssue.jspa [c.c.r.irms.jms.MsgProducer] Fail to send the message due to it was not received or rejected.
2024-05-28 13:25:08,077+0000 JiraIndexCommitThread-14 INFO [c.a.jira.index.MonitoringIndexWriter] [JIRA-STATS] [lucene-stats] flush stats: isForegroundIndexing=false, snapshotCount=12, totalCount=18, periodSec=13139, flushIntervalMillis=1094984, snapshotNoDocs=0, totalNoDocs=0, indexDirectory=/var/atlassian/application-data/jira/caches/indexesV2/changes, indexWriterId=com.atlassian.jira.index.MonitoringIndexWriter@32e4125c, indexDirectoryId=MMapDirectory@/var/atlassian/application-data/jira/caches/indexesV2/changes lockFactory=org.apache.lucene.store.NativeFSLockFactory@308258da
2024-05-28 13:25:08,738+0000 JiraIndexCommitThread-7 INFO [c.a.jira.index.MonitoringIndexWriter] [JIRA-STATS] [lucene-stats] flush stats: isForegroundIndexing=false, snapshotCount=13, totalCount=19, periodSec=13140, flushIntervalMillis=1010798, snapshotNoDocs=0, totalNoDocs=0, indexDirectory=/var/atlassian/application-data/jira/caches/indexesV2/issues, indexWriterId=com.atlassian.jira.index.MonitoringIndexWriter@370744c0, indexDirectoryId=MMapDirectory@/var/atlassian/application-data/jira/caches/indexesV2/issues lockFactory=org.apache.lucene.store.NativeFSLockFactory@308258da

[Pavel Junek]

Hi Sandesh,

Thank you for your reply. The Jira log seem OK. I found this Jira Bug - duplicate issues created when clicking on create button multiple times, but it will be fixed from Jira 9.12.0.

If you have a valid license, please contact Atlassian support to investigate this issue further.

Pavel

[Sandesh TM]

@Pavel Junek this is 9.12.7 version.

[Pavel Junek]

Yes, I know. That's why I wrote:

…but it will be fixed from Jira 9.12.0.

If you have a valid license, please contact Atlassian support to investigate this issue further.