Matthias/conduit
1
0
Fork 0
Code Issues 179 Pull requests 44 Projects Releases 5 Packages Wiki Activity

"sent invalid signature in sendjoin signatures for event" #411

New issue
Open
opened 2023-12-03 18:01:32 +00:00 by _ZN3val · 8 comments
_ZN3val commented 2023-12-03 18:01:32 +00:00 (Migrated from gitlab.com)
Copy link

After a report in #conduit:fachschaften.org, I tried to join #hm:rycee.net, and I am getting this error in my logs:

Dec 03 10:00:10 conduit conduit[922614]: 2023-12-03T10:00:10.776217Z  WARN conduit::api::client_server::membership: Server rycee.net sent invalid signature in sendjoin signatures for event {"auth_events": Array([String("$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA"), String("$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY"), String("$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc")]), "content": Object({"avatar_url": String("mxc://trix.re/9wk7Dp4jm78a8v0hcI5RqHZGVsqbyTC1"), "displayname": String("val"), "membership": String("join")}), "depth": 30058, "hashes": Object({"sha256": String("mHkvgyk2Ni8op/SmZZCI7nsiK8errfAJ9e7O3pRohqw")}), "origin": String("trix.re"), "origin_server_ts": 1701597604877, "prev_events": Array([String("$4P9UGzJySvtwKNk_sNmxrc6gAiFOHu1Xkk4HXguraEY")]), "room_id": String("!YllBCgVdcoakoavZvX:rycee.net"), "sender": String("@val:trix.re"), "signatures": Object({"trix.re": Object({"ed25519:voCzWLcp": String("hhmigofEDddF51C9OCDoMMMf7KUkpC4GcQkIHTojdNR0kWbQ8EvpqkWM/A48ed59zr7wCwIswHrE2RuLRN9UDQ")})}), "state_key": String("@val:trix.re"), "type": String("m.room.member"), "unsigned": Object({})}

which does seem valid when I am manually checking it with a custom Python script:

$ curl -s https://conduit.trix.re/_matrix/key/v2/server                                                                                                                    
{"old_verify_keys":{},"server_name":"trix.re","signatures":{"trix.re":{"ed25519:voCzWLcp":"ns/KbyvcZMoUiCe/E5SOpQsEFjLOxBMXXyLHchN2V3fVFtWwXTJK5AbfWtGpdKoDeaG6We95iBNalMwDdxpGCw"}},"valid_until_ts":1702231162336,"verify_keys":{"ed25519:voCzWLcp":{"key":"gyEyvcRNuH4Kq+69WFCfQQYtBZO7GsupEQ47DbBPE8k"}}}
$ python3
>>> import json, canonicaljson, mhstest, nacl.signing
>>> d3 = {"auth_events": ["$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA", "$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY", "$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc"], "content": {"avatar_url": "mxc://trix.re/9wk7Dp4jm78a8v0hcI5RqHZGVsqbyTC1", "displayname": "val", "mem
bership": "join"}, "depth": 30058, "hashes": {"sha256": "mHkvgyk2Ni8op/SmZZCI7nsiK8errfAJ9e7O3pRohqw"}, "origin": "trix.re", "origin_server_ts": 1701597604877, "prev_events": ["$4P9UGzJySvtwKNk_sNmxrc6gAiFOHu1Xkk4HXguraEY"], "room_id": "!YllBCgVdcoakoavZvX:rycee.net", "sen
der": "@val:trix.re", "signatures": {"trix.re": {"ed25519:voCzWLcp": "hhmigofEDddF51C9OCDoMMMf7KUkpC4GcQkIHTojdNR0kWbQ8EvpqkWM/A48ed59zr7wCwIswHrE2RuLRN9UDQ"}}, "state_key": "@val:trix.re", "type": "m.room.member", "unsigned": {}}
>>> d4 = canonicaljson.encode_canonical_json(mhstest.crypto.Crypto("6").strip_non_essential_keys({k: v for (k, v) in d3.items() if k not in ("signatures", "unsigned")}))
>>> d4
b'{"auth_events":["$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA","$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY","$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc"],"content":{"membership":"join"},"depth":30058,"hashes":{"sha256":"mHkvgyk2Ni8op/SmZZCI7nsiK8errfAJ9e7O3pRohqw"},"origin":"trix.re","origin_server_ts":1701597604877,"prev_events":["$4P9UGzJySvtwKNk_sNmxrc6gAiFOHu1Xkk4HXguraEY"],"room_id":"!YllBCgVdcoakoavZvX:rycee.net","sender":"@val:trix.re","state_key":"@val:trix.re","type":"m.room.member"}'
>>> k = nacl.signing.VerifyKey(base64.b64decode("gyEyvcRNuH4Kq+69WFCfQQYtBZO7GsupEQ47DbBPE8k="))
>>> k.verify(d4, signature=base64.b64decode(d3["signatures"]["trix.re"]["ed25519:voCzWLcp"] + "=="))
b'{"auth_events":["$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA","$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY","$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc"],"content":{"membership":"join"},"depth":30058,"hashes":{"sha256":"mHkvgyk2Ni8op/SmZZCI7nsiK8errfAJ9e7O3pRohqw"},"origin":"trix.re","origin_server_ts":1701597604877,"prev_events":["$4P9UGzJySvtwKNk_sNmxrc6gAiFOHu1Xkk4HXguraEY"],"room_id":"!YllBCgVdcoakoavZvX:rycee.net","sender":"@val:trix.re","state_key":"@val:trix.re","type":"m.room.member"}'

(k.verify not raising an exception means the signature is valid)

Additionally, Conduit's log looks exactly like the JSON that matrix.org serves when requesting this event:

{
  "origin": "matrix.org",
  "origin_server_ts": 1701624885170,
  "pdus": [
    {
      "auth_events": [
        "$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA",
        "$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY",
        "$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc"
      ],
      "content": { "avatar_url": "mxc://trix.re/9wk7Dp4jm78a8v0hcI5RqHZGVsqbyTC1", "displayname": "val", "membership": "join" },
      "depth": 30058,
      "hashes": { "sha256": "mHkvgyk2Ni8op/SmZZCI7nsiK8errfAJ9e7O3pRohqw" },
      "origin": "trix.re",
      "origin_server_ts": 1701597604877,
      "prev_events": [ "$4P9UGzJySvtwKNk_sNmxrc6gAiFOHu1Xkk4HXguraEY" ],
      "room_id": "!YllBCgVdcoakoavZvX:rycee.net",
      "sender": "@val:trix.re",
      "state_key": "@val:trix.re",
      "type": "m.room.member",
      "signatures": { "trix.re": { "ed25519:voCzWLcp": "hhmigofEDddF51C9OCDoMMMf7KUkpC4GcQkIHTojdNR0kWbQ8EvpqkWM/A48ed59zr7wCwIswHrE2RuLRN9UDQ" } },
      "unsigned": { }
    }
  ]
}
After a report in #conduit:fachschaften.org, I tried to join `#hm:rycee.net`, and I am getting this error in my logs: ``` Dec 03 10:00:10 conduit conduit[922614]: 2023-12-03T10:00:10.776217Z WARN conduit::api::client_server::membership: Server rycee.net sent invalid signature in sendjoin signatures for event {"auth_events": Array([String("$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA"), String("$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY"), String("$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc")]), "content": Object({"avatar_url": String("mxc://trix.re/9wk7Dp4jm78a8v0hcI5RqHZGVsqbyTC1"), "displayname": String("val"), "membership": String("join")}), "depth": 30058, "hashes": Object({"sha256": String("mHkvgyk2Ni8op/SmZZCI7nsiK8errfAJ9e7O3pRohqw")}), "origin": String("trix.re"), "origin_server_ts": 1701597604877, "prev_events": Array([String("$4P9UGzJySvtwKNk_sNmxrc6gAiFOHu1Xkk4HXguraEY")]), "room_id": String("!YllBCgVdcoakoavZvX:rycee.net"), "sender": String("@val:trix.re"), "signatures": Object({"trix.re": Object({"ed25519:voCzWLcp": String("hhmigofEDddF51C9OCDoMMMf7KUkpC4GcQkIHTojdNR0kWbQ8EvpqkWM/A48ed59zr7wCwIswHrE2RuLRN9UDQ")})}), "state_key": String("@val:trix.re"), "type": String("m.room.member"), "unsigned": Object({})} ``` which does seem valid when I am manually checking it with a custom Python script: ``` $ curl -s https://conduit.trix.re/_matrix/key/v2/server {"old_verify_keys":{},"server_name":"trix.re","signatures":{"trix.re":{"ed25519:voCzWLcp":"ns/KbyvcZMoUiCe/E5SOpQsEFjLOxBMXXyLHchN2V3fVFtWwXTJK5AbfWtGpdKoDeaG6We95iBNalMwDdxpGCw"}},"valid_until_ts":1702231162336,"verify_keys":{"ed25519:voCzWLcp":{"key":"gyEyvcRNuH4Kq+69WFCfQQYtBZO7GsupEQ47DbBPE8k"}}} $ python3 >>> import json, canonicaljson, mhstest, nacl.signing >>> d3 = {"auth_events": ["$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA", "$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY", "$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc"], "content": {"avatar_url": "mxc://trix.re/9wk7Dp4jm78a8v0hcI5RqHZGVsqbyTC1", "displayname": "val", "mem bership": "join"}, "depth": 30058, "hashes": {"sha256": "mHkvgyk2Ni8op/SmZZCI7nsiK8errfAJ9e7O3pRohqw"}, "origin": "trix.re", "origin_server_ts": 1701597604877, "prev_events": ["$4P9UGzJySvtwKNk_sNmxrc6gAiFOHu1Xkk4HXguraEY"], "room_id": "!YllBCgVdcoakoavZvX:rycee.net", "sen der": "@val:trix.re", "signatures": {"trix.re": {"ed25519:voCzWLcp": "hhmigofEDddF51C9OCDoMMMf7KUkpC4GcQkIHTojdNR0kWbQ8EvpqkWM/A48ed59zr7wCwIswHrE2RuLRN9UDQ"}}, "state_key": "@val:trix.re", "type": "m.room.member", "unsigned": {}} >>> d4 = canonicaljson.encode_canonical_json(mhstest.crypto.Crypto("6").strip_non_essential_keys({k: v for (k, v) in d3.items() if k not in ("signatures", "unsigned")})) >>> d4 b'{"auth_events":["$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA","$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY","$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc"],"content":{"membership":"join"},"depth":30058,"hashes":{"sha256":"mHkvgyk2Ni8op/SmZZCI7nsiK8errfAJ9e7O3pRohqw"},"origin":"trix.re","origin_server_ts":1701597604877,"prev_events":["$4P9UGzJySvtwKNk_sNmxrc6gAiFOHu1Xkk4HXguraEY"],"room_id":"!YllBCgVdcoakoavZvX:rycee.net","sender":"@val:trix.re","state_key":"@val:trix.re","type":"m.room.member"}' >>> k = nacl.signing.VerifyKey(base64.b64decode("gyEyvcRNuH4Kq+69WFCfQQYtBZO7GsupEQ47DbBPE8k=")) >>> k.verify(d4, signature=base64.b64decode(d3["signatures"]["trix.re"]["ed25519:voCzWLcp"] + "==")) b'{"auth_events":["$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA","$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY","$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc"],"content":{"membership":"join"},"depth":30058,"hashes":{"sha256":"mHkvgyk2Ni8op/SmZZCI7nsiK8errfAJ9e7O3pRohqw"},"origin":"trix.re","origin_server_ts":1701597604877,"prev_events":["$4P9UGzJySvtwKNk_sNmxrc6gAiFOHu1Xkk4HXguraEY"],"room_id":"!YllBCgVdcoakoavZvX:rycee.net","sender":"@val:trix.re","state_key":"@val:trix.re","type":"m.room.member"}' ``` (`k.verify` not raising an exception means the signature is valid) Additionally, Conduit's log looks exactly like the JSON that matrix.org serves when requesting this event: ```json { "origin": "matrix.org", "origin_server_ts": 1701624885170, "pdus": [ { "auth_events": [ "$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA", "$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY", "$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc" ], "content": { "avatar_url": "mxc://trix.re/9wk7Dp4jm78a8v0hcI5RqHZGVsqbyTC1", "displayname": "val", "membership": "join" }, "depth": 30058, "hashes": { "sha256": "mHkvgyk2Ni8op/SmZZCI7nsiK8errfAJ9e7O3pRohqw" }, "origin": "trix.re", "origin_server_ts": 1701597604877, "prev_events": [ "$4P9UGzJySvtwKNk_sNmxrc6gAiFOHu1Xkk4HXguraEY" ], "room_id": "!YllBCgVdcoakoavZvX:rycee.net", "sender": "@val:trix.re", "state_key": "@val:trix.re", "type": "m.room.member", "signatures": { "trix.re": { "ed25519:voCzWLcp": "hhmigofEDddF51C9OCDoMMMf7KUkpC4GcQkIHTojdNR0kWbQ8EvpqkWM/A48ed59zr7wCwIswHrE2RuLRN9UDQ" } }, "unsigned": { } } ] } ```
_ZN3val commented 2023-12-03 18:03:12 +00:00 (Migrated from gitlab.com)
Copy link

changed the description

changed the description
_ZN3val commented 2023-12-03 18:38:37 +00:00 (Migrated from gitlab.com)
Copy link

mentioned in merge request !563

mentioned in merge request !563
_ZN3val commented 2023-12-03 18:39:17 +00:00 (Migrated from gitlab.com)
Copy link

Using https://gitlab.com/famedly/conduit/-/merge_requests/563, I see that the underlying error seems to be a missing signature: BadRequest(InvalidParam, "Server did not send its signature")

More logs, btw:

2023-12-03T18:33:46.791508Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Sending request to rycee.net at https://matrix.rycee.net/_matrix/federation/v1/query/directory?room_alias=%23hm%3Arycee.net
2023-12-03T18:34:14.925242Z DEBUG http_request{path=/_matrix/client/v3/sync}: tower_http::trace::on_response: finished processing request latency=30021 ms status=200
2023-12-03T18:34:14.954580Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Received response from rycee.net at https://matrix.rycee.net/_matrix/federation/v1/query/directory?room_alias=%23hm%3Arycee.net
2023-12-03T18:34:14.954682Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Getting response bytes from rycee.net
2023-12-03T18:34:14.954803Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Got response bytes from rycee.net
2023-12-03T18:34:14.954869Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Parsing response bytes from rycee.net
2023-12-03T18:34:14.955349Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}: tower_http::trace::on_response: finished processing request latency=28377 ms status=200
2023-12-03T18:34:14.957409Z DEBUG http_request{path=/_matrix/client/v3/sync}: tower_http::trace::on_request: started processing request
2023-12-03T18:34:14.957621Z DEBUG http_request{path=/_matrix/client/v3/sync}: tower_http::trace::on_response: finished processing request latency=0 ms status=200
2023-12-03T18:34:14.973081Z DEBUG http_request{path=/_matrix/client/v3/sync}: tower_http::trace::on_request: started processing request
2023-12-03T18:34:14.973785Z DEBUG http_request{path=/_matrix/client/v3/sync}: conduit::api::ruma_wrapper::axum: Request { method: GET, uri: /_matrix/client/v3/sync?filter=cQRR&timeout=30000&set_presence=online&since=1278, version: HTTP/1.1, headers: {"host": "conduit-test.magnesium.progval.net", "x-real-ip": "2a01:cb08:b80:2100:fdc4:d65c:9c25:799f", "x-forwarded-for": "2a01:cb08:b80:2100:fdc4:d65c:9c25:799f", "x-forwarded-proto": "https", "user-agent": "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/114.0", "accept": "application/json", "accept-language": "en-US,en;q=0.5", "accept-encoding": "gzip, deflate, br", "origin": "https://app.element.io", "sec-fetch-dest": "empty", "sec-fetch-mode": "cors", "sec-fetch-site": "cross-site", "authorization": Sensitive, "cache-control": "max-age=0"}, body: [] }
2023-12-03T18:34:15.018803Z DEBUG http_request{path=/_matrix/client/v3/rooms/:room_id/initialSync}: tower_http::trace::on_request: started processing request
2023-12-03T18:34:15.019008Z DEBUG http_request{path=/_matrix/client/v3/rooms/:room_id/initialSync}: tower_http::trace::on_response: finished processing request latency=0 ms status=200
2023-12-03T18:34:15.032245Z DEBUG http_request{path=/_matrix/client/v3/rooms/:room_id/initialSync}: tower_http::trace::on_request: started processing request
2023-12-03T18:34:15.032474Z  INFO http_request{path=/_matrix/client/v3/rooms/:room_id/initialSync}: conduit::utils::error: Returning an error: 403 Forbidden: M_GUEST_ACCESS_FORBIDDEN: Guest access not implemented
2023-12-03T18:34:15.032656Z DEBUG http_request{path=/_matrix/client/v3/rooms/:room_id/initialSync}: tower_http::trace::on_response: finished processing request latency=0 ms status=403
2023-12-03T18:34:15.042757Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: tower_http::trace::on_request: started processing request
2023-12-03T18:34:15.042849Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: tower_http::trace::on_response: finished processing request latency=0 ms status=200
2023-12-03T18:34:15.082507Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: tower_http::trace::on_request: started processing request
2023-12-03T18:34:15.084923Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::ruma_wrapper::axum: Request { method: POST, uri: /_matrix/client/v3/join/%23hm%3Arycee.net, version: HTTP/1.1, headers: {"host": "conduit-test.magnesium.progval.net", "x-real-ip": "2a01:cb08:b80:2100:fdc4:d65c:9c25:799f", "x-forwarded-for": "2a01:cb08:b80:2100:fdc4:d65c:9c25:799f", "x-forwarded-proto": "https", "content-length": "2", "user-agent": "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/114.0", "accept": "application/json", "accept-language": "en-US,en;q=0.5", "accept-encoding": "gzip, deflate, br", "content-type": "application/json", "origin": "https://app.element.io", "sec-fetch-dest": "empty", "sec-fetch-mode": "cors", "sec-fetch-site": "cross-site", "authorization": Sensitive}, body: [123, 125] }
2023-12-03T18:34:15.085095Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Waiting for permit
2023-12-03T18:34:15.085124Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Got permit
2023-12-03T18:34:15.085176Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Preparing to send request to rycee.net
2023-12-03T18:34:15.085743Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Sending request to rycee.net at https://matrix.rycee.net/_matrix/federation/v1/query/directory?room_alias=%23hm%3Arycee.net
2023-12-03T18:34:15.367590Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Received response from rycee.net at https://matrix.rycee.net/_matrix/federation/v1/query/directory?room_alias=%23hm%3Arycee.net
2023-12-03T18:34:15.367697Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Getting response bytes from rycee.net
2023-12-03T18:34:15.367832Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Got response bytes from rycee.net
2023-12-03T18:34:15.367887Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Parsing response bytes from rycee.net
2023-12-03T18:34:15.368542Z  INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: Joining !YllBCgVdcoakoavZvX:rycee.net over federation.
2023-12-03T18:34:15.368624Z  INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: Asking rycee.net for make_join
2023-12-03T18:34:15.368709Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Waiting for permit
2023-12-03T18:34:15.368755Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Got permit
2023-12-03T18:34:15.368870Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Preparing to send request to rycee.net
2023-12-03T18:34:15.369982Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Sending request to rycee.net at https://matrix.rycee.net/_matrix/federation/v1/make_join/!YllBCgVdcoakoavZvX:rycee.net/@valtest:conduit-test.magnesium.progval.net?ver=6&ver=7&ver=8&ver=9&ver=10&ver=3&ver=4&ver=5&ver=11
2023-12-03T18:34:15.525470Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Received response from rycee.net at https://matrix.rycee.net/_matrix/federation/v1/make_join/!YllBCgVdcoakoavZvX:rycee.net/@valtest:conduit-test.magnesium.progval.net?ver=6&ver=7&ver=8&ver=9&ver=10&ver=3&ver=4&ver=5&ver=11
2023-12-03T18:34:15.525580Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Getting response bytes from rycee.net
2023-12-03T18:34:15.525691Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Got response bytes from rycee.net
2023-12-03T18:34:15.525757Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Parsing response bytes from rycee.net
2023-12-03T18:34:15.526004Z  INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: make_join finished
2023-12-03T18:34:15.528987Z  INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: Asking rycee.net for send_join
2023-12-03T18:34:15.529355Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Waiting for permit
2023-12-03T18:34:15.529413Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Got permit
2023-12-03T18:34:15.529521Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Preparing to send request to rycee.net
2023-12-03T18:34:15.531047Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Sending request to rycee.net at https://matrix.rycee.net/_matrix/federation/v2/send_join/!YllBCgVdcoakoavZvX:rycee.net/$iQZO4V5IAubO7kxYCCbokyLrxvGSPIsBQ5t8z1sLvDw
2023-12-03T18:34:16.326639Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_request: started processing request
2023-12-03T18:34:16.326819Z  WARN http_request{path=/.well-known/matrix/server}: conduit: Not found: /.well-known/matrix/server
2023-12-03T18:34:16.326892Z  INFO http_request{path=/.well-known/matrix/server}: conduit::utils::error: Returning an error: 404 Not Found: M_UNRECOGNIZED: Unrecognized request
2023-12-03T18:34:16.327055Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_response: finished processing request latency=0 ms status=404
2023-12-03T18:34:16.957677Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Received response from rycee.net at https://matrix.rycee.net/_matrix/federation/v2/send_join/!YllBCgVdcoakoavZvX:rycee.net/$iQZO4V5IAubO7kxYCCbokyLrxvGSPIsBQ5t8z1sLvDw
2023-12-03T18:34:16.957747Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Getting response bytes from rycee.net
2023-12-03T18:34:17.226534Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_request: started processing request
2023-12-03T18:34:17.226621Z  WARN http_request{path=/.well-known/matrix/server}: conduit: Not found: /.well-known/matrix/server
2023-12-03T18:34:17.226648Z  INFO http_request{path=/.well-known/matrix/server}: conduit::utils::error: Returning an error: 404 Not Found: M_UNRECOGNIZED: Unrecognized request
2023-12-03T18:34:17.226726Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_response: finished processing request latency=0 ms status=404
2023-12-03T18:34:17.343670Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_request: started processing request
2023-12-03T18:34:17.343833Z  WARN http_request{path=/.well-known/matrix/server}: conduit: Not found: /.well-known/matrix/server
2023-12-03T18:34:17.343885Z  INFO http_request{path=/.well-known/matrix/server}: conduit::utils::error: Returning an error: 404 Not Found: M_UNRECOGNIZED: Unrecognized request
2023-12-03T18:34:17.344062Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_response: finished processing request latency=0 ms status=404
2023-12-03T18:34:17.351017Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}: tower_http::trace::on_request: started processing request
2023-12-03T18:34:17.351619Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}: conduit::api::ruma_wrapper::axum: Unexpected field `destination` in X-Matrix Authorization header
2023-12-03T18:34:17.351963Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}:fetch_signing_keys: conduit::service::rooms::event_handler: Fetching signing keys for rycee.net over federation
2023-12-03T18:34:17.352052Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}:fetch_signing_keys:send_federation_request: conduit::service::sending: Waiting for permit
2023-12-03T18:34:17.352091Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}:fetch_signing_keys:send_federation_request: conduit::service::sending: Got permit
2023-12-03T18:34:17.352177Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}:fetch_signing_keys:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Preparing to send request to rycee.net
2023-12-03T18:34:17.353020Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}:fetch_signing_keys:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Sending request to rycee.net at https://matrix.rycee.net/_matrix/key/v2/server
[...]
2023-12-03T18:34:27.932329Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_response: finished processing request latency=0 ms status=404
2023-12-03T18:34:28.195259Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Got response bytes from rycee.net
2023-12-03T18:34:28.195359Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Parsing response bytes from rycee.net
2023-12-03T18:34:28.298262Z  INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: send_join finished
2023-12-03T18:34:28.298293Z  INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: There is a signed event. This room is probably using restricted joins. Adding signature to our event
2023-12-03T18:34:28.298511Z  WARN http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: Server rycee.net sent invalid signature in sendjoin signatures for event {"auth_events": Array([String("$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA"), String("$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY"), String("$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc")]), "content": Object({"displayname": String("valtest ⚡\u{fe0f}"), "membership": String("join")}), "depth": 30067, "hashes": Object({"sha256": String("RbdWqS9ITKDyyrhNWyPl3HJh0dGei6cAYww2YNSwzgY")}), "origin": String("conduit-test.magnesium.progval.net"), "origin_server_ts": 1701628455526, "prev_events": Array([String("$JLPE00oc8PoNUw1VIM0QdihC7kUhNvQlFvdX4uG5EpU")]), "room_id": String("!YllBCgVdcoakoavZvX:rycee.net"), "sender": String("@valtest:conduit-test.magnesium.progval.net"), "signatures": Object({"conduit-test.magnesium.progval.net": Object({"ed25519:vfVYq8Ey": String("RtUU4OfUyBxKm+CT0feCvUT9la/IchEK0Kjb7C1AFX2ROrPSkDtA22NoVjhApZc57q7byY1q7lsLChEKZ6DOCw")})}), "state_key": String("@valtest:conduit-test.magnesium.progval.net"), "type": String("m.room.member"), "unsigned": Object({})}: BadRequest(InvalidParam, "Server did not send its signature")
Using https://gitlab.com/famedly/conduit/-/merge_requests/563, I see that the underlying error seems to be a missing signature: `BadRequest(InvalidParam, "Server did not send its signature")` More logs, btw: ``` 2023-12-03T18:33:46.791508Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Sending request to rycee.net at https://matrix.rycee.net/_matrix/federation/v1/query/directory?room_alias=%23hm%3Arycee.net 2023-12-03T18:34:14.925242Z DEBUG http_request{path=/_matrix/client/v3/sync}: tower_http::trace::on_response: finished processing request latency=30021 ms status=200 2023-12-03T18:34:14.954580Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Received response from rycee.net at https://matrix.rycee.net/_matrix/federation/v1/query/directory?room_alias=%23hm%3Arycee.net 2023-12-03T18:34:14.954682Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Getting response bytes from rycee.net 2023-12-03T18:34:14.954803Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Got response bytes from rycee.net 2023-12-03T18:34:14.954869Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Parsing response bytes from rycee.net 2023-12-03T18:34:14.955349Z DEBUG http_request{path=/_matrix/client/v3/directory/room/:room_alias}: tower_http::trace::on_response: finished processing request latency=28377 ms status=200 2023-12-03T18:34:14.957409Z DEBUG http_request{path=/_matrix/client/v3/sync}: tower_http::trace::on_request: started processing request 2023-12-03T18:34:14.957621Z DEBUG http_request{path=/_matrix/client/v3/sync}: tower_http::trace::on_response: finished processing request latency=0 ms status=200 2023-12-03T18:34:14.973081Z DEBUG http_request{path=/_matrix/client/v3/sync}: tower_http::trace::on_request: started processing request 2023-12-03T18:34:14.973785Z DEBUG http_request{path=/_matrix/client/v3/sync}: conduit::api::ruma_wrapper::axum: Request { method: GET, uri: /_matrix/client/v3/sync?filter=cQRR&timeout=30000&set_presence=online&since=1278, version: HTTP/1.1, headers: {"host": "conduit-test.magnesium.progval.net", "x-real-ip": "2a01:cb08:b80:2100:fdc4:d65c:9c25:799f", "x-forwarded-for": "2a01:cb08:b80:2100:fdc4:d65c:9c25:799f", "x-forwarded-proto": "https", "user-agent": "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/114.0", "accept": "application/json", "accept-language": "en-US,en;q=0.5", "accept-encoding": "gzip, deflate, br", "origin": "https://app.element.io", "sec-fetch-dest": "empty", "sec-fetch-mode": "cors", "sec-fetch-site": "cross-site", "authorization": Sensitive, "cache-control": "max-age=0"}, body: [] } 2023-12-03T18:34:15.018803Z DEBUG http_request{path=/_matrix/client/v3/rooms/:room_id/initialSync}: tower_http::trace::on_request: started processing request 2023-12-03T18:34:15.019008Z DEBUG http_request{path=/_matrix/client/v3/rooms/:room_id/initialSync}: tower_http::trace::on_response: finished processing request latency=0 ms status=200 2023-12-03T18:34:15.032245Z DEBUG http_request{path=/_matrix/client/v3/rooms/:room_id/initialSync}: tower_http::trace::on_request: started processing request 2023-12-03T18:34:15.032474Z INFO http_request{path=/_matrix/client/v3/rooms/:room_id/initialSync}: conduit::utils::error: Returning an error: 403 Forbidden: M_GUEST_ACCESS_FORBIDDEN: Guest access not implemented 2023-12-03T18:34:15.032656Z DEBUG http_request{path=/_matrix/client/v3/rooms/:room_id/initialSync}: tower_http::trace::on_response: finished processing request latency=0 ms status=403 2023-12-03T18:34:15.042757Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: tower_http::trace::on_request: started processing request 2023-12-03T18:34:15.042849Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: tower_http::trace::on_response: finished processing request latency=0 ms status=200 2023-12-03T18:34:15.082507Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: tower_http::trace::on_request: started processing request 2023-12-03T18:34:15.084923Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::ruma_wrapper::axum: Request { method: POST, uri: /_matrix/client/v3/join/%23hm%3Arycee.net, version: HTTP/1.1, headers: {"host": "conduit-test.magnesium.progval.net", "x-real-ip": "2a01:cb08:b80:2100:fdc4:d65c:9c25:799f", "x-forwarded-for": "2a01:cb08:b80:2100:fdc4:d65c:9c25:799f", "x-forwarded-proto": "https", "content-length": "2", "user-agent": "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/114.0", "accept": "application/json", "accept-language": "en-US,en;q=0.5", "accept-encoding": "gzip, deflate, br", "content-type": "application/json", "origin": "https://app.element.io", "sec-fetch-dest": "empty", "sec-fetch-mode": "cors", "sec-fetch-site": "cross-site", "authorization": Sensitive}, body: [123, 125] } 2023-12-03T18:34:15.085095Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Waiting for permit 2023-12-03T18:34:15.085124Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Got permit 2023-12-03T18:34:15.085176Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Preparing to send request to rycee.net 2023-12-03T18:34:15.085743Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Sending request to rycee.net at https://matrix.rycee.net/_matrix/federation/v1/query/directory?room_alias=%23hm%3Arycee.net 2023-12-03T18:34:15.367590Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Received response from rycee.net at https://matrix.rycee.net/_matrix/federation/v1/query/directory?room_alias=%23hm%3Arycee.net 2023-12-03T18:34:15.367697Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Getting response bytes from rycee.net 2023-12-03T18:34:15.367832Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Got response bytes from rycee.net 2023-12-03T18:34:15.367887Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Parsing response bytes from rycee.net 2023-12-03T18:34:15.368542Z INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: Joining !YllBCgVdcoakoavZvX:rycee.net over federation. 2023-12-03T18:34:15.368624Z INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: Asking rycee.net for make_join 2023-12-03T18:34:15.368709Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Waiting for permit 2023-12-03T18:34:15.368755Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Got permit 2023-12-03T18:34:15.368870Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Preparing to send request to rycee.net 2023-12-03T18:34:15.369982Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Sending request to rycee.net at https://matrix.rycee.net/_matrix/federation/v1/make_join/!YllBCgVdcoakoavZvX:rycee.net/@valtest:conduit-test.magnesium.progval.net?ver=6&ver=7&ver=8&ver=9&ver=10&ver=3&ver=4&ver=5&ver=11 2023-12-03T18:34:15.525470Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Received response from rycee.net at https://matrix.rycee.net/_matrix/federation/v1/make_join/!YllBCgVdcoakoavZvX:rycee.net/@valtest:conduit-test.magnesium.progval.net?ver=6&ver=7&ver=8&ver=9&ver=10&ver=3&ver=4&ver=5&ver=11 2023-12-03T18:34:15.525580Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Getting response bytes from rycee.net 2023-12-03T18:34:15.525691Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Got response bytes from rycee.net 2023-12-03T18:34:15.525757Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Parsing response bytes from rycee.net 2023-12-03T18:34:15.526004Z INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: make_join finished 2023-12-03T18:34:15.528987Z INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: Asking rycee.net for send_join 2023-12-03T18:34:15.529355Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Waiting for permit 2023-12-03T18:34:15.529413Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request: conduit::service::sending: Got permit 2023-12-03T18:34:15.529521Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Preparing to send request to rycee.net 2023-12-03T18:34:15.531047Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Sending request to rycee.net at https://matrix.rycee.net/_matrix/federation/v2/send_join/!YllBCgVdcoakoavZvX:rycee.net/$iQZO4V5IAubO7kxYCCbokyLrxvGSPIsBQ5t8z1sLvDw 2023-12-03T18:34:16.326639Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_request: started processing request 2023-12-03T18:34:16.326819Z WARN http_request{path=/.well-known/matrix/server}: conduit: Not found: /.well-known/matrix/server 2023-12-03T18:34:16.326892Z INFO http_request{path=/.well-known/matrix/server}: conduit::utils::error: Returning an error: 404 Not Found: M_UNRECOGNIZED: Unrecognized request 2023-12-03T18:34:16.327055Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_response: finished processing request latency=0 ms status=404 2023-12-03T18:34:16.957677Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Received response from rycee.net at https://matrix.rycee.net/_matrix/federation/v2/send_join/!YllBCgVdcoakoavZvX:rycee.net/$iQZO4V5IAubO7kxYCCbokyLrxvGSPIsBQ5t8z1sLvDw 2023-12-03T18:34:16.957747Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Getting response bytes from rycee.net 2023-12-03T18:34:17.226534Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_request: started processing request 2023-12-03T18:34:17.226621Z WARN http_request{path=/.well-known/matrix/server}: conduit: Not found: /.well-known/matrix/server 2023-12-03T18:34:17.226648Z INFO http_request{path=/.well-known/matrix/server}: conduit::utils::error: Returning an error: 404 Not Found: M_UNRECOGNIZED: Unrecognized request 2023-12-03T18:34:17.226726Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_response: finished processing request latency=0 ms status=404 2023-12-03T18:34:17.343670Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_request: started processing request 2023-12-03T18:34:17.343833Z WARN http_request{path=/.well-known/matrix/server}: conduit: Not found: /.well-known/matrix/server 2023-12-03T18:34:17.343885Z INFO http_request{path=/.well-known/matrix/server}: conduit::utils::error: Returning an error: 404 Not Found: M_UNRECOGNIZED: Unrecognized request 2023-12-03T18:34:17.344062Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_response: finished processing request latency=0 ms status=404 2023-12-03T18:34:17.351017Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}: tower_http::trace::on_request: started processing request 2023-12-03T18:34:17.351619Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}: conduit::api::ruma_wrapper::axum: Unexpected field `destination` in X-Matrix Authorization header 2023-12-03T18:34:17.351963Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}:fetch_signing_keys: conduit::service::rooms::event_handler: Fetching signing keys for rycee.net over federation 2023-12-03T18:34:17.352052Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}:fetch_signing_keys:send_federation_request: conduit::service::sending: Waiting for permit 2023-12-03T18:34:17.352091Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}:fetch_signing_keys:send_federation_request: conduit::service::sending: Got permit 2023-12-03T18:34:17.352177Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}:fetch_signing_keys:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Preparing to send request to rycee.net 2023-12-03T18:34:17.353020Z DEBUG http_request{path=/_matrix/federation/v1/send/:transaction_id}:fetch_signing_keys:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Sending request to rycee.net at https://matrix.rycee.net/_matrix/key/v2/server [...] 2023-12-03T18:34:27.932329Z DEBUG http_request{path=/.well-known/matrix/server}: tower_http::trace::on_response: finished processing request latency=0 ms status=404 2023-12-03T18:34:28.195259Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Got response bytes from rycee.net 2023-12-03T18:34:28.195359Z DEBUG http_request{path=/_matrix/client/v3/join/:room_id_or_alias}:send_federation_request:send_request{destination="rycee.net"}: conduit::api::server_server: Parsing response bytes from rycee.net 2023-12-03T18:34:28.298262Z INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: send_join finished 2023-12-03T18:34:28.298293Z INFO http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: There is a signed event. This room is probably using restricted joins. Adding signature to our event 2023-12-03T18:34:28.298511Z WARN http_request{path=/_matrix/client/v3/join/:room_id_or_alias}: conduit::api::client_server::membership: Server rycee.net sent invalid signature in sendjoin signatures for event {"auth_events": Array([String("$B8NBX6oUzLTq8OuFK_YvCJhqn2P_0fARY29XSZ6T3DA"), String("$0pt22mJ5ZFjIdguJmKzZyBjhMQGEzOgtoe_3IwqE8KY"), String("$LR-AN_TTZyWaFQ9mbw5mW4S_pWgOEx6yWGq49tQertc")]), "content": Object({"displayname": String("valtest ⚡\u{fe0f}"), "membership": String("join")}), "depth": 30067, "hashes": Object({"sha256": String("RbdWqS9ITKDyyrhNWyPl3HJh0dGei6cAYww2YNSwzgY")}), "origin": String("conduit-test.magnesium.progval.net"), "origin_server_ts": 1701628455526, "prev_events": Array([String("$JLPE00oc8PoNUw1VIM0QdihC7kUhNvQlFvdX4uG5EpU")]), "room_id": String("!YllBCgVdcoakoavZvX:rycee.net"), "sender": String("@valtest:conduit-test.magnesium.progval.net"), "signatures": Object({"conduit-test.magnesium.progval.net": Object({"ed25519:vfVYq8Ey": String("RtUU4OfUyBxKm+CT0feCvUT9la/IchEK0Kjb7C1AFX2ROrPSkDtA22NoVjhApZc57q7byY1q7lsLChEKZ6DOCw")})}), "state_key": String("@valtest:conduit-test.magnesium.progval.net"), "type": String("m.room.member"), "unsigned": Object({})}: BadRequest(InvalidParam, "Server did not send its signature") ```
_ZN3val commented 2023-12-03 18:51:35 +00:00 (Migrated from gitlab.com)
Copy link

In https://spec.matrix.org/v1.9/server-server-api/#put_matrixfederationv2send_joinroomideventid the spec says: "The signed copy of the membership event sent to other servers by the resident server, including the resident server’s signature."

So it seems the Synapse at rycee.net is to blame here

In https://spec.matrix.org/v1.9/server-server-api/#put_matrixfederationv2send_joinroomideventid the spec says: "The signed copy of the membership event sent to other servers by the resident server, including the resident server’s signature." So it seems the Synapse at `rycee.net` is to blame here
_ZN3val commented 2023-12-03 19:03:38 +00:00 (Migrated from gitlab.com)
Copy link

Reproduced with matrix.org and reported to Synapse: https://github.com/matrix-org/synapse/issues/16717

Reproduced with matrix.org and reported to Synapse: https://github.com/matrix-org/synapse/issues/16717
_ZN3val commented 2024-01-11 16:16:18 +00:00 (Migrated from gitlab.com)
Copy link

WONTFIX from Synapse: https://github.com/element-hq/synapse/issues/16717#issuecomment-1887343791

WONTFIX from Synapse: https://github.com/element-hq/synapse/issues/16717#issuecomment-1887343791
CobaltCause commented 2024-01-20 06:24:32 +00:00 (Migrated from gitlab.com)
Copy link

Expanding on that: the spec at the time Conduit implemented it was misleading and will be clarified in the future. This means that Conduit will need to change its behavior here. See also https://github.com/matrix-org/matrix-spec/issues/1708

Expanding on that: the spec at the time Conduit implemented it was misleading and will be clarified in the future. This means that Conduit will need to change its behavior here. See also <https://github.com/matrix-org/matrix-spec/issues/1708>
CobaltCause commented 2024-01-20 19:21:24 +00:00 (Migrated from gitlab.com)
Copy link

Here's another report of this issue: https://matrix.to/#/!SMloEYlhCiqKwRLAgY:fachschaften.org/$WgBaN0Bdbrwbvlm-OhSmjmh7HgqgjCWHZpdZ4IqpUiw?via=flipdot.org&via=matrix.org&via=conduit.rs

Here's another report of this issue: https://matrix.to/#/!SMloEYlhCiqKwRLAgY:fachschaften.org/$WgBaN0Bdbrwbvlm-OhSmjmh7HgqgjCWHZpdZ4IqpUiw?via=flipdot.org&via=matrix.org&via=conduit.rs
Sign in to join this conversation.
No Branch/Tag specified
Branches Tags
next
performance
jonas-test-ci-docker-building-quemu-on-fsinfo-runner
presence
master
askstate
recoverbroken
Nyaaori/temp-combined-feature-branch
Nyaaori/destructive-rocksdb-recovery
Nyaaori/full-profile-directory
Nyaaori/read-receipt-config-options
Nyaaori/private-read-receipts
Nyaaori/federation-backfill
Nyaaori/declare-msc3827
Nyaaori/remove-misisng-e2ee-readme
refactor
Nyaaori/refactor-next
refactor-ci-fix
bump-docker-alpine-to-3.16.2
jplatte/up-ruma
mdbook-docs-preparation
async
244-support-well-known
complement-integration
brokenjoinfix
state_full_ids_cache
ticho34782694/conduit-rocksdb-memory-usage
docs-the-second
serve-element-web
perf
nyaaori/older-room-versions
serve-well-known
unbeatable-101-next-patch-64009
docs-overhaul
state-res-perf
stablecrosssigning
member-reason
admin-command-for-cache-memory-usage
measure-cache-memory-usage
lesscache
locks
state-res-closure-rebased
ruma-client
ruma
v0.6.0
v0.5.0
v0.4.0
v0.3.0
v0.2.0
Labels
Clear labels   
Android

This label indicates that the issue affects Android devices.

  
CS::needs customer feedback

Issue has been finished and needs feedback from the customer if everything is okay OR the customer needs to answer questions before issue can be worked on.

  
CS::needs follow up

We have received questions or new information from the customer that needs discussing in the meeting with Product and Sales

  
CS::needs on prem installation

This Issue needs to be installed on prem at the customer after closing

  
CS::waiting

This Issue is in progress in other teams (prod/tech)

  
Chrome

This label indicates that the issue affects the chrome browser. You should always combine it with other platforms like Android, if it only affects Android.

  
Design:: Ready

   
Design:: in progress

   
Design::UX

@Famedly: Do not use this label

  
E2EE

   
Edge

This label indicates that the issue affects Edge devices.

  
Firefox

This label indicates that the issue affects Firefox devices.

  
GDPR

   
Iteration 13 IM

   
Linux

This label indicates that the issue affects Linux devices.

  
MacOS

This label indicates that the issue affects MacOS devices.

  
Need::Discussion

Topic needs more discussion

  
Need::Steps to reproduce

   
Need::Upstream fix

   
Needs:: Planning

   
Needs::Dev-Team

This label indicates that the issue needs to be assigned to a dev team.

  
Needs::More information

   
Needs::Priority

This label should be set if there is no priority on feature or improvement requests.

  
Needs::Product

   
Needs::Refinement

the issue is triaged and needs to go to the refinement meeting to be able to set story points to the issue

  
Needs::Severity

This label should be set if bug reports need a severity.

  
Priority::1-Critical

The Priority 1 label indicates a critically dire issue such as a regression, or failure in existing features. We look at issues with this label frequently. If you find yourself assigning a Prio 1 label to an issue, please be sure that there's a positive handoff between filing and a prospective owner for the issue. Prio 1 issues should be rare. Normally there should be zero open P1 issues. "The world is on fire."

  
Priority::2-Max

The Priority 2 label indicates that the issue deserves immediate attention, such as affecting a top-tier customer. There are generally only one or two Prio 2 bugs at a time, and we aim for there to be none. "A customer is on fire."

  
Priority::3-Impending

A bug with Priority 3 flag indicates that a top-tier customer is about to encounter this bug. Generally, there are less than ten Priority 3 bugs. Issues may not be handled immediately (sometimes some Prio 4 issues are handled first), but are top-of-mind and we explicitly consider them weekly. "A customer is about to be on fire."

  
Priority::4-High

The Priority 4 label indicates high-priority issues that are at the top of the work list. This is the highest priority level an issue can have if it isn't affecting a top-tier customer or breaking the app. Bugs marked Priority 4 are generally actively being worked on unless the assignee is dealing with a P1-P3 bug(or another P4 issue).

  
Priority::5-Medium

The Priority 5 label indicates issues that we agree are important to work on, but not at the top of the work list. This is the default level for a bug. An issue at this priority level may not be worked on for a long time. Often an issue at this level will first migrate to Prio 4 before we work on them.

  
Priority::6-Low

The Priority 6 label indicates issues we think are valid but not important. This is the default level for new feature requests. We are unlikely to work on such issues unless they form part of a long-term strategic plan (see the Roadmap).

  
Priority::7-None

The Priority 7 label indicates valid issues that are unlikely to ever be worked on. We use "thumbs-up" on these issues to promote them to Priority 6 or higher based on demand.

  
Progress::Backlog

The Refinement of the issue is completed and DoR for the Squad is fulfilled

  
Progress::Review

Merge Request is ready for review. By definition MR review needs to be done by a second developer.

  
Progress::Started

The issue has been picked up for work by a developer in the team and the developer started to work on actively on the issue.

  
Progress::Testing

In testing

  
Progress::Triage

This is the first step of the process. Issue has been recently opened. Product team will identify/evaluate the following: - Clarify questions if required / verify DoR for Product is met. - It’s been identified if Design work is required or not. - Technical Feasibility - Components involved. - Team to work on that issue. - Severity (in case the issue refers to a bug)

  
Progress::Waiting

A blocker has been identified and work cannot continue.

  
Reporter::Sentry

   
Safari

This label indicates that the issue affects Safari devices.

  
Target::Community

   
Target::Customer

   
Target::Internal

   
Target::PoC

Proof of Concept - issues necessary for potential customer acquisition. Can be scoped by deadline for planned presentations

  
Target::Security

   
Team:Customer-Success

   
Team:Design

Issues which are in the competence area of the frontend chapter. It is not a scoped label anymore as issues can be part of different chapters. Before we can start implementing/changing this, we need a UI/UX design first.

  
Team:Infrastructure

Issues which are in the competence area of the infrastructure chapter. It is not a scoped label anymore as issues can be part of different chapters.

  
Team:Instant-Messaging

   
Team:Product

Issues which are in the responsibility of the product team.

  
Team:Workflows

   
Type::Bug

A software bug is an error, flaw or fault in a computer program or system that causes it to produce an incorrect or unexpected result, or to behave in unintended ways.

  
Type::Design

A task about creating or improving the UI/UX design

  
Type::Documentation

   
Type::Feature

Nothing broken; Request for a new feature.

  
Type::Improvement

An improvement or enhancement to an existing feature, technically solution or code. It makes a working functionality faster, more secure, add some additional value, refactors code etc. or helps to design a better UX and UI.

  
Type::Support

   
Type::Tests

A task about writing or improving automated or manual tests.

  
Windows

This label indicates that the issue affects Windows devices.

  
blocked

   
blocked-by-spec

   
cla-signed

   
conduit

   
contribution::advanced

   
contribution::easy

Easy task to start with a contribution

  
contribution::help needed

   
from::review

Reported by famedly review

  
iOS

This label indicates that the issue affects iOS devices.

  
p::ti-tenant

This label indicates that the issues is a requirement of the ti-tenant

  
performance

   
product::triage

   
proposal

A feature propsal.

  
refactor

   
release-blocker

This issue needs to be fixed before the next release.

  
s: dart_openapi_codegen

   
s::Famedly-Patient

Service: Famedly-Patient App

  
s::Org-Directory

For issues that relate to the organisation directory https://gitlab.com/famedly/company/backend/services/organisation-directory/

  
s::Passport-Generator

Service: PASSporT-Generator

  
s::Requeuest

For anything relevant to the requeuest library

  
s:CRM

Service: CRM

  
s:Famedly-App

Service: Famedly-App

  
s:Famedly-Web

Service: Famedly-Web

  
s:Fhiroxide

Service: Fhiroxide

  
s:Fhiroxide-cli

Fhir(oxide) maintenance and debug CLI

  
s:Fhiroxide-client

Fhiroxide client library

  
s:Fhirs

Service: FHIRS

  
s:Hedwig

Service: Hedwig

  
s:LISA

Service: LISA

  
s:Matrix-Dart-SDK

Service: Matrix-Dart-SDK

  
s:Role-Manager

Service: Role-Manager

  
s:Synapse

Service: Synapse

  
s:User-Directory

Service: User-Directory

  
s:WFS-Matrix

Service: Workflow-Service-Matrix

  
s:Workflow Engine

Service: Workflow-Engine

  
s:dtls

   
s:famedly-error

   
s:fcm-shared-isolate

   
s:matrix-api-lite

   
s:multiple-tab-detector

   
s:native-imaging

   
severity::1

Blocker: Broken feature with no workaround or any data-loss. It makes the feature unusable!

  
severity::2

Critical: Broken feature with an unacceptably complex workaround.

  
severity::3

Major: Broken feature with a workaround.

  
severity::4

Low: Functionality is inconvenient.

  
technical-debt

This Issue is a technical debt. Mark issues with this label to have an overview of the technical debts in a repository or group.

  
voip
No labels
Android
CS::needs customer feedback
CS::needs follow up
CS::needs on prem installation
CS::waiting
Chrome
Design:: Ready
Design:: in progress
Design::UX
E2EE
Edge
Firefox
GDPR
Iteration 13 IM
Linux
MacOS
Need::Discussion
Need::Steps to reproduce
Need::Upstream fix
Needs:: Planning
Needs::Dev-Team
Needs::More information
Needs::Priority
Needs::Product
Needs::Refinement
Needs::Severity
Priority::1-Critical
Priority::2-Max
Priority::3-Impending
Priority::4-High
Priority::5-Medium
Priority::6-Low
Priority::7-None
Progress::Backlog
Progress::Review
Progress::Started
Progress::Testing
Progress::Triage
Progress::Waiting
Reporter::Sentry
Safari
Target::Community
Target::Customer
Target::Internal
Target::PoC
Target::Security
Team:Customer-Success
Team:Design
Team:Infrastructure
Team:Instant-Messaging
Team:Product
Team:Workflows
Type::Bug
Type::Design
Type::Documentation
Type::Feature
Type::Improvement
Type::Support
Type::Tests
Windows
blocked
blocked-by-spec
cla-signed
conduit
contribution::advanced
contribution::easy
contribution::help needed
from::review
iOS
p::ti-tenant
performance
product::triage
proposal
refactor
release-blocker
s: dart_openapi_codegen
s::Famedly-Patient
s::Org-Directory
s::Passport-Generator
s::Requeuest
s:CRM
s:Famedly-App
s:Famedly-Web
s:Fhiroxide
s:Fhiroxide-cli
s:Fhiroxide-client
s:Fhirs
s:Hedwig
s:LISA
s:Matrix-Dart-SDK
s:Role-Manager
s:Synapse
s:User-Directory
s:WFS-Matrix
s:Workflow Engine
s:dtls
s:famedly-error
s:fcm-shared-isolate
s:matrix-api-lite
s:multiple-tab-detector
s:native-imaging
severity::1
severity::2
severity::3
severity::4
technical-debt
voip
Milestone
Clear milestone
No items
No milestone
Projects
Clear projects
No items
No project
Assignees
Clear assignees
No assignees
1 participant
Notifications
Due date
The due date is invalid or out of range. Please use the format "yyyy-mm-dd".

No due date set.

Dependencies

No dependencies set.

Reference: Matthias/conduit#411
No description provided.
Delete branch "%!s()"

Deleting a branch is permanent. Although the deleted branch may continue to exist for a short time before it actually gets removed, it CANNOT be undone in most cases. Continue?