Bot doesn't handle mentions originating from workflows

[weppos]

Description

We just migrated from the previous adapter to this one, and we also moved from the Slack-developed bot to using a custom Slack app per the instructions in this docs.

In the migration, we noticed the bot stopped to respond to messages sent via Slack workflow. We have workflows that we use for deploy, that eventually trigger a message like the following:

@hubot deploy redirector staging --version main-d349664

If the message is posted by a user, the bot responds and handles the action. If the message is posted by the workflow, the bot doesn't handle the message.

I enabled the debug mode, and I see the message is being discovered by the bot. However it doesn't handle it. It is worth noting the payload of a message sent by the workflow is a subtype bot_message, which is slightly different variant of a message.

What type of issue is this? (place an x in one of the [ ])

• bug
• enhancement (feature request)
• question
• documentation related
• testing related
• discussion

Requirements (place an x in each of the [ ])

• I've read and understood the Contributing guidelines and have done my best effort to follow them.
• I've read and agree to the Code of Conduct.
• I've searched for any related issues and avoided creating a duplicate issue.

---

Bug Report

Filling out the following details about bugs will help us solve your issue sooner.

Reproducible in:

hubot-slack version: 3.0.2

node version: 20.x

OS version(s): Linux

Steps to reproduce:

1. Create a simple workflow that post a message to the channel mentioning the bot.
2. Trigger the workflow. The bot will not react.
3. Copy/past the same message as user. The bot will react.

Expected result:

The bot should properly detect mentions originating from a workflow, and handle the message.

Actual result:

The bot receves the message, but it ignores the message. The following are the logs from a handled message:

2024-04-03T09:06:52.709012+00:00 app[web.1]: {"level":20,"time":1712135212707,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Text = @alan deploy redirector staging --version main-d349664"}
2024-04-03T09:06:52.709015+00:00 app[web.1]: {"level":20,"time":1712135212707,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Event subtype = undefined"}
2024-04-03T09:06:52.709016+00:00 app[web.1]: {"level":20,"time":1712135212707,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Received generic message: message"}
2024-04-03T09:06:52.776427+00:00 app[web.1]: {"level":20,"time":1712135212776,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Message '@alan deploy redirector staging --version main-d349664' matched regex //^\\s*[@]?alan[:,]?\\s*(?:deploy (.*) (production|sandbox|staging|canary|tst)(.*))/i/; listener.options = { id: null }"}
2024-04-03T09:06:52.787157+00:00 app[web.1]: {"level":20,"time":1712135212784,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Sending message to Slack"}
2024-04-03T09:06:52.787160+00:00 app[web.1]: {"level":20,"time":1712135212784,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"SlackClient#send() room: C033EJ9PH, message: Deploying *redirector* version `main-d349664` to *staging*"}
2024-04-03T09:06:52.875232+00:00 app[web.1]: {"level":20,"time":1712135212875,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Successfully sent message (string) to C033EJ9PH"}

In case of the same message sent from the workflow, the bot simply doesn't reach. It is almost as if the message is not parsed/discovered, despite the text is the same.

Attachments:

This is the log when the message is sent from the workflow:

2024-04-03T09:06:23.914011+00:00 app[web.1]: {"level":20,"time":1712135183913,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"eventHandler {\n  \"envelope_id\": \"8a6021ec-680f-4a68-a1d4-c38372153b89\",\n  \"body\": {\n    \"token\": \"4skigd3VOfqvUKXMye9n4OA6\",\n    \"team_id\": \"T0250UABH\",\n    \"context_team_id\": \"T0250UABH\",\n    \"context_enterprise_id\": null,\n    \"api_app_id\": \"A06RVNS11L5\",\n    \"event\": {\n      \"subtype\": \"bot_message\",\n      \"text\": \"<@U06RS1V096J> deploy redirector staging --version main-d349664\",\n      \"username\": \"Deploy Redirector\",\n      \"type\": \"message\",\n      \"ts\": \"1712135183.540679\",\n      \"bot_id\": \"B06RMMGK2ES\",\n      \"app_id\": \"A06RRATC1TP\",\n      \"blocks\": [\n        {\n          \"type\": \"rich_text\",\n          \"block_id\": \"yB+TO\",\n          \"elements\": [\n            {\n              \"type\": \"rich_text_section\",\n              \"elements\": [\n                {\n                  \"type\": \"user\",\n                  \"user_id\": \"U06RS1V096J\"\n                },\n                {\n                  \"type\": \"text\",\n                  \"text\": \" deploy redirector \"\n                },\n                {\n                  \"type\": \"text\",\n                  \"text\": \"staging\"\n                },\n                {\n                  \"type\": \"text\",\n                  \"text\": \" --version \"\n                },\n                {\n                  \"type\": \"text\",\n                  \"text\": \"main-d349664\"\n                }\n              ]\n            }\n          ]\n        }\n      ],\n      \"channel\": \"C033EJ9PH\",\n      \"event_ts\": \"1712135183.540679\",\n      \"channel_type\": \"channel\"\n    },\n    \"type\": \"event_callback\",\n    \"event_id\": \"Ev06SK43NLLB\",\n    \"event_time\": 1712135183,\n    \"authorizations\": [\n      {\n        \"enterprise_id\": null,\n        \"team_id\": \"T0250UABH\",\n        \"user_id\": \"U06RS1V096J\",\n        \"is_bot\": true,\n        \"is_enterprise_install\": false\n      }\n    ],\n    \"is_ext_shared_channel\": false,\n    \"event_context\": \"4-eyJldCI6Im1lc3NhZ2UiLCJ0aWQiOiJUMDI1MFVBQkgiLCJhaWQiOiJBMDZSVk5TMTFMNSIsImNpZCI6IkMwMzNFSjlQSCJ9\"\n  },\n  \"event\": {\n    \"subtype\": \"bot_message\",\n    \"text\": \"<@U06RS1V096J> deploy redirector staging --version main-d349664\",\n    \"username\": \"Deploy Redirector\",\n    \"type\": \"message\",\n    \"ts\": \"1712135183.540679\",\n    \"bot_id\": \"B06RMMGK2ES\",\n    \"app_id\": \"A06RRATC1TP\",\n    \"blocks\": [\n      {\n        \"type\": \"rich_text\",\n        \"block_id\": \"yB+TO\",\n        \"elements\": [\n          {\n            \"type\": \"rich_text_section\",\n            \"elements\": [\n              {\n                \"type\": \"user\",\n                \"user_id\": \"U06RS1V096J\"\n              },\n              {\n                \"type\": \"text\",\n                \"text\": \" deploy redirector \"\n              },\n              {\n                \"type\": \"text\",\n                \"text\": \"staging\"\n              },\n              {\n                \"type\": \"text\",\n                \"text\": \" --version \"\n              },\n              {\n                \"type\": \"text\",\n                \"text\": \"main-d349664\"\n              }\n            ]\n          }\n        ]\n      }\n    ],\n    \"channel\": \"C033EJ9PH\",\n    \"event_ts\": \"1712135183.540679\",\n    \"channel_type\": \"channel\"\n  },\n  \"retry_num\": 0,\n  \"retry_reason\": \"\",\n  \"accepts_response_payload\": false\n}"}

This is the log when the message is sent by a user:

2024-04-03T09:06:52.707253+00:00 app[web.1]: {"level":20,"time":1712135212707,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"eventHandler {\n  \"envelope_id\": \"98a5ae44-332a-43e2-b16d-d7a6999aaa6a\",\n  \"body\": {\n    \"token\": \"XXX\",\n    \"team_id\": \"TXXX\",\n    \"context_team_id\": \"TXXX\",\n    \"context_enterprise_id\": null,\n    \"api_app_id\": \"A06RVNS11L5\",\n    \"event\": {\n      \"user\": \"U02516XXX\",\n      \"type\": \"message\",\n      \"ts\": \"1712135212.448729\",\n      \"client_msg_id\": \"ac98efb1-a545-4bb8-ab86-efa7d61eb291\",\n      \"text\": \"<@U06RS1V096J> deploy redirector staging --version main-d349664\",\n      \"team\": \"TXXX\",\n      \"blocks\": [\n        {\n          \"type\": \"rich_text\",\n          \"block_id\": \"AowYx\",\n          \"elements\": [\n            {\n              \"type\": \"rich_text_section\",\n              \"elements\": [\n                {\n                  \"type\": \"user\",\n                  \"user_id\": \"U06RS1V096J\"\n                },\n                {\n                  \"type\": \"text\",\n                  \"text\": \" deploy redirector staging --version main-d349664\"\n                }\n              ]\n            }\n          ]\n        }\n      ],\n      \"channel\": \"C033EJ9PH\",\n      \"event_ts\": \"1712135212.448729\",\n      \"channel_type\": \"channel\"\n    },\n    \"type\": \"event_callback\",\n    \"event_id\": \"Ev06S4LG3D8X\",\n    \"event_time\": 1712135212,\n    \"authorizations\": [\n      {\n        \"enterprise_id\": null,\n        \"team_id\": \"TXXX\",\n        \"user_id\": \"U06RS1V096J\",\n        \"is_bot\": true,\n        \"is_enterprise_install\": false\n      }\n    ],\n    \"is_ext_shared_channel\": false,\n    \"event_context\": \"4-eyJldCI6Im1lc3NhZ2UiLCJ0aWQiOiJUMDI1MFVBQkgiLCJhaWQiOiJBMDZSVk5TMTFMNSIsImNpZCI6IkMwMzNFSjlQSCJ9\"\n  },\n  \"event\": {\n    \"user\": \"U02516XXX\",\n    \"type\": \"message\",\n    \"ts\": \"1712135212.448729\",\n    \"client_msg_id\": \"ac98efb1-a545-4bb8-ab86-efa7d61eb291\",\n    \"text\": \"<@U06RS1V096J> deploy redirector staging --version main-d349664\",\n    \"team\": \"TXXX\",\n    \"blocks\": [\n      {\n        \"type\": \"rich_text\",\n        \"block_id\": \"AowYx\",\n        \"elements\": [\n          {\n            \"type\": \"rich_text_section\",\n            \"elements\": [\n              {\n                \"type\": \"user\",\n                \"user_id\": \"U06RS1V096J\"\n              },\n              {\n                \"type\": \"text\",\n                \"text\": \" deploy redirector staging --version main-d349664\"\n              }\n            ]\n          }\n        ]\n      }\n    ],\n    \"channel\": \"C033EJ9PH\",\n    \"event_ts\": \"1712135212.448729\",\n    \"channel_type\": \"channel\"\n  },\n  \"retry_num\": 0,\n  \"retry_reason\": \"\",\n  \"accepts_response_payload\": false\n}"}
2024-04-03T09:06:52.709010+00:00 app[web.1]: {"level":20,"time":1712135212707,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"event {\n  \"envelope_id\": \"98a5ae44-332a-43e2-b16d-d7a6999aaa6a\",\n  \"body\": {\n    \"token\": \"XXX\",\n    \"team_id\": \"TXXX\",\n    \"context_team_id\": \"TXXX\",\n    \"context_enterprise_id\": null,\n    \"api_app_id\": \"A06RVNS11L5\",\n    \"event\": {\n      \"user\": \"U02516XXX\",\n      \"type\": \"message\",\n      \"ts\": \"1712135212.448729\",\n      \"client_msg_id\": \"ac98efb1-a545-4bb8-ab86-efa7d61eb291\",\n      \"text\": \"<@U06RS1V096J> deploy redirector staging --version main-d349664\",\n      \"team\": \"TXXX\",\n      \"blocks\": [\n        {\n          \"type\": \"rich_text\",\n          \"block_id\": \"AowYx\",\n          \"elements\": [\n            {\n              \"type\": \"rich_text_section\",\n              \"elements\": [\n                {\n                  \"type\": \"user\",\n                  \"user_id\": \"U06RS1V096J\"\n                },\n                {\n                  \"type\": \"text\",\n                  \"text\": \" deploy redirector staging --version main-d349664\"\n                }\n              ]\n            }\n          ]\n        }\n      ],\n      \"channel\": \"C033EJ9PH\",\n      \"event_ts\": \"1712135212.448729\",\n      \"channel_type\": \"channel\"\n    },\n    \"type\": \"event_callback\",\n    \"event_id\": \"Ev06S4LG3D8X\",\n    \"event_time\": 1712135212,\n    \"authorizations\": [\n      {\n        \"enterprise_id\": null,\n        \"team_id\": \"TXXX\",\n        \"user_id\": \"U06RS1V096J\",\n        \"is_bot\": true,\n        \"is_enterprise_install\": false\n      }\n    ],\n    \"is_ext_shared_channel\": false,\n    \"event_context\": \"4-eyJldCI6Im1lc3NhZ2UiLCJ0aWQiOiJUMDI1MFVBQkgiLCJhaWQiOiJBMDZSVk5TMTFMNSIsImNpZCI6IkMwMzNFSjlQSCJ9\"\n  },\n  \"event\": {\n    \"user\": \"U02516XXX\",\n    \"type\": \"message\",\n    \"ts\": \"1712135212.448729\",\n    \"client_msg_id\": \"ac98efb1-a545-4bb8-ab86-efa7d61eb291\",\n    \"text\": \"<@U06RS1V096J> deploy redirector staging --version main-d349664\",\n    \"team\": \"TXXX\",\n    \"blocks\": [\n      {\n        \"type\": \"rich_text\",\n        \"block_id\": \"AowYx\",\n        \"elements\": [\n          {\n            \"type\": \"rich_text_section\",\n            \"elements\": [\n              {\n                \"type\": \"user\",\n                \"user_id\": \"U06RS1V096J\"\n              },\n              {\n                \"type\": \"text\",\n                \"text\": \" deploy redirector staging --version main-d349664\"\n              }\n            ]\n          }\n        ]\n      }\n    ],\n    \"channel\": \"C033EJ9PH\",\n    \"event_ts\": \"1712135212.448729\",\n    \"channel_type\": \"channel\"\n  },\n  \"retry_num\": 0,\n  \"retry_reason\": \"\",\n  \"accepts_response_payload\": false\n} user = U02516XXX"}
nse_payload\": false\n} user = U02516XXX"}
2024-04-03T09:06:52.709012+00:00 app[web.1]: {"level":20,"time":1712135212707,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Text = @alan deploy redirector staging --version main-d349664"}
2024-04-03T09:06:52.709015+00:00 app[web.1]: {"level":20,"time":1712135212707,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Event subtype = undefined"}
2024-04-03T09:06:52.709016+00:00 app[web.1]: {"level":20,"time":1712135212707,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Received generic message: message"}
2024-04-03T09:06:52.776427+00:00 app[web.1]: {"level":20,"time":1712135212776,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Message '@alan deploy redirector staging --version main-d349664' matched regex //^\\s*[@]?alan[:,]?\\s*(?:deploy (.*) (production|sandbox|staging|canary|tst)(.*))/i/; listener.options = { id: null }"}
2024-04-03T09:06:52.787157+00:00 app[web.1]: {"level":20,"time":1712135212784,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Sending message to Slack"}

Worth mentioning that when the message is sent by the workflow, the adapter doesn't even output a message line. It is almost as if it is unable to read the message at all, no matter if it is a mention or not.

The following is the output for a message that is not a mention. The last 4 lines output the parsed message. These lines are never present for a message sent by the workflow.

2024-04-03T09:30:50.155091+00:00 app[web.1]: {"level":20,"time":1712136650154,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"eventHandler {\n  \"envelope_id\": \"140ac4f6-786f-4a43-a45d-c248a2083618\",\n  \"body\": {\n    \"token\": \"xxx\",\n    \"team_id\": \"T0250UXXX\",\n    \"context_team_id\": \"T0250UXXX\",\n    \"context_enterprise_id\": null,\n    \"api_app_id\": \"A06RVNS11L5\",\n    \"event\": {\n      \"user\": \"U02516XXX\",\n      \"type\": \"message\",\n      \"ts\": \"1712136649.831659\",\n      \"client_msg_id\": \"11d8c742-ef27-424c-93ac-069e31aa4764\",\n      \"text\": \"Message\",\n      \"team\": \"T0250UXXX\",\n      \"blocks\": [\n        {\n          \"type\": \"rich_text\",\n          \"block_id\": \"UOZc/\",\n          \"elements\": [\n            {\n              \"type\": \"rich_text_section\",\n              \"elements\": [\n                {\n                  \"type\": \"text\",\n                  \"text\": \"Message\"\n                }\n              ]\n            }\n          ]\n        }\n      ],\n      \"channel\": \"C033EJ9PH\",\n      \"event_ts\": \"1712136649.831659\",\n      \"channel_type\": \"channel\"\n    },\n    \"type\": \"event_callback\",\n    \"event_id\": \"Ev06SKBD32JW\",\n    \"event_time\": 1712136649,\n    \"authorizations\": [\n      {\n        \"enterprise_id\": null,\n        \"team_id\": \"T0250UXXX\",\n        \"user_id\": \"U06RS1V096J\",\n        \"is_bot\": true,\n        \"is_enterprise_install\": false\n      }\n    ],\n    \"is_ext_shared_channel\": false,\n    \"event_context\": \"4-eyJldCI6Im1lc3NhZ2UiLCJ0aWQiOiJUMDI1MFVBQkgiLCJhaWQiOiJBMDZSVk5TMTFMNSIsImNpZCI6IkMwMzNFSjlQSCJ9\"\n  },\n  \"event\": {\n    \"user\": \"U02516XXX\",\n    \"type\": \"message\",\n    \"ts\": \"1712136649.831659\",\n    \"client_msg_id\": \"11d8c742-ef27-424c-93ac-069e31aa4764\",\n    \"text\": \"Message\",\n    \"team\": \"T0250UXXX\",\n    \"blocks\": [\n      {\n        \"type\": \"rich_text\",\n        \"block_id\": \"UOZc/\",\n        \"elements\": [\n          {\n            \"type\": \"rich_text_section\",\n            \"elements\": [\n              {\n                \"type\": \"text\",\n                \"text\": \"Message\"\n              }\n            ]\n          }\n        ]\n      }\n    ],\n    \"channel\": \"C033EJ9PH\",\n    \"event_ts\": \"1712136649.831659\",\n    \"channel_type\": \"channel\"\n  },\n  \"retry_num\": 0,\n  \"retry_reason\": \"\",\n  \"accepts_response_payload\": false\n}"}
2024-04-03T09:30:50.157232+00:00 app[web.1]: {"level":20,"time":1712136650155,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"event {\n  \"envelope_id\": \"140ac4f6-786f-4a43-a45d-c248a2083618\",\n  \"body\": {\n    \"token\": \"xxx\",\n    \"team_id\": \"T0250UXXX\",\n    \"context_team_id\": \"T0250UXXX\",\n    \"context_enterprise_id\": null,\n    \"api_app_id\": \"A06RVNS11L5\",\n    \"event\": {\n      \"user\": \"U02516XXX\",\n      \"type\": \"message\",\n      \"ts\": \"1712136649.831659\",\n      \"client_msg_id\": \"11d8c742-ef27-424c-93ac-069e31aa4764\",\n      \"text\": \"Message\",\n      \"team\": \"T0250UXXX\",\n      \"blocks\": [\n        {\n          \"type\": \"rich_text\",\n          \"block_id\": \"UOZc/\",\n          \"elements\": [\n            {\n              \"type\": \"rich_text_section\",\n              \"elements\": [\n                {\n                  \"type\": \"text\",\n                  \"text\": \"Message\"\n                }\n              ]\n            }\n          ]\n        }\n      ],\n      \"channel\": \"C033EJ9PH\",\n      \"event_ts\": \"1712136649.831659\",\n      \"channel_type\": \"channel\"\n    },\n    \"type\": \"event_callback\",\n    \"event_id\": \"Ev06SKBD32JW\",\n    \"event_time\": 1712136649,\n    \"authorizations\": [\n      {\n        \"enterprise_id\": null,\n        \"team_id\": \"T0250UXXX\",\n        \"user_id\": \"U06RS1V096J\",\n        \"is_bot\": true,\n        \"is_enterprise_install\": false\n      }\n    ],\n    \"is_ext_shared_channel\": false,\n    \"event_context\": \"4-eyJldCI6Im1lc3NhZ2UiLCJ0aWQiOiJUMDI1MFVBQkgiLCJhaWQiOiJBMDZSVk5TMTFMNSIsImNpZCI6IkMwMzNFSjlQSCJ9\"\n  },\n  \"event\": {\n    \"user\": \"U02516XXX\",\n    \"type\": \"message\",\n    \"ts\": \"1712136649.831659\",\n    \"client_msg_id\": \"11d8c742-ef27-424c-93ac-069e31aa4764\",\n    \"text\": \"Message\",\n    \"team\": \"T0250UXXX\",\n    \"blocks\": [\n      {\n        \"type\": \"rich_text\",\n        \"block_id\": \"UOZc/\",\n        \"elements\": [\n          {\n            \"type\": \"rich_text_section\",\n            \"elements\": [\n              {\n                \"type\": \"text\",\n                \"text\": \"Message\"\n              }\n            ]\n          }\n        ]\n      }\n    ],\n    \"channel\": \"C033EJ9PH\",\n    \"event_ts\": \"1712136649.831659\",\n    \"channel_type\": \"channel\"\n  },\n  \"retry_num\": 0,\n  \"retry_reason\": \"\",\n  \"accepts_response_payload\": false\n} user = U02516XXX"}
2024-04-03T09:30:50.157234+00:00 app[web.1]: {"level":20,"time":1712136650155,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Text = Message"}
2024-04-03T09:30:50.157235+00:00 app[web.1]: {"level":20,"time":1712136650155,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Event subtype = undefined"}
2024-04-03T09:30:50.157236+00:00 app[web.1]: {"level":20,"time":1712136650155,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"Received generic message: message"}
2024-04-03T09:30:50.194378+00:00 app[web.1]: {"level":20,"time":1712136650194,"pid":2,"hostname":"dc0575bb-a9c2-457e-8173-f618fff6d271","name":"alan","msg":"No listeners executed; falling back to catch-all"}