Open
Description
Hi,
Everything was working great with v0.4.3
and signald
, but then after the upgrade things no longer work, and I can't get any response from @signalbot
to re-login.
On startup, my logs:
2024-11-08T15:13:55.300665958Z 2024-11-08T15:13:55.297Z INF Initializing bridge built_at=2024-10-19T20:30:07Z go_version=go1.23.2 name=mautrix-signal version=0.7.2+dev.ad41c86f
2024-11-08T15:13:55.300715454Z 2024-11-08T15:13:55.297Z DBG Initializing database connection
2024-11-08T15:13:55.300721341Z 2024-11-08T15:13:55.297Z DBG Bridge built with end-to-bridge encryption, but disabled in config
2024-11-08T15:13:55.303444703Z 2024-11-08T15:13:55.303Z INF Initializing libsignal version:0.58.3 component=libsignal file=rust/bridge/ffi/src/logging.rs line=106 target=signal_ffi::logging
2024-11-08T15:13:55.334385127Z 2024-11-08T15:13:55.329Z INF Starting bridge
2024-11-08T15:13:55.343159243Z 2024-11-08T15:13:55.342Z INF Database is up to date current_version=18 db_section=main latest_known_version=18 oldest_compatible_version=9
2024-11-08T15:13:55.343180071Z 2024-11-08T15:13:55.343Z INF Starting Matrix connector
2024-11-08T15:13:55.348185632Z 2024-11-08T15:13:55.348Z INF Database is up to date current_version=7 db_section=matrix_state latest_known_version=7 oldest_compatible_version=3
2024-11-08T15:13:55.348797113Z 2024-11-08T15:13:55.348Z DBG Starting appservice HTTP server
2024-11-08T15:13:55.348818900Z 2024-11-08T15:13:55.348Z DBG Checking connection to homeserver
2024-11-08T15:13:55.350257732Z 2024-11-08T15:13:55.348Z INF Starting HTTP listener address=0.0.0.0:29328
2024-11-08T15:13:55.353468937Z 2024-11-08T15:13:55.353Z DBG Request completed duration=4.882685 method=GET req_id=1 response_length=1065 response_mime=application/json status_code=200 url=http://matrix-synapse:8008/_matrix/client/versions?user_id=%40signalbot%3Amatrix.MYDOMAIN.com
2024-11-08T15:13:55.356969139Z 2024-11-08T15:13:55.356Z DBG Request completed duration=1.847457 method=GET req_id=2 response_length=59 response_mime=application/json status_code=200 url=http://matrix-synapse:8008/_matrix/client/v3/account/whoami?user_id=%40signalbot%3Amatrix.MYDOMAIN.com
2024-11-08T15:13:55.364955244Z 2024-11-08T15:13:55.364Z DBG Received ping from homeserver txn_id=mautrix-go_1731078835356941746_1
2024-11-08T15:13:55.366838339Z 2024-11-08T15:13:55.366Z DBG Request completed duration=9.655578 method=POST req_body={"transaction_id":"mautrix-go_1731078835356941746_1"} req_id=3 response_length=17 response_mime=application/json status_code=200 url=http://matrix-synapse:8008/_matrix/client/v1/appservice/signal/ping?user_id=%40signalbot%3Amatrix.MYDOMAIN.com
2024-11-08T15:13:55.366919677Z 2024-11-08T15:13:55.366Z DBG Homeserver -> bridge connection works duration_ms=7 txn_id=mautrix-go_1731078835356941746_1
2024-11-08T15:13:55.367074479Z 2024-11-08T15:13:55.366Z INF Starting network connector
2024-11-08T15:13:55.368832664Z 2024-11-08T15:13:55.368Z DBG Updating bot profile
2024-11-08T15:13:55.372540781Z 2024-11-08T15:13:55.371Z DBG Request completed duration=3.01161 method=GET req_id=4 response_length=26 response_mime=application/json status_code=200 url=http://matrix-synapse:8008/_matrix/client/v1/media/config?user_id=%40signalbot%3Amatrix.MYDOMAIN.com
2024-11-08T15:13:55.372911108Z 2024-11-08T15:13:55.372Z INF Database is up to date current_version=17 db_section=signalmeow latest_known_version=17 oldest_compatible_version=13
2024-11-08T15:13:55.373927687Z 2024-11-08T15:13:55.373Z DBG Disappearing message loop starting component="disappear loop"
2024-11-08T15:13:55.378407780Z 2024-11-08T15:13:55.377Z INF Loading user user_id=@MYUSER:matrix.MYDOMAIN.com
2024-11-08T15:13:55.384236496Z 2024-11-08T15:13:55.383Z DBG Request completed duration=6.568655 method=GET req_id=5 response_length=59 response_mime=application/json status_code=200 url=http://matrix-synapse:8008/_matrix/client/v3/profile/@signalbot:matrix.MYDOMAIN.com/avatar_url?user_id=%40signalbot%3Amatrix.MYDOMAIN.com
2024-11-08T15:13:55.389300412Z 2024-11-08T15:13:55.389Z DBG Request completed duration=3.856399 method=GET req_id=6 response_length=35 response_mime=application/json status_code=200 url=http://matrix-synapse:8008/_matrix/client/v3/profile/@signalbot:matrix.MYDOMAIN.com/displayname?user_id=%40signalbot%3Amatrix.MYDOMAIN.com
2024-11-08T15:13:55.395536576Z 2024-11-08T15:13:55.394Z INF Starting user login id=e7b2facd-b1ff-4d43-b723-75469dfa7000
2024-11-08T15:13:55.395562575Z 2024-11-08T15:13:55.394Z INF Bridge started
2024-11-08T15:13:55.395567083Z 2024-11-08T15:13:55.395Z DBG Sent new bridge state bridge_state={"message":"You're not logged into Signal","remote_id":"e7b2facd-b1ff-4d43-b723-75469dfa7000","remote_name":"+19193558332","remote_profile":{},"source":"bridge","state_event":"BAD_CREDENTIALS","timestamp":1731078835,"ttl":21600,"user_id":"@MYUSER:matrix.MYDOMAIN.com"}
This makes sense, as I was logged in through signald
but not via mautrix/signal
. But if I do a help
, login
, or logout
with @signalbot
now, I only get this in the logs:
|matrix-synapse | 2024-11-08T15:19:35.746539353Z 2024-11-08 10:19:35,746 - synapse.access.http.8008 - 473 - INFO - PUT-420 - ::ffff:172.20.0.14 - 8008 - {@MYUSER:matrix.MYDOMAIN.com} Processed request: 0.144sec/0.001sec (0.011sec, 0.002sec) (0.004sec/0.012sec/7) 59B 200 "PUT /_matrix/client/v3/rooms/!vzbtDDVEAXqvgqTokU:matrix.MYDOMAIN.com/send/m.room.message/m1731079175551.6 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36" [0 dbevts]
|matrix-synapse | 2024-11-08T15:19:35.748553498Z 2024-11-08 10:19:35,748 - synapse.federation.sender - 596 - INFO - process_event_queue_for_federation-9 - Unexpectedly did not have cached prev group for $5gRUzXKBWduq3LVc62XNc2f8TyEWB4hhauD0Y4yJRZM
|matrix-signal | 2024-11-08T15:19:35.757045258Z 2024-11-08T15:19:35.756Z DBG Starting handling of transaction content={"pdu":1,"unstable_edu":0,"unstable_to_device":0} transaction_id=8467817
|matrix-signal | 2024-11-08T15:19:35.757565654Z 2024-11-08T15:19:35.757Z DBG Finished dispatching events from transaction transaction_id=8467817
|matrix-synapse | 2024-11-08T15:19:35.758466667Z 2024-11-08 10:19:35,758 - synapse.http.client - 428 - INFO - as-sender-20 - Received response to PUT http://matrix-signal:29328/_matrix/app/v1/transactions/8467817: 200
|matrix-synapse | 2024-11-08T15:19:35.760353500Z 2024-11-08 10:19:35,760 - synapse.access.http.8008 - 473 - INFO - GET-418 - ::ffff:172.20.0.14 - 8008 - {@MYUSER:matrix.MYDOMAIN.com} Processed request: 5.311sec/0.001sec (0.001sec, 0.001sec) (0.004sec/0.009sec/5) 1023B 200 "GET /_matrix/client/v3/sync?filter=0&timeout=30000&set_presence=unavailable&since=s189510_4985054_0_15678_20141_44_546_288_0_1 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36" [0 dbevts]
|matrix-signal | 2024-11-08T15:19:35.769478186Z 2024-11-08T15:19:35.769Z DBG Starting handling of transaction content={"pdu":0,"unstable_edu":1,"unstable_to_device":0} transaction_id=8467818
|matrix-signal | 2024-11-08T15:19:35.769509905Z 2024-11-08T15:19:35.769Z DBG Finished dispatching events from transaction transaction_id=8467818
|matrix-synapse | 2024-11-08T15:19:35.769890788Z 2024-11-08 10:19:35,769 - synapse.http.client - 428 - INFO - as-sender-20 - Received response to PUT http://matrix-signal:29328/_matrix/app/v1/transactions/8467818: 200
|matrix-discord | 2024-11-08T15:19:35.773386662Z Nov 8, 2024 15:19:35 DBG Starting handling of transaction content={"pdu":0,"unstable_edu":1,"unstable_to_device":0} transaction_id=8467819
|matrix-discord | 2024-11-08T15:19:35.773570079Z Nov 8, 2024 15:19:35 DBG Finished dispatching events from transaction transaction_id=8467819
|matrix-synapse | 2024-11-08T15:19:35.773701753Z 2024-11-08 10:19:35,773 - synapse.http.client - 428 - INFO - as-sender-21 - Received response to PUT http://matrix-discord:29334/_matrix/app/v1/transactions/8467819: 200
|matrix-synapse | 2024-11-08T15:19:36.166528998Z 2024-11-08 10:19:36,166 - synapse.access.http.8008 - 473 - INFO - POST-424 - ::ffff:172.20.0.14 - 8008 - {@MYUSER:matrix.MYDOMAIN.com} Processed request: 0.015sec/0.000sec (0.001sec, 0.000sec) (0.001sec/0.011sec/4) 2B 200 "POST /_matrix/client/v3/rooms/!vzbtDDVEAXqvgqTokU:matrix.MYDOMAIN.com/read_markers HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36" [0 dbevts]
|matrix-synapse | 2024-11-08T15:19:36.174056668Z 2024-11-08 10:19:36,173 - synapse.access.http.8008 - 473 - INFO - GET-422 - ::ffff:172.20.0.14 - 8008 - {@MYUSER:matrix.MYDOMAIN.com} Processed request: 0.348sec/0.000sec (0.001sec, 0.001sec) (0.002sec/0.004sec/5) 661B 200 "GET /_matrix/client/v3/sync?filter=0&timeout=30000&set_presence=online&since=s189511_4985055_0_15678_20141_44_546_288_0_1 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36" [0 dbevts]
And there's no response from the bot at all.
My config:
network:
displayname_template: '{{or .ProfileName .PhoneNumber "Unknown user"}} (Signal)'
use_contact_avatars: false
sync_contacts_on_startup: true
use_outdated_profiles: false
number_in_topic: true
device_name: mautrix-signal
note_to_self_avatar: mxc://maunium.net/REBIVrqjZwmaWpssCZpBlmlL
location_format: 'https://www.google.com/maps/place/{lat},{long}'
bridge:
command_prefix: '!signal'
personal_filtering_spaces: false
private_chat_portal_meta: true
async_events: false
split_portals: false
resend_bridge_info: false
bridge_matrix_leave: false
tag_only_on_create: true
mute_only_on_create: true
cleanup_on_logout:
enabled: false
manual:
private: nothing
relayed: nothing
shared_no_users: nothing
shared_has_users: nothing
bad_credentials:
private: nothing
relayed: nothing
shared_no_users: nothing
shared_has_users: nothing
relay:
enabled: false
admin_only: true
default_relays: []
message_formats:
m.text: "<b>{{ .Sender.DisambiguatedName }}</b>: {{ .Message }}"
m.notice: "<b>{{ .Sender.DisambiguatedName }}</b>: {{ .Message }}"
m.emote: "* <b>{{ .Sender.DisambiguatedName }}</b> {{ .Message }}"
m.file: "<b>{{ .Sender.DisambiguatedName }}</b> sent a file{{ if .Caption }}: {{ .Caption }}{{ end }}"
m.image: "<b>{{ .Sender.DisambiguatedName }}</b> sent an image{{ if .Caption }}: {{ .Caption }}{{ end }}"
m.audio: "<b>{{ .Sender.DisambiguatedName }}</b> sent an audio file{{ if .Caption }}: {{ .Caption }}{{ end }}"
m.video: "<b>{{ .Sender.DisambiguatedName }}</b> sent a video{{ if .Caption }}: {{ .Caption }}{{ end }}"
m.location: "<b>{{ .Sender.DisambiguatedName }}</b> sent a location{{ if .Caption }}: {{ .Caption }}{{ end }}"
displayname_format: "{{ .DisambiguatedName }}"
permissions:
'*': relay
matrix.MYDOMAIN.com: user
'@root:matrix.MYDOMAIN.com': admin
'@MYUSER:matrix.MYDOMAIN.com': admin
database:
type: postgres
uri: postgres://<redacted>:<redacted>@matrix-signal-postgres/<redacted>?sslmode=disable
max_open_conns: 10
max_idle_conns: 1
max_conn_idle_time: null
max_conn_lifetime: null
homeserver:
address: http://matrix-synapse:8008
domain: matrix.MYDOMAIN.com
software: standard
status_endpoint:
message_send_checkpoint_endpoint:
async_media: false
websocket: false
ping_interval_seconds: 0
appservice:
address: http://matrix-signal:29328
public_address: https://matrix-signal.MYDOMAIN.com:<redacted>
hostname: 0.0.0.0
port: 29328
id: signal
bot:
username: signalbot
displayname: Signal bridge bot
avatar: mxc://maunium.net/wPJgTQbZOtpBFmDNkiNEMDUp
ephemeral_events: true
async_transactions: false
as_token: <redacted>
hs_token: <redacted>
username_template: signal_{{.}}
matrix:
message_status_events: false
delivery_receipts: false
message_error_notices: true
sync_direct_chat_list: false
federate_rooms: true
upload_file_threshold: 5242880
analytics:
token: null
url: https://api.segment.io/v1/track
user_id: null
provisioning:
prefix: /_matrix/provision
shared_secret: <redacted>
allow_matrix_auth: true
debug_endpoints: false
public_media:
enabled: false
signing_key: <redacted>
expiry: 0
hash_length: 32
direct_media:
enabled: false
server_name: discord-media.example.com
well_known_response:
media_id_prefix:
allow_proxy: true
server_key: <redacted>
backfill:
enabled: false
max_initial_messages: 50
max_catchup_messages: 500
unread_hours_threshold: 720
threads:
max_initial_messages: 50
queue:
enabled: false
batch_size: 100
batch_delay: 20
max_batches: -1
max_batches_override: {}
double_puppet:
servers:
example.com: https://example.com
allow_discovery: false
secrets:
example.com: foo
encryption:
allow: false
default: false
require: false
appservice: false
allow_key_sharing: false
pickle_key: mautrix.bridge.e2ee
delete_keys:
delete_outbound_on_ack: false
dont_store_outbound: false
ratchet_on_decrypt: false
delete_fully_used_on_decrypt: false
delete_prev_on_new_session: false
delete_on_device_delete: false
periodically_delete_expired: false
delete_outdated_inbound: false
verification_levels:
receive: unverified
send: unverified
share: cross-signed-tofu
rotation:
enable_custom: false
milliseconds: 604800000
messages: 100
disable_device_change_key_rotation: false
logging:
min_level: debug
writers:
- type: stdout
format: pretty-colored
- type: file
format: json
filename: ./logs/bridge.log
max_size: 100
max_backups: 10
compress: false
Activity