Project

General

Profile

RE: Redmine 0.8.7 -> 0.9.3 now very slow to display an issue ยป development.log

Alan Liu, 2010-04-15 09:05

 
1
# Logfile created on Thu Apr 15 14:55:01 +0800 2010  SQL (0.0ms)   SET NAMES 'utf8'
2
  SQL (0.0ms)   SET SQL_AUTO_IS_NULL=0
3
  SQL (0.0ms)   SET NAMES 'utf8'
4
  SQL (0.0ms)   SET SQL_AUTO_IS_NULL=0
5

    
6

    
7
Processing WelcomeController#index (for 10.50.240.158 at 2010-04-15 14:56:24) [GET]
8
  Parameters: {"action"=>"index", "controller"=>"welcome"}
9
  Setting Columns (0.0ms)   SHOW FIELDS FROM `settings`
10
  SQL (0.0ms)   SELECT max(`settings`.updated_on) AS max_updated_on FROM `settings` 
11
  User Columns (16.0ms)   SHOW FIELDS FROM `users`
12
  User Load (0.0ms)   SELECT * FROM `users` WHERE (`users`.`id` = 3) AND (users.status = 1) AND ( (`users`.`type` = 'User' OR `users`.`type` = 'AnonymousUser' ) ) 
13
  News Columns (15.0ms)   SHOW FIELDS FROM `news`
14
  Project Columns (0.0ms)   SHOW FIELDS FROM `projects`
15
  News Load Including Associations (0.0ms)   SELECT `news`.`id` AS t0_r0, `news`.`project_id` AS t0_r1, `news`.`title` AS t0_r2, `news`.`summary` AS t0_r3, `news`.`description` AS t0_r4, `news`.`author_id` AS t0_r5, `news`.`created_on` AS t0_r6, `news`.`comments_count` AS t0_r7, `users`.`id` AS t1_r0, `users`.`login` AS t1_r1, `users`.`hashed_password` AS t1_r2, `users`.`firstname` AS t1_r3, `users`.`lastname` AS t1_r4, `users`.`mail` AS t1_r5, `users`.`mail_notification` AS t1_r6, `users`.`admin` AS t1_r7, `users`.`status` AS t1_r8, `users`.`last_login_on` AS t1_r9, `users`.`language` AS t1_r10, `users`.`auth_source_id` AS t1_r11, `users`.`created_on` AS t1_r12, `users`.`updated_on` AS t1_r13, `users`.`type` AS t1_r14, `users`.`identity_url` AS t1_r15, `projects`.`id` AS t2_r0, `projects`.`name` AS t2_r1, `projects`.`description` AS t2_r2, `projects`.`homepage` AS t2_r3, `projects`.`is_public` AS t2_r4, `projects`.`parent_id` AS t2_r5, `projects`.`created_on` AS t2_r6, `projects`.`updated_on` AS t2_r7, `projects`.`identifier` AS t2_r8, `projects`.`status` AS t2_r9, `projects`.`lft` AS t2_r10, `projects`.`rgt` AS t2_r11 FROM `news` LEFT OUTER JOIN `users` ON `users`.id = `news`.author_id AND (`users`.`type` = 'User' OR `users`.`type` = 'AnonymousUser' ) LEFT OUTER JOIN `projects` ON `projects`.id = `news`.project_id WHERE (projects.status=1 AND projects.id IN (SELECT em.project_id FROM enabled_modules em WHERE em.name='news')) ORDER BY news.created_on DESC LIMIT 5
16
  Project Load (0.0ms)   SELECT * FROM `projects` WHERE (projects.status=1) ORDER BY created_on DESC LIMIT 5
17
Rendering template within layouts/base
18
Rendering welcome/index
19
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'welcome_text') LIMIT 1
20
  UserPreference Load (0.0ms)   SELECT * FROM `user_preferences` WHERE (`user_preferences`.user_id = 3) LIMIT 1
21
  UserPreference Columns (0.0ms)   SHOW FIELDS FROM `user_preferences`
22
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'time_format') LIMIT 1
23
  Token Load (0.0ms)   SELECT * FROM `tokens` WHERE (`tokens`.user_id = 3 AND (action='feeds')) LIMIT 1
24
  Token Columns (15.0ms)   SHOW FIELDS FROM `tokens`
25
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'app_title') LIMIT 1
26
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'ui_theme') LIMIT 1
27
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'text_formatting') LIMIT 1
28
  Project Load (16.0ms)   SELECT `projects`.* FROM `projects` INNER JOIN `members` ON `projects`.id = `members`.project_id WHERE ((`members`.user_id = 3) AND ((projects.status=1))) 
29
Completed in 2016ms (View: 1829, DB: 62) | 200 OK [http://10.50.241.20/]
30
  SQL (0.0ms)   SET NAMES 'utf8'
31
  SQL (0.0ms)   SET SQL_AUTO_IS_NULL=0
32

    
33

    
34
Processing AccountController#logout (for 10.50.240.158 at 2010-04-15 14:57:39) [GET]
35
  Parameters: {"action"=>"logout", "controller"=>"account"}
36
  Setting Columns (16.0ms)   SHOW FIELDS FROM `settings`
37
  SQL (0.0ms)   SELECT max(`settings`.updated_on) AS max_updated_on FROM `settings` 
38
  User Columns (0.0ms)   SHOW FIELDS FROM `users`
39
  User Load (0.0ms)   SELECT * FROM `users` WHERE (`users`.`id` = 3) AND (users.status = 1) AND ( (`users`.`type` = 'User' OR `users`.`type` = 'AnonymousUser' ) ) 
40
  Token Delete all (0.0ms)   DELETE FROM `tokens` WHERE (user_id = 3 AND action = 'autologin') 
41
  AnonymousUser Columns (0.0ms)   SHOW FIELDS FROM `users`
42
  AnonymousUser Load (0.0ms)   SELECT * FROM `users` WHERE ( (`users`.`type` = 'AnonymousUser' ) ) LIMIT 1
43
Redirected to http://10.50.241.20:3000/
44
Completed in 78ms (DB: 16) | 302 Found [http://10.50.241.20/logout]
45
  SQL (0.0ms)   SET NAMES 'utf8'
46
  SQL (0.0ms)   SET SQL_AUTO_IS_NULL=0
47

    
48

    
49
Processing WelcomeController#index (for 10.50.240.158 at 2010-04-15 14:57:46) [GET]
50
  Parameters: {"action"=>"index", "controller"=>"welcome"}
51
  Setting Columns (0.0ms)   SHOW FIELDS FROM `settings`
52
  SQL (0.0ms)   SELECT max(`settings`.updated_on) AS max_updated_on FROM `settings` 
53
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'rest_api_enabled') LIMIT 1
54
  AnonymousUser Columns (15.0ms)   SHOW FIELDS FROM `users`
55
  AnonymousUser Load (0.0ms)   SELECT * FROM `users` WHERE ( (`users`.`type` = 'AnonymousUser' ) ) LIMIT 1
56
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'login_required') LIMIT 1
57
  Role Load (0.0ms)   SELECT * FROM `roles` WHERE (`roles`.`builtin` = 2) LIMIT 1
58
  Role Columns (0.0ms)   SHOW FIELDS FROM `roles`
59
  News Columns (0.0ms)   SHOW FIELDS FROM `news`
60
  User Columns (16.0ms)   SHOW FIELDS FROM `users`
61
  Project Columns (0.0ms)   SHOW FIELDS FROM `projects`
62
  News Load Including Associations (0.0ms)   SELECT `news`.`id` AS t0_r0, `news`.`project_id` AS t0_r1, `news`.`title` AS t0_r2, `news`.`summary` AS t0_r3, `news`.`description` AS t0_r4, `news`.`author_id` AS t0_r5, `news`.`created_on` AS t0_r6, `news`.`comments_count` AS t0_r7, `users`.`id` AS t1_r0, `users`.`login` AS t1_r1, `users`.`hashed_password` AS t1_r2, `users`.`firstname` AS t1_r3, `users`.`lastname` AS t1_r4, `users`.`mail` AS t1_r5, `users`.`mail_notification` AS t1_r6, `users`.`admin` AS t1_r7, `users`.`status` AS t1_r8, `users`.`last_login_on` AS t1_r9, `users`.`language` AS t1_r10, `users`.`auth_source_id` AS t1_r11, `users`.`created_on` AS t1_r12, `users`.`updated_on` AS t1_r13, `users`.`type` AS t1_r14, `users`.`identity_url` AS t1_r15, `projects`.`id` AS t2_r0, `projects`.`name` AS t2_r1, `projects`.`description` AS t2_r2, `projects`.`homepage` AS t2_r3, `projects`.`is_public` AS t2_r4, `projects`.`parent_id` AS t2_r5, `projects`.`created_on` AS t2_r6, `projects`.`updated_on` AS t2_r7, `projects`.`identifier` AS t2_r8, `projects`.`status` AS t2_r9, `projects`.`lft` AS t2_r10, `projects`.`rgt` AS t2_r11 FROM `news` LEFT OUTER JOIN `users` ON `users`.id = `news`.author_id AND (`users`.`type` = 'User' OR `users`.`type` = 'AnonymousUser' ) LEFT OUTER JOIN `projects` ON `projects`.id = `news`.project_id WHERE (((projects.status=1 AND projects.id IN (SELECT em.project_id FROM enabled_modules em WHERE em.name='news')) AND (1=0 OR projects.is_public = 1))) ORDER BY news.created_on DESC LIMIT 5
63
  Member Columns (0.0ms)   SHOW FIELDS FROM `members`
64
  SQL (0.0ms)   SELECT count(DISTINCT `members`.id) AS count_all FROM `members` LEFT OUTER JOIN `projects` ON `projects`.id = `members`.project_id LEFT OUTER JOIN `member_roles` ON (`members`.`id` = `member_roles`.`member_id`) LEFT OUTER JOIN `roles` ON (`roles`.`id` = `member_roles`.`role_id`) WHERE (`members`.user_id = 2 AND (projects.status=1)) 
65
  Project Load (0.0ms)   SELECT * FROM `projects` WHERE (projects.status=1 AND projects.is_public = 1) ORDER BY created_on DESC LIMIT 5
66
Rendering template within layouts/base
67
Rendering welcome/index
68
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'welcome_text') LIMIT 1
69
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'time_format') LIMIT 1
70
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'app_title') LIMIT 1
71
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'ui_theme') LIMIT 1
72
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'text_formatting') LIMIT 1
73
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'self_registration') LIMIT 1
74
  Project Load (0.0ms)   SELECT `projects`.* FROM `projects` INNER JOIN `members` ON `projects`.id = `members`.project_id WHERE ((`members`.user_id = 2) AND ((projects.status=1))) 
75
Completed in 1532ms (View: 1391, DB: 31) | 200 OK [http://10.50.241.20/]
76
  SQL (0.0ms)   SET NAMES 'utf8'
77
  SQL (0.0ms)   SET SQL_AUTO_IS_NULL=0
78

    
79

    
80
Processing AccountController#login (for 10.50.240.158 at 2010-04-15 14:59:06) [GET]
81
  Parameters: {"action"=>"login", "controller"=>"account"}
82
  Setting Columns (0.0ms)   SHOW FIELDS FROM `settings`
83
  SQL (0.0ms)   SELECT max(`settings`.updated_on) AS max_updated_on FROM `settings` 
84
  Setting Load (15.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'rest_api_enabled') LIMIT 1
85
  AnonymousUser Columns (0.0ms)   SHOW FIELDS FROM `users`
86
  AnonymousUser Load (0.0ms)   SELECT * FROM `users` WHERE ( (`users`.`type` = 'AnonymousUser' ) ) LIMIT 1
87
  CACHE (0.0ms)   SELECT * FROM `users` WHERE ( (`users`.`type` = 'AnonymousUser' ) ) LIMIT 1
88
Rendering template within layouts/base
89
Rendering account/login
90
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'autologin') LIMIT 1
91
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'lost_password') LIMIT 1
92
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'app_title') LIMIT 1
93
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'ui_theme') LIMIT 1
94
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'text_formatting') LIMIT 1
95
  Setting Load (0.0ms)   SELECT * FROM `settings` WHERE (`settings`.`name` = 'self_registration') LIMIT 1
96
  Project Load (0.0ms)   SELECT `projects`.* FROM `projects` INNER JOIN `members` ON `projects`.id = `members`.project_id WHERE ((`members`.user_id = 2) AND ((projects.status=1))) 
97
Completed in 1015ms (View: 969, DB: 15) | 200 OK [http://10.50.241.20/login]
    (1-1/1)