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

Synapse v1.50.0 starts and federation tester reports as good but clients cannot connect #11762

Closed
scottwallacesh opened this issue Jan 18, 2022 · 23 comments
Labels
S-Critical Blocks development, potential data loss, more than 25% of users possibly affected, no workarounds. T-Defect Bugs, crashes, hangs, security vulnerabilities, or other reported issues.

Comments

@scottwallacesh
Copy link

scottwallacesh commented Jan 18, 2022

Description

Synapse v1.50 starts correctly and the federation tester shows everything as working but Android and Web clients cannot connect.

Steps to reproduce

  • Upgrade Synapse from v1.49.2 to v1.50.0
  • Try to connect with Android Element client and send a message -or- Try to connect with Element Web.

Element Web says the server is offline and unavailable.

Element Android connects but when posting a message show the 'Retry' button(s).

Version information

  • Homeserver: Synapse

  • Version: v1.50

  • Install method: Docker container (matrixdotorg/synapse:latest)

  • Platform: Linux - Docker CE

@scottwallacesh scottwallacesh changed the title Synapse v1.50 starts and federation tester reports as good but clients cannot connect Synapse v1.50.0 starts and federation tester reports as good but clients cannot connect Jan 18, 2022
@jonasled
Copy link

I have exactly the same problem

@anoadragon453
Copy link
Member

anoadragon453 commented Jan 18, 2022

Hi @scottwallacesh! Thank you for making an issue. A few questions to try and understand the problem:

  1. Do you see any stack traces (or any other clues) in Synapse's logfile? Try searching for the word Traceback.
    • Otherwise, feel free to attach your logfile (removing any personal information first) and we can take a look.
  2. Are you able to connect to your homeserver manually by navigating to http://your-homeserver.com/_matrix/client/versions?
  3. Finally, if you could send debug logs from Element Android (shake your phone) or Web (Settings -> Help & About -> Submit Debug Logs) and simply write your github name somewhere in the description box and/or include a link to this github issue, we'll be able to take a look at what might be happening from the client side.

Thank you!

@jonasled
Copy link

  1. see below
  2. No, I get only a 404 error
  3. Is send
synapse_1           | 2022-01-18 14:30:56,891 - root - 349 - WARNING - main - ***** STARTING SERVER *****
synapse_1           | 2022-01-18 14:30:56,891 - root - 350 - WARNING - main - Server /usr/local/lib/python3.8/site-packages/synapse/app/homeserver.py version 1.50.0
synapse_1           | 2022-01-18 14:30:56,891 - root - 351 - INFO - main - Server hostname: jonasled.de
synapse_1           | 2022-01-18 14:30:56,891 - root - 352 - INFO - main - Instance name: master
synapse_1           | 2022-01-18 14:30:56,891 - synapse.app.homeserver - 377 - INFO - main - Setting up server
synapse_1           | 2022-01-18 14:30:56,892 - synapse.server - 313 - INFO - main - Setting up.
synapse_1           | 2022-01-18 14:30:56,933 - synapse.storage.databases - 65 - INFO - main - [database config 'master']: Checking database server
synapse_1           | 2022-01-18 14:30:56,943 - synapse.storage.databases - 68 - INFO - main - [database config 'master']: Preparing for databases ['main', 'state']
synapse_1           | 2022-01-18 14:30:56,944 - synapse.storage.prepare_database - 116 - INFO - main - ['main', 'state']: Checking existing schema version
synapse_1           | 2022-01-18 14:30:56,952 - synapse.storage.prepare_database - 120 - INFO - main - ['main', 'state']: Existing schema is 67 (+0 deltas)
synapse_1           | 2022-01-18 14:30:56,952 - synapse.storage.databases.main - 355 - INFO - main - Checking database for consistency with configuration...
synapse_1           | 2022-01-18 14:30:56,956 - synapse.storage.prepare_database - 412 - INFO - main - Applying schema deltas for v67
synapse_1           | 2022-01-18 14:30:56,962 - synapse.storage.prepare_database - 544 - INFO - main - Schema now up to date
synapse_1           | 2022-01-18 14:30:56,964 - synapse.storage.databases - 83 - INFO - main - [database config 'master']: Starting 'main' database
synapse_1           | 2022-01-18 14:30:56,968 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for device_lists_stream(stream_id): 101267
synapse_1           | 2022-01-18 14:30:56,970 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for user_signature_stream(stream_id): 98943
synapse_1           | 2022-01-18 14:30:56,973 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for device_lists_outbound_pokes(stream_id): 101082
synapse_1           | 2022-01-18 14:30:56,976 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for push_rules(id): 143
synapse_1           | 2022-01-18 14:30:56,978 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for push_rules_enable(id): 168
synapse_1           | 2022-01-18 14:30:56,979 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for local_group_updates(stream_id): 118
synapse_1           | 2022-01-18 14:30:56,989 - synapse.config.appservice - 90 - INFO - main - Loaded application service: ApplicationService: {'token': '<redacted>', 'url': 'http://10.3.0.178:8434', 'hs_token': '<redacted>', 'sender': '@_discordpuppet_bot:jonasled.de', 'server_name': 'jonasled.de', 'namespaces': {'users': [Namespace(exclusive=True, group_id=None, regex=re.compile('@_discordpuppet_.*'))], 'aliases': [Namespace(exclusive=True, group_id=None, regex=re.compile('#_discordpuppet_.*'))], 'rooms': []}, 'id': 'discord-puppet', 'ip_range_whitelist': None, 'supports_ephemeral': True, 'protocols': set(), 'rate_limited': False}
synapse_1           | 2022-01-18 14:30:56,993 - synapse.config.appservice - 90 - INFO - main - Loaded application service: ApplicationService: {'token': '<redacted>', 'url': 'http://whatsapp-bridge:29318', 'hs_token': '<redacted>', 'sender': '@jzxj3ULGU5l1dRASeNTDH8GP4WYEoTQN:jonasled.de', 'server_name': 'jonasled.de', 'namespaces': {'users': [Namespace(exclusive=True, group_id=None, regex=re.compile('^@whatsapp_[0-9]+:jonasled.de$')), Namespace(exclusive=True, group_id=None, regex=re.compile('^@whatsappbot:jonasled.de$'))], 'aliases': [], 'rooms': []}, 'id': 'whatsapp', 'ip_range_whitelist': None, 'supports_ephemeral': False, 'protocols': set(), 'rate_limited': False}
synapse_1           | 2022-01-18 14:30:56,998 - synapse.config.appservice - 90 - INFO - main - Loaded application service: ApplicationService: {'token': '<redacted>', 'url': 'http://telegram-bridge:29317', 'hs_token': '<redacted>', 'sender': '@cr-BBr65G7K0e06m3g6WEMX4pZ37gFjeJKBi-sVUfJgD0qbI27fh7ansEO2mkxb9:jonasled.de', 'server_name': 'jonasled.de', 'namespaces': {'users': [Namespace(exclusive=True, group_id=None, regex=re.compile('@tg_.*:jonasled\\.de')), Namespace(exclusive=True, group_id=None, regex=re.compile('@telegrambot:jonasled\\.de'))], 'aliases': [Namespace(exclusive=True, group_id=None, regex=re.compile('\\#tg_.*:jonasled\\.de'))], 'rooms': []}, 'id': 'telegram', 'ip_range_whitelist': None, 'supports_ephemeral': False, 'protocols': set(), 'rate_limited': False}
synapse_1           | 2022-01-18 14:30:57,002 - synapse.config.appservice - 90 - INFO - main - Loaded application service: ApplicationService: {'token': '<redacted>', 'url': 'http://signal-bridge:29328', 'hs_token': '<redacted>', 'sender': '@wvRDUUUG72L3t4e3ueHb3Z7jcelPED4MiLDk662Dqjp8CSVvvqApBcHDCouxt9ka:jonasled.de', 'server_name': 'jonasled.de', 'namespaces': {'users': [Namespace(exclusive=True, group_id=None, regex=re.compile('@signal_.*:jonasled\\.de')), Namespace(exclusive=True, group_id=None, regex=re.compile('@signalbot:jonasled\\.de'))], 'aliases': [], 'rooms': []}, 'id': 'signal', 'ip_range_whitelist': None, 'supports_ephemeral': False, 'protocols': set(), 'rate_limited': False}
synapse_1           | 2022-01-18 14:30:57,007 - synapse.config.appservice - 90 - INFO - main - Loaded application service: ApplicationService: {'token': '<redacted>', 'url': 'http://10.3.0.178:29327', 'hs_token': '<redacted>', 'sender': '@Pb4ikNxT4R5lH8NrPoiGeQZhvmZ_W6pks1rDrwc_leWIdELzboNpZMQb9KwB31O7:jonasled.de', 'server_name': 'jonasled.de', 'namespaces': {'users': [Namespace(exclusive=True, group_id=None, regex=re.compile('@twitter_.*:jonasled\\.de')), Namespace(exclusive=True, group_id=None, regex=re.compile('@twitterbot:jonasled\\.de'))], 'aliases': [], 'rooms': []}, 'id': 'twitter', 'ip_range_whitelist': None, 'supports_ephemeral': False, 'protocols': set(), 'rate_limited': False}
synapse_1           | 2022-01-18 14:30:57,012 - synapse.config.appservice - 90 - INFO - main - Loaded application service: ApplicationService: {'token': '<redacted>', 'url': 'http://instagram-bridge:29330', 'hs_token': '<redacted>', 'sender': '@z9UlrRw7syDRdRPO9LZHHeu4RVBGomZSz-48m3UwrSk-DLJj9hXnWALoQqkO4oUn:jonasled.de', 'server_name': 'jonasled.de', 'namespaces': {'users': [Namespace(exclusive=True, group_id=None, regex=re.compile('@instagram_.*:jonasled\\.de')), Namespace(exclusive=True, group_id=None, regex=re.compile('@instagrambot:jonasled\\.de'))], 'aliases': [], 'rooms': []}, 'id': 'instagram', 'ip_range_whitelist': None, 'supports_ephemeral': False, 'protocols': set(), 'rate_limited': False}
synapse_1           | 2022-01-18 14:30:57,022 - synapse.storage.databases.main.event_push_actions - 608 - INFO - main - Searching for stream ordering 1 month ago
synapse_1           | 2022-01-18 14:30:57,040 - synapse.storage.databases.main.event_push_actions - 612 - INFO - main - Found stream ordering 1 month ago: it's 943472
synapse_1           | 2022-01-18 14:30:57,040 - synapse.storage.databases.main.event_push_actions - 615 - INFO - main - Searching for stream ordering 1 day ago
synapse_1           | 2022-01-18 14:30:57,051 - synapse.storage.databases.main.event_push_actions - 619 - INFO - main - Found stream ordering 1 day ago: it's 968070
synapse_1           | 2022-01-18 14:30:57,106 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for e2e_cross_signing_keys(stream_id): 3898
synapse_1           | 2022-01-18 14:30:57,110 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for pushers(id): 204
synapse_1           | 2022-01-18 14:30:57,111 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for deleted_pushers(stream_id): 197
synapse_1           | 2022-01-18 14:30:57,112 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for push_rules_stream(stream_id): 194
synapse_1           | 2022-01-18 14:30:57,293 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for access_tokens(id): 10283
synapse_1           | 2022-01-18 14:30:57,295 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for refresh_tokens(id): 1
synapse_1           | 2022-01-18 14:30:57,296 - synapse.storage.util.id_generators - 88 - INFO - main - Initialising stream generator for event_reports(id): 3
synapse_1           | 2022-01-18 14:30:57,303 - synapse.storage.databases - 100 - INFO - main - [database config 'master']: Starting 'state' database
synapse_1           | 2022-01-18 14:30:57,306 - synapse.storage.databases - 115 - INFO - main - [database config 'master']: prepared
synapse_1           | 2022-01-18 14:30:57,306 - synapse.server - 316 - INFO - main - Finished setting up.
synapse_1           | 2022-01-18 14:30:57,323 - synapse.push.pusher - 38 - INFO - main - email enable notifs: True
synapse_1           | 2022-01-18 14:30:57,323 - synapse.push.pusher - 47 - INFO - main - defined email pusher type
synapse_1           | 2022-01-18 14:30:57,341 - synapse.server - 824 - INFO - main - Connecting to redis (host='10.3.0.153' port=6379) for external cache
synapse_1           | 2022-01-18 14:30:57,342 - synapse.replication.tcp.redis - 273 - INFO - main - Connecting to redis server 10.3.0.153:6379
synapse_1           | 2022-01-18 14:30:57,343 - synapse.federation.federation_server - 1264 - INFO - main - Registering federation EDU handler for 'm.device_list_update'
synapse_1           | 2022-01-18 14:30:57,343 - synapse.federation.federation_server - 1284 - INFO - main - Registering federation query handler for 'profile'
synapse_1           | 2022-01-18 14:30:57,345 - synapse.federation.federation_server - 1264 - INFO - main - Registering federation EDU handler for 'm.presence'
synapse_1           | 2022-01-18 14:30:57,348 - synapse.federation.federation_server - 1264 - INFO - main - Registering federation EDU handler for 'm.typing'
synapse_1           | 2022-01-18 14:30:57,349 - synapse.federation.federation_server - 1284 - INFO - main - Registering federation query handler for 'directory'
synapse_1           | 2022-01-18 14:30:57,350 - twisted - 279 - INFO - main - Redirected stdout/stderr to logs
synapse_1           | 2022-01-18 14:30:57,350 - synapse.app.homeserver - 157 - INFO - sentinel - Running
synapse_1           | 2022-01-18 14:30:57,354 - synapse.app.homeserver - 29 - INFO - sentinel - Set file limit to: 1048576
synapse_1           | 2022-01-18 14:30:57,357 - synapse.handlers.deactivate_account - 205 - INFO - user_parter_loop-0 - Starting user parter
synapse_1           | 2022-01-18 14:30:57,362 - synapse.replication.tcp.redis - 273 - INFO - sentinel - Connecting to redis server 10.3.0.153:6379
synapse_1           | 2022-01-18 14:30:57,384 - synapse.push.mailer - 123 - INFO - sentinel - Created Mailer for app_name jonasled
synapse_1           | 2022-01-18 14:30:57,386 - synapse.push.mailer - 123 - INFO - sentinel - Created Mailer for app_name jonasled
synapse_1           | 2022-01-18 14:30:57,386 - synapse.push.mailer - 123 - INFO - sentinel - Created Mailer for app_name jonasled
synapse_1           | 2022-01-18 14:30:57,387 - synapse.federation.federation_server - 1264 - INFO - sentinel - Registering federation EDU handler for 'm.receipt'
synapse_1           | 2022-01-18 14:30:57,388 - synapse.federation.federation_server - 1264 - INFO - sentinel - Registering federation EDU handler for 'm.signing_key_update'
synapse_1           | 2022-01-18 14:30:57,388 - synapse.federation.federation_server - 1264 - INFO - sentinel - Registering federation EDU handler for 'org.matrix.signing_key_update'
synapse_1           | 2022-01-18 14:30:57,388 - synapse.federation.federation_server - 1284 - INFO - sentinel - Registering federation query handler for 'client_keys'
synapse_1           | 2022-01-18 14:30:57,389 - synapse.federation.federation_server - 1264 - INFO - sentinel - Registering federation EDU handler for 'm.direct_to_device'
synapse_1           | 2022-01-18 14:30:57,413 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.rest.health.HealthResource object at 0x7f21229f1d60> to path b'/health'
synapse_1           | 2022-01-18 14:30:57,413 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.http.server.RootRedirect object at 0x7f2122175310> to path b'/_matrix/client'
synapse_1           | 2022-01-18 14:30:57,413 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <twisted.web.resource.Resource object at 0x7f21221c0940> to path b'/.well-known'
synapse_1           | 2022-01-18 14:30:57,413 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.rest.admin.AdminRestResource object at 0x7f21221c0b20> to path b'/_synapse/admin'
synapse_1           | 2022-01-18 14:30:57,414 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0x7f21221503d0> to path b'/_synapse/client/pick_idp'
synapse_1           | 2022-01-18 14:30:57,414 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <twisted.web.resource.Resource object at 0x7f2122150280> to path b'/_synapse/client/pick_username'
synapse_1           | 2022-01-18 14:30:57,414 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0x7f21221506a0> to path b'/_synapse/client/new_user_consent'
synapse_1           | 2022-01-18 14:30:57,414 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0x7f21221506d0> to path b'/_synapse/client/sso_register'
synapse_1           | 2022-01-18 14:30:57,414 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.rest.synapse.client.password_reset.PasswordResetSubmitTokenResource object at 0x7f2122150940> to path b'/_synapse/client/password_reset/email/submit_token'
synapse_1           | 2022-01-18 14:30:57,415 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching FilePath('/usr/local/lib/python3.8/site-packages/synapse/static') to path b'/_matrix/static'
synapse_1           | 2022-01-18 14:30:57,415 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f2122150970> to path b'/_matrix/media/r0'
synapse_1           | 2022-01-18 14:30:57,415 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f2122150970> to path b'/_matrix/media/v3'
synapse_1           | 2022-01-18 14:30:57,415 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f2122150970> to path b'/_matrix/media/v1'
synapse_1           | 2022-01-18 14:30:57,415 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.federation.transport.server.TransportLayerServer object at 0x7f212215d250> to path b'/_matrix/federation'
synapse_1           | 2022-01-18 14:30:57,416 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.rest.key.v2.KeyApiV2Resource object at 0x7f212215d3d0> to path b'/_matrix/key/v2'
synapse_1           | 2022-01-18 14:30:57,416 - synapse.util.httpresourcetree - 47 - INFO - sentinel - Attaching <synapse.metrics._exposition.MetricsResource object at 0x7f21229df490> to path b'/_synapse/metrics'
synapse_1           | 2022-01-18 14:30:57,417 - twisted - 279 - INFO - sentinel - SynapseSite starting on 8008
synapse_1           | 2022-01-18 14:30:57,417 - synapse.app - 49 - WARNING - sentinel - Failed to listen on 0.0.0.0, continuing because listening on [::]
synapse_1           | 2022-01-18 14:30:57,418 - synapse.app.homeserver - 172 - INFO - sentinel - Synapse now listening on TCP port 8008
synapse_1           | 2022-01-18 14:30:57,532 - synapse.storage.background_updates - 264 - INFO - background_updates-0 - Starting background schema updates
synapse_1           | 2022-01-18 14:30:57,532 - synapse.handlers.deactivate_account - 215 - INFO - user_parter_loop-0 - User parter finished: stopping
synapse_1           | 2022-01-18 14:30:57,538 - synapse.replication.tcp.redis - 112 - INFO - sentinel - Connected to redis
synapse_1           | 2022-01-18 14:30:57,541 - synapse.replication.tcp.redis - 120 - INFO - subscribe-replication-0 - Sending redis SUBSCRIBE for jonasled.de
synapse_1           | 2022-01-18 14:30:57,543 - synapse.storage.background_updates - 272 - INFO - background_updates-0 - No more background updates to do. Unscheduling background update task.
synapse_1           | 2022-01-18 14:30:57,546 - synapse.replication.tcp.redis - 122 - INFO - subscribe-replication-0 - Successfully subscribed to redis stream, sending REPLICATE command
synapse_1           | 2022-01-18 14:30:57,550 - synapse.replication.tcp.redis - 127 - INFO - subscribe-replication-0 - REPLICATE successfully sent
synapse_1           | 2022-01-18 14:30:57,565 - synapse.push.mailer - 123 - INFO - start_pushers-0 - Created Mailer for app_name jonasled
synapse_1           | 2022-01-18 14:30:57,581 - synapse.push.pusherpool - 314 - INFO - start_pushers-0 - Started pushers
synapse_1           | 2022-01-18 14:30:57,585 - synapse.replication.tcp.resource - 191 - INFO - replication_notifier-0 - Streaming: caches -> 358431
synapse_1           | 2022-01-18 14:30:57,590 - synapse.replication.tcp.resource - 191 - INFO - replication_notifier-0 - Streaming: caches -> 358431
synapse_1           | 2022-01-18 14:30:58,212 - synapse.access.http.8008 - 424 - INFO - GET-0 - ::ffff:172.31.0.8 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/account/whoami?user_id=%40whatsappbot%3Ajonasled.de HTTP/1.1" "mautrix-whatsapp/0.2.3+dev.1ebfeedc mautrix-go/v0.10.10" [0 dbevts]
synapse_1           | 2022-01-18 14:31:00,348 - synapse.access.http.8008 - 424 - INFO - GET-1 - ::ffff:172.31.0.6 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/account/whoami?user_id=@signalbot:jonasled.de HTTP/1.1" "mautrix-signal/0.2.2+dev.a26576bd mautrix-python/0.14.5 aiohttp/3.8.1 Python/3.9.7" [0 dbevts]
synapse_1           | 2022-01-18 14:31:00,395 - synapse.access.http.8008 - 424 - INFO - GET-2 - ::ffff:172.31.0.7 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/account/whoami?user_id=@instagrambot:jonasled.de HTTP/1.1" "mautrix-instagram/0.1.2+dev.7feca1a7 mautrix-python/0.14.2 aiohttp/3.8.1 Python/3.9.7" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,153 - synapse.access.http.8008 - 424 - INFO - GET-4 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,154 - synapse.access.http.8008 - 424 - INFO - GET-5 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,220 - synapse.access.http.8008 - 424 - INFO - GET-6 - ::ffff:172.31.0.8 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/account/whoami?user_id=%40whatsappbot%3Ajonasled.de HTTP/1.1" "mautrix-whatsapp/0.2.3+dev.1ebfeedc mautrix-go/v0.10.10" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,261 - synapse.access.http.8008 - 424 - INFO - GET-7 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,262 - synapse.access.http.8008 - 424 - INFO - GET-8 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,368 - synapse.access.http.8008 - 424 - INFO - GET-9 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,369 - synapse.access.http.8008 - 424 - INFO - GET-10 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,475 - synapse.access.http.8008 - 424 - INFO - GET-11 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,477 - synapse.access.http.8008 - 424 - INFO - GET-12 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,582 - synapse.access.http.8008 - 424 - INFO - GET-13 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,583 - synapse.access.http.8008 - 424 - INFO - GET-14 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,689 - synapse.access.http.8008 - 424 - INFO - GET-15 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,690 - synapse.access.http.8008 - 424 - INFO - GET-16 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,796 - synapse.access.http.8008 - 424 - INFO - GET-17 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,797 - synapse.access.http.8008 - 424 - INFO - GET-18 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,905 - synapse.access.http.8008 - 424 - INFO - GET-19 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:08,907 - synapse.access.http.8008 - 424 - INFO - GET-20 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.003sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,015 - synapse.access.http.8008 - 424 - INFO - GET-21 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,017 - synapse.access.http.8008 - 424 - INFO - GET-22 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,126 - synapse.access.http.8008 - 424 - INFO - GET-23 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/account/whoami?user_id=@telegrambot:jonasled.de HTTP/1.1" "mautrix-telegram/0.11.1+dev.8f61be76 mautrix-python/0.14.3 aiohttp/3.8.1 Python/3.9.7" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,128 - synapse.access.http.8008 - 424 - INFO - GET-24 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,131 - synapse.access.http.8008 - 424 - INFO - GET-25 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,236 - synapse.access.http.8008 - 424 - INFO - GET-26 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,238 - synapse.access.http.8008 - 424 - INFO - GET-27 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,344 - synapse.access.http.8008 - 424 - INFO - GET-28 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,346 - synapse.access.http.8008 - 424 - INFO - GET-29 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,451 - synapse.access.http.8008 - 424 - INFO - GET-30 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,453 - synapse.access.http.8008 - 424 - INFO - GET-31 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,560 - synapse.access.http.8008 - 424 - INFO - GET-32 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,561 - synapse.access.http.8008 - 424 - INFO - GET-33 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,669 - synapse.access.http.8008 - 424 - INFO - GET-34 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,670 - synapse.access.http.8008 - 424 - INFO - GET-35 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,776 - synapse.access.http.8008 - 424 - INFO - GET-36 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,777 - synapse.access.http.8008 - 424 - INFO - GET-37 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,883 - synapse.access.http.8008 - 424 - INFO - GET-38 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,885 - synapse.access.http.8008 - 424 - INFO - GET-39 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,993 - synapse.access.http.8008 - 424 - INFO - GET-40 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:09,995 - synapse.access.http.8008 - 424 - INFO - GET-41 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,102 - synapse.access.http.8008 - 424 - INFO - GET-42 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,103 - synapse.access.http.8008 - 424 - INFO - GET-43 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,126 - synapse.access.http.8008 - 424 - INFO - GET-44 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/versions HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,209 - synapse.access.http.8008 - 424 - INFO - GET-45 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,211 - synapse.access.http.8008 - 424 - INFO - GET-46 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.003sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,317 - synapse.access.http.8008 - 424 - INFO - GET-47 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,319 - synapse.access.http.8008 - 424 - INFO - GET-48 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,366 - synapse.access.http.8008 - 424 - INFO - GET-49 - ::ffff:172.31.0.6 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/account/whoami?user_id=@signalbot:jonasled.de HTTP/1.1" "mautrix-signal/0.2.2+dev.a26576bd mautrix-python/0.14.5 aiohttp/3.8.1 Python/3.9.7" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,413 - synapse.access.http.8008 - 424 - INFO - GET-50 - ::ffff:172.31.0.7 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/account/whoami?user_id=@instagrambot:jonasled.de HTTP/1.1" "mautrix-instagram/0.1.2+dev.7feca1a7 mautrix-python/0.14.2 aiohttp/3.8.1 Python/3.9.7" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,424 - synapse.access.http.8008 - 424 - INFO - GET-51 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,426 - synapse.access.http.8008 - 424 - INFO - GET-52 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,534 - synapse.access.http.8008 - 424 - INFO - GET-53 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,536 - synapse.access.http.8008 - 424 - INFO - GET-54 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,641 - synapse.access.http.8008 - 424 - INFO - GET-55 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,643 - synapse.access.http.8008 - 424 - INFO - GET-56 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,747 - synapse.access.http.8008 - 424 - INFO - GET-57 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,749 - synapse.access.http.8008 - 424 - INFO - GET-58 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,854 - synapse.access.http.8008 - 424 - INFO - GET-59 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,856 - synapse.access.http.8008 - 424 - INFO - GET-60 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,962 - synapse.access.http.8008 - 424 - INFO - GET-61 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:10,964 - synapse.access.http.8008 - 424 - INFO - GET-62 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,071 - synapse.access.http.8008 - 424 - INFO - GET-63 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,073 - synapse.access.http.8008 - 424 - INFO - GET-64 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,179 - synapse.access.http.8008 - 424 - INFO - GET-65 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,181 - synapse.access.http.8008 - 424 - INFO - GET-66 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.002sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,286 - synapse.access.http.8008 - 424 - INFO - GET-67 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,288 - synapse.access.http.8008 - 424 - INFO - GET-68 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,394 - synapse.access.http.8008 - 424 - INFO - GET-69 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,396 - synapse.access.http.8008 - 424 - INFO - GET-70 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,502 - synapse.access.http.8008 - 424 - INFO - GET-71 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,504 - synapse.access.http.8008 - 424 - INFO - GET-72 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,610 - synapse.access.http.8008 - 424 - INFO - GET-73 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,612 - synapse.access.http.8008 - 424 - INFO - GET-74 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,718 - synapse.access.http.8008 - 424 - INFO - GET-75 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,719 - synapse.access.http.8008 - 424 - INFO - GET-76 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,825 - synapse.access.http.8008 - 424 - INFO - GET-77 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,827 - synapse.access.http.8008 - 424 - INFO - GET-78 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.003sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,933 - synapse.access.http.8008 - 424 - INFO - GET-79 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:11,935 - synapse.access.http.8008 - 424 - INFO - GET-80 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.003sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,040 - synapse.access.http.8008 - 424 - INFO - GET-81 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,042 - synapse.access.http.8008 - 424 - INFO - GET-82 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,148 - synapse.access.http.8008 - 424 - INFO - GET-83 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,150 - synapse.access.http.8008 - 424 - INFO - GET-84 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,255 - synapse.access.http.8008 - 424 - INFO - GET-85 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,257 - synapse.access.http.8008 - 424 - INFO - GET-86 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,348 - synapse.federation.sender - 747 - INFO - wake_destinations_needing_catchup-0 - Destination smith.gdgd.jp.net has outstanding catch-up, waking up.
synapse_1           | 2022-01-18 14:31:12,363 - synapse.access.http.8008 - 424 - INFO - GET-87 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,365 - synapse.access.http.8008 - 424 - INFO - GET-88 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,381 - synapse.federation.sender.per_destination_queue - 499 - INFO - federation_transaction_transmission_loop-0 - Catching up rooms to chat.avlikos.gr: '!JiBnIoyxTdNPsqbMwu:maunium.net'
synapse_1           | 2022-01-18 14:31:12,381 - synapse.federation.sender.transaction_manager - 119 - INFO - federation_transaction_transmission_loop-0 - TX [chat.avlikos.gr] {1642516257332} Sending transaction [1642516257332], (PDUs: 1, EDUs: 0)
synapse_1           | 2022-01-18 14:31:12,383 - synapse.http.federation.well_known_resolver - 253 - INFO - federation_transaction_transmission_loop-0 - Fetching https://chat.avlikos.gr/.well-known/matrix/server
synapse_1           | 2022-01-18 14:31:12,439 - synapse.http.federation.well_known_resolver - 190 - INFO - federation_transaction_transmission_loop-0 - Response from .well-known: {'m.server': 'chat.avlikos.gr:8448'}
synapse_1           | 2022-01-18 14:31:12,471 - synapse.access.http.8008 - 424 - INFO - GET-89 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,473 - synapse.access.http.8008 - 424 - INFO - GET-90 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,496 - synapse.http.matrixfederationclient - 607 - INFO - federation_transaction_transmission_loop-0 - {PUT-O-1} [chat.avlikos.gr] Got response headers: 403 Forbidden
synapse_1           | 2022-01-18 14:31:12,497 - synapse.http.matrixfederationclient - 685 - WARNING - federation_transaction_transmission_loop-0 - {PUT-O-1} [chat.avlikos.gr] Request failed: PUT matrix://chat.avlikos.gr/_matrix/federation/v1/send/1642516257332: HttpResponseException('403: Forbidden')
synapse_1           | 2022-01-18 14:31:12,497 - synapse.federation.sender.transaction_manager - 155 - INFO - federation_transaction_transmission_loop-0 - TX [chat.avlikos.gr] {1642516257332} got 403 response
synapse_1           | 2022-01-18 14:31:12,498 - synapse.federation.sender.per_destination_queue - 347 - WARNING - federation_transaction_transmission_loop-0 - TX [chat.avlikos.gr] Received 403 response to transaction: 403: Forbidden
synapse_1           | 2022-01-18 14:31:12,579 - synapse.access.http.8008 - 424 - INFO - GET-91 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,581 - synapse.access.http.8008 - 424 - INFO - GET-92 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,688 - synapse.access.http.8008 - 424 - INFO - GET-93 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,691 - synapse.access.http.8008 - 424 - INFO - GET-94 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,795 - synapse.access.http.8008 - 424 - INFO - GET-95 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,798 - synapse.access.http.8008 - 424 - INFO - GET-96 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,902 - synapse.access.http.8008 - 424 - INFO - GET-97 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:12,905 - synapse.access.http.8008 - 424 - INFO - GET-98 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,010 - synapse.access.http.8008 - 424 - INFO - GET-99 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,012 - synapse.access.http.8008 - 424 - INFO - GET-100 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,118 - synapse.access.http.8008 - 424 - INFO - GET-101 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,120 - synapse.access.http.8008 - 424 - INFO - GET-102 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,226 - synapse.access.http.8008 - 424 - INFO - GET-103 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,227 - synapse.access.http.8008 - 424 - INFO - GET-104 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,334 - synapse.access.http.8008 - 424 - INFO - GET-105 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,336 - synapse.access.http.8008 - 424 - INFO - GET-106 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,441 - synapse.access.http.8008 - 424 - INFO - GET-107 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,443 - synapse.access.http.8008 - 424 - INFO - GET-108 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.003sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,549 - synapse.access.http.8008 - 424 - INFO - GET-109 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,550 - synapse.access.http.8008 - 424 - INFO - GET-110 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,655 - synapse.access.http.8008 - 424 - INFO - GET-111 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,657 - synapse.access.http.8008 - 424 - INFO - GET-112 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,764 - synapse.access.http.8008 - 424 - INFO - GET-113 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,766 - synapse.access.http.8008 - 424 - INFO - GET-114 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.003sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,872 - synapse.access.http.8008 - 424 - INFO - GET-115 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,874 - synapse.access.http.8008 - 424 - INFO - GET-116 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,981 - synapse.access.http.8008 - 424 - INFO - GET-117 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:13,982 - synapse.access.http.8008 - 424 - INFO - GET-118 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,089 - synapse.access.http.8008 - 424 - INFO - GET-119 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,091 - synapse.access.http.8008 - 424 - INFO - GET-120 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,196 - synapse.access.http.8008 - 424 - INFO - GET-121 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,198 - synapse.access.http.8008 - 424 - INFO - GET-122 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,304 - synapse.access.http.8008 - 424 - INFO - GET-123 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,305 - synapse.access.http.8008 - 424 - INFO - GET-124 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,412 - synapse.access.http.8008 - 424 - INFO - GET-125 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,413 - synapse.access.http.8008 - 424 - INFO - GET-126 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,512 - synapse.access.http.8008 - 424 - INFO - GET-127 - 217.231.31.212 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/versions HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Element/1.9.8 Chrome/91.0.4472.164 Electron/13.6.7 Safari/537.36" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,520 - synapse.access.http.8008 - 424 - INFO - GET-128 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,522 - synapse.access.http.8008 - 424 - INFO - GET-129 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,628 - synapse.access.http.8008 - 424 - INFO - GET-130 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,630 - synapse.access.http.8008 - 424 - INFO - GET-131 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,737 - synapse.access.http.8008 - 424 - INFO - GET-132 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,739 - synapse.access.http.8008 - 424 - INFO - GET-133 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.003sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,844 - synapse.access.http.8008 - 424 - INFO - GET-134 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,846 - synapse.access.http.8008 - 424 - INFO - GET-135 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,951 - synapse.access.http.8008 - 424 - INFO - GET-136 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:14,952 - synapse.access.http.8008 - 424 - INFO - GET-137 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,058 - synapse.access.http.8008 - 424 - INFO - GET-138 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,059 - synapse.access.http.8008 - 424 - INFO - GET-139 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,165 - synapse.access.http.8008 - 424 - INFO - GET-140 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,167 - synapse.access.http.8008 - 424 - INFO - GET-141 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,272 - synapse.access.http.8008 - 424 - INFO - GET-142 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,273 - synapse.access.http.8008 - 424 - INFO - GET-143 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,380 - synapse.access.http.8008 - 424 - INFO - GET-144 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,382 - synapse.access.http.8008 - 424 - INFO - GET-145 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,488 - synapse.access.http.8008 - 424 - INFO - GET-146 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,490 - synapse.access.http.8008 - 424 - INFO - GET-147 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,596 - synapse.access.http.8008 - 424 - INFO - GET-148 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,598 - synapse.access.http.8008 - 424 - INFO - GET-149 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,711 - synapse.access.http.8008 - 424 - INFO - GET-150 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]
synapse_1           | 2022-01-18 14:31:15,713 - synapse.access.http.8008 - 424 - INFO - GET-151 - 10.3.0.1 - 8008 - {None} Processed request: 0.000sec/-0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 153B 404 "GET /_matrix/client/r0/sync?access_token=<redacted>&timeout=30000&filter=%7B%22room%22:%7B%22state%22:%7B%22lazy_load_members%22:true%7D%7D%7D HTTP/1.1" "Python/3.8 aiohttp/3.7.4.post0" [0 dbevts]

@anoadragon453
Copy link
Member

Interesting, thank you @jonasled. Testing locally using the 1.50.0 docker image with a default config, I seem to receiving 200s on those endpoints.

Could you paste the listeners: portion of your homeserver.yaml config file here please? Perhaps we have a notable difference.

@callahad
Copy link
Contributor

I'm also curious about how you're deploying. E.g., @jonasled are you also using Docker? Which image are you using: the one we publish as matrixdotorg/synapse on Docker Hub or some other one? How are you launching it (with Compose?) Do you have any other servers / reverse proxies in front of your Synapse?

@jonasled
Copy link

I've attached the listener section below:

listeners:
  # TLS-enabled listener: for when matrix traffic is sent directly to synapse.
  #
  # Disabled by default. To enable it, uncomment the following. (Note that you
  # will also need to give Synapse a TLS key and certificate: see the TLS section
  # below.)
  #
  #- port: 8448
  #  type: http
  #  tls: true
  #  resources:
  #    - names: [client, federation]

  # Unsecure HTTP listener: for when matrix traffic passes through a reverse proxy
  # that unwraps TLS.
  #
  # If you plan to use a reverse proxy, please see
  # https:/matrix-org/synapse/blob/master/docs/reverse_proxy.md.
  #
  - port: 8008
    tls: false
    type: http
    x_forwarded: true

    resources:
      - names: [client, federation, webclient, metrics]
        compress: true

@callahad callahad added P1 T-Defect Bugs, crashes, hangs, security vulnerabilities, or other reported issues. S-Critical Blocks development, potential data loss, more than 25% of users possibly affected, no workarounds. labels Jan 18, 2022
@anoadragon453
Copy link
Member

Thanks @jonasled. Note to others that copying those settings exactly on my local setup seems to not produce any issues.

@jonasled
Copy link

Interesting. I use a postgres server for the database, have added multiple bots and in front of the synapse is as reverse proxy a traefik 2. Downgrading the docker to version 1.49.0 resolved the issue.

@anoadragon453
Copy link
Member

anoadragon453 commented Jan 18, 2022

@jonasled I assume your Postgres server version is at least 10+? Note that Synapse v1.50.0 dropped support for older versions.

@scottwallacesh would you happen to also be using traefik as your reverse proxy?

@jonasled
Copy link

Postgres is version 13

@surakin
Copy link

surakin commented Jan 18, 2022

Same problem here (Debian bullseye container, postgres 14, running behind an apache proxy)

@scottwallacesh
Copy link
Author

@anoadragon453 I am using Traefik, yes.

I think this might be related to CORS headers but I'm finding it hard to work out why that's changed in this release.

Reverting to v1.49.2 gets everything working again.

@jonasled
Copy link

I've uploaded my complete config (without keys & passwords) here if it helps: https://paste.jonasled.de/riguvifeqi.yaml

@HelderFSFerreira
Copy link

HelderFSFerreira commented Jan 18, 2022

No problems with 1.50.0 behind Traefik and Postgres 13

@anoadragon453
Copy link
Member

Thanks @scottwallacesh. Are you seeing the 404's appear in your Synapse logs as well? If this were a CORS issue then the client would refuse to make the request, and thus it would never reach your server.

@psychopathio
Copy link

psychopathio commented Jan 18, 2022

I have a strange issue as well, I have 6 servers on 4 domains & 2 subdomains, all on the same host docker. All servers are identical in config but only one subdomain & one domain are working, the rest cant connect to. From the only domain that is working, I can access the others via federation once logged in on one of the working servers.

found out the 2 working server had no webclient under listener. removing it fixed the issue.

@surakin
Copy link

surakin commented Jan 18, 2022

These are the requests I saw in the debugging tools
image

@anoadragon453
Copy link
Member

We've managed to narrow this down to having the webclient resource listener enabled. Could affected parties attempt to run 1.50.0 without a webclient resource listed in their listeners: config block and see if that helps?

@jonasled
Copy link

Removing the webclient listener works

@scottwallacesh
Copy link
Author

Thanks @scottwallacesh. Are you seeing the 404's appear in your Synapse logs as well? If this were a CORS issue then the client would refuse to make the request, and thus it would never reach your server.

No 404s in my Synapse logs, no. The reason I suspect CORS header is because I see lots of CORS issues in the brower console and when making a HTTP request to Synapse I see CORS headers in v1.49.2 and none in v1.50.

v1.49.2:

$ curl -vsq https://matrix.wallace.sh/_matrix/client/versions |& grep -i access-control
< access-control-allow-headers: X-Requested-With, Content-Type, Authorization, Date
< access-control-allow-methods: GET, HEAD, POST, PUT, DELETE, OPTIONS
< access-control-allow-origin: *
$

v1.50.0:

$ curl -vsq https://matrix.wallace.sh/_matrix/client/versions |& grep -i access-control
$

@anoadragon453
Copy link
Member

Note that #11763 has been created with more details on the issue and will serve to track the fix.

@scottwallacesh
Copy link
Author

We've managed to narrow this down to having the webclient resource listener enabled. Could affected parties attempt to run 1.50.0 without a webclient resource listed in their listeners: config block and see if that helps?

Yep ... this fixed it for me.

@anoadragon453
Copy link
Member

A fix for this issue was implemented in #11764 and has been released in Synapse v1.50.1.

Thank you all for your swift help in debugging this ❤️

@kittykat kittykat added the z-p1 label Sep 6, 2022
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
S-Critical Blocks development, potential data loss, more than 25% of users possibly affected, no workarounds. T-Defect Bugs, crashes, hangs, security vulnerabilities, or other reported issues.
Projects
None yet
Development

No branches or pull requests

8 participants