Duplicated issue from mail handler

[ZakirovRR]

Hello,

After our upgrade from jira 6.3 to jira 7.13 we are having duplicated issue problem, when the issue is created by mail.

Here is DEBUG log when I create an issue by sending a mail:

2019-02-22 08:38:25,511 DEBUG [] Caesium-1-4 anonymous Create_tickets MailFetcherService run() method has been called
2019-02-22 08:38:25,516 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Execution id: 10301
2019-02-22 08:38:25,518 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Removed 0 expired dead letters from store
2019-02-22 08:38:25,518 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Using mail server [com.atlassian.mail.server.impl.PopMailServerImpl@28c8f64e[id=10100,name=jira_cftup,description=jira_cftup,server name=mailr5.mail.ru,username=jira_cftup,password=***,socks host=<null>,socks port=<null>]]
2019-02-22 08:38:25,518 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Getting session
2019-02-22 08:38:25,518 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Debug messages from JavaMail session initialization will not appear in this log. These messages are sent to standard out.
2019-02-22 08:38:25,591 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Session providers: [[javax.mail.Provider[STORE,imap,com.sun.mail.imap.IMAPStore,Oracle], javax.mail.Provider[STORE,imaps,com.sun.mail.imap.IMAPSSLStore,Oracle], javax.mail.Provider[TRANSPORT,smtp,com.sun.mail.smtp.SMTPTransport,Oracle], javax.mail.Provider[TRANSPORT,smtps,com.sun.mail.smtp.SMTPSSLTransport,Oracle], javax.mail.Provider[STORE,pop3,com.sun.mail.pop3.POP3Store,Oracle], javax.mail.Provider[STORE,pop3s,com.sun.mail.pop3.POP3SSLStore,Oracle]]]
2019-02-22 08:38:25,591 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Session addressMap: [{rfc822=smtp}]
2019-02-22 08:38:25,591 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Getting store from the session using protocol [pop3]
2019-02-22 08:38:25,592 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG: getProvider() returning javax.mail.Provider[STORE,pop3,com.sun.mail.pop3.POP3Store,Oracle]
2019-02-22 08:38:25,592 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.rsetbeforequit: false
2019-02-22 08:38:25,592 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.disabletop: false
2019-02-22 08:38:25,592 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.forgettopheaders: false
2019-02-22 08:38:25,593 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.cachewriteto: false
2019-02-22 08:38:25,593 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.filecache.enable: false
2019-02-22 08:38:25,593 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.keepmessagecontent: false
2019-02-22 08:38:25,593 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.starttls.enable: false
2019-02-22 08:38:25,594 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.starttls.required: false
2019-02-22 08:38:25,594 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.finalizecleanclose: false
2019-02-22 08:38:25,594 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Connecting to mail store to host [mailr5.mail.ru] and port [110]
2019-02-22 08:38:25,594 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.apop.enable: false
2019-02-22 08:38:25,594 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: mail.pop3.disablecapa: false
2019-02-22 08:38:25,595 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: connecting to host "mailr5.mail.ru", port 110, isSSL false
2019-02-22 08:38:25,604 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets +OK Lotus Notes POP3 server version Release 8.5.3FP6 ready on MailR5/NikoilR5/RU.
2019-02-22 08:38:25,604 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets CAPA
2019-02-22 08:38:25,605 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets +OK
2019-02-22 08:38:25,605 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets USER
2019-02-22 08:38:25,605 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets UIDL
2019-02-22 08:38:25,605 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets TOP
2019-02-22 08:38:25,605 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets .
2019-02-22 08:38:25,605 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: authentication command trace suppressed
2019-02-22 08:38:25,629 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: authentication command succeeded
2019-02-22 08:38:25,629 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Successfully connected to mail store
2019-02-22 08:38:25,629 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Getting folder [INBOX]
2019-02-22 08:38:25,629 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Got folder [INBOX], now opening it for read/write
2019-02-22 08:38:25,629 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets STAT
2019-02-22 08:38:25,629 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets +OK 1 1024
2019-02-22 08:38:25,630 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets NOOP
2019-02-22 08:38:25,630 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets +OK
2019-02-22 08:38:25,630 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Create_tickets[10100]: Found 1 unprocessed message(s) in the pop3 folder
2019-02-22 08:38:25,630 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Processing message
2019-02-22 08:38:25,631 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets TOP 1 0
2019-02-22 08:38:25,651 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets +OK
2019-02-22 08:38:25,651 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Subject: test
2019-02-22 08:38:25,651 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets X-KeepSent: A22D095B:DA892362-452583A9:001EA608;
2019-02-22 08:38:25,651 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets type=4; name=$KeepSent
2019-02-22 08:38:25,651 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets To: jira_cftup@company.ru
2019-02-22 08:38:25,652 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Message-ID: <OFA22D095B.DA892362-ON452583A9.001EA608-452583A9.001EA8DF@mail.ru>
2019-02-22 08:38:25,652 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets From: ZakirovRR@ufa.company.ru
2019-02-22 08:38:25,652 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Date: Fri, 22 Feb 2019 10:34:52 +0500
2019-02-22 08:38:25,653 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets X-MIMETrack: Serialize by POP3 Server on MailR5/NikoilR5/RU at 22.02.2019 08:38:29
2019-02-22 08:38:25,653 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets MIME-Version: 1.0
2019-02-22 08:38:25,653 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Content-type: multipart/alternative;
2019-02-22 08:38:25,653 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Boundary="0__=CABB093ADF8D20988f9e8a93df938690918cCABB093ADF8D2098"
2019-02-22 08:38:25,654 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Content-Disposition: inline
2019-02-22 08:38:25,654 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets .
2019-02-22 08:38:25,654 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Message Subject: test
2019-02-22 08:38:25,654 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Message-ID: <OFA22D095B.DA892362-ON452583A9.001EA608-452583A9.001EA8DF@mail.ru>
2019-02-22 08:38:25,656 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Calling handleMessage
2019-02-22 08:38:25,659 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Looking for Issue Key in subject 'test'.
2019-02-22 08:38:25,659 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Issue Key not found in subject 'test'. Inspecting the in-reply-to message ID.
2019-02-22 08:38:25,659 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets No Issue found for email 'test' - creating a new Issue.
2019-02-22 08:38:25,659 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets CreateIssueHandler.init(params: {issuetype=1, ccwatcher=false, stripquotes=false, createusers=false, notifyusers=false, project=CFTUP, reporteruserkey=test_cft_msk, bulk=forward, ccassignee=false})
2019-02-22 08:38:25,659 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Defaulting to fingerprint policy of 'forward'
2019-02-22 08:38:25,659 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets CreateIssueHandler.handleMessage
2019-02-22 08:38:25,670 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Project key = CFTUP
2019-02-22 08:38:25,670 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Project = Project: CFTUP
2019-02-22 08:38:25,672 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Issue Type Key = 1
2019-02-22 08:38:25,672 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Issue Type Object = Bug
2019-02-22 08:38:25,673 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets RETR 1
2019-02-22 08:38:25,685 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets +OK 1824 octets
2019-02-22 08:38:25,685 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Subject: test
2019-02-22 08:38:25,685 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets X-KeepSent: A22D095B:DA892362-452583A9:001EA608;
2019-02-22 08:38:25,685 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets type=4; name=$KeepSent
2019-02-22 08:38:25,686 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets To: jira_cftup@company.ru
2019-02-22 08:38:25,687 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Message-ID: <OFA22D095B.DA892362-ON452583A9.001EA608-452583A9.001EA8DF@mail.ru>
2019-02-22 08:38:25,687 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets From: ZakirovRR@ufa.company.ru
2019-02-22 08:38:25,687 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Date: Fri, 22 Feb 2019 10:34:52 +0500
2019-02-22 08:38:25,688 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets X-MIMETrack: Serialize by POP3 Server on MailR5/NikoilR5/RU at 22.02.2019 08:38:29
2019-02-22 08:38:25,688 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets MIME-Version: 1.0
2019-02-22 08:38:25,689 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Content-type: multipart/alternative;
2019-02-22 08:38:25,689 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Boundary="0__=CABB093ADF8D20988f9e8a93df938690918cCABB093ADF8D2098"
2019-02-22 08:38:25,689 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Content-Disposition: inline
2019-02-22 08:38:25,690 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets --0__=CABB093ADF8D20988f9e8a93df938690918cCABB093ADF8D2098
2019-02-22 08:38:25,690 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Content-type: text/plain; charset=KOI8-R
2019-02-22 08:38:25,690 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Content-transfer-encoding: base64
2019-02-22 08:38:25,691 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DQoNCg0KDQp0ZXN0DQotLS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0t
2019-02-22 08:38:25,691 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets LS0tLS0NCvLBzcnM2CD6wcvJ0s/XLA0K5MnSxcvDydEg08/Q0s/Xz9bExc7J0SDG0s/O1C3PxsnT
2019-02-22 08:38:25,692 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets ztnIINPJ09TFzQ0KZW1haWw6IFpha2lyb3ZSUkB1ZmEudXJhbHNpYi5ydQ0K184u1MXMLjogKDAx
2019-02-22 08:38:25,692 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets MCktNDEzNg==
2019-02-22 08:38:25,692 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets --0__=CABB093ADF8D20988f9e8a93df938690918cCABB093ADF8D2098
2019-02-22 08:38:25,692 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Content-type: text/html; charset=KOI8-R
2019-02-22 08:38:25,692 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Content-Disposition: inline
2019-02-22 08:38:25,693 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Content-transfer-encoding: base64
2019-02-22 08:38:25,693 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets PGh0bWw+PGJvZHk+DQo8cD48Zm9udCBzaXplPSIyIiBmYWNlPSJzYW5zLXNlcmlmIj50ZXN0PC9m
2019-02-22 08:38:25,694 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets b250Pjxicj4NCjxmb250IHNpemU9IjEiIGZhY2U9InNhbnMtc2VyaWYiPi0tLS0tLS0tLS0tLS0t
2019-02-22 08:38:25,694 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets LS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0tLS0tLTwvZm9udD48YnI+DQo8Zm9udCBzaXpl
2019-02-22 08:38:25,695 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets PSIxIiBmYWNlPSJzYW5zLXNlcmlmIj7ywc3JzNgg+sHLydLP1yw8L2ZvbnQ+PGJyPg0KPGZvbnQg
2019-02-22 08:38:25,695 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets c2l6ZT0iMSIgZmFjZT0ic2Fucy1zZXJpZiI+5MnSxcvDydEg08/Q0s/Xz9bExc7J0SDG0s/O1C3P
2019-02-22 08:38:25,696 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets xsnTztnIINPJ09TFzTwvZm9udD48YnI+DQo8Zm9udCBzaXplPSIxIiBmYWNlPSJzYW5zLXNlcmlm
2019-02-22 08:38:25,696 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Ij5lbWFpbDogWmFraXJvdlJSQHVmYS51cmFsc2liLnJ1PC9mb250Pjxicj4NCjxmb250IHNpemU9
2019-02-22 08:38:25,697 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets IjEiIGZhY2U9InNhbnMtc2VyaWYiPtfOLtTFzC46ICgwMTApLTQxMzwvZm9udD48Zm9udCBzaXpl
2019-02-22 08:38:25,697 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets PSIyIiBmYWNlPSJzYW5zLXNlcmlmIj42PC9mb250PjwvYm9keT48L2h0bWw+
2019-02-22 08:38:25,697 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets --0__=CABB093ADF8D20988f9e8a93df938690918cCABB093ADF8D2098--
2019-02-22 08:38:25,697 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets .
2019-02-22 08:38:25,697 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: guessing message size: 1824
2019-02-22 08:38:25,698 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets DEBUG POP3: got message size 1824
2019-02-22 08:38:25,923 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Closing folder
2019-02-22 08:38:25,923 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets NOOP
2019-02-22 08:38:25,924 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets +OK
2019-02-22 08:38:25,924 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets QUIT
2019-02-22 08:38:25,925 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets +OK Lotus Notes POP3 server signing off.
2019-02-22 08:38:25,925 DEBUG [jira_cftup] Caesium-1-4 anonymous Create_tickets Closing store

So, mail handler created issue at 08:33 after it got my mail, and after 5 min it found that same mail and created new issue again.

So, mail handler created issue at 08:33 after it got my mail, and after 5 min it found that same mail and created new issue again.

Could anybody help? Is it a bug?

[Mihai Schwarz]

Hi @ZakirovRR 

Do you use a third party add-on as a mail handler?