Issue Details (XML | Word | Printable)

Key: JRA-5342
Type: Bug Bug
Status: Resolved Resolved
Resolution: Fixed
Priority: Blocker Blocker
Assignee: Scott Farquhar [Atlassian]
Reporter: Mike Aizatsky
Votes: 1
Watchers: 2
Operations

If you were logged in you would be able to see more operations.
JIRA

Bad performance of Navigator

Created: 24/Nov/04 08:50 AM   Updated: 30/Jul/06 07:34 PM
Component/s: Web interface
Affects Version/s: 3.0.2
Fix Version/s: 3.2

Time Tracking:
Not Specified

File Attachments: 1. PDF File Picture 1.pdf (11 kB)

Issue Links:
Part
 

Participants: Anton Mazkovoi [Atlassian], Jeff Turner [Atlassian], Keith Brophy, Mike Aizatsky and Scott Farquhar [Atlassian]
Since last comment: 3 years, 21 weeks, 2 days ago
Resolution Date: 17/May/05 04:37 AM
Labels:


 Description  « Hide
I made a search by saved filter. The search returns 377 items. 200 of them are shown on the page. After editing the item and pressing 'Return to search' link it takes 15 seconds to display the navigator. It's a pain to edit issues this way.

PS The cache size is big enough to have all issues in memory.



 All   Comments   Work Log   Change History      Sort Order: Ascending order - Click to sort in descending order
Keith Brophy added a comment - 25/Nov/04 09:20 PM
Hi Mike,

Have you had a chance to take a look at 3.0.3?

This release includes a number of fixes and improvements in the 'Issue Navigator' area detailed in the following issues:

http://jira.atlassian.com/browse/JRA-5166
http://jira.atlassian.com/browse/JRA-4987

Let us know if the performance lag persists.

-Keith


Mike Aizatsky added a comment - 02/Dec/04 08:47 AM
It's still that bad. 8 seconds for 60 issues.

Scott Farquhar [Atlassian] added a comment - 06/Dec/04 01:04 AM
Mike,

Can you please turn on profiling (add '?jira_profile=on' to the URL, or else choose it from the admin section), and show us the profiling trace that comes to stardard out?

Apologies if you have sent this before - please point us to the appropriate email.


Mike Aizatsky added a comment - 06/Dec/04 06:16 AM
The log doesn't seem to include profiling info:

[Filter: profiling] Using parameter [jira_profile]
[Filter: profiling] defaulting to off [autostart=false]
[Filter: profiling] Turning filter off [jira_profile=off]


Scott Farquhar [Atlassian] added a comment - 06/Dec/04 06:24 AM
Mike - can you let me know what you did? The trace indicates that you turned profiling off?

Did you change it through the administration section?


Mike Aizatsky added a comment - 06/Dec/04 06:29 AM
Nope, I've add the &jira_profile=on to the navigator URL. Looks like it was written there on server start since catalina.out is not updated on consequent runs.

I will try turning on the system option now.


Mike Aizatsky added a comment - 06/Dec/04 06:31 AM
Hm... Do you really mean the profiling info comes to console? Why not to log? It's a pain to get it out of there since JIRA is running as daemon.

Scott Farquhar [Atlassian] added a comment - 06/Dec/04 03:33 PM
Mike,

It runs to System out, as we didn't want to introduce any dependency on log4j into the profiling module. That way other people can use it (it is an open source project).

The profiling should go to the same place that the other output went:

[Filter: profiling] Using parameter [jira_profile]
[Filter: profiling] defaulting to off [autostart=false]
[Filter: profiling] Turning filter off [jira_profile=off]

The other option is to change the parameter in web.xml (it is an init param), but that would require a server restart.

Cheers,
Scott


Mike Aizatsky added a comment - 09/Dec/04 11:21 AM
Scott,

I've just checked that all the std output indeed goes to catalina.out. I've also checked that Administration screen says profiling is turned on.

Still no profiling info is apparently printed either by default and after providing jira_profile URL parameter.


Mike Aizatsky added a comment - 09/Dec/04 11:28 AM
Screenshot to be sure.

Anton Mazkovoi [Atlassian] added a comment - 13/Dec/04 07:18 PM
Mike,

Your screenshot indicates that profiling is indeed turned on.

If you go to Issue Navigator while profiling is turned on and do a search you should see something like this appear in the logs:

PROFILED : 221 keys (4 unique) took 19ms/239ms : 7.949791% 0ms/query avg.
[1146ms] - /secure/IssueNavigator.jspa
[113ms] - IssueNavigator.execute()
[101ms] - IssueManager.execute()
[101ms] - Searching Issues
[19ms] - Lucene Query
[2ms] - Lucene Search
[5ms] - Retrieve From cache/db and filter
[1ms] - IssueManager.getIssue()
[1ms] - IssueManager.getIssue()
[1ms] - IssueManager.getIssue()
[1ms] - IssueManager.getIssue()
[77ms] - Sorting
[2ms] - Collection.iterator()
[1ms] - Collection.size()

Do these traces appear anywhere in the logs? Is JIRA updating the log, sometimes if the log is erroneously rotated by another active JIRA process the actual running version of JIRA does not have the reference to the log file anymore. In this case JIRA will need to be bounced, profiling turned on again and the search rerun.

Please let us knwo how you go.

Thanks,
Anton


Mike Aizatsky added a comment - 28/Dec/04 10:57 AM
I still get no such output in log file. Any idea why it can happen?

Jeff Turner [Atlassian] added a comment - 28/Dec/04 09:51 PM
The profile log goes to System.out (see UtilTimerStack.java). Perhaps something has rerouted System.out elsewhere?

The following JSP should help with debugging:

<%@ page import="com.atlassian.util.profiling.UtilTimerStack"%>
Writing to stdout.. <% System.out.println("To stdout"); %>
Writing to stderr.. <% System.err.println("To stderr"); %>
<% UtilTimerStack.push("Sleeping");
Thread.sleep(500);
UtilTimerStack.pop("Sleeping");
%>


Scott Farquhar [Atlassian] added a comment - 12/Apr/05 05:00 AM
Mike,

I'm currently working on improving this. I'll get back to you with more information as I have it.

I'll ask you for your data sometime over the next few weeks to ensure that our performance improvements work for you.


Scott Farquhar [Atlassian] added a comment - 17/May/05 04:37 AM
We now have much improved issue navigator searching.