search

matrix-org / synapse

Synapse: Matrix homeserver written in Python/Twisted.
https://matrix-org.github.io/synapse
Apache License 2.0
11.8k stars 2.13k forks source link

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

Closed scottwallacesh closed 2 years ago

scottwallacesh commented 2 years ago

Description

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

Steps to reproduce

Element Web says the server is offline and unavailable.

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

Version information

jonasled commented 2 years ago

I have exactly the same problem

anoadragon453 commented 2 years ago

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 commented 2 years ago
  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 commented 2 years ago

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 commented 2 years ago

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 commented 2 years ago

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://github.com/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
anoadragon453 commented 2 years ago

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

jonasled commented 2 years ago

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 commented 2 years ago

@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 commented 2 years ago

Postgres is version 13

surakin commented 2 years ago

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

scottwallacesh commented 2 years ago

@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 commented 2 years ago

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

HelderFSFerreira commented 2 years ago

No problems with 1.50.0 behind Traefik and Postgres 13

anoadragon453 commented 2 years ago

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 commented 2 years ago

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 commented 2 years ago

These are the requests I saw in the debugging tools image

anoadragon453 commented 2 years ago

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 commented 2 years ago

Removing the webclient listener works

scottwallacesh commented 2 years ago

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 commented 2 years ago

Note that https://github.com/matrix-org/synapse/issues/11763 has been created with more details on the issue and will serve to track the fix.

scottwallacesh commented 2 years ago

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 commented 2 years ago

A fix for this issue was implemented in https://github.com/matrix-org/synapse/pull/11764 and has been released in Synapse v1.50.1.

Thank you all for your swift help in debugging this :heart: