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

[WebFreak001]

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]

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]

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]

sounds like not

[richvdh]

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

[richvdh]

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).