Redmine API getting errors but I cannot tell what is causing the error

85 views Asked by At

I am using the Redmine API with PHP, which works very well. I've used this code that I'm using right now in the past, and it was fine. All of a sudden now, it's giving me an error, and I don't know exactly what the error is. I have set the logs to debug and this is what I'm getting below. Maybe I'm just blind. I don't know, but I do not see it.

Any help would be most appreciated.

Environment:
  Redmine version                5.0.3.stable
  Ruby version                   3.0.2-p107 (2021-07-07) [x86_64-linux-gnu]
  Rails version                  6.1.7
  Environment                    production
  Database adapter               Mysql2
  Mailer queue                   ActiveJob::QueueAdapters::AsyncAdapter
  Mailer delivery                smtp
Redmine settings:
  Redmine theme                  Redmine-evolution-classic (includes JavaScript)
SCM:
  Git                            2.34.1
  Filesystem                     
Redmine plugins:
  redmine_agile                  1.6.4
  redmine_checklists             3.1.21
  redmine_contacts               4.3.6
  redmine_contacts_helpdesk      4.2.0
  redmine_zenedit                2.0.3
  redmineup_tags                 2.0.12
I, [2023-04-18T11:04:46.304234 #777496]  INFO -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2] Started GET "/trackers.json" for 192.168.3.42 at 2023-04-18 11:04:46 -0700
I, [2023-04-18T11:04:46.309249 #777496]  INFO -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2] Processing by TrackersController#index as JSON
D, [2023-04-18T11:04:46.326303 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]    (0.4ms)  SELECT MAX(`settings`.`updated_on`) FROM `settings`
D, [2023-04-18T11:04:46.330909 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   Setting Load (0.4ms)  SELECT `settings`.* FROM `settings` WHERE `settings`.`name` = 'rest_api_enabled' ORDER BY `settings`.`id` DESC LIMIT 1
D, [2023-04-18T11:04:46.341494 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   Token Load (0.4ms)  SELECT `tokens`.* FROM `tokens` WHERE `tokens`.`action` = 'api' AND `tokens`.`value` = '***********' LIMIT 1
D, [2023-04-18T11:04:46.353928 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   User Load (0.5ms)  SELECT `users`.* FROM `users` WHERE `users`.`type` IN ('User', 'AnonymousUser') AND `users`.`id` = 5 LIMIT 1
D, [2023-04-18T11:04:46.380891 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   User Load (0.7ms)  SELECT `users`.* FROM `users` WHERE `users`.`type` IN ('User', 'AnonymousUser') AND `users`.`login` = 'SCESD'
I, [2023-04-18T11:04:46.381378 #777496]  INFO -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   User switched by: Rick (id=5)
I, [2023-04-18T11:04:46.381620 #777496]  INFO -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   Current user: SCESD (id=42)
D, [2023-04-18T11:04:46.382640 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   Setting Load (0.4ms)  SELECT `settings`.* FROM `settings` WHERE `settings`.`name` = 'force_default_language_for_loggedin' ORDER BY `settings`.`id` DESC LIMIT 1
D, [2023-04-18T11:04:46.386813 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   Tracker Load (0.4ms)  SELECT `trackers`.* FROM `trackers` ORDER BY `trackers`.`position` ASC
D, [2023-04-18T11:04:46.396924 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   Rendering trackers/index.api.rsb
D, [2023-04-18T11:04:46.402461 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   IssueStatus Load (0.4ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.405723 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.406359 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.406991 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.407582 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.408194 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.408812 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.409433 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.410049 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.410657 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.411294 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.411903 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.412520 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.413216 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.413852 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.414416 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.414998 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.415564 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.416150 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.416744 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   CACHE IssueStatus Load (0.0ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 1 LIMIT 1
D, [2023-04-18T11:04:46.417710 #777496] DEBUG -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   IssueStatus Load (0.4ms)  SELECT `issue_statuses`.* FROM `issue_statuses` WHERE `issue_statuses`.`id` = 5 LIMIT 1
I, [2023-04-18T11:04:46.419663 #777496]  INFO -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2]   Rendered trackers/index.api.rsb (Duration: 22.5ms | Allocations: 9644)
I, [2023-04-18T11:04:46.420182 #777496]  INFO -- : [b1644675-c4bb-46c6-91c6-0e58aaca09b2] Completed 200 OK in 111ms (Views: 22.2ms | ActiveRecord: 17.4ms | Allocations: 43569)
I, [2023-04-18T11:04:46.434524 #777496]  INFO -- : [93f4009c-a50b-4789-bb65-200062a347db] Started POST "/issues.xml" for 192.168.3.42 at 2023-04-18 11:04:46 -0700
I, [2023-04-18T11:04:46.568276 #777496]  INFO -- : [93f4009c-a50b-4789-bb65-200062a347db] Processing by IssuesController#create as XML
I, [2023-04-18T11:04:46.568488 #777496]  INFO -- : [93f4009c-a50b-4789-bb65-200062a347db]   Parameters: {"issue"=>{"subject"=>"Chromebook repair", "description"=>"Refurbish Chromebook", "project_id"=>"23", "category_id"=>"10", "tracker_id"=>"4", "custom_fields"=>[{"name"=>"Serial Number", "id"=>"1", "value"=>"5CD0252HMK"}, {"name"=>"Brand", "id"=>"2", "value"=>"HPI"}, {"name"=>"Model", "id"=>"3", "value"=>"Chromebook x360 11 G3 EE"}, {"name"=>"Asset Tag", "id"=>"4", "value"=>"A024066"}]}}
D, [2023-04-18T11:04:46.570395 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]    (0.4ms)  SELECT MAX(`settings`.`updated_on`) FROM `settings`
D, [2023-04-18T11:04:46.571482 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   Token Load (0.4ms)  SELECT `tokens`.* FROM `tokens` WHERE `tokens`.`action` = 'api' AND `tokens`.`value` = '************' LIMIT 1
D, [2023-04-18T11:04:46.573198 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   User Load (0.5ms)  SELECT `users`.* FROM `users` WHERE `users`.`type` IN ('User', 'AnonymousUser') AND `users`.`id` = 5 LIMIT 1
D, [2023-04-18T11:04:46.574590 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   User Load (0.6ms)  SELECT `users`.* FROM `users` WHERE `users`.`type` IN ('User', 'AnonymousUser') AND `users`.`login` = 'SCESD'
I, [2023-04-18T11:04:46.574866 #777496]  INFO -- : [93f4009c-a50b-4789-bb65-200062a347db]   User switched by: Rick (id=5)
I, [2023-04-18T11:04:46.575000 #777496]  INFO -- : [93f4009c-a50b-4789-bb65-200062a347db]   Current user: SCESD (id=42)
D, [2023-04-18T11:04:46.586988 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   Role Load (0.6ms)  SELECT DISTINCT `roles`.* FROM `roles` INNER JOIN `member_roles` ON `member_roles`.`role_id` = `roles`.`id` INNER JOIN `members` ON `members`.`id` = `member_roles`.`member_id` INNER JOIN `projects` ON `projects`.`id` = `members`.`project_id` WHERE (projects.status <> 9) AND `members`.`user_id` = 42
D, [2023-04-18T11:04:46.589115 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   GroupNonMember Load (0.5ms)  SELECT `users`.* FROM `users` WHERE `users`.`type` = 'GroupNonMember' ORDER BY `users`.`id` ASC LIMIT 1
D, [2023-04-18T11:04:46.627316 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   Role Load (0.5ms)  SELECT DISTINCT `roles`.* FROM `roles` INNER JOIN `member_roles` ON `member_roles`.`role_id` = `roles`.`id` INNER JOIN `members` ON `members`.`id` = `member_roles`.`member_id` INNER JOIN `projects` ON `projects`.`id` = `members`.`project_id` WHERE (projects.status <> 9 AND projects.is_public = TRUE) AND `members`.`user_id` = 3
D, [2023-04-18T11:04:46.630379 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   Role Load (0.4ms)  SELECT `roles`.* FROM `roles` WHERE `roles`.`builtin` = 1 LIMIT 1
D, [2023-04-18T11:04:46.656989 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   IssuePriority Load (0.5ms)  SELECT `enumerations`.* FROM `enumerations` WHERE `enumerations`.`type` = 'IssuePriority' AND `enumerations`.`is_default` = TRUE ORDER BY `enumerations`.`position` ASC LIMIT 1
D, [2023-04-18T11:04:46.669876 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   Setting Load (0.4ms)  SELECT `settings`.* FROM `settings` WHERE `settings`.`name` = 'default_issue_start_date_to_creation_date' ORDER BY `settings`.`id` DESC LIMIT 1
D, [2023-04-18T11:04:46.673424 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   UserPreference Load (0.5ms)  SELECT `user_preferences`.* FROM `user_preferences` WHERE `user_preferences`.`user_id` = 42 LIMIT 1
D, [2023-04-18T11:04:46.696481 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   GroupNonMember Load (0.5ms)  SELECT `users`.* FROM `users` WHERE `users`.`type` = 'GroupNonMember' ORDER BY id LIMIT 1
D, [2023-04-18T11:04:46.697527 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]    (0.4ms)  SELECT `users`.`id` FROM `users` WHERE `users`.`type` = 'GroupNonMember' LIMIT 1
D, [2023-04-18T11:04:46.698973 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]    (0.5ms)  SELECT `members`.`user_id`, `role_id`, `members`.`project_id` FROM `members` INNER JOIN `projects` ON `projects`.`id` = `members`.`project_id` INNER JOIN `member_roles` ON `member_roles`.`member_id` = `members`.`id` WHERE (projects.status <> 9) AND (members.user_id = 42 OR (projects.is_public = TRUE AND members.user_id = 3))
D, [2023-04-18T11:04:46.704421 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   Project Exists? (0.6ms)  SELECT 1 AS one FROM `projects` WHERE (((projects.status = 1 AND EXISTS (SELECT 1 AS one FROM enabled_modules em WHERE em.project_id = projects.id AND em.name='issue_tracking')) AND ((projects.is_public = TRUE AND projects.id NOT IN (SELECT project_id FROM members WHERE user_id IN (42,3)))))) AND (projects.id IN (SELECT DISTINCT project_id FROM projects_trackers)) AND `projects`.`id` = 23 LIMIT 1
D, [2023-04-18T11:04:46.710659 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   CACHE GroupNonMember Load (0.0ms)  SELECT `users`.* FROM `users` WHERE `users`.`type` = 'GroupNonMember' ORDER BY `users`.`id` ASC LIMIT 1
D, [2023-04-18T11:04:46.711368 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   CACHE Role Load (0.0ms)  SELECT DISTINCT `roles`.* FROM `roles` INNER JOIN `member_roles` ON `member_roles`.`role_id` = `roles`.`id` INNER JOIN `members` ON `members`.`id` = `member_roles`.`member_id` INNER JOIN `projects` ON `projects`.`id` = `members`.`project_id` WHERE (projects.status <> 9 AND projects.is_public = TRUE) AND `members`.`user_id` = 3
D, [2023-04-18T11:04:46.712751 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   TRANSACTION (0.3ms)  BEGIN
D, [2023-04-18T11:04:46.716535 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   IssueCategory Load (0.4ms)  SELECT `issue_categories`.* FROM `issue_categories` WHERE `issue_categories`.`id` = 10 LIMIT 1
D, [2023-04-18T11:04:46.720232 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   Setting Load (0.4ms)  SELECT `settings`.* FROM `settings` WHERE `settings`.`name` = 'issue_done_ratio' ORDER BY `settings`.`id` DESC LIMIT 1
D, [2023-04-18T11:04:46.721484 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   Setting Load (0.4ms)  SELECT `settings`.* FROM `settings` WHERE `settings`.`name` = 'plugin_redmine_checklists' ORDER BY `settings`.`id` DESC LIMIT 1
D, [2023-04-18T11:04:46.723117 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   TRANSACTION (0.3ms)  ROLLBACK
D, [2023-04-18T11:04:46.729216 #777496] DEBUG -- : [93f4009c-a50b-4789-bb65-200062a347db]   Rendering common/error_messages.api.rsb
I, [2023-04-18T11:04:46.729850 #777496]  INFO -- : [93f4009c-a50b-4789-bb65-200062a347db]   Rendered common/error_messages.api.rsb (Duration: 0.5ms | Allocations: 356)
I, [2023-04-18T11:04:46.730291 #777496]  INFO -- : [93f4009c-a50b-4789-bb65-200062a347db] Completed 422 Unprocessable Entity in 162ms (Views: 3.4ms | ActiveRecord: 26.4ms | Allocations: 77113)
1

There are 1 answers

1
Holger Just On BEST ANSWER

Some validation failed, e.g. because you (or an admin) have added some required custom fields or have set some fields as required in the workflows.

You should get a full list of validation errors in the response to your POST request. See the documentation for details about how to retrieve validation errors.