Skip to content
This repository has been archived by the owner on Apr 26, 2024. It is now read-only.

Error 403 "no create event in auth events" when trying to reject invitation after inviter leaves #7139

Closed
WebFreak001 opened this issue Mar 25, 2020 · 5 comments · Fixed by #7980
Closed

Error 403 "no create event in auth events" when trying to reject invitation after inviter leaves #7139

WebFreak001 opened this issue Mar 25, 2020 · 5 comments · Fixed by #7980
Labels
A-Stuck-Invite Incoming invitations that won't go away z-bug (Deprecated Label) z-p2 (Deprecated Label)

Comments

@WebFreak001
Copy link

WebFreak001 commented Mar 25, 2020 •
edited
Loading

Description

A user was invited to a DM by another user, who has promptly left the room without the invitee accepting the request. Now the invitee can't reject the room invite and has a stuck invite.

Steps to reproduce

(doesn't work reliably, but this is what reportedly was being done)

  • invite a user to DM
  • leave DM before user joined DM
  • (wait a little bit)
  • user can't accept nor reject DM

log when room was created:

2020-03-24 07:13:41,242 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783871 - 10.56.3.4 - 8008 - Received request: OPTIONS /_matrix/client/r0/createRoom
2020-03-24 07:13:41,243 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783871 - 10.56.3.4 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/createRoom HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,250 - synapse.access.http.8008 - 233 - INFO - POST-5783872 - 10.56.3.4 - 8008 - Received request: POST /_matrix/client/r0/createRoom
2020-03-24 07:13:41,263 - synapse.handlers.room - 768 - INFO - POST-5783872 - Sending m.room.create in new room
2020-03-24 07:13:41,291 - synapse.storage.persist_events - 384 - INFO - persist_events-3984 - Calculating state delta for room !RoOmIdHeRe:home.server
2020-03-24 07:13:41,309 - synapse.handlers.room - 782 - INFO - POST-5783872 - Sending m.room.member in new room
2020-03-24 07:13:41,311 - synapse.handlers.user_directory - 152 - INFO - user_directory.notify_new_event-50069 - Handling 1 state deltas
2020-03-24 07:13:41,323 - synapse.handlers.stats - 303 - INFO - stats.notify_new_event-50036 - Updating room_stats_state for !RoOmIdHeRe:home.server: {'is_federatable': True}
2020-03-24 07:13:41,377 - synapse.storage.persist_events - 384 - INFO - persist_events-3985 - Calculating state delta for room !RoOmIdHeRe:home.server
2020-03-24 07:13:41,394 - synapse.handlers.sync - 930 - INFO - GET-5783812 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17923', presence_key='4092238', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17925', presence_key=4092238, typing_key=14338, receipt_key=15653, account_data_key=40312, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:41,395 - synapse.handlers.sync - 930 - INFO - GET-5783815 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17923', presence_key='4092238', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17925', presence_key=4092238, typing_key=14338, receipt_key=15653, account_data_key=40312, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:41,396 - synapse.handlers.room - 768 - INFO - POST-5783872 - Sending m.room.power_levels in new room
2020-03-24 07:13:41,401 - synapse.handlers.user_directory - 152 - INFO - user_directory.notify_new_event-50070 - Handling 1 state deltas
2020-03-24 07:13:41,403 - synapse.handlers.stats - 303 - INFO - stats.notify_new_event-50037 - Updating room_stats_state for !RoOmIdHeRe:home.server: {}
2020-03-24 07:13:41,425 - synapse.handlers.sync - 1466 - INFO - GET-5783815 - Membership changes in !RoOmIdHeRe:home.server: [$some-internal-id (join)]
2020-03-24 07:13:41,426 - synapse.handlers.sync - 1466 - INFO - GET-5783812 - Membership changes in !RoOmIdHeRe:home.server: [$some-internal-id (join)]
2020-03-24 07:13:41,469 - synapse.storage.persist_events - 384 - INFO - persist_events-3986 - Calculating state delta for room !RoOmIdHeRe:home.server
2020-03-24 07:13:41,489 - synapse.handlers.room - 768 - INFO - POST-5783872 - Sending m.room.join_rules in new room
2020-03-24 07:13:41,494 - synapse.handlers.user_directory - 152 - INFO - user_directory.notify_new_event-50071 - Handling 1 state deltas
2020-03-24 07:13:41,495 - synapse.handlers.stats - 303 - INFO - stats.notify_new_event-50038 - Updating room_stats_state for !RoOmIdHeRe:home.server: {}
2020-03-24 07:13:41,497 - synapse.metrics - 462 - INFO -  - Collecting gc 1
2020-03-24 07:13:41,522 - synapse.access.http.8008 - 302 - INFO - GET-5783812 - 10.56.3.4 - 8008 - {@inviter:home.server} Processed request: 9.649sec/0.002sec (0.006sec, 0.000sec) (0.073sec/0.001sec/9) 647B 200 "GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17923_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,523 - synapse.access.http.8008 - 302 - INFO - GET-5783815 - 80.151.78.15 - 8008 - {@inviter:home.server} Processed request: 9.644sec/0.001sec (0.002sec, 0.000sec) (0.073sec/0.002sec/10) 648B 200 "GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17923_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.1" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.5.13 Chrome/78.0.3904.130 Electron/7.1.12 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,545 - synapse.access.http.8008 - 233 - INFO - POST-5783873 - 10.56.3.4 - 8008 - Received request: POST /_matrix/client/r0/keys/query
2020-03-24 07:13:41,601 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783874 - 10.56.3.4 - 8008 - Received request: OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17925_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,601 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783874 - 10.56.3.4 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17925_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,605 - synapse.access.http.8008 - 233 - INFO - GET-5783875 - 10.56.3.4 - 8008 - Received request: GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17925_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,607 - synapse.handlers.sync - 930 - INFO - GET-5783875 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17925', presence_key='4092238', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17926', presence_key=4092238, typing_key=14338, receipt_key=15653, account_data_key=40312, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:41,618 - synapse.access.http.8008 - 302 - INFO - GET-5783875 - 10.56.3.4 - 8008 - {@inviter:home.server} Processed request: 0.012sec/0.001sec (0.006sec, 0.000sec) (0.002sec/0.000sec/1) 563B 200 "GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17925_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,621 - synapse.access.http.8008 - 302 - INFO - POST-5783873 - 10.56.3.4 - 8008 - {@inviter:home.server} Processed request: 0.075sec/0.001sec (0.003sec, 0.000sec) (0.014sec/0.000sec/2) 1617B 200 "POST /_matrix/client/r0/keys/query HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,622 - synapse.storage.persist_events - 384 - INFO - persist_events-3987 - Calculating state delta for room !RoOmIdHeRe:home.server
2020-03-24 07:13:41,629 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783876 - 10.56.3.4 - 8008 - Received request: OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17926_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,629 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783876 - 10.56.3.4 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17926_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,632 - synapse.access.http.8008 - 233 - INFO - GET-5783877 - 10.56.3.4 - 8008 - Received request: GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17926_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,637 - synapse.handlers.sync - 930 - INFO - GET-5783877 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17926', presence_key='4092238', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17927', presence_key=4092238, typing_key=14338, receipt_key=15653, account_data_key=40312, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:41,639 - synapse.handlers.room - 768 - INFO - POST-5783872 - Sending m.room.history_visibility in new room
2020-03-24 07:13:41,643 - synapse.handlers.user_directory - 152 - INFO - user_directory.notify_new_event-50072 - Handling 1 state deltas
2020-03-24 07:13:41,644 - synapse.handlers.stats - 303 - INFO - stats.notify_new_event-50039 - Updating room_stats_state for !RoOmIdHeRe:home.server: {'join_rules': 'invite'}
2020-03-24 07:13:41,661 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783878 - 80.151.78.15 - 8008 - Received request: OPTIONS /_matrix/client/r0/keys/query
2020-03-24 07:13:41,662 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783878 - 80.151.78.15 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/keys/query HTTP/1.1" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.5.13 Chrome/78.0.3904.130 Electron/7.1.12 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,679 - synapse.access.http.8008 - 302 - INFO - GET-5783877 - 10.56.3.4 - 8008 - {@inviter:home.server} Processed request: 0.043sec/0.003sec (0.006sec, 0.000sec) (0.026sec/0.000sec/3) 462B 200 "GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17926_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,693 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783879 - 80.151.78.15 - 8008 - Received request: OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17925_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,694 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783879 - 80.151.78.15 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17925_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.1" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.5.13 Chrome/78.0.3904.130 Electron/7.1.12 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,694 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783880 - 10.56.3.4 - 8008 - Received request: OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17927_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,695 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783880 - 10.56.3.4 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17927_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,700 - synapse.access.http.8008 - 233 - INFO - GET-5783881 - 10.56.3.4 - 8008 - Received request: GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17927_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,702 - synapse.storage.persist_events - 384 - INFO - persist_events-3988 - Calculating state delta for room !RoOmIdHeRe:home.server
2020-03-24 07:13:41,719 - synapse.handlers.sync - 930 - INFO - GET-5783881 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17927', presence_key='4092238', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17928', presence_key=4092238, typing_key=14338, receipt_key=15653, account_data_key=40312, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:41,720 - synapse.handlers.room - 768 - INFO - POST-5783872 - Sending m.room.guest_access in new room
2020-03-24 07:13:41,724 - synapse.handlers.user_directory - 152 - INFO - user_directory.notify_new_event-50073 - Handling 1 state deltas
2020-03-24 07:13:41,726 - synapse.handlers.stats - 303 - INFO - stats.notify_new_event-50040 - Updating room_stats_state for !RoOmIdHeRe:home.server: {'history_visibility': 'shared'}
2020-03-24 07:13:41,727 - synapse.access.http.8008 - 233 - INFO - POST-5783882 - 80.151.78.15 - 8008 - Received request: POST /_matrix/client/r0/keys/query
2020-03-24 07:13:41,737 - synapse.access.http.8008 - 233 - INFO - GET-5783883 - 80.151.78.15 - 8008 - Received request: GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17925_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,739 - synapse.handlers.sync - 930 - INFO - GET-5783883 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17925', presence_key='4092238', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17928', presence_key=4092238, typing_key=14338, receipt_key=15653, account_data_key=40312, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:41,765 - synapse.access.http.8008 - 302 - INFO - GET-5783881 - 10.56.3.4 - 8008 - {@inviter:home.server} Processed request: 0.059sec/0.006sec (0.001sec, 0.000sec) (0.026sec/0.000sec/3) 472B 200 "GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17927_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,766 - synapse.access.http.8008 - 302 - INFO - POST-5783882 - 80.151.78.15 - 8008 - {@inviter:home.server} Processed request: 0.037sec/0.001sec (0.005sec, 0.000sec) (0.026sec/0.000sec/2) 1617B 200 "POST /_matrix/client/r0/keys/query HTTP/1.1" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.5.13 Chrome/78.0.3904.130 Electron/7.1.12 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,766 - synapse.access.http.8008 - 302 - INFO - GET-5783883 - 80.151.78.15 - 8008 - {@inviter:home.server} Processed request: 0.026sec/0.004sec (0.002sec, 0.000sec) (0.016sec/0.000sec/1) 700B 200 "GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17925_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.1" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.5.13 Chrome/78.0.3904.130 Electron/7.1.12 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,777 - synapse.handlers.presence - 255 - INFO - persist_presence_changes-76136 - Persisting 46 unpersisted presence updates
2020-03-24 07:13:41,778 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783884 - 10.56.3.4 - 8008 - Received request: OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17928_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,779 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783884 - 10.56.3.4 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17928_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,783 - synapse.access.http.8008 - 233 - INFO - GET-5783885 - 10.56.3.4 - 8008 - Received request: GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17928_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,798 - synapse.storage.persist_events - 384 - INFO - persist_events-3989 - Calculating state delta for room !RoOmIdHeRe:home.server
2020-03-24 07:13:41,810 - synapse.rest.media.v1.preview_url_resource - 404 - INFO - expire_url_cache_data-456827 - Running url preview cache expiry
2020-03-24 07:13:41,817 - synapse.metrics - 462 - INFO -  - Collecting gc 1
2020-03-24 07:13:41,827 - synapse.handlers.sync - 930 - INFO - GET-5783885 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17928', presence_key='4092238', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17929', presence_key=4092284, typing_key=14338, receipt_key=15653, account_data_key=40312, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:41,831 - synapse.rest.media.v1.preview_url_resource - 483 - INFO - expire_url_cache_data-456827 - Deleted 0 media from url cache
2020-03-24 07:13:41,833 - synapse.handlers.user_directory - 152 - INFO - user_directory.notify_new_event-50074 - Handling 1 state deltas
2020-03-24 07:13:41,834 - synapse.handlers.stats - 303 - INFO - stats.notify_new_event-50041 - Updating room_stats_state for !RoOmIdHeRe:home.server: {'guest_access': 'can_join'}
2020-03-24 07:13:41,836 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783886 - 80.151.78.15 - 8008 - Received request: OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17928_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,840 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783886 - 80.151.78.15 - 8008 - {None} Processed request: 0.000sec/0.003sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17928_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.1" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.5.13 Chrome/78.0.3904.130 Electron/7.1.12 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,867 - synapse.storage.TIME - 347 - INFO -  - Total database time: 5.091% {store_state_group(7): 1.434%, persist_events(6): 0.848%, update_user_directory_stream_pos(6): 0.568%}
2020-03-24 07:13:41,869 - synapse.access.http.8008 - 302 - INFO - GET-5783885 - 10.56.3.4 - 8008 - {@inviter:home.server} Processed request: 0.084sec/0.003sec (0.005sec, 0.000sec) (0.025sec/0.000sec/3) 953B 200 "GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17928_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,874 - synapse.access.http.8008 - 233 - INFO - GET-5783887 - 80.151.78.15 - 8008 - Received request: GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17928_4092238_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,876 - synapse.handlers.sync - 930 - INFO - GET-5783887 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17928', presence_key='4092238', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17929', presence_key=4092284, typing_key=14338, receipt_key=15653, account_data_key=40312, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:41,885 - synapse.access.http.8008 - 302 - INFO - GET-5783887 - 80.151.78.15 - 8008 - {@inviter:home.server} Processed request: 0.010sec/0.001sec (0.004sec, 0.000sec) (0.002sec/0.000sec/1) 953B 200 "GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17928_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.1" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.5.13 Chrome/78.0.3904.130 Electron/7.1.12 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,888 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783888 - 10.56.3.4 - 8008 - Received request: OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17929_4092284_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,889 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783888 - 10.56.3.4 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17929_4092284_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,893 - synapse.access.http.8008 - 233 - INFO - GET-5783889 - 10.56.3.4 - 8008 - Received request: GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17929_4092284_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,903 - synapse.storage.persist_events - 384 - INFO - persist_events-3990 - Calculating state delta for room !RoOmIdHeRe:home.server
2020-03-24 07:13:41,919 - synapse.handlers.sync - 930 - INFO - GET-5783889 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17929', presence_key='4092284', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17930', presence_key=4092284, typing_key=14338, receipt_key=15653, account_data_key=40312, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:41,920 - synapse.handlers.sync - 930 - INFO - GET-5783850 - Calculating sync response for @user:home.server between StreamToken(room_key='s17923', presence_key='4092238', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17930', presence_key=4092284, typing_key=14338, receipt_key=15653, account_data_key=40312, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:41,924 - synapse.access.http.8008 - 302 - INFO - POST-5783872 - 10.56.3.4 - 8008 - {@inviter:home.server} Processed request: 0.673sec/0.002sec (0.045sec, 0.004sec) (0.214sec/0.167sec/46) 79B 200 "POST /_matrix/client/r0/createRoom HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,927 - synapse.handlers.user_directory - 152 - INFO - user_directory.notify_new_event-50075 - Handling 1 state deltas
2020-03-24 07:13:41,928 - synapse.handlers.stats - 303 - INFO - stats.notify_new_event-50042 - Updating room_stats_state for !RoOmIdHeRe:home.server: {}
2020-03-24 07:13:41,933 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783890 - 10.56.3.4 - 8008 - Received request: OPTIONS /_matrix/client/r0/user/%40inviter%3Ahome.server/account_data/m.direct
2020-03-24 07:13:41,934 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783890 - 10.56.3.4 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/user/%40inviter%3Ahome.server/account_data/m.direct HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,937 - synapse.access.http.8008 - 233 - INFO - PUT-5783891 - 10.56.3.4 - 8008 - Received request: PUT /_matrix/client/r0/user/%40inviter%3Ahome.server/account_data/m.direct
2020-03-24 07:13:41,950 - synapse.handlers.sync - 1466 - INFO - GET-5783850 - Membership changes in !RoOmIdHeRe:home.server: [$some-other-internal-id (invite)]
2020-03-24 07:13:41,953 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783892 - 80.151.78.15 - 8008 - Received request: OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17929_4092284_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,954 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783892 - 80.151.78.15 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17929_4092284_14338_15653_40312_126_1384_1061_20 HTTP/1.1" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.5.13 Chrome/78.0.3904.130 Electron/7.1.12 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,977 - synapse.access.http.8008 - 302 - INFO - PUT-5783891 - 10.56.3.4 - 8008 - {@inviter:home.server} Processed request: 0.039sec/0.000sec (0.003sec, 0.000sec) (0.016sec/0.019sec/2) 22B 200 "PUT /_matrix/client/r0/user/%40inviter%3Ahome.server/account_data/m.direct HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,988 - synapse.access.http.8008 - 302 - INFO - GET-5783889 - 10.56.3.4 - 8008 - {@inviter:home.server} Processed request: 0.094sec/0.001sec (0.001sec, 0.000sec) (0.051sec/0.001sec/5) 584B 200 "GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17929_4092284_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,992 - synapse.access.http.8008 - 302 - INFO - GET-5783850 - 10.56.3.27 - 8008 - {@user:home.server} Processed request: 10.032sec/0.000sec (0.009sec, 0.000sec) (0.049sec/0.001sec/6) 1030B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s17923_4092238_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:41,992 - synapse.access.http.8008 - 233 - INFO - GET-5783893 - 80.151.78.15 - 8008 - Received request: GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17929_4092284_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:41,995 - synapse.handlers.sync - 930 - INFO - GET-5783893 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17929', presence_key='4092284', typing_key='14338', receipt_key='15653', account_data_key='40312', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17930', presence_key=4092284, typing_key=14338, receipt_key=15653, account_data_key=40313, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:13:42,003 - synapse.access.http.8008 - 302 - INFO - GET-5783893 - 80.151.78.15 - 8008 - {@inviter:home.server} Processed request: 0.010sec/0.000sec (0.007sec, 0.000sec) (0.001sec/0.001sec/4) 1204B 200 "GET /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17929_4092284_14338_15653_40312_126_1384_1061_20 HTTP/1.1" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.5.13 Chrome/78.0.3904.130 Electron/7.1.12 Safari/537.36" [0 dbevts]
2020-03-24 07:13:42,004 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783894 - 10.56.3.4 - 8008 - Received request: OPTIONS /_matrix/client/r0/user/%40inviter%3Ahome.server/account_data/im.vector.riot.breadcrumb_rooms
2020-03-24 07:13:42,004 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783894 - 10.56.3.4 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.003sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/user/%40inviter%3Ahome.server/account_data/im.vector.riot.breadcrumb_rooms HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:42,048 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783895 - 10.56.3.27 - 8008 - Received request: OPTIONS /_matrix/client/r0/sync?filter=0&timeout=30000&since=s17930_4092284_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:42,049 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783895 - 10.56.3.27 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/sync?filter=0&timeout=30000&since=s17930_4092284_14338_15653_40312_126_1384_1061_20 HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:13:42,053 - synapse.access.http.8008 - 233 - INFO - GET-5783896 - 10.56.3.27 - 8008 - Received request: GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s17930_4092284_14338_15653_40312_126_1384_1061_20
2020-03-24 07:13:42,067 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5783897 - 80.151.78.15 - 8008 - Received request: OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17930_4092284_14338_15653_40313_126_1384_1061_20
2020-03-24 07:13:42,067 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5783897 - 80.151.78.15 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/sync?filter=4&timeout=30000&since=s17930_4092284_14338_15653_40313_126_1384_1061_20 HTTP/1.1" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.5.13 Chrome/78.0.3904.130 Electron/7.1.12 Safari/537.36" [0 dbevts]

log when inviter left: (after some typing and a message)

2020-03-24 07:14:09,310 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5784116 - 10.56.3.4 - 8008 - Received request: OPTIONS /_matrix/client/r0/rooms/!RoOmIdHeRe%3Ahome.server/leave
2020-03-24 07:14:09,310 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5784116 - 10.56.3.4 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/rooms/!RoOmIdHeRe%3Ahome.server/leave HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:14:09,314 - synapse.access.http.8008 - 233 - INFO - POST-5784117 - 10.56.3.4 - 8008 - Received request: POST /_matrix/client/r0/rooms/!RoOmIdHeRe%3Ahome.server/leave
2020-03-24 07:14:09,335 - synapse.storage.persist_events - 384 - INFO - persist_events-3998 - Calculating state delta for room !RoOmIdHeRe:home.server
2020-03-24 07:14:09,354 - synapse.handlers.sync - 930 - INFO - GET-5784102 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17937', presence_key='4092284', typing_key='14342', receipt_key='15654', account_data_key='40318', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17938', presence_key=4092284, typing_key=14343, receipt_key=15655, account_data_key=40318, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:14:09,355 - synapse.handlers.sync - 930 - INFO - GET-5784105 - Calculating sync response for @inviter:home.server between StreamToken(room_key='s17937', presence_key='4092284', typing_key='14342', receipt_key='15654', account_data_key='40318', push_rules_key='126', to_device_key='1384', device_list_key='1061', groups_key='20') and StreamToken(room_key='s17938', presence_key=4092284, typing_key=14343, receipt_key=15655, account_data_key=40318, push_rules_key=126, to_device_key=1384, device_list_key=1061, groups_key=20)
2020-03-24 07:14:09,359 - synapse.access.http.8008 - 302 - INFO - POST-5784117 - 10.56.3.4 - 8008 - {@inviter:home.server} Processed request: 0.043sec/0.002sec (0.007sec, 0.000sec) (0.002sec/0.011sec/6) 22B 200 "POST /_matrix/client/r0/rooms/!RoOmIdHeRe%3Ahome.server/leave HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:14:09,361 - synapse.handlers.user_directory - 152 - INFO - user_directory.notify_new_event-50096 - Handling 1 state deltas
2020-03-24 07:14:09,363 - synapse.handlers.stats - 303 - INFO - stats.notify_new_event-50062 - Updating room_stats_state for !RoOmIdHeRe:home.server: {}
2020-03-24 07:14:09,366 - synapse.handlers.user_directory - 198 - INFO - user_directory.notify_new_event-50096 - Server left room: '!RoOmIdHeRe:home.server'
2020-03-24 07:14:09,374 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5784118 - 10.56.3.4 - 8008 - Received request: OPTIONS /_matrix/client/r0/user/%40inviter%3Ahome.server/account_data/im.vector.riot.breadcrumb_rooms
2020-03-24 07:14:09,374 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5784118 - 10.56.3.4 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/user/%40inviter%3Ahome.server/account_data/im.vector.riot.breadcrumb_rooms HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:14:09,377 - synapse.handlers.sync - 1466 - INFO - GET-5784102 - Membership changes in !RoOmIdHeRe:home.server: [$another-internal-id (leave)]
2020-03-24 07:14:09,378 - synapse.handlers.sync - 1466 - INFO - GET-5784105 - Membership changes in !RoOmIdHeRe:home.server: [$another-internal-id (leave)]
2020-03-24 07:14:09,394 - synapse.metrics - 462 - INFO -  - Collecting gc 1

log when trying to join room then:

2020-03-24 07:27:06,483 - synapse.access.http.8008 - 233 - INFO - OPTIONS-5789040 - 10.56.3.27 - 8008 - Received request: OPTIONS /_matrix/client/r0/join/!RoOmIdHeRe%3Ahome.server
2020-03-24 07:27:06,484 - synapse.access.http.8008 - 302 - INFO - OPTIONS-5789040 - 10.56.3.27 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/join/!RoOmIdHeRe%3Ahome.server HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-24 07:27:06,487 - synapse.access.http.8008 - 233 - INFO - POST-5789041 - 10.56.3.27 - 8008 - Received request: POST /_matrix/client/r0/join/!RoOmIdHeRe%3Ahome.server
2020-03-24 07:27:06,492 - synapse.http.server - 81 - INFO - POST-5789041 - <XForwardedForRequest at 0x7f03c75cef98 method='POST' uri='/_matrix/client/r0/join/!RoOmIdHeRe%3Ahome.server' clientproto='HTTP/1.0' site=8008> SynapseError: 404 - No known servers
2020-03-24 07:27:06,493 - synapse.access.http.8008 - 302 - INFO - POST-5789041 - 10.56.3.27 - 8008 - {@user:home.server} Processed request: 0.006sec/0.000sec (0.004sec, 0.000sec) (0.001sec/0.001sec/5) 66B 404 "POST /_matrix/client/r0/join/!RoOmIdHeRe%3Ahome.server HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]

(and another more recent try):

2020-03-25 07:43:28,066 - synapse.access.http.8008 - 302 - INFO - OPTIONS-86448 - 10.56.3.27 - 8008 - {None} Processed request: 0.001sec/0.001sec (0.002sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/join/!RoOmId%3Ahome.server HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]
2020-03-25 07:43:28,084 - synapse.http.server - 81 - INFO - POST-86449 - <XForwardedForRequest at 0x7f73764e6a58 method='POST' uri='/_matrix/client/r0/join/!RoOmId%3Ahome.server' clientproto='HTTP/1.0' site=8008> SynapseError: 404 - No known servers
2020-03-25 07:43:28,085 - synapse.access.http.8008 - 302 - INFO - POST-86449 - 10.56.3.27 - 8008 - {@user:home.server} Processed request: 0.013sec/0.000sec (0.003sec, 0.000sec) (0.001sec/0.008sec/7) 66B 404 "POST /_matrix/client/r0/join/!RoOmId%3Ahome.server HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Riot/1.3.2 Chrome/73.0.3683.121 Electron/5.0.7 Safari/537.36" [0 dbevts]

additionally rooms were cleared but this already happened after a failed join:

2020-03-24 12:27:45,178 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'delete_old_current_state_events'
2020-03-24 12:27:45,184 - synapse.storage.data_stores.main.state - 391 - INFO - background_updates-0 - Deleting current state left rooms: {..., '!RoOmId:home.server', ...}
2020-03-24 12:27:45,216 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'delete_old_current_state_events'. Processed 270 items in 37ms. (total_rate=2.7027027027027026/ms, current_rate=2.7027027027027026/ms, total_updated=100, batch_size=270)

log trying to reject room:

2020-03-25 09:22:54,232 - synapse.access.http.8008 - 302 - INFO - OPTIONS-133052 - 10.56.3.27 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 22B 200 "OPTIONS /_matrix/client/r0/rooms/!RoOmId%3Ahome.server/leave HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:74.0) Gecko/20100101 Firefox/74.0" [0 dbevts]
2020-03-25 09:22:54,259 - synapse.handlers.message - 810 - WARNING - POST-133053 - Denying new event <FrozenEventV3 event_id='$24EveNTiD', type='m.room.member', state_key='@user:home.server'> because 403: No create event in auth events
2020-03-25 09:22:54,260 - synapse.http.server - 81 - INFO - POST-133053 - <XForwardedForRequest at 0x7f735deeea20 method='POST' uri='/_matrix/client/r0/rooms/!RoOmId%3Ahome.server/leave' clientproto='HTTP/1.0' site=8008> SynapseError: 403 - No create event in auth events
2020-03-25 09:22:54,261 - synapse.access.http.8008 - 302 - INFO - POST-133053 - 10.56.3.27 - 8008 - {@user:home.server} Processed request: 0.026sec/0.000sec (0.007sec, 0.000sec) (0.001sec/0.020sec/3) 78B 403 "POST /_matrix/client/r0/rooms/!RoOmId%3Ahome.server/leave HTTP/1.0" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:74.0) Gecko/20100101 Firefox/74.0" [0 dbevts]

Doesn't seem to be a cache problem because it happens on new logins on entirely new devices and also stays when clearing cache in riot.

Version information

  • Homeserver: (disabled federation, all local LDAP accounts only)

  • Version: {"server_version": "1.12.0", "python_version": "3.6.7"}

  • Install method: pip (Python 3.6.7)

  • Platform:
    OS: Ubuntu 18.04.4 LTS x86_64
    Host: VMware Virtual Platform
    Kernel: 4.15.0-91-generic
@anoadragon453 anoadragon453 added z-bug (Deprecated Label) A-Stuck-Invite Incoming invitations that won't go away labels Apr 2, 2020
@anoadragon453
Copy link
Member

Thank you for your detailed report! I'm going to link and file this as a duplicate of #2181 though as this seems to be a symptom of that.

We could probably have a better error message here though.

@WebFreak001
Copy link
Author

but as I said in the issue the stuck invite didn't go away when clearing cache. It's also all on a local server without any remote servers it federates with which could cause this. Is it really the same as the tagged issue?

@richvdh
Copy link
Member

richvdh commented Apr 2, 2020

sounds like not

@richvdh richvdh reopened this Apr 2, 2020
@neilisfragile neilisfragile added the z-p2 (Deprecated Label) label Apr 9, 2020
@richvdh
Copy link
Member

richvdh commented Apr 9, 2020

I wonder if this is related to #6902; possibly the error that we see has changed but it is a similar underlying problem.

@richvdh richvdh mentioned this issue Jun 5, 2020
Closed
@richvdh richvdh changed the title Error 403 no create event in auth events when trying to deny DM invitation with no user Error 403 "no create event in auth events" when trying to reject invitation after inviter leaves Jul 14, 2020
@richvdh
Copy link
Member

richvdh commented Jul 14, 2020

ok, this is due to the cleanup done by delete_old_current_state_events. The cleanup done by delete_old_current_state_events removes the forward extremities, meaning that the local server cannot generate a "leave" event to reject the invite.

(Note: it's not the same as #2181, since this affects invites on the local server. Ironically, now that #2181 is addressed, federated invites are now handled better than local ones in this regard).

@richvdh richvdh mentioned this issue Jul 14, 2020
Open
@richvdh richvdh mentioned this issue Jul 29, 2020
Merged
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Assignees
No one assigned
Labels
A-Stuck-Invite Incoming invitations that won't go away z-bug (Deprecated Label) z-p2 (Deprecated Label)
Projects
None yet
Milestone
No milestone
Development

Successfully merging a pull request may close this issue.

Fix invite rejection when we have no forward-extremeties matrix-org/synapse
4 participants
@neilisfragile @anoadragon453 @richvdh @WebFreak001