Bot is auto-assigned only 1 every 2 Livechats -- Always misses 1 LiveChat

Hi guys,

I am running the Commuinity version of LiveChat 4.3.2.

I enable the DialogFlow bot, which works OK, but I have a problem with the Bot auto-assigment. Currently, the Bot is only auto-assigned to a LiveChat once every 2 sessions, like it is shown here:

So, always 1 LiveChat session does not auto-assign the Bot. Automatic Routing is set:

What might be missing ? Thanks for the help.

Server Setup Information

  • Version of Rocket.Chat Server: 4.3.2
  • Operating System: Linux
  • Deployment Method: Helm Chart
  • Number of Running Instances: 1
  • NodeJS Version: v12.22.8
  • MongoDB Version: 4.4.11
  • Proxy: traefik

Any additional Information

I couldn’t find any useful information in the logs, but here it is the log when I tested the LiveChat an the Bot was not assigned: (I enable debugging)

{"level":20,"time":"2022-02-11T16:02:15.722Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"Integrations","section":"Outgoing WebHook","msg":"Got the event arguments for the event: sendMessage","argObject":{"event":"sendMessage","message":{"_id":"z9TMGqQgQrqY4NZDs","rid":"fYah9vrTBYXrKG2H6","msg":"hola !","token":"8fa86fbe07907850a8803e3b213a1bc3bd3f4fb6e8ba9726f8bcf1dec551102d","ts":"2022-02-11T16:02:15.698Z","u":{"_id":"EjBAxkQJ8iCJ5eLQT","username":"guest-21"},"_updatedAt":"2022-02-11T16:02:15.718Z","urls":[],"mentions":[],"channels":[],"md":[{"type":"PARAGRAPH","value":[{"type":"PLAIN_TEXT","value":"hola !"}]}],"newRoom":false,"showConnecting":false},"room":{"_id":"fYah9vrTBYXrKG2H6","msgs":1,"usersCount":1,"lm":"2022-02-11T16:02:14.506Z","fname":"guest-21","t":"l","ts":"2022-02-11T16:02:14.505Z","v":{"_id":"EjBAxkQJ8iCJ5eLQT","username":"guest-21","token":"8fa86fbe07907850a8803e3b213a1bc3bd3f4fb6e8ba9726f8bcf1dec551102d","status":"online"},"cl":false,"open":true,"source":{"type":"widget"},"queuedAt":"2022-02-11T16:02:14.505Z","_updatedAt":"2022-02-11T16:02:14.540Z","lastMessage":{"t":"livechat-started","msg":"","groupable":false,"ts":"2022-02-11T16:02:14.506Z","u":{"_id":"EjBAxkQJ8iCJ5eLQT","username":"guest-21"},"rid":"fYah9vrTBYXrKG2H6","_id":"JwzcrTLPbjnFbJ8n6","_updatedAt":"2022-02-11T16:02:14.529Z","urls":[],"mentions":[],"channels":[]},"responseBy":{"_id":"EjBAxkQJ8iCJ5eLQT","username":"guest-21","lastMessageTs":"2022-02-11T16:02:14.539Z"},"metrics":{"reaction":{"fd":"2022-02-11T16:02:14.539Z","ft":0.034,"tt":0.034},"response":{"avg":0.034,"fd":"2022-02-11T16:02:14.539Z","ft":0.034,"total":1,"tt":0.034}}}}} 
{"level":20,"time":"2022-02-11T16:02:15.723Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"Integrations","section":"Outgoing WebHook","msg":"Starting search for triggers for the room: fYah9vrTBYXrKG2H6"} 
{"level":20,"time":"2022-02-11T16:02:15.723Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"Integrations","section":"Outgoing WebHook","msg":"Found 0 to iterate over and see if the match the event."} 
{"level":35,"time":"2022-02-11T16:02:15.726Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"API","method":"POST","url":"/api/v1/livechat/message","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/98.0.4758.82 Safari/537.36","length":"117","host":"chat.marketshop.io","referer":"https://chat.marketshop.io/livechat","remoteIP":"141.101.103.41","status":200,"responseTime":36} 
{"level":20,"time":"2022-02-11T16:02:15.728Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"Callbacks","msg":"Executing callback with id emojione-shortnameToUnicode for hook beforeSendMessageNotifications"} 
{"level":35,"time":"2022-02-11T16:02:15.740Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"API","method":"POST","url":"/api/v1/livechat/visitor.status","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/98.0.4758.82 Safari/537.36","length":"94","host":"chat.marketshop.io","referer":"https://chat.marketshop.io/livechat","remoteIP":"141.101.103.41","status":200,"responseTime":4} 
{"level":35,"time":"2022-02-11T16:02:16.011Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"API","method":"GET","url":"/api/v1/livechat/messages.history/fYah9vrTBYXrKG2H6?token=8fa86fbe07907850a8803e3b213a1bc3bd3f4fb6e8ba9726f8bcf1dec551102d","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/98.0.4758.82 Safari/537.36","host":"chat.marketshop.io","referer":"https://chat.marketshop.io/livechat","remoteIP":"141.101.103.41","status":200,"responseTime":6} 
{"level":35,"time":"2022-02-11T16:02:17.500Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"API","method":"POST","url":"/api/v1/method.call/license%3AgetModules","userId":"iA6biKMLn3bJ6wrc8","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/98.0.4758.82 Safari/537.36","length":"96","host":"chat.marketshop.io","referer":"https://chat.marketshop.io/admin/view-logs","remoteIP":"141.101.102.20","status":200,"responseTime":1} 
{"level":35,"time":"2022-02-11T16:02:18.830Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"API","method":"GET","url":"/api/info","userAgent":"kube-probe/1.20","host":"10.233.96.202:3000","remoteIP":"162.252.59.86","status":200,"responseTime":0} 
{"level":35,"time":"2022-02-11T16:02:18.896Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"API","method":"POST","url":"/api/v1/method.call/license%3AgetModules","userId":"iA6biKMLn3bJ6wrc8","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/98.0.4758.82 Safari/537.36","length":"96","host":"chat.marketshop.io","referer":"https://chat.marketshop.io/admin/view-logs","remoteIP":"141.101.102.20","status":200,"responseTime":1} 
{"level":20,"time":"2022-02-11T16:02:19.548Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"Processing items for queue Public"} 
{"level":20,"time":"2022-02-11T16:02:19.550Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Helper","msg":"Processing items on queue Public"} 
{"level":20,"time":"2022-02-11T16:02:19.552Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Helper","msg":"No items to process on queue Public"} 
{"level":20,"time":"2022-02-11T16:02:19.552Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"Queue Public processed. Unlocking"} 
{"level":20,"time":"2022-02-11T16:02:19.553Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"No more registered queues. Refreshing"} 
{"level":20,"time":"2022-02-11T16:02:19.555Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"Executing queue Public with timeout of 5000"} 
{"level":20,"time":"2022-02-11T16:02:24.555Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"Processing items for queue Public"} 
{"level":20,"time":"2022-02-11T16:02:24.558Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Helper","msg":"Processing items on queue Public"} 
{"level":20,"time":"2022-02-11T16:02:24.560Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Helper","msg":"No items to process on queue Public"} 
{"level":20,"time":"2022-02-11T16:02:24.560Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"Queue Public processed. Unlocking"} 
{"level":20,"time":"2022-02-11T16:02:24.561Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"No more registered queues. Refreshing"} 
{"level":20,"time":"2022-02-11T16:02:24.563Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"Executing queue Public with timeout of 5000"} 
{"level":35,"time":"2022-02-11T16:02:28.122Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"API","method":"GET","url":"/api/info","userAgent":"kube-probe/1.20","host":"10.233.96.202:3000","remoteIP":"162.252.59.86","status":200,"responseTime":1} 
{"level":20,"time":"2022-02-11T16:02:29.564Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"Processing items for queue Public"} 
{"level":20,"time":"2022-02-11T16:02:29.566Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Helper","msg":"Processing items on queue Public"} 
{"level":20,"time":"2022-02-11T16:02:29.568Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Helper","msg":"No items to process on queue Public"} 
{"level":20,"time":"2022-02-11T16:02:29.569Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"Queue Public processed. Unlocking"} 
{"level":20,"time":"2022-02-11T16:02:29.570Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"No more registered queues. Refreshing"} 
{"level":20,"time":"2022-02-11T16:02:29.571Z","pid":1,"hostname":"rocketchat-rocketchat-78b84f5f86-mhfld","name":"LivechatEnterprise","section":"Queue","msg":"Executing queue Public with timeout of 5000"}