Logging user spent time problem

Hello,

I have a question, we have Redmine version 3.3.1.stable and we have problem with logging spent time for user. If some of our custom fields are not filled out, it fires an error, but time is logged anyway, so the user has doubled time logged, how can I setup this?

Thank you for any answer.
Regards,
Andrej

Replies (7)

RE: Logging user spent time problem - Added by Pavel Potcheptsov over 8 years ago

Provide screenshots of your custom field settings and how page looks before and after pressing Save.
And provide log for that time.

RE: Logging user spent time problem - Added by Andrej Simko over 8 years ago

I am attaching screenshots...

Download all files

redmine-before.png (42.5 KB) redmine-before.png
redmine-after.png (44.2 KB) redmine-after.png
redmine-spent-time.png (35.9 KB) redmine-spent-time.png

RE: Logging user spent time problem - Added by Pavel Potcheptsov over 8 years ago

Maybe you don't need to set those fields as Required?

RE: Logging user spent time problem - Added by Andrej Simko over 8 years ago

Yes we need them as required. That is not solution for this problem :-)
Any other suggestion?

RE: Logging user spent time problem - Added by Pavel Potcheptsov over 8 years ago

And provide log for that time.

RE: Logging user spent time problem - Added by Andrej Simko over 8 years ago

Here is the log:

Started PATCH "/issues/19909" for 95.170.246.42 at 2017-02-16 10:13:46 +0100
Processing by IssuesController#update as HTML
  Parameters: {"utf8"=>"✓", "authenticity_token"=>"blabla", "form_update_triggered_by"=>"", "issue"=>{"description"=>"blabla", "status_id"=>"23", "priority_id"=>"3", "assigned_to_id"=>"50", "due_date"=>"2017-02-18", "estimated_hours"=>"", "done_ratio"=>"0", "custom_field_values"=>{"48"=>"Bratislava", "42"=>"Ano", "25"=>"blabla", "27"=>"", "51"=>"", "46"=>"blabla", "73"=>"blay", "74"=>"bla"}, "notes"=>"", "lock_version"=>"24"}, "time_entry"=>{"hours"=>"10", "activity_id"=>"20", "comments"=>""}, "helpdesk"=>{"canned_response"=>"", "is_cc"=>""}, "journal_message"=>{"to_address"=>"sbla", "cc_address"=>"blabla", "bcc_address"=>""}, "last_journal_id"=>"262818", "commit"=>"Submit", "next_issue_id"=>"19790", "issue_position"=>"1", "issue_count"=>"4", "id"=>"19909"}
  Setting Load (0.2ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'session_lifetime'  ORDER BY `settings`.`id` DESC LIMIT 1
  Setting Load (0.1ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'session_timeout'  ORDER BY `settings`.`id` DESC LIMIT 1
  SQL (0.6ms)  UPDATE `tokens` SET `tokens`.`updated_on` = '2017-02-16 10:13:46' WHERE `tokens`.`user_id` = 50 AND `tokens`.`value` = 'fd1425c0b866e7b12d8f78fcbca59e18395dac3a' AND `tokens`.`action` = 'session'
   (0.2ms)  SELECT MAX(`settings`.`updated_on`) FROM `settings`
  User Load (0.2ms)  SELECT  `users`.* FROM `users` WHERE `users`.`type` IN ('User', 'AnonymousUser') AND `users`.`status` = 1 AND `users`.`id` = 50 LIMIT 1
  Current user: simkoa (id=50)
   (0.3ms)  SELECT MAX(`contacts_settings`.`updated_on`) FROM `contacts_settings`
  Setting Load (0.5ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'force_default_language_for_loggedin'  ORDER BY `settings`.`id` DESC LIMIT 1
  Issue Load (0.8ms)  SELECT  `issues`.* FROM `issues` WHERE `issues`.`id` = 19909 LIMIT 1
  Project Load (0.3ms)  SELECT  `projects`.* FROM `projects` WHERE `projects`.`id` = 102 LIMIT 1
   (0.4ms)  SELECT `enabled_modules`.`name` FROM `enabled_modules` WHERE `enabled_modules`.`project_id` = 102
  Member Load (0.4ms)  SELECT  `members`.* FROM `members` INNER JOIN `projects` ON `projects`.`id` = `members`.`project_id` WHERE `members`.`user_id` = 50 AND (projects.status<>9) AND `members`.`project_id` = 102  ORDER BY `members`.`id` ASC LIMIT 1
  Project Load (0.5ms)  SELECT `projects`.* FROM `projects` WHERE `projects`.`id` IN (102)
  MemberRole Load (0.3ms)  SELECT `member_roles`.* FROM `member_roles` WHERE `member_roles`.`member_id` IN (1696)
  Role Load (0.3ms)  SELECT `roles`.* FROM `roles` WHERE `roles`.`id` IN (19)
  TimeEntryActivity Load (0.7ms)  SELECT  `enumerations`.* FROM `enumerations` WHERE `enumerations`.`type` IN ('TimeEntryActivity') AND `enumerations`.`is_default` = 1  ORDER BY `enumerations`.`position` ASC LIMIT 1
  Tracker Load (1.3ms)  SELECT  `trackers`.* FROM `trackers` WHERE `trackers`.`id` = 9 LIMIT 1
  Setting Load (0.4ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'issue_done_ratio'  ORDER BY `settings`.`id` DESC LIMIT 1
  IssueCustomField Load (1.4ms)  SELECT `custom_fields`.* FROM `custom_fields` WHERE `custom_fields`.`type` IN ('IssueCustomField') AND (is_for_all = 1 OR id IN (SELECT DISTINCT cfp.custom_field_id FROM custom_fields_projects cfp WHERE cfp.project_id = 102))  ORDER BY `custom_fields`.`position` ASC
  IssueCustomField Load (0.8ms)  SELECT `custom_fields`.* FROM `custom_fields` INNER JOIN `custom_fields_trackers` ON `custom_fields`.`id` = `custom_fields_trackers`.`custom_field_id` WHERE `custom_fields`.`type` IN ('IssueCustomField') AND `custom_fields_trackers`.`tracker_id` = 9
  SQL (2.2ms)  SELECT `custom_values`.`id` AS t0_r0, `custom_values`.`customized_type` AS t0_r1, `custom_values`.`customized_id` AS t0_r2, `custom_values`.`custom_field_id` AS t0_r3, `custom_values`.`value` AS t0_r4, `custom_fields`.`id` AS t1_r0, `custom_fields`.`type` AS t1_r1, `custom_fields`.`name` AS t1_r2, `custom_fields`.`field_format` AS t1_r3, `custom_fields`.`possible_values` AS t1_r4, `custom_fields`.`regexp` AS t1_r5, `custom_fields`.`min_length` AS t1_r6, `custom_fields`.`max_length` AS t1_r7, `custom_fields`.`is_required` AS t1_r8, `custom_fields`.`is_for_all` AS t1_r9, `custom_fields`.`is_filter` AS t1_r10, `custom_fields`.`position` AS t1_r11, `custom_fields`.`searchable` AS t1_r12, `custom_fields`.`default_value` AS t1_r13, `custom_fields`.`editable` AS t1_r14, `custom_fields`.`visible` AS t1_r15, `custom_fields`.`multiple` AS t1_r16, `custom_fields`.`format_store` AS t1_r17, `custom_fields`.`description` AS t1_r18 FROM `custom_values` LEFT OUTER JOIN `custom_fields` ON `custom_fields`.`id` = `custom_values`.`custom_field_id` WHERE `custom_values`.`customized_id` = 19909 AND `custom_values`.`customized_type` = 'Issue'  ORDER BY custom_fields.position
  IssueStatus Load (0.7ms)  SELECT  `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 23 LIMIT 1
  User Load (0.7ms)  SELECT  `users`.* FROM `users` WHERE `users`.`type` IN ('User', 'AnonymousUser') AND `users`.`id` = 71 LIMIT 1
  IssueStatus Load (0.7ms)  SELECT DISTINCT `issue_statuses`.* FROM `issue_statuses` INNER JOIN `workflows` ON `workflows`.`new_status_id` = `issue_statuses`.`id` AND `workflows`.`type` IN ('WorkflowTransition') WHERE `workflows`.`old_status_id` = 23 AND `workflows`.`tracker_id` = 9 AND `workflows`.`role_id` = 19 AND (author = 0 OR assignee = 1)
  IssueRelation Load (0.6ms)  SELECT `issue_relations`.* FROM `issue_relations` WHERE `issue_relations`.`issue_to_id` = 19909
  Setting Load (0.4ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'plugin_redmine_agile'  ORDER BY `settings`.`id` DESC LIMIT 1
  WorkflowPermission Load (0.4ms)  SELECT `workflows`.* FROM `workflows` WHERE `workflows`.`type` IN ('WorkflowPermission') AND `workflows`.`tracker_id` = 9 AND `workflows`.`old_status_id` = 23 AND `workflows`.`role_id` = 19
   (5.2ms)  SELECT `custom_fields`.`id`, role_id FROM `custom_fields` INNER JOIN `custom_fields_roles` ON `custom_fields_roles`.`custom_field_id` = `custom_fields`.`id` INNER JOIN `roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields`.`type` IN ('IssueCustomField') AND `custom_fields`.`visible` = 0
  Setting Load (1.0ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'issue_group_assignment'  ORDER BY `settings`.`id` DESC LIMIT 1
  Setting Load (0.4ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'user_format'  ORDER BY `settings`.`id` DESC LIMIT 1
  Role Load (0.4ms)  SELECT `roles`.* FROM `roles` WHERE `roles`.`assignable` = 1
  Principal Load (0.4ms)  SELECT DISTINCT `users`.* FROM `users` INNER JOIN `members` ON `members`.`user_id` = `users`.`id` INNER JOIN `member_roles` ON `member_roles`.`member_id` = `members`.`id` INNER JOIN `roles` ON `roles`.`id` = `member_roles`.`role_id` WHERE `users`.`status` = 1 AND `members`.`project_id` = 102 AND `users`.`type` = 'User' AND `roles`.`assignable` = 1 AND `roles`.`id` IN (1, 36, 2, 3, 4, 5, 7, 9, 10, 11, 12, 14, 34, 35, 15, 16, 17, 19, 18, 20, 41, 21, 23, 24, 25, 26, 27, 28, 29, 30, 37, 42, 43, 44, 45, 46, 48, 51, 52)  ORDER BY users.type DESC, users.lastname, users.firstname, users.id
  Principal Load (1.1ms)  SELECT  `users`.* FROM `users` WHERE `users`.`id` = 50 LIMIT 1
  CACHE (0.0ms)  SELECT `workflows`.* FROM `workflows` WHERE `workflows`.`type` IN ('WorkflowPermission') AND `workflows`.`tracker_id` = 9 AND `workflows`.`old_status_id` = 23 AND `workflows`.`role_id` = 19  [["tracker_id", 9], ["old_status_id", 23]]
  CACHE (0.0ms)  SELECT `custom_fields`.`id`, role_id FROM `custom_fields` INNER JOIN `custom_fields_roles` ON `custom_fields_roles`.`custom_field_id` = `custom_fields`.`id` INNER JOIN `roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields`.`type` IN ('IssueCustomField') AND `custom_fields`.`visible` = 0  [["visible", false]]
  CACHE (0.0ms)  SELECT `workflows`.* FROM `workflows` WHERE `workflows`.`type` IN ('WorkflowPermission') AND `workflows`.`tracker_id` = 9 AND `workflows`.`old_status_id` = 23 AND `workflows`.`role_id` = 19  [["tracker_id", 9], ["old_status_id", 23]]
  CACHE (0.0ms)  SELECT `custom_fields`.`id`, role_id FROM `custom_fields` INNER JOIN `custom_fields_roles` ON `custom_fields_roles`.`custom_field_id` = `custom_fields`.`id` INNER JOIN `roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields`.`type` IN ('IssueCustomField') AND `custom_fields`.`visible` = 0  [["visible", false]]
  Role Load (0.3ms)  SELECT `roles`.* FROM `roles` INNER JOIN `custom_fields_roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields_roles`.`custom_field_id` = 51
  Role Load (0.3ms)  SELECT `roles`.* FROM `roles` INNER JOIN `custom_fields_roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields_roles`.`custom_field_id` = 61
  Role Load (0.5ms)  SELECT `roles`.* FROM `roles` INNER JOIN `custom_fields_roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields_roles`.`custom_field_id` = 60
  Role Load (0.6ms)  SELECT `roles`.* FROM `roles` INNER JOIN `custom_fields_roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields_roles`.`custom_field_id` = 46
  Role Load (0.8ms)  SELECT `roles`.* FROM `roles` INNER JOIN `custom_fields_roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields_roles`.`custom_field_id` = 73
  Role Load (0.3ms)  SELECT `roles`.* FROM `roles` INNER JOIN `custom_fields_roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields_roles`.`custom_field_id` = 74
  CACHE (0.1ms)  SELECT DISTINCT `issue_statuses`.* FROM `issue_statuses` INNER JOIN `workflows` ON `workflows`.`new_status_id` = `issue_statuses`.`id` AND `workflows`.`type` IN ('WorkflowTransition') WHERE `workflows`.`old_status_id` = 23 AND `workflows`.`tracker_id` = 9 AND `workflows`.`role_id` = 19 AND (author = 0 OR assignee = 1)  [["old_status_id", 23], ["tracker_id", 9]]
   (0.3ms)  BEGIN
  UserPreference Load (0.3ms)  SELECT  `user_preferences`.* FROM `user_preferences` WHERE `user_preferences`.`user_id` = 50 LIMIT 1
  CustomField Load (0.3ms)  SELECT `custom_fields`.* FROM `custom_fields` WHERE (type = 'TimeEntryCustomField')  ORDER BY `custom_fields`.`position` ASC
   (1.5ms)  SELECT `enumerations`.`parent_id` FROM `enumerations` WHERE `enumerations`.`type` IN ('TimeEntryActivity') AND `enumerations`.`project_id` = 102  ORDER BY `enumerations`.`position` ASC
  TimeEntryActivity Load (0.4ms)  SELECT  `enumerations`.* FROM `enumerations` WHERE `enumerations`.`type` IN ('TimeEntryActivity') AND `enumerations`.`id` = 20  ORDER BY `enumerations`.`position` ASC LIMIT 1
  TimeEntryActivity Load (0.2ms)  SELECT `enumerations`.* FROM `enumerations` WHERE `enumerations`.`type` IN ('TimeEntryActivity') AND (enumerations.project_id IS NULL OR enumerations.project_id = 102) AND (enumerations.id NOT IN (11,8,9,30)) AND `enumerations`.`active` = 1  ORDER BY `enumerations`.`position` ASC
  SQL (1.0ms)  INSERT INTO `time_entries` (`issue_id`, `project_id`, `activity_id`, `hours`, `comments`, `user_id`, `spent_on`, `tyear`, `tmonth`, `tweek`, `created_on`, `updated_on`) VALUES (19909, 102, 20, 10.0, '', 50, '2017-02-16', 2017, 2, 7, '2017-02-16 10:13:47', '2017-02-16 10:13:47')
  SQL (1.2ms)  SELECT `custom_values`.`id` AS t0_r0, `custom_values`.`customized_type` AS t0_r1, `custom_values`.`customized_id` AS t0_r2, `custom_values`.`custom_field_id` AS t0_r3, `custom_values`.`value` AS t0_r4, `custom_fields`.`id` AS t1_r0, `custom_fields`.`type` AS t1_r1, `custom_fields`.`name` AS t1_r2, `custom_fields`.`field_format` AS t1_r3, `custom_fields`.`possible_values` AS t1_r4, `custom_fields`.`regexp` AS t1_r5, `custom_fields`.`min_length` AS t1_r6, `custom_fields`.`max_length` AS t1_r7, `custom_fields`.`is_required` AS t1_r8, `custom_fields`.`is_for_all` AS t1_r9, `custom_fields`.`is_filter` AS t1_r10, `custom_fields`.`position` AS t1_r11, `custom_fields`.`searchable` AS t1_r12, `custom_fields`.`default_value` AS t1_r13, `custom_fields`.`editable` AS t1_r14, `custom_fields`.`visible` AS t1_r15, `custom_fields`.`multiple` AS t1_r16, `custom_fields`.`format_store` AS t1_r17, `custom_fields`.`description` AS t1_r18 FROM `custom_values` LEFT OUTER JOIN `custom_fields` ON `custom_fields`.`id` = `custom_values`.`custom_field_id` WHERE `custom_values`.`customized_id` = 31226 AND `custom_values`.`customized_type` = 'TimeEntry'  ORDER BY custom_fields.position
  EnabledModule Load (0.8ms)  SELECT `enabled_modules`.* FROM `enabled_modules` WHERE `enabled_modules`.`project_id` = 102
  Issue Load (1.5ms)  SELECT  `issues`.* FROM `issues` WHERE `issues`.`id` = 19909 LIMIT 1
  AgileColor Load (0.4ms)  SELECT  `agile_colors`.* FROM `agile_colors` WHERE `agile_colors`.`container_id` = 19909 AND `agile_colors`.`container_type` = 'Issue' LIMIT 1
  CACHE (0.0ms)  SELECT  `agile_colors`.* FROM `agile_colors` WHERE `agile_colors`.`container_id` = 19909 AND `agile_colors`.`container_type` = 'Issue' LIMIT 1  [["container_id", 19909], ["container_type", "Issue"]]
  AgileData Load (0.5ms)  SELECT  `agile_data`.* FROM `agile_data` WHERE `agile_data`.`issue_id` = 19909 LIMIT 1
  CACHE (0.0ms)  SELECT  `agile_data`.* FROM `agile_data` WHERE `agile_data`.`issue_id` = 19909 LIMIT 1  [["issue_id", 19909]]
  WorkflowPermission Load (1.7ms)  SELECT `workflows`.* FROM `workflows` WHERE `workflows`.`type` IN ('WorkflowPermission') AND `workflows`.`tracker_id` = 9 AND `workflows`.`old_status_id` = 23 AND `workflows`.`role_id` = 19
   (0.7ms)  SELECT `custom_fields`.`id`, role_id FROM `custom_fields` INNER JOIN `custom_fields_roles` ON `custom_fields_roles`.`custom_field_id` = `custom_fields`.`id` INNER JOIN `roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields`.`type` IN ('IssueCustomField') AND `custom_fields`.`visible` = 0
  CACHE (0.0ms)  SELECT `workflows`.* FROM `workflows` WHERE `workflows`.`type` IN ('WorkflowPermission') AND `workflows`.`tracker_id` = 9 AND `workflows`.`old_status_id` = 23 AND `workflows`.`role_id` = 19  [["tracker_id", 9], ["old_status_id", 23]]
  CACHE (0.1ms)  SELECT `custom_fields`.`id`, role_id FROM `custom_fields` INNER JOIN `custom_fields_roles` ON `custom_fields_roles`.`custom_field_id` = `custom_fields`.`id` INNER JOIN `roles` ON `roles`.`id` = `custom_fields_roles`.`role_id` WHERE `custom_fields`.`type` IN ('IssueCustomField') AND `custom_fields`.`visible` = 0  [["visible", false]]
   (0.4ms)  ROLLBACK
  Tracker Load (0.4ms)  SELECT  `trackers`.* FROM `trackers` WHERE `trackers`.`id` = 9 LIMIT 1
  Rendered plugins/redmine_contacts_helpdesk/app/views/issues/_ticket_data_form.html.erb (1.9ms)
  Setting Load (0.4ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'text_formatting'  ORDER BY `settings`.`id` DESC LIMIT 1
  ThemeChangerUserSetting Load (0.3ms)  SELECT  `theme_changer_user_settings`.* FROM `theme_changer_user_settings` WHERE `theme_changer_user_settings`.`user_id` = 50 LIMIT 1
  Setting Load (0.3ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'ui_theme'  ORDER BY `settings`.`id` DESC LIMIT 1
  IssueStatus Load (0.4ms)  SELECT  `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
  IssuePriority Load (0.2ms)  SELECT `enumerations`.* FROM `enumerations` WHERE `enumerations`.`type` IN ('IssuePriority') AND `enumerations`.`active` = 1  ORDER BY `enumerations`.`position` ASC
  Setting Load (0.6ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'start_of_week'  ORDER BY `settings`.`id` DESC LIMIT 1
  Rendered issues/_form_custom_fields.html.erb (8.2ms)
  Rendered issues/_attributes.html.erb (38.0ms)
  Rendered plugins/redmine_agile/app/views/issues/_issue_color_form.html.erb (1.4ms)
  Rendered plugins/redmine_agile/app/views/issues/_issue_story_points_form.html.erb (0.8ms)
  Rendered plugins/redmine_agile/app/views/issues/_agile_data_fields.html.erb (8.3ms)
  Rendered plugins/redmine_contacts/app/views/deals_issues/_form.html.erb (1.3ms)
  Rendered issues/_form.html.erb (262.4ms)
   (2.8ms)  SELECT `enumerations`.`parent_id` FROM `enumerations` WHERE `enumerations`.`type` IN ('TimeEntryActivity') AND `enumerations`.`project_id` = 102  ORDER BY `enumerations`.`position` ASC
  TimeEntryActivity Load (1.8ms)  SELECT `enumerations`.* FROM `enumerations` WHERE `enumerations`.`type` IN ('TimeEntryActivity') AND (enumerations.project_id IS NULL OR enumerations.project_id = 102) AND (enumerations.id NOT IN (11,8,9,30)) AND `enumerations`.`active` = 1  ORDER BY `enumerations`.`position` ASC
  Contact Load (1.4ms)  SELECT  `contacts`.* FROM `contacts` INNER JOIN `helpdesk_tickets` ON `contacts`.`id` = `helpdesk_tickets`.`contact_id` WHERE `helpdesk_tickets`.`issue_id` = 19909 LIMIT 1
  Contact Load (1.4ms)  SELECT DISTINCT `contacts`.* FROM `contacts` INNER JOIN `contacts_issues` ON `contacts`.`id` = `contacts_issues`.`contact_id` WHERE `contacts_issues`.`issue_id` = 19909
  HelpdeskTicket Load (2.8ms)  SELECT  `helpdesk_tickets`.* FROM `helpdesk_tickets` WHERE `helpdesk_tickets`.`issue_id` = 19909 LIMIT 1
  ContactsSetting Load (0.4ms)  SELECT  `contacts_settings`.* FROM `contacts_settings` WHERE `contacts_settings`.`name` = 'helpdesk_answered_status' AND `contacts_settings`.`project_id` = 102 LIMIT 1
  Role Load (1.4ms)  SELECT  `roles`.* FROM `roles` WHERE `roles`.`builtin` = 1  ORDER BY `roles`.`id` ASC LIMIT 1
  Member Load (0.9ms)  SELECT `members`.* FROM `members` INNER JOIN `projects` ON `projects`.`id` = `members`.`project_id` INNER JOIN `users` ON `users`.`id` = `members`.`user_id` WHERE (projects.status <> 9) AND (members.user_id = 50 OR (projects.is_public = 1 AND users.type = 'GroupNonMember'))
  Project Load (0.9ms)  SELECT `projects`.* FROM `projects` WHERE `projects`.`id` IN (114, 111, 39, 4, 96, 25, 17, 80, 102, 90, 5, 6, 7, 10, 11, 45, 65, 68, 73, 78, 86, 88, 89, 91, 92, 93, 94, 95, 97, 98, 40, 100, 16, 108, 106, 105, 112, 113, 115, 116, 117, 121, 119, 26, 118, 103, 120, 122)
  MemberRole Load (0.5ms)  SELECT `member_roles`.* FROM `member_roles` WHERE `member_roles`.`member_id` IN (2961, 2482, 2253, 1107, 1111, 1114, 1152, 1325, 1696, 1394, 1428, 1429, 1430, 1431, 1432, 1433, 1434, 1435, 1436, 1437, 1438, 1439, 1440, 1441, 1442, 1443, 1444, 1445, 1446, 1447, 1450, 1506, 1623, 2061, 2126, 2411, 2692, 2770, 3133, 3246, 3296, 4655, 3453, 3463, 3470, 3547, 4308, 4891)
  Role Load (0.4ms)  SELECT `roles`.* FROM `roles` WHERE `roles`.`id` IN (43, 4, 32, 21, 14, 5, 19, 33, 29, 38, 3, 26)
   (0.3ms)  SELECT COUNT(DISTINCT `canned_responses`.`id`) FROM `canned_responses` LEFT OUTER JOIN `projects` ON `projects`.`id` = `canned_responses`.`project_id` WHERE ((canned_responses.project_id IS NULL OR (((projects.status = 1 AND projects.id IN (SELECT em.project_id FROM enabled_modules em WHERE em.name='contacts_helpdesk')) AND (projects.id IN (96) OR projects.id IN (102,116) OR projects.id IN (108) OR projects.id IN (105,117) OR projects.id IN (121) OR projects.id IN (103))))) AND (canned_responses.is_public = 1 OR canned_responses.user_id = 50)) AND ((canned_responses.project_id IS NULL AND canned_responses.is_public = 1) OR canned_responses.project_id = 102)
  SQL (0.3ms)  SELECT `canned_responses`.`id` AS t0_r0, `canned_responses`.`name` AS t0_r1, `canned_responses`.`content` AS t0_r2, `canned_responses`.`project_id` AS t0_r3, `canned_responses`.`user_id` AS t0_r4, `canned_responses`.`is_public` AS t0_r5, `projects`.`id` AS t1_r0, `projects`.`name` AS t1_r1, `projects`.`description` AS t1_r2, `projects`.`homepage` AS t1_r3, `projects`.`is_public` AS t1_r4, `projects`.`parent_id` AS t1_r5, `projects`.`created_on` AS t1_r6, `projects`.`updated_on` AS t1_r7, `projects`.`identifier` AS t1_r8, `projects`.`status` AS t1_r9, `projects`.`lft` AS t1_r10, `projects`.`rgt` AS t1_r11, `projects`.`inherit_members` AS t1_r12, `projects`.`default_version_id` AS t1_r13 FROM `canned_responses` LEFT OUTER JOIN `projects` ON `projects`.`id` = `canned_responses`.`project_id` WHERE ((canned_responses.project_id IS NULL OR (((projects.status = 1 AND projects.id IN (SELECT em.project_id FROM enabled_modules em WHERE em.name='contacts_helpdesk')) AND (projects.id IN (96) OR projects.id IN (102,116) OR projects.id IN (108) OR projects.id IN (105,117) OR projects.id IN (121) OR projects.id IN (103))))) AND (canned_responses.is_public = 1 OR canned_responses.user_id = 50)) AND ((canned_responses.project_id IS NULL AND canned_responses.is_public = 1) OR canned_responses.project_id = 102)  ORDER BY canned_responses.name
  ContactsSetting Load (0.4ms)  SELECT  `contacts_settings`.* FROM `contacts_settings` WHERE `contacts_settings`.`name` = 'helpdesk_emails_header' AND `contacts_settings`.`project_id` = 102 LIMIT 1
  Setting Load (1.7ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'plugin_redmine_contacts'  ORDER BY `settings`.`id` DESC LIMIT 1
  Setting Load (0.3ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'date_format'  ORDER BY `settings`.`id` DESC LIMIT 1
  IssuePriority Load (0.6ms)  SELECT  `enumerations`.* FROM `enumerations` WHERE `enumerations`.`type` IN ('IssuePriority') AND `enumerations`.`id` = 3  ORDER BY `enumerations`.`position` ASC LIMIT 1
  Setting Load (0.3ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'plugin_redmine_contacts_helpdesk'  ORDER BY `settings`.`id` DESC LIMIT 1
  CustomField Load (0.5ms)  SELECT `custom_fields`.* FROM `custom_fields` WHERE (type = 'ContactCustomField')  ORDER BY `custom_fields`.`position` ASC
  SQL (2.0ms)  SELECT `custom_values`.`id` AS t0_r0, `custom_values`.`customized_type` AS t0_r1, `custom_values`.`customized_id` AS t0_r2, `custom_values`.`custom_field_id` AS t0_r3, `custom_values`.`value` AS t0_r4, `custom_fields`.`id` AS t1_r0, `custom_fields`.`type` AS t1_r1, `custom_fields`.`name` AS t1_r2, `custom_fields`.`field_format` AS t1_r3, `custom_fields`.`possible_values` AS t1_r4, `custom_fields`.`regexp` AS t1_r5, `custom_fields`.`min_length` AS t1_r6, `custom_fields`.`max_length` AS t1_r7, `custom_fields`.`is_required` AS t1_r8, `custom_fields`.`is_for_all` AS t1_r9, `custom_fields`.`is_filter` AS t1_r10, `custom_fields`.`position` AS t1_r11, `custom_fields`.`searchable` AS t1_r12, `custom_fields`.`default_value` AS t1_r13, `custom_fields`.`editable` AS t1_r14, `custom_fields`.`visible` AS t1_r15, `custom_fields`.`multiple` AS t1_r16, `custom_fields`.`format_store` AS t1_r17, `custom_fields`.`description` AS t1_r18 FROM `custom_values` LEFT OUTER JOIN `custom_fields` ON `custom_fields`.`id` = `custom_values`.`custom_field_id` WHERE `custom_values`.`customized_id` = 5498 AND `custom_values`.`customized_type` = 'Contact'  ORDER BY custom_fields.position
  CustomField Load (0.8ms)  SELECT `custom_fields`.* FROM `custom_fields` WHERE (type = 'UserCustomField')  ORDER BY `custom_fields`.`position` ASC
  SQL (3.3ms)  SELECT `custom_values`.`id` AS t0_r0, `custom_values`.`customized_type` AS t0_r1, `custom_values`.`customized_id` AS t0_r2, `custom_values`.`custom_field_id` AS t0_r3, `custom_values`.`value` AS t0_r4, `custom_fields`.`id` AS t1_r0, `custom_fields`.`type` AS t1_r1, `custom_fields`.`name` AS t1_r2, `custom_fields`.`field_format` AS t1_r3, `custom_fields`.`possible_values` AS t1_r4, `custom_fields`.`regexp` AS t1_r5, `custom_fields`.`min_length` AS t1_r6, `custom_fields`.`max_length` AS t1_r7, `custom_fields`.`is_required` AS t1_r8, `custom_fields`.`is_for_all` AS t1_r9, `custom_fields`.`is_filter` AS t1_r10, `custom_fields`.`position` AS t1_r11, `custom_fields`.`searchable` AS t1_r12, `custom_fields`.`default_value` AS t1_r13, `custom_fields`.`editable` AS t1_r14, `custom_fields`.`visible` AS t1_r15, `custom_fields`.`multiple` AS t1_r16, `custom_fields`.`format_store` AS t1_r17, `custom_fields`.`description` AS t1_r18 FROM `custom_values` LEFT OUTER JOIN `custom_fields` ON `custom_fields`.`id` = `custom_values`.`custom_field_id` WHERE `custom_values`.`customized_id` = 50 AND `custom_values`.`customized_type` = 'Principal'  ORDER BY custom_fields.position
  Setting Load (0.5ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'cache_formatted_text'  ORDER BY `settings`.`id` DESC LIMIT 1
  ContactsSetting Load (1.2ms)  SELECT  `contacts_settings`.* FROM `contacts_settings` WHERE `contacts_settings`.`name` = 'helpdesk_emails_footer' AND `contacts_settings`.`project_id` = 102 LIMIT 1
  Setting Load (3.3ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'protocol'  ORDER BY `settings`.`id` DESC LIMIT 1
  Setting Load (0.3ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'host_name'  ORDER BY `settings`.`id` DESC LIMIT 1
  Group Load (1.3ms)  SELECT `users`.* FROM `users` INNER JOIN `groups_users` ON `users`.`id` = `groups_users`.`group_id` WHERE `users`.`type` IN ('Group', 'GroupBuiltin', 'GroupAnonymous', 'GroupNonMember') AND `groups_users`.`user_id` = 50
  Issue Load (3.0ms)  SELECT  `issues`.* FROM `issues` INNER JOIN `projects` ON `projects`.`id` = `issues`.`project_id` WHERE (((projects.status <> 9 AND projects.id IN (SELECT em.project_id FROM enabled_modules em WHERE em.name='issue_tracking')) AND ((projects.id IN (114,112,118,120) AND ((issues.is_private = 0 OR issues.author_id = 50 OR issues.assigned_to_id IN (50,60)))) OR (projects.id IN (111,4,17,80,90,5,6,7,10,11,45,65,68,73,78,86,88,89,91,92,93,94,95,97,98,100,113,121,119,26,122) AND ((issues.is_private = 0 OR issues.author_id = 50 OR issues.assigned_to_id IN (50,60)))) OR (projects.id IN (39,40,106) AND ((issues.author_id = 50 OR issues.assigned_to_id IN (50,60)))) OR (projects.id IN (96) AND ((issues.is_private = 0 OR issues.author_id = 50 OR issues.assigned_to_id IN (50,60)))) OR (projects.id IN (25) AND ((issues.is_private = 0 OR issues.author_id = 50 OR issues.assigned_to_id IN (50,60)))) OR (projects.id IN (17,80,16) AND ((issues.is_private = 0 OR issues.author_id = 50 OR issues.assigned_to_id IN (50,60)))) OR (projects.id IN (102,116) AND ((issues.is_private = 0 OR issues.author_id = 50 OR issues.assigned_to_id IN (50,60)))) OR (projects.id IN (108) AND ((issues.is_private = 0 OR issues.author_id = 50 OR issues.assigned_to_id IN (50,60)))) OR (projects.id IN (105,117) AND ((issues.is_private = 0 OR issues.author_id = 50 OR issues.assigned_to_id IN (50,60)))) OR (projects.id IN (121) AND (1=1)) OR (projects.id IN (103) AND ((issues.is_private = 0 OR issues.author_id = 50 OR issues.assigned_to_id IN (50,60))))))) AND `issues`.`id` = 19909 LIMIT 1
  CACHE (0.1ms)  SELECT  `enumerations`.* FROM `enumerations` WHERE `enumerations`.`type` IN ('IssuePriority') AND `enumerations`.`id` = 3  ORDER BY `enumerations`.`position` ASC LIMIT 1  [["id", 3]]
  IssueStatus Load (0.4ms)  SELECT  `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 23 LIMIT 1
  CACHE (0.0ms)  SELECT  `trackers`.* FROM `trackers` WHERE `trackers`.`id` = 9 LIMIT 1  [["id", 9]]
  ContactsSetting Load (0.3ms)  SELECT  `contacts_settings`.* FROM `contacts_settings` WHERE `contacts_settings`.`name` = 'send_note_by_default' AND `contacts_settings`.`project_id` = 102 LIMIT 1
  JournalMessage Load (1.9ms)  SELECT  `journal_messages`.* FROM `journal_messages` INNER JOIN `journals` ON `journals`.`id` = `journal_messages`.`journal_id` WHERE `journals`.`journalized_id` = 19909  ORDER BY journal_messages.message_date DESC LIMIT 1
  JournalMessage Load (1.7ms)  SELECT  `journal_messages`.* FROM `journal_messages` INNER JOIN `journals` ON `journals`.`id` = `journal_messages`.`journal_id` WHERE `journals`.`journalized_id` = 19909 AND `journal_messages`.`is_incoming` = 1  ORDER BY journal_messages.message_date DESC LIMIT 1
  Contact Load (10.2ms)  SELECT  `contacts`.* FROM `contacts` WHERE `contacts`.`email` = 'sblabla'  ORDER BY `contacts`.`id` ASC LIMIT 1
  CACHE (0.1ms)  SELECT  `journal_messages`.* FROM `journal_messages` INNER JOIN `journals` ON `journals`.`id` = `journal_messages`.`journal_id` WHERE `journals`.`journalized_id` = 19909 AND `journal_messages`.`is_incoming` = 1  ORDER BY journal_messages.message_date DESC LIMIT 1  [["journalized_id", 19909], ["is_incoming", true]]
  Project Load (0.5ms)  SELECT DISTINCT `projects`.* FROM `projects` INNER JOIN `contacts_projects` ON `projects`.`id` = `contacts_projects`.`project_id` WHERE `contacts_projects`.`contact_id` = 5498
   (0.3ms)  SELECT `enabled_modules`.`name` FROM `enabled_modules` WHERE `enabled_modules`.`project_id` = 102
  Attachment Load (1.0ms)  SELECT  `attachments`.* FROM `attachments` WHERE `attachments`.`container_id` = 5498 AND `attachments`.`container_type` = 'Contact' AND (attachments.description = 'avatar') LIMIT 1
  Setting Load (0.6ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'gravatar_enabled'  ORDER BY `settings`.`id` DESC LIMIT 1
  Setting Load (1.3ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'gravatar_default'  ORDER BY `settings`.`id` DESC LIMIT 1
  Rendered plugins/redmine_contacts_helpdesk/app/views/issues/_customer_to_email.html.erb (99.0ms)
  CACHE (0.0ms)  SELECT  `journal_messages`.* FROM `journal_messages` INNER JOIN `journals` ON `journals`.`id` = `journal_messages`.`journal_id` WHERE `journals`.`journalized_id` = 19909 AND `journal_messages`.`is_incoming` = 1  ORDER BY journal_messages.message_date DESC LIMIT 1  [["journalized_id", 19909], ["is_incoming", true]]
  CACHE (0.0ms)  SELECT  `journal_messages`.* FROM `journal_messages` INNER JOIN `journals` ON `journals`.`id` = `journal_messages`.`journal_id` WHERE `journals`.`journalized_id` = 19909 AND `journal_messages`.`is_incoming` = 1  ORDER BY journal_messages.message_date DESC LIMIT 1  [["journalized_id", 19909], ["is_incoming", true]]
  Rendered plugins/redmine_contacts_helpdesk/app/views/issues/_send_response.html.erb (602.3ms)
  Setting Load (2.3ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'attachment_max_size'  ORDER BY `settings`.`id` DESC LIMIT 1
  Rendered attachments/_form.html.erb (12.8ms)
  Rendered issues/_edit.html.erb (958.8ms)
  Rendered issues/edit.html.erb within layouts/base (966.2ms)
  Setting Load (0.3ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'app_title'  ORDER BY `settings`.`id` DESC LIMIT 1
  EmailAddress Load (0.4ms)  SELECT  `email_addresses`.* FROM `email_addresses` WHERE `email_addresses`.`user_id` = 50 AND `email_addresses`.`is_default` = 1 LIMIT 1
  Setting Load (0.5ms)  SELECT  `settings`.* FROM `settings` WHERE `settings`.`name` = 'new_item_menu_tab'  ORDER BY `settings`.`id` DESC LIMIT 1
  Member Load (0.4ms)  SELECT `members`.* FROM `members` INNER JOIN `projects` ON `projects`.`id` = `members`.`project_id` WHERE `members`.`user_id` = 50 AND (projects.status<>9)
  CACHE (0.1ms)  SELECT `projects`.* FROM `projects` WHERE `projects`.`id` IN (114, 111, 39, 4, 96, 25, 17, 80, 102, 90, 5, 6, 7, 10, 11, 45, 65, 68, 73, 78, 86, 88, 89, 91, 92, 93, 94, 95, 97, 98, 40, 100, 16, 108, 106, 105, 112, 113, 115, 116, 117, 121, 119, 26, 118, 103, 120, 122)
  CACHE (0.1ms)  SELECT `member_roles`.* FROM `member_roles` WHERE `member_roles`.`member_id` IN (2961, 2482, 2253, 1107, 1111, 1114, 1152, 1325, 1696, 1394, 1428, 1429, 1430, 1431, 1432, 1433, 1434, 1435, 1436, 1437, 1438, 1439, 1440, 1441, 1442, 1443, 1444, 1445, 1446, 1447, 1450, 1506, 1623, 2061, 2126, 2411, 2692, 2770, 3133, 3246, 3296, 4655, 3453, 3463, 3470, 3547, 4308, 4891)
  CACHE (0.0ms)  SELECT `roles`.* FROM `roles` WHERE `roles`.`id` IN (43, 4, 32, 21, 14, 5, 19, 33, 29, 38, 3, 26)
  CACHE (0.0ms)  SELECT  `roles`.* FROM `roles` WHERE `roles`.`builtin` = 1  ORDER BY `roles`.`id` ASC LIMIT 1  [["builtin", 1]]
  CACHE (0.1ms)  SELECT  `roles`.* FROM `roles` WHERE `roles`.`builtin` = 1  ORDER BY `roles`.`id` ASC LIMIT 1  [["builtin", 1]]
  Project Load (0.5ms)  SELECT `projects`.`id`, `projects`.`name`, `projects`.`identifier`, `projects`.`lft`, `projects`.`rgt` FROM `projects` INNER JOIN `members` ON `projects`.`id` = `members`.`project_id` WHERE `members`.`user_id` = 50 AND (projects.status<>9) AND `projects`.`status` = 1
  GroupNonMember Load (1.3ms)  SELECT  `users`.* FROM `users` WHERE `users`.`type` IN ('GroupNonMember')  ORDER BY id LIMIT 1
  Project Load (0.3ms)  SELECT `projects`.* FROM `projects` WHERE (projects.lft < 181 AND projects.rgt > 182) AND (((projects.status <> 9) AND ((projects.is_public = 1 AND projects.id NOT IN (SELECT project_id FROM members WHERE user_id IN (50,116))) OR projects.id IN (114,112,118,120) OR projects.id IN (111,4,17,80,90,5,6,7,10,11,45,65,68,73,78,86,88,89,91,92,93,94,95,97,98,100,113,121,119,26,122) OR projects.id IN (39,40,106) OR projects.id IN (96) OR projects.id IN (25) OR projects.id IN (17,80,16) OR projects.id IN (102,116) OR projects.id IN (108) OR projects.id IN (105,117) OR projects.id IN (115) OR projects.id IN (121) OR projects.id IN (103))))  ORDER BY `projects`.`lft` ASC
   (0.4ms)  SELECT COUNT(*) FROM `trackers` INNER JOIN `projects_trackers` ON `trackers`.`id` = `projects_trackers`.`tracker_id` WHERE `projects_trackers`.`project_id` = 102
  Project Load (4.8ms)  SELECT  `projects`.* FROM `projects` WHERE (projects.lft <= 181 AND projects.rgt >= 182)  ORDER BY `projects`.`lft` ASC LIMIT 1
   (0.3ms)  SELECT COUNT(*) FROM `versions` INNER JOIN `projects` ON `projects`.`id` = `versions`.`project_id` WHERE (projects.id = 102 OR (projects.status <> 9 AND ( versions.sharing = 'system' OR (projects.lft >= 1 AND projects.rgt <= 190 AND versions.sharing = 'tree') OR (projects.lft < 181 AND projects.rgt > 182 AND versions.sharing IN ('hierarchy', 'descendants')) OR (projects.lft > 181 AND projects.rgt < 182 AND versions.sharing = 'hierarchy'))))
  CACHE (0.0ms)  SELECT COUNT(*) FROM `trackers` INNER JOIN `projects_trackers` ON `trackers`.`id` = `projects_trackers`.`tracker_id` WHERE `projects_trackers`.`project_id` = 102  [["project_id", 102]]
  Rendered plugins/sidebar_hide/app/views/sidebar/_hideButton_partial.html.erb (1.7ms)
Completed 200 OK in 2093ms (Views: 1209.8ms | ActiveRecord: 183.8ms)

RE: Logging user spent time problem - Added by Dimitar (RedmineUP) almost 3 years ago

Hi Andrej,

This is Dimitar from the RedmineUP Support Team.

I see that you have an Agile plugin on your logs.

So, could you please contact our support team at support@redmineup.com? And our support engineers will help you investigate the situation.

We look forward to hearing from you.

Best Regards,
Dimitar from the RedmineUP Support Team

Andrej Simko wrote:

Here is the log:
[...]

(1-7/7)

Project

General

Profile

Redmine