Комментарии добавляются, но в чат приходят сообщения об ошибках

[ikuchugov]

Добрый день.
Поднял бота на тестовой сборке, Redmine на том же хосте.
Новые задачи из Телеграма в Редмайне создаются отлично. Комментарии из Редмайна в Телеграм так же падают без проблем. Но при добавлении комментария из Телеграма - комментарий правильно создается в нужной задаче, все хорошо, но в Телеграме в ответ приходит сообщение об ошибки с предложением завершить текущий диалог, и пока этого не сделаешь - бот на новые сообщения реагирует также просьбой завершить диалог.

И так при любом варианте - добавления к предыдущей задаче, выбор задачи или Reply на сообщение бота.
В логах вот что в этот момент:
[25.08.2021 09:16:30.833188] (168) DEBUG: [rest-api]: received message from redmine (auth token: "id35kn7resb536bfc8zywdgaugk1ur69cyrkfyh6kpiq5ngw41w" (correct), post body: "{"action":"issue_edit","data":{"issue":{"id":8376,"project":{"id":2,"name":"Миграция домена mcnt.ru в it24x7.su","members":[{"id":5,"name":"Иван Кучугов","access":{"view_current_issue":true,"view_private_notes":true},"roles":[{"id":3,"name":"Менеджер","permissions":{"issues_visibility":"all","view_private_notes":true}}]},{"id":305,"name":"Nixys Chat","access":{"view_current_issue":true,"view_private_notes":true},"roles":[{"id":3,"name":"Менеджер","permissions":{"issues_visibility":"all","view_private_notes":true}}]},{"id":306,"name":"Nixys Presale Chat","access":{"view_current_issue":true,"view_private_notes":true},"roles":[{"id":3,"name":"Менеджер","permissions":{"issues_visibility":"all","view_private_notes":true}}]},{"id":1,"name":"Redmine Administrator Admin","access":{"view_current_issue":true,"view_private_notes":true},"roles":[{"id":3,"name":"Менеджер","permissions":{"issues_visibility":"all","view_private_notes":true}}]}]},"tracker":{"id":2,"name":"Внедрение"},"status":{"id":1,"name":"Новая"},"priority":{"id":2,"name":"Нормальный"},"author":{"id":5,"name":"Иван Кучугов"},"assigned_to":{"id":5,"name":"Иван Кучугов"},"subject":"Новая задача","description":"Новая задача","start_date":"2021-08-24","due_date":null,"done_ratio":0,"is_private":false,"estimated_hours":null,"spent_hours":0.0,"custom_fields":[{"id":10,"name":"Оценка исполнителя","value":""},{"id":11,"name":"Время реакции","value":""}],"created_on":"2021-08-24 14:02:24 UTC","updated_on":"2021-08-25 09:16:29 UTC","closed_on":null,"attachments":[{"id":9898,"filename":"screenshot_1_1629880150.png","filesize":69404,"content_type":"image/png","description":"","author":{"id":1,"name":"Redmine Administrator Admin"},"created_on":"2021-08-25 08:29:09 UTC"},{"id":9899,"filename":"mRemoteNG111.xml","filesize":241816,"content_type":"text/xml","description":"","author":{"id":1,"name":"Redmine Administrator Admin"},"created_on":"2021-08-25 09:05:42 UTC"},{"id":9900,"filename":"screenshot_2_1629882351.png","filesize":69404,"content_type":"image/png","description":"","author":{"id":1,"name":"Redmine Administrator Admin"},"created_on":"2021-08-25 09:05:50 UTC"}],"journals":[{"id":42898,"user":{"id":5,"name":"Иван Кучугов"},"notes":"454654465","private_notes":false,"created_on":"2021-08-25 09:16:29 UTC","details":[]}],"watchers":[]}}} [25.08.2021 09:16:30.834018] (168) DEBUG: [rest-api]: ra queue add: success [25.08.2021 09:16:30.834107] (168) INFO: connection accepted 95.143.210.230:51774 "POST /redmine?token=id35kn7resb536bfc8zywdgaugk1ur69cyrkfyh6kpiq5ngw41w" 200 52 "Ruby" [25.08.2021 09:16:30.890408] (164) ERROR: [queue-worker]: rdmn issue add note error: wrong Redmine response code (issue id: 8376, response code: 200, response body: "") [25.08.2021 09:16:30.890465] (164) WARNING: [queue-worker]: can't send user message to Redmine issue: can't add note into Redmine issue (rdmn user id: 5) [25.08.2021 09:16:30.903382] (165) DEBUG: [queue-worker]: ra queue get: success [25.08.2021 09:16:30.906363] (165) DEBUG: [queue-worker]: db presale get: success [25.08.2021 09:16:30.906586] (165) DEBUG: [queue-worker]: db waitings get: success [25.08.2021 09:16:30.907211] (165) DEBUG: [queue-worker]: db cache get: success (cache type: projects) [25.08.2021 09:16:30.907758] (165) DEBUG: [queue-worker]: db cache get: success (cache type: users) [25.08.2021 09:16:30.910394] (165) DEBUG: [queue-worker]: db cache get: success (cache type: issue_priorities) [25.08.2021 09:16:30.910948] (165) DEBUG: [queue-worker]: db statistic add: success [25.08.2021 09:16:31.171225] (164) DEBUG: [queue-worker]: tlgrm request: success [25.08.2021 09:16:31.171344] (164) WARNING: [queue-worker]: error in 'proc.queue-worker.tlgrm-update.handler_callback_sess_type_message' (user id: 441374388, error code: 2) [25.08.2021 09:16:31.171527] (164) WARNING: [queue-worker]: error in 'proc.queue-worker.tlgrm-update.handler_callback_exec' (user id: 441374388, error code: 2) [25.08.2021 09:16:31.424134] (164) DEBUG: [queue-worker]: tlgrm request: success [25.08.2021 09:16:43.002676] (168) DEBUG: [rest-api]: received message from telegram (auth token: "id35kn7resb536bfc8zywdgaugk1ur69cyrkfyh6kpiq5ngw41w" (correct), post body: "{"update_id":398569489, "callback_query":{"id":"1895688563228388570","from":{"id":441374388,"is_bot":false,"first_name":"\u0418\u0432\u0430\u043d","last_name":"\u041a\u0443\u0447\u0443\u0433\u043e\u0432","username":"i_kuchugov","language_code":"ru"},"message":{"message_id":21595,"from":{"id":621916110,"is_bot":true,"first_name":"it24x7_zabbix","username":"it24x7_zabbixbot"},"chat":{"id":441374388,"first_name":"\u0418\u0432\u0430\u043d","last_name":"\u041a\u0443\u0447\u0443\u0433\u043e\u0432","username":"i_kuchugov","type":"private"},"date":1629882985,"text":"\u041a\u0430\u043a \u043f\u043e\u0441\u0442\u0443\u043f\u0438\u0442\u044c \u0441 \u0412\u0430\u0448\u0438\u043c \u043a\u043e\u043c\u043c\u0435\u043d\u0442\u0430\u0440\u0438\u0435\u043c? \u0414\u043e\u0431\u0430\u0432\u0438\u0442\u044c \u0432 \u043f\u043e\u0441\u043b\u0435\u0434\u043d\u044e\u044e \u0437\u0430\u0434\u0430\u0447\u0443, \u0432\u044b\u0431\u0440\u0430\u0442\u044c \u0437\u0430\u0434\u0430\u0447\u0443 \u0438\u0437 \u0441\u043f\u0438\u0441\u043a\u0430 \u0438\u043b\u0438 \u0441\u043e\u0437\u0434\u0430\u0442\u044c \u043d\u043e\u0432\u0443\u044e?\n\u041a\u043e\u043b\u0438\u0447\u0435\u0441\u0442\u0432\u043e \u043f\u0440\u0438\u043a\u0440\u0435\u043f\u043b\u0451\u043d\u043d\u044b\u0445 \u0444\u0430\u0439\u043b\u043e\u0432: 0\n---------\n\u041f\u043e\u0441\u043b\u0435\u0434\u043d\u044f\u044f \u0437\u0430\u0434\u0430\u0447\u0430: [\u041c\u0438\u0433\u0440\u0430\u0446\u0438\u044f \u0434\u043e\u043c\u0435\u043d\u0430 mcnt.ru \u0432 it24x7.su - #8376] \u041d\u043e\u0432\u0430\u044f \u0437\u0430\u0434\u0430\u0447\u0430","entities":[{"offset":109,"length":32,"type":"bold"},{"offset":172,"length":58,"type":"text_link","url":"https://helpbot.sistema.plus/redmine/issues/8376"}],"reply_markup":{"inline_keyboard":[[{"text":"\u0414\u043e\u0431\u0430\u0432\u0438\u0442\u044c \u0432 \u043f\u043e\u0441\u043b\u0435\u0434\u043d\u044e\u044e \u0437\u0430\u0434\u0430\u0447\u0443","callback_data":"{\"t\":2,\"o_id\":8376}"}],[{"text":"\u0412\u044b\u0431\u0440\u0430\u0442\u044c \u0437\u0430\u0434\u0430\u0447\u0443","callback_data":"{\"t\":14,\"o_id\":0}"}],[{"text":"\u0421\u043e\u0437\u0434\u0430\u0442\u044c \u043d\u043e\u0432\u0443\u044e \u0437\u0430\u0434\u0430\u0447\u0443","callback_data":"{\"t\":1,\"o_id\":0}"}],[{"text":"\u0417\u0430\u0432\u0435\u0440\u0448\u0438\u0442\u044c \u0442\u0435\u043a\u0443\u0449\u0438\u0439 \u0434\u0438\u0430\u043b\u043e\u0433","callback_data":"{\"t\":16,\"o_id\":0}"}]]}},"chat_instance":"-4680673016198315302","data":"{\"t\":16,\"o_id\":0}"}} [25.08.2021 09:16:43.003290] (168) DEBUG: [rest-api]: ra queue add: success [25.08.2021 09:16:43.003415] (168) INFO: connection accepted 91.108.6.49:49688 "POST /tlgrm?token=id35kn7resb536bfc8zywdgaugk1ur69cyrkfyh6kpiq5ngw41w" 200 52 "" [25.08.2021 09:16:44.256462] (163) DEBUG: [queue-worker]: ra queue get: success [25.08.2021 09:16:44.258544] (163) DEBUG: [queue-worker]: db cache get: success (cache type: projects) [25.08.2021 09:16:44.259291] (163) DEBUG: [queue-worker]: db cache get: success (cache type: users) [25.08.2021 09:16:44.261973] (163) DEBUG: [queue-worker]: db cache get: success (cache type: issue_priorities) [25.08.2021 09:16:44.266127] (163) DEBUG: [queue-worker]: db queue add: success (tlgrm userid: 441374388) [25.08.2021 09:16:44.295196] (162) DEBUG: [queue-worker]: db queue set lock: success (tlgrm user id: 441374388, lock expire ms: 30000) [25.08.2021 09:16:44.295561] (162) DEBUG: [queue-worker]: db queue get: success (tlgrm user id: 441374388) [25.08.2021 09:16:44.295786] (162) DEBUG: [queue-worker]: db queue get: success (tlgrm user id: 441374388) [25.08.2021 09:16:44.296467] (162) DEBUG: [queue-worker]: db cache get: success (cache type: projects) [25.08.2021 09:16:44.297043] (162) DEBUG: [queue-worker]: db cache get: success (cache type: users) [25.08.2021 09:16:44.299677] (162) DEBUG: [queue-worker]: db cache get: success (cache type: issue_priorities) [25.08.2021 09:16:44.300257] (162) DEBUG: [queue-worker]: db session get: success (tlgrm userid: 441374388) [25.08.2021 09:16:44.302673] (162) DEBUG: [queue-worker]: db statistic add: success [25.08.2021 09:16:44.302967] (162) DEBUG: [queue-worker]: db session del: success (tlgrm userid: 441374388) [25.08.2021 09:16:44.555533] (162) DEBUG: [queue-worker]: tlgrm request: success [25.08.2021 09:16:44.784736] (162) DEBUG: [queue-worker]: tlgrm request: success

В production.log Redmine вот что:
Started GET "/redmine/users/5.json" for 95.143.210.230 at 2021-08-25 14:16:26 +0300 Processing by UsersController#show as JSON Parameters: {"id"=>"5"} Current user: chat_bot (id=305) Rendering users/show.api.rsb Rendered users/show.api.rsb (8.1ms) Completed 200 OK in 29ms (Views: 6.9ms | ActiveRecord: 5.5ms) Started GET "/redmine/issues/8377.json" for 95.143.210.230 at 2021-08-25 14:16:26 +0300 Processing by IssuesController#show as JSON Parameters: {"id"=>"8377"} Current user: I.Kuchugov (id=5) Rendering issues/show.api.rsb Rendered issues/show.api.rsb (13.8ms) Completed 200 OK in 53ms (Views: 11.7ms | ActiveRecord: 12.0ms) Started GET "/redmine/projects.json?limit=100&offset=0" for 95.143.210.230 at 2021-08-25 14:16:33 +0300 Processing by ProjectsController#index as JSON Parameters: {"limit"=>"100", "offset"=>"0"} Current user: chat_bot (id=305) Rendering projects/index.api.rsb Rendered projects/index.api.rsb (38.5ms) Completed 200 OK in 49ms (Views: 37.0ms | ActiveRecord: 4.4ms) Started GET "/redmine/enumerations/issue_priorities.json" for 95.143.210.230 at 2021-08-25 14:16:33 +0300 Processing by EnumerationsController#index as JSON Parameters: {"type"=>"issue_priorities"} Current user: chat_bot (id=305) Rendering enumerations/index.api.rsb Rendered enumerations/index.api.rsb (3.0ms) Completed 200 OK in 11ms (Views: 3.2ms | ActiveRecord: 2.1ms) Started PUT "/redmine/issues/8377.json" for 95.143.210.230 at 2021-08-25 14:16:36 +0300 Processing by IssuesController#update as JSON Parameters: {"issue"=>{"notes"=>"sdfdsfdsfdsfsd", "private_notes"=>false, "uploads"=>[]}, "id"=>"8377"} Current user: I.Kuchugov (id=5) Notification has been sent successfully: URI: https://helpbot.sistema.plus:8443/redmine?token=id35kn7resb536bfc8zywdgaugk1ur69cyrkfyh6kpiq5ngw41w Response code: 200 [ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb) to Async(mailers) with arguments: "Mailer", "issue_edit", "deliver_now", #<GlobalID:0x00007efd7c019200 @uri=#<URI::GID gid://redmine-app/User/5>>, #<GlobalID:0x00007efd7c0188a0 @uri=#<URI::GID gid://redmine-app/Journal/42902>> [ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 9af54fe7-27e0-41bf-abc5-ec6696b3b3ca) to Async(mailers) with arguments: "Mailer", "issue_edit", "deliver_now", #<GlobalID:0x00007efd8d327be8 @uri=#<URI::GID gid://redmine-app/User/1>>, #<GlobalID:0x00007efd8d327148 @uri=#<URI::GID gid://redmine-app/Journal/42902>> Completed 200 OK in 145ms (ActiveRecord: 21.4ms) [ActiveJob] [ActionMailer::DeliveryJob] [9af54fe7-27e0-41bf-abc5-ec6696b3b3ca] Performing ActionMailer::DeliveryJob (Job ID: 9af54fe7-27e0-41bf-abc5-ec6696b3b3ca) from Async(mailers) with arguments: "Mailer", "issue_edit", "deliver_now", #<GlobalID:0x00007efd8d308838 @uri=#<URI::GID gid://redmine-app/User/1>>, #<GlobalID:0x00007efd8d2f7df8 @uri=#<URI::GID gid://redmine-app/Journal/42902>> [ActiveJob] [ActionMailer::DeliveryJob] [2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb] Performing ActionMailer::DeliveryJob (Job ID: 2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb) from Async(mailers) with arguments: "Mailer", "issue_edit", "deliver_now", #<GlobalID:0x00007efd8d2de178 @uri=#<URI::GID gid://redmine-app/User/5>>, #<GlobalID:0x00007efd8d2dd778 @uri=#<URI::GID gid://redmine-app/Journal/42902>> [ActiveJob] [ActionMailer::DeliveryJob] [2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb] Rendering mailer/issue_edit.text.erb within layouts/mailer [ActiveJob] [ActionMailer::DeliveryJob] [9af54fe7-27e0-41bf-abc5-ec6696b3b3ca] Rendering mailer/issue_edit.text.erb within layouts/mailer [ActiveJob] [ActionMailer::DeliveryJob] [2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb] Rendered mailer/_issue.text.erb (25.3ms) [ActiveJob] [ActionMailer::DeliveryJob] [2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb] Rendered mailer/issue_edit.text.erb within layouts/mailer (26.8ms) [ActiveJob] [ActionMailer::DeliveryJob] [2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb] Rendering mailer/issue_edit.html.erb within layouts/mailer [ActiveJob] [ActionMailer::DeliveryJob] [2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb] Rendered mailer/_issue.html.erb (1.2ms) [ActiveJob] [ActionMailer::DeliveryJob] [2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb] Rendered mailer/issue_edit.html.erb within layouts/mailer (5.0ms) [ActiveJob] [ActionMailer::DeliveryJob] [9af54fe7-27e0-41bf-abc5-ec6696b3b3ca] Rendered mailer/_issue.text.erb (32.7ms) [ActiveJob] [ActionMailer::DeliveryJob] [9af54fe7-27e0-41bf-abc5-ec6696b3b3ca] Rendered mailer/issue_edit.text.erb within layouts/mailer (34.5ms) [ActiveJob] [ActionMailer::DeliveryJob] [9af54fe7-27e0-41bf-abc5-ec6696b3b3ca] Rendering mailer/issue_edit.html.erb within layouts/mailer [ActiveJob] [ActionMailer::DeliveryJob] [2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb] Failed writing maillog: To не может быть пустым [ActiveJob] [ActionMailer::DeliveryJob] [2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb] Performed ActionMailer::DeliveryJob (Job ID: 2d5dcb0b-7ac6-4f2c-9e8c-9f644a5f53fb) from Async(mailers) in 76.06ms [ActiveJob] [ActionMailer::DeliveryJob] [9af54fe7-27e0-41bf-abc5-ec6696b3b3ca] Rendered mailer/_issue.html.erb (1.3ms) [ActiveJob] [ActionMailer::DeliveryJob] [9af54fe7-27e0-41bf-abc5-ec6696b3b3ca] Rendered mailer/issue_edit.html.erb within layouts/mailer (4.0ms) [ActiveJob] [ActionMailer::DeliveryJob] [9af54fe7-27e0-41bf-abc5-ec6696b3b3ca] Performed ActionMailer::DeliveryJob (Job ID: 9af54fe7-27e0-41bf-abc5-ec6696b3b3ca) from Async(mailers) in 176.79ms
При этом комментарии создаются
Сборка Redmine такая:

[no-name-no-game]

Привет! Я мимо крокодилла, но могу предположить, что дело в совместимости версий, ибо в README указано: "Compatible with Redmine 4.2+".

[ikuchugov]

Привет! Я мимо крокодилла, но могу предположить, что дело в совместимости версий, ибо в README указано: "Compatible with Redmine 4.2+".

Так точно, в версии 4.2.2 все работает отлично. Но в этой версии не работает несколько других плагинов, которые для нас тоже важны. Хотелось бы поддержку для версии 4.0.9.
Последняя версия бота для 4.0.3 также выдает ошибки на 4.0.9
Будем надеяться и верить, как говорится.

[AndreiMuvila]

Доброго времени суток.

Уточните, включены ли debug-логи? Есть ли что то в логах при отправке комментария?

[ikuchugov]

Добрый день! Последнее обновление решило проблему, большое спасибо!

[ikuchugov]

Добрый день! Действительно, это я забыл обновить образ. Последняя версия работает без ошибок, все отлично. Большое спасибо за помощь, Вы делаете шикарный продукт!     С уважением, Кучугов Иван.    

Пятница, 8 октября 2021, 15:12 +03:00 от Andrei Yusupov ***@***.***>:     Доброго времени суток. Поправили данную проблему в v1.0.1. Попробуйте последнюю версию образа, должно работать корректно. — You are receiving this because you modified the open/close state. Reply to this email directly, view it on GitHub , or unsubscribe . Triage notifications on the go with GitHub Mobile for iOS or Android .