Loading...

XML

Word

Printable

Details

Type: Bug
Resolution: Fixed
Priority: Highest
Fix Version/s: 8.20.12, 9.2.0, 8.13.25
Affects Version/s: 7.4.3, 7.6.4, 7.2.15, 7.13.0, 7.6.10, 8.13.11, 8.20.10
Component/s: Webhooks
Labels:
- docum
- performance
- pse-request
- triaged

Introduced in Version:
7.02
Support reference count:
87
Symptom Severity:
Severity 2 - Major
UIS:
366
Bug Fix Policy:
View Atlassian Server bug fix policy

Description

Summary

Create Issue takes longer when there are configured WebHooks with Create Issue event.

The WebHooks are fired asynchronously, but the logic that evaluates whether a WebHook should be called is synchronous and executes one JQL query per each configured WebHook.

On instances that have problems with slow JQLs or with overly complicated WebHook filters configured, this can increase the time it takes to perform actions in Jira.

Environment

An environment where there are WebHooks with Create Issue event and with complex JQL queries.

Expected Results

When a customer creates an issue, they should swiftly see it's created on the UI. Slow JQLs configured for the WebHooks shouldn't impact the issue creation time.

Actual Results

The customer clicks on Create Issue, Issue is created and the logic determining which WebHooks to call is executed. After all JQL filters are executed, user sees the new issue in the UI and the actual WebHooks are called in separate background threads.

Jira log:

2018-10-26 05:15:47,287 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXXX"} AND {filter = 98453} AND {key in ("TEST-123")}' produced lucene query and took '59' ms to run.
2018-10-26 05:15:47,373 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXXX"} AND {filter = 98465} AND {key in ("TEST-123")}' produced lucene query and took '54' ms to run.
2018-10-26 05:15:48,491 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '955' ms to run.
2018-10-26 05:15:49,541 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "RGHT"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1050' ms to run.
2018-10-26 05:15:50,507 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '959' ms to run.
2018-10-26 05:15:51,630 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1123' ms to run.
2018-10-26 05:15:52,739 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1040' ms to run.
2018-10-26 05:15:53,773 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1019' ms to run.
2018-10-26 05:15:54,812 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '927' ms to run.
2018-10-26 05:15:55,752 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "GRSD"} AND {FILTER = 89511} AND {key in ("TEST-123")}' produced lucene query and took '940' ms to run.
2018-10-26 05:15:55,807 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXXX"} AND {filter = 98454} AND {key in ("TEST-123")}' produced lucene query and took '55' ms to run.
2018-10-26 05:15:55,862 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXXX"} AND {filter = 98452} AND {key in ("TEST-123")}' produced lucene query and took '54' ms to run.
2018-10-26 05:15:56,978 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1062' ms to run.
2018-10-26 05:15:58,400 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1313' ms to run.
2018-10-26 05:15:58,535 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXXX"} AND {filter = 98459} AND {key in ("TEST-123")}' produced lucene query and took '65' ms to run.
2018-10-26 05:15:59,709 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1075' ms to run.
2018-10-26 05:16:00,760 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1051' ms to run.
2018-10-26 05:16:01,841 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1080' ms to run.
2018-10-26 05:16:03,013 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1123' ms to run.
2018-10-26 05:16:12,856 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXXX"} AND {filter = 98453} AND {key in ("TEST-123")}' produced lucene query and took '65' ms to run.
2018-10-26 05:16:12,955 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXXX"} AND {filter = 98465} AND {key in ("TEST-123")}' produced lucene query and took '68' ms to run.
2018-10-26 05:16:14,022 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '938' ms to run.
 2018-10-26 05:16:14,919 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project =
 "XXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '896' ms to run.
2018-10-26 05:16:15,788 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "XXXXX"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '862' ms to run.
2018-10-26 05:16:16,721 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '932' ms to run.
2018-10-26 05:16:17,667 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '879' ms to run.
2018-10-26 05:16:18,564 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '884' ms to run.
2018-10-26 05:16:19,542 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '868' ms to run.
2018-10-26 05:16:20,451 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxx"} AND {FILTER = 89511} AND {key in ("TEST-123")}' produced lucene query and took '890' ms to run.
2018-10-26 05:16:20,573 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "XXXXX"} AND {filter = 98452} AND {key in ("TEST-123")}' produced lucene query and took '72' ms to run.
2018-10-26 05:16:20,628 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "XXXXX"} AND {filter = 98455} AND {key in ("TEST-123")}' produced lucene query and took '54' ms to run.
2018-10-26 05:16:21,569 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '932' ms to run.
2018-10-26 05:16:22,589 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '918' ms to run.
2018-10-26 05:16:23,752 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '940' ms to run.
2018-10-26 05:16:24,699 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '946' ms to run.
2018-10-26 05:16:25,632 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '932' ms to run.
2018-10-26 05:16:26,553 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '881' ms to run.
2018-10-26 05:16:26,670 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.h.s.task.impl.SendNotificationTask] Found 1 messages to send to HipChat
2018-10-26 05:16:26,721 http-nio-8090-exec-298 WARN UMercEu 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.o.scriptrunner.runner.ScriptRunnerImpl] EH3: Current PCF Status is null. New value is also the same, so not updating PCF Status field and its time aggregator.
2018-10-26 05:16:29,035 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "XXXXX"} AND {filter = 98453} AND {key in ("TEST-123")}' produced lucene query and took '286' ms to run.
2018-10-26 05:16:29,091 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project in ("xxxxx", "xxxxxx")} AND {project = "xxxxx"} AND {status not in ("closed", "cancelled", "archived", "Done")} AND {component in ("xxxx", "xxxxx")} AND {key in ("TEST-123")}' produced lucene query and took '56' ms to run.
2018-10-26 05:16:29,153 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "XXXXX"} AND {filter = 98465} AND {key in ("TEST-123")}' produced lucene query and took '60' ms to run.
2018-10-26 05:16:30,388 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1100' ms to run.
2018-10-26 05:16:31,383 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '994' ms to run.
2018-10-26 05:16:33,627 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1181' ms to run.
2018-10-26 05:16:34,785 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1096' ms to run.
2018-10-26 05:16:35,801 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1002' ms to run.
2018-10-26 05:16:36,946 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1023' ms to run.
2018-10-26 05:16:37,957 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxx"} AND {FILTER = 89511} AND {key in ("TEST-123")}' produced lucene query and took '1011' ms to run.
2018-10-26 05:16:38,011 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "XXXXX"} AND {filter = 98454} AND {key in ("TEST-123")}' produced lucene query and took '53' ms to run.
2018-10-26 05:16:38,076 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "XXXXX"} AND {filter = 98452} AND {key in ("TEST-123")}' produced lucene query and took '64' ms to run.
2018-10-26 05:16:39,049 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '917' ms to run.
2018-10-26 05:16:40,044 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '878' ms to run.
2018-10-26 05:16:41,026 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '828' ms to run.
2018-10-26 05:16:41,854 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '827' ms to run.
2018-10-26 05:16:42,706 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '851' ms to run.
2018-10-26 05:16:43,622 http-nio-8090-exec-298 INFO XXXXXXXXX 315x2689003x27 cxcxcxccx XXXXXXXXXXXXX /secure/CreateIssueDetails.jspa [c.a.j.p.webhooks.matcher.JqlEventMatcher_SLOW] JQL query '{project = "xxxx"} AND {filter = 89511} AND {key in ("TEST-123")}' produced lucene query and took '881' ms to run.

Workaround

No workaround if Webhooks will be used. But if the aim is to increase the performance of creating issue, then Webhooks should be disabled or JQLs should be tuned.

Fix

Asynchronous Webhooks were introduced in version 8.19.1, although it has some minor side effects (e.g. Jira shutdown may be delayed). We recommend using versions listed in "Fix Version/s" or newer. They should significantly improve the performance of Webhooks with complex JQL queries. To enable them:

manually enable the "com.atlassian.jira.webhookEventsAsyncProcessing.enabled" feature flag with following instruction.
restart your Jira instance (or each node in case of DC)

Attachments

- Sort By Name
- Sort By Date
- Ascending
- Descending
- Thumbnails
- List

oie_G6x2BRf0mTm8.png
275 kB
29/Oct/2018 9:58 AM

Issue Links

is related to

JRASERVER-71557 Asynchronous Webhooks in Jira

Gathering Interest

SSE-514 Loading...

relates to

JRASERVER-69442 Validate and report if JQL is "slow" during any save function in the product

Gathering Interest

PSR-279 Loading...

is blocked by: PSR-207 Loading...

mentioned in: Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...; Page Loading...

(33 mentioned in)

Activity

People

Assignee:: Patryk Latkowski

Reporter:: Alp

Votes:: 141 Vote for this issue

Watchers:: 125 Start watching this issue

Dates

Created:: 29/Oct/2018 9:57 AM

Updated:: 04/Oct/2023 8:59 AM

Resolved:: 24/Aug/2022 4:40 PM