Bot can receive incoming messages but cannot send

[jmaris]

Hi,

I'm running the latest version of the bot with synapse. I have successfully connected to my gchat account, and can see all messages coming in, however when I try to send a message, the message appears in the conversation in the Matrix chat, but doesn't appear to be forwarded to gchat.

I get the following error message in the logs:

[2024-08-26 10:02:39,958] [DEBUG@mau.as.api.@me:[redacted].tld] req #115: POST http://synapse:8448/_matrix/client/v3/rooms/%(roominfohidden)%3A[redacted].tld/read_markers {"m.fully_read": "$(also redacted)", "m.read": "$(also redacted)", "com.beeper.fully_read.extra": {"fi.mau.double_puppet_source": "mautrix-googlechat"}, "com.beeper.read.extra": {"fi.mau.double_puppet_source": "mautrix-googlechat"}}
[2024-08-26 10:02:39,979] [DEBUG@mau.as.api.@me:[redacted].tld] req #115 (/v3/rooms/%(roominfohidden)%3A[redacted].tld/read_markers) completed in 19.8ms with status 200
[2024-08-26 10:02:40,370] [DEBUG@mau.portal.dm:nv-UdsAAAAE] Unhandled event type GROUP_UNREAD_SUBSCRIBED_TOPIC_COUNT_UPDATED
[2024-08-26 10:02:40,370] [DEBUG@mau.portal.dm:nv-UdsAAAAE] Unhandled event type GROUP_UNREAD_SUBSCRIBED_TOPIC_COUNT_UPDATED
[2024-08-26 10:02:40,841] [DEBUG@mau.portal.dm:nv-UdsAAAAE] Unhandled event type TOPIC_CREATED
[2024-08-26 10:02:40,843] [DEBUG@mau.portal.dm:nv-UdsAAAAE] Handling Google Chat message sLnt2FjSahU
[2024-08-26 10:02:40,848] [DEBUG@mau.as.api.@me:[redacted].tld] req #116: PUT http://synapse:8448/_matrix/client/v3/rooms/%(roominfohidden)%3A[redacted].tld/send/m.room.message/mautrix-python_1724666560848423303_14?ts=1724666560645 {"msgtype": "m.text", "body": "test 2", "format": "org.matrix.custom.html", "formatted_body": "test 2", "com.beeper.linkpreviews": [], "fi.mau.double_puppet_source": "mautrix-googlechat"}
[2024-08-26 10:02:40,916] [DEBUG@mau.as.api.@me:[redacted].tld] req #116 (/v3/rooms/%(roominfohidden)%3A[redacted].tld/send/m.room.message/mautrix-python_1724666560848423303_14) completed in 67.3ms with status 200
[2024-08-26 10:02:40,917] [DEBUG@mau.as.api.@me:[redacted].tld] req #117: POST http://synapse:8448/_matrix/client/v3/rooms/%(roominfohidden)%3A[redacted].tld/read_markers {"m.fully_read": "$(redacted)", "m.read": "$(redacted)", "com.beeper.fully_read.extra": {"fi.mau.double_puppet_source": "mautrix-googlechat"}, "com.beeper.read.extra": {"fi.mau.double_puppet_source": "mautrix-googlechat"}}
[2024-08-26 10:02:40,918] [DEBUG@mau.portal.dm:nv-UdsAAAAE] Handled Google Chat message sLnt2FjSahU -> [('$(redacted)', <MessageType.TEXT: 'm.text'>)]
[2024-08-26 10:02:40,919] [DEBUG@mau.as.api.@me:[redacted].tld] req #118: POST http://synapse:8448/_matrix/client/v3/rooms/%(roominfohidden)%3A[redacted].tld/read_markers {"m.fully_read": "$(redacted)", "m.read": "$(redacted)", "com.beeper.fully_read.extra": {"fi.mau.double_puppet_source": "mautrix-googlechat"}, "com.beeper.read.extra": {"fi.mau.double_puppet_source": "mautrix-googlechat"}}
[2024-08-26 10:02:40,953] [INFO@aiohttp.access] 192.168.192.4 [26/Aug/2024:10:02:40 +0000] "PUT /_matrix/app/v1/transactions/743 HTTP/1.1" 200 159 "-" "Synapse/1.113.0"
[2024-08-26 10:02:41,002] [DEBUG@mau.as.api.@me:[redacted].tld] req #118 (/v3/rooms/%(roominfohidden)%3A[redacted].tld/read_markers) completed in 82.1ms with status 200
[2024-08-26 10:02:41,002] [DEBUG@mau.as.api.@me:[redacted].tld] req #117 (/v3/rooms/%(roominfohidden)%3A[redacted].tld/read_markers) completed in 85.4ms with status 200
[2024-08-26 10:02:41,004] [INFO@aiohttp.access] 192.168.192.4 [26/Aug/2024:10:02:41 +0000] "PUT /_matrix/app/v1/transactions/744 HTTP/1.1" 200 159 "-" "Synapse/1.113.0"
[2024-08-26 10:02:41,240] [WARNING@maugclib.pblite] Message 'EventBody' ignoring field event_type: Unknown enum value: 83
[2024-08-26 10:02:41,240] [WARNING@maugclib.pblite] Message 'EventBody' ignoring field event_type: Unknown enum value: 64
[2024-08-26 10:02:41,241] [DEBUG@mau.portal.dm:nv-UdsAAAAE] Unhandled event type UNKNOWN
[2024-08-26 10:02:41,243] [DEBUG@mau.portal.dm:nv-UdsAAAAE] Unhandled event type UNKNOWN

Is there something I'm missing?

Thanks,

[jmaris]

An additional point I failed to mention but that could be important given that the issue only occurs one way (Me -> bot -> gchat) and not the other (gchat -> bot -> me). Could it be related to encryption? because I enabled encryption

[swmarks]

I haven't tested it recently because I rarely use Google Chat these days, but I had noticed this issue a while back and, after restarting the bridge, I could freely chat from matrix to Google Chat for an indeterminate amount of time until whatever happens that seems to lock up outgoing messages. Is this what's happening for you @jmaris ?

(If it matters for troubleshooting, I'm running this bridge as a self-hosted Beeper bridge).

[jmaris]

I haven't tested it recently because I rarely use Google Chat these days, but I had noticed this issue a while back and, after restarting the bridge, I could freely chat from matrix to Google Chat for an indeterminate amount of time until whatever happens that seems to lock up outgoing messages. Is this what's happening for you @jmaris ?

(If it matters for troubleshooting, I'm running this bridge as a self-hosted Beeper bridge).

Unfortunately, on restart the bridge still doesn't work on my side