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

Unconfigurable harsh ratelimiting for leaving rooms #16006

Open
gabrc52 opened this issue Jul 26, 2023 · 9 comments
Open

Unconfigurable harsh ratelimiting for leaving rooms #16006

gabrc52 opened this issue Jul 26, 2023 · 9 comments
Assignees
Labels
A-Config Configuration, or the documentation thereof A-Leave Leaving a room O-Occasional Affects or can be seen by some users regularly or most users rarely S-Minor Blocks non-critical functionality, workarounds exist. T-Enhancement New features, changes in functionality, improvements in performance, or user-facing enhancements.

Comments

@gabrc52
Copy link
Contributor

gabrc52 commented Jul 26, 2023

Description

Clients (at least Element) have an option to leave a space and leave all rooms in it.

image

If you choose to leave all rooms, the client will make leave requests for every room you are on.

If you are on many rooms, this will take a long time, to the point it seems like it hangs. Looking at the network tab in the developer tools shows a lot of 429 Too Many Requests responses.

In conclusion, the defualt ratelimiting for leaving rooms is too harsh. It doesn't seem like it can be overriden either:

https://matrix-org.github.io/synapse/latest/usage/configuration/config_documentation.html has mention of rc_joins but there is no rc_leaves

Steps to reproduce

  • join a space on Element on a homeserver using the default rate limits
  • join many rooms
  • leave the space, choosing to leave all rooms

Homeserver

matrix.mit.edu

Synapse Version

{"server_version":"1.88.0","python_version":"3.10.6"}

Installation Method

Debian packages from packages.matrix.org

Database

PostgreSQL

Workers

Single process

Platform

Ubuntu 22.04.2, PowerEdge R710

Configuration

No response

Relevant log output

N/A

Anything else that would be useful to know?

No response

@MatMaul MatMaul added A-Config Configuration, or the documentation thereof S-Minor Blocks non-critical functionality, workarounds exist. T-Enhancement New features, changes in functionality, improvements in performance, or user-facing enhancements. O-Occasional Affects or can be seen by some users regularly or most users rarely A-Leave Leaving a room labels Jul 27, 2023
@DMRobertson
Copy link
Contributor

Initial reactions:

  • do we need a bulk "leave these rooms" endpoint?
  • we should confirm which rate limiter gets hit and why. (Probably the generic one for creating events)
  • can we get Synapse's logs to at least show you which rate limiter you hit?

@gabrc52
Copy link
Contributor Author

gabrc52 commented Jul 27, 2023

image

Here are some logs

2023-07-27 17:03:16,519 - synapse.storage.databases.main.event_push_actions - 1163 - INFO - event_push_action_stream_orderings-90 - Found stream ordering 1 day ago: it's 42908
2023-07-27 17:03:16,520 - synapse.storage.databases.main.event_push_actions - 1306 - INFO - rotate_notifs-1819 - Rotating notifications
2023-07-27 17:03:16,521 - synapse.storage.databases.main.event_push_actions - 1510 - INFO - rotate_notifs-1819 - Rotating notifications up to: 43135
2023-07-27 17:03:16,521 - synapse.storage.databases.main.event_push_actions - 1596 - INFO - rotate_notifs-1819 - Rotating notifications, handling 0 rows
2023-07-27 17:03:16,523 - synapse.storage.databases.main.event_push_actions - 1684 - INFO - rotate_notifs-1819 - Rotating notifications, deleted 0 push actions
2023-07-27 17:03:16,574 - synapse.handlers.presence - 795 - INFO - persist_presence_changes-908 - Persisting 1 unpersisted presence updates
2023-07-27 17:03:16,582 - synapse.util.caches.lrucache - 210 - INFO - LruCache._expire_old_entries-1819 - Dropped 0 items from caches
2023-07-27 17:03:17,080 - synapse.storage.databases.main.metrics - 392 - INFO - generate_user_daily_visits-181 - Calling _generate_user_daily_visits
2023-07-27 17:03:17,546 - synapse.access.http.8008 - 461 - INFO - GET-7676 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 30.003sec/0.001sec (0.002sec, 0.000sec) (0.000sec/0.000sec/1) 239B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43135_6634_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:17,622 - synapse.access.http.8008 - 461 - INFO - GET-7684 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.004sec/0.000sec (0.001sec, 0.000sec) (0.001sec/0.000sec/2) 397B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43135_6634_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:41,442 - synapse.access.http.8008 - 461 - INFO - POST-7686 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.037sec/0.001sec (0.007sec, 0.000sec) (0.002sec/0.006sec/7) 2B 200 "POST /_matrix/client/r0/rooms/!wLHBBFeOjIuBwwdFRD%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:41,452 - synapse.handlers.sync - 1385 - INFO - GET-7685 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !wLHBBFeOjIuBwwdFRD:uplink.mit.edu
2023-07-27 17:03:41,455 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-61 - Unexpectedly did not have cached prev group for $O0LpVA4i7q49sorhewjj8Cjd_rRy_NkecZKD-a1zocA
2023-07-27 17:03:41,483 - synapse.http.client - 414 - INFO - as-sender-zephyr-20 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111354?access_token=<redacted>: 200
2023-07-27 17:03:41,508 - synapse.access.http.8008 - 461 - INFO - GET-7685 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 23.866sec/0.001sec (0.007sec, 0.005sec) (0.039sec/0.015sec/15) 1366B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43135_6636_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:41,560 - synapse.access.http.8008 - 461 - INFO - GET-7688 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.005sec/0.001sec (0.000sec, 0.000sec) (0.002sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:41,586 - synapse.access.http.8008 - 461 - INFO - POST-7687 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.042sec/0.001sec (0.013sec, 0.000sec) (0.002sec/0.013sec/7) 2B 200 "POST /_matrix/client/r0/rooms/!ysYdfsnmPJqlexJrnO%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:41,594 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-62 - Unexpectedly did not have cached prev group for $Rey1PJy2BvtkBHMN1ck3Zd95vAweCib349AhjKCjIbY
2023-07-27 17:03:41,767 - synapse.handlers.sync - 1385 - INFO - GET-7689 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !ysYdfsnmPJqlexJrnO:uplink.mit.edu
2023-07-27 17:03:41,793 - synapse.access.http.8008 - 461 - INFO - GET-7689 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.029sec/0.001sec (0.002sec, 0.006sec) (0.003sec/0.013sec/14) 1307B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43136_6636_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:41,825 - synapse.access.http.8008 - 461 - INFO - GET-7691 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.004sec/0.001sec (0.001sec, 0.000sec) (0.001sec/0.002sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:41,845 - synapse.access.http.8008 - 461 - INFO - GET-7692 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.009sec/0.000sec (0.001sec, 0.000sec) (0.004sec/0.004sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:41,861 - synapse.access.http.8008 - 461 - INFO - POST-7690 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.041sec/0.001sec (0.007sec, 0.001sec) (0.003sec/0.012sec/7) 2B 200 "POST /_matrix/client/r0/rooms/!YqTqMnWOwEAbpIYvYc%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:41,869 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-63 - Unexpectedly did not have cached prev group for $84I_4ZwPPDMgp3ZqsMqtWwurlZB9ZKRD9qbhnI2zkqM
2023-07-27 17:03:41,950 - synapse.handlers.sync - 1385 - INFO - GET-7693 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !YqTqMnWOwEAbpIYvYc:uplink.mit.edu
2023-07-27 17:03:41,989 - synapse.access.http.8008 - 461 - INFO - POST-7694 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.042sec/0.001sec (0.009sec, 0.001sec) (0.002sec/0.007sec/4) 2B 200 "POST /_matrix/client/r0/rooms/!pOMWKhxfyDMlHZWkOW%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,002 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-64 - Unexpectedly did not have cached prev group for $ZDfaez6SksS9GO4lUvbQrpC6u4oi1zT1P3yWf3hGQ1Y
2023-07-27 17:03:42,020 - synapse.access.http.8008 - 461 - INFO - GET-7693 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.075sec/0.001sec (0.005sec, 0.001sec) (0.034sec/0.028sec/14) 1307B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43137_6636_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,053 - synapse.access.http.8008 - 461 - INFO - POST-7695 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.028sec/0.001sec (0.006sec, 0.000sec) (0.001sec/0.005sec/4) 2B 200 "POST /_matrix/client/r0/rooms/!SNnuLSpkMGAbzVplcz%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,062 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-65 - Unexpectedly did not have cached prev group for $sAO7kQHPp8rBa5z3iJmGUcEy2Hht3ax3pPGIjGGz90w
2023-07-27 17:03:42,087 - synapse.access.http.8008 - 461 - INFO - GET-7696 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.003sec/0.000sec (0.001sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,089 - synapse.access.http.8008 - 461 - INFO - GET-7697 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.003sec/0.000sec (0.001sec, 0.000sec) (0.001sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,198 - synapse.handlers.sync - 1385 - INFO - GET-7698 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !SNnuLSpkMGAbzVplcz:uplink.mit.edu
2023-07-27 17:03:42,278 - synapse.access.http.8008 - 461 - INFO - POST-7699 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.084sec/0.001sec (0.013sec, 0.000sec) (0.004sec/0.016sec/7) 2B 200 "POST /_matrix/client/r0/rooms/!JbTGgUckBJlegSilQv%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,300 - synapse.access.http.8008 - 461 - INFO - GET-7700 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.046sec/0.001sec (0.002sec, 0.001sec) (0.043sec/0.002sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,303 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-66 - Unexpectedly did not have cached prev group for $QmLZoELSfTgaVcpyi1eqEQwo026W19oVaqL-1bmLewY
2023-07-27 17:03:42,327 - synapse.access.http.8008 - 461 - INFO - GET-7698 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.135sec/0.001sec (0.023sec, 0.004sec) (0.144sec/0.047sec/24) 2531B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43138_6636_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,369 - synapse.access.http.8008 - 461 - INFO - GET-7702 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.005sec/0.002sec (0.001sec, 0.000sec) (0.001sec/0.003sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,395 - synapse.access.http.8008 - 461 - INFO - POST-7701 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.042sec/0.001sec (0.012sec, 0.000sec) (0.003sec/0.015sec/7) 2B 200 "POST /_matrix/client/r0/rooms/!mifWyKmVobKXvvtXLL%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,404 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-67 - Unexpectedly did not have cached prev group for $CyG6OWbEsAr_Rr6SA_fZy7rSJ44LCOmyCLfK6qc4D-4
2023-07-27 17:03:42,532 - synapse.handlers.sync - 1385 - INFO - GET-7703 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !mifWyKmVobKXvvtXLL:uplink.mit.edu
2023-07-27 17:03:42,607 - synapse.access.http.8008 - 461 - INFO - GET-7705 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.017sec/0.001sec (0.002sec, 0.000sec) (0.014sec/0.002sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,633 - synapse.access.http.8008 - 461 - INFO - POST-7704 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.096sec/0.001sec (0.017sec, 0.002sec) (0.027sec/0.022sec/7) 2B 200 "POST /_matrix/client/r0/rooms/!mZRFfmKhUnblNhTYVo%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,636 - synapse.access.http.8008 - 461 - INFO - GET-7703 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.108sec/0.001sec (0.026sec, 0.003sec) (0.089sec/0.059sec/24) 2420B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43140_6636_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,644 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-68 - Unexpectedly did not have cached prev group for $M2WPq1zllrmbMJBa78pPFSbrR_A6852gxubGUs7mMws
2023-07-27 17:03:42,671 - synapse.access.http.8008 - 461 - INFO - GET-7707 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.007sec/0.000sec (0.001sec, 0.000sec) (0.001sec/0.004sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,694 - synapse.access.http.8008 - 461 - INFO - POST-7706 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.037sec/0.001sec (0.001sec, 0.004sec) (0.007sec/0.007sec/4) 2B 200 "POST /_matrix/client/r0/rooms/!ZEygCIdieQergqSEfI%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,702 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-69 - Unexpectedly did not have cached prev group for $DclYmQhyGBZrcQkVRZY1XiZE0sQrVgYjiyksrngJVIc
2023-07-27 17:03:42,766 - synapse.handlers.sync - 1385 - INFO - GET-7708 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !ZEygCIdieQergqSEfI:uplink.mit.edu
2023-07-27 17:03:42,805 - synapse.access.http.8008 - 461 - INFO - POST-7709 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.040sec/0.002sec (0.013sec, 0.000sec) (0.002sec/0.009sec/4) 2B 200 "POST /_matrix/client/r0/rooms/!AEAHdPLDByVqKzoaQh%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,816 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-70 - Unexpectedly did not have cached prev group for $To-CyPoxPx1gKw-HunWKNHdkJ3eWcgUPdiSuAfy7kNU
2023-07-27 17:03:42,879 - synapse.http.client - 414 - INFO - as-sender-zephyr-21 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111373?access_token=<redacted>: 200
2023-07-27 17:03:42,894 - synapse.http.server - 117 - INFO - POST-7710 - <XForwardedForRequest at 0x7f70afd68e50 method='POST' uri='/_matrix/client/r0/rooms/!MqPAlVTFnrJecEOsJe%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:03:42,895 - synapse.access.http.8008 - 461 - INFO - POST-7710 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.052sec/0.001sec (0.006sec, 0.000sec) (0.035sec/0.006sec/5) 80B 429 "POST /_matrix/client/r0/rooms/!MqPAlVTFnrJecEOsJe%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,898 - synapse.access.http.8008 - 461 - INFO - GET-7708 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.137sec/0.000sec (0.038sec, 0.001sec) (0.132sec/0.059sec/24) 2532B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43142_6636_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,991 - synapse.access.http.8008 - 461 - INFO - GET-7711 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.003sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:42,992 - synapse.access.http.8008 - 461 - INFO - GET-7712 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.003sec/0.000sec (0.001sec, 0.000sec) (0.001sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:43,057 - synapse.handlers.sync - 1385 - INFO - GET-7713 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !AEAHdPLDByVqKzoaQh:uplink.mit.edu
2023-07-27 17:03:43,088 - synapse.access.http.8008 - 461 - INFO - GET-7713 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.035sec/0.001sec (0.009sec, 0.000sec) (0.003sec/0.019sec/14) 1367B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43144_6636_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:43,196 - synapse.access.http.8008 - 461 - INFO - GET-7714 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:46,519 - synapse.storage.databases.main.event_push_actions - 1306 - INFO - rotate_notifs-1820 - Rotating notifications
2023-07-27 17:03:46,520 - synapse.storage.databases.main.event_push_actions - 1510 - INFO - rotate_notifs-1820 - Rotating notifications up to: 43145
2023-07-27 17:03:46,520 - synapse.storage.databases.main.event_push_actions - 1596 - INFO - rotate_notifs-1820 - Rotating notifications, handling 0 rows
2023-07-27 17:03:46,524 - synapse.storage.databases.main.event_push_actions - 1684 - INFO - rotate_notifs-1820 - Rotating notifications, deleted 0 push actions
2023-07-27 17:03:46,536 - synapse.access.http.8008 - 461 - INFO - POST-7716 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.032sec/0.001sec (0.004sec, 0.003sec) (0.001sec/0.004sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!MqPAlVTFnrJecEOsJe%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:46,545 - synapse.handlers.sync - 1385 - INFO - GET-7715 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !MqPAlVTFnrJecEOsJe:uplink.mit.edu
2023-07-27 17:03:46,546 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-71 - Unexpectedly did not have cached prev group for $4ChUKEL6Ja2Sn90FF_fGTUWxuiMWOjPc8OYY5anmIG0
2023-07-27 17:03:46,582 - synapse.util.caches.lrucache - 210 - INFO - LruCache._expire_old_entries-1820 - Dropped 0 items from caches
2023-07-27 17:03:46,587 - synapse.access.http.8008 - 461 - INFO - GET-7715 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 3.346sec/0.001sec (0.005sec, 0.001sec) (0.028sec/0.013sec/15) 1402B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43145_6636_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:46,723 - synapse.access.http.8008 - 461 - INFO - GET-7718 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.012sec/0.000sec (0.000sec, 0.000sec) (0.005sec/0.005sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:46,724 - synapse.http.server - 117 - INFO - POST-7717 - <XForwardedForRequest at 0x7f70bdfa24a0 method='POST' uri='/_matrix/client/r0/rooms/!SycimLfAVpUKHOPnJT%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:03:46,725 - synapse.access.http.8008 - 461 - INFO - POST-7717 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.019sec/0.000sec (0.004sec, 0.002sec) (0.001sec/0.008sec/5) 80B 429 "POST /_matrix/client/r0/rooms/!SycimLfAVpUKHOPnJT%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:46,993 - synapse.access.http.8008 - 461 - INFO - GET-7720 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:51,596 - synapse.access.http.8008 - 461 - INFO - POST-7721 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.031sec/0.001sec (0.007sec, 0.001sec) (0.001sec/0.006sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!SycimLfAVpUKHOPnJT%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:51,606 - synapse.handlers.sync - 1385 - INFO - GET-7719 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !SycimLfAVpUKHOPnJT:uplink.mit.edu
2023-07-27 17:03:51,608 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-72 - Unexpectedly did not have cached prev group for $0v3olF4ucvCq3QeI9mf0kJSTK-zLk_BObn-L_Lr5br0
2023-07-27 17:03:51,655 - synapse.access.http.8008 - 461 - INFO - GET-7719 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.846sec/0.001sec (0.010sec, 0.005sec) (0.031sec/0.018sec/15) 1370B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43146_6636_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:51,856 - synapse.access.http.8008 - 461 - INFO - PUT-7723 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.006sec/0.000sec (0.001sec, 0.002sec) (0.002sec/0.001sec/1) 2B 200 "PUT /_matrix/client/r0/user/%40rgabriel%3Auplink.mit.edu/account_data/im.vector.setting.breadcrumbs HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:51,884 - synapse.http.server - 117 - INFO - POST-7722 - <XForwardedForRequest at 0x7f70afd8a2f0 method='POST' uri='/_matrix/client/r0/rooms/!JsuUXXzLEIsoECNBMe%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:03:51,885 - synapse.access.http.8008 - 461 - INFO - POST-7722 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.036sec/0.001sec (0.011sec, 0.001sec) (0.009sec/0.015sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!JsuUXXzLEIsoECNBMe%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:51,895 - synapse.access.http.8008 - 461 - INFO - GET-7725 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.003sec/0.002sec (0.001sec, 0.000sec) (0.001sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:51,902 - synapse.access.http.8008 - 461 - INFO - GET-7726 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.009sec/0.001sec (0.000sec, 0.001sec) (0.002sec/0.002sec/4) 781B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43147_6636_0_225_332_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:51,907 - synapse.access.http.8008 - 461 - INFO - GET-7724 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.056sec/0.001sec (0.007sec, 0.002sec) (0.020sec/0.020sec/21) 17829B 200 "GET /_matrix/client/v1/rooms/!eplqOrllpoXBvcNBKo%3Auplink.mit.edu/hierarchy?suggested_only=false&limit=20 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:52,005 - synapse.access.http.8008 - 461 - INFO - GET-7728 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:54,339 - synapse.federation.transport.server.federation - 105 - INFO - PUT-7729 - Received txn 1689885633983 from exozy.me. (PDUs: 0, EDUs: 1)
2023-07-27 17:03:54,348 - synapse.access.http.8008 - 461 - INFO - PUT-7729 - 192.168.122.1 - 8008 - {exozy.me} Processed request: 0.010sec/0.001sec (0.001sec, 0.000sec) (0.002sec/0.003sec/3) 11B 200 "PUT /_matrix/federation/v1/send/1689885633983 HTTP/1.1" "Synapse/1.88.0" [0 dbevts]
2023-07-27 17:03:55,757 - synapse.api.auth.internal - 275 - WARNING - GET-7730 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:03:55,757 - synapse.http.server - 117 - INFO - GET-7730 - <XForwardedForRequest at 0x7f70afd8a2f0 method='GET' uri='/_matrix/client/v3/account/whoami' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:03:55,758 - synapse.access.http.8008 - 461 - INFO - GET-7730 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.001sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/account/whoami HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:03:55,835 - synapse.api.auth.internal - 275 - WARNING - GET-7731 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:03:55,835 - synapse.http.server - 117 - INFO - GET-7731 - <XForwardedForRequest at 0x7f70afd88d00 method='GET' uri='/_matrix/client/v3/joined_rooms' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:03:55,836 - synapse.access.http.8008 - 461 - INFO - GET-7731 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/joined_rooms HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:03:56,515 - synapse.access.http.8008 - 461 - INFO - POST-7732 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.030sec/0.001sec (0.006sec, 0.000sec) (0.001sec/0.005sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!JsuUXXzLEIsoECNBMe%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:56,525 - synapse.handlers.sync - 1385 - INFO - GET-7727 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !JsuUXXzLEIsoECNBMe:uplink.mit.edu
2023-07-27 17:03:56,527 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-73 - Unexpectedly did not have cached prev group for $EYrWJlMMg4h8IyKOTjjtpGUpwgLqBjLJwsOn42VAEsQ
2023-07-27 17:03:56,590 - synapse.http.server - 117 - INFO - POST-7733 - <XForwardedForRequest at 0x7f70afdc2e90 method='POST' uri='/_matrix/client/r0/rooms/!zYYkCNMSqumPBKFbXt%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:03:56,591 - synapse.access.http.8008 - 461 - INFO - POST-7733 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.048sec/0.001sec (0.016sec, 0.000sec) (0.019sec/0.018sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!zYYkCNMSqumPBKFbXt%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:56,595 - synapse.access.http.8008 - 461 - INFO - GET-7727 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.637sec/0.000sec (0.011sec, 0.001sec) (0.045sec/0.023sec/16) 1370B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43147_6636_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:56,617 - synapse.access.http.8008 - 461 - INFO - GET-7734 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:56,816 - synapse.access.http.8008 - 461 - INFO - GET-7736 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:03:56,978 - synapse.metrics._gc - 120 - INFO - sentinel - Collecting gc 1
2023-07-27 17:04:01,542 - synapse.access.http.8008 - 461 - INFO - POST-7737 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.030sec/0.001sec (0.006sec, 0.000sec) (0.001sec/0.005sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!zYYkCNMSqumPBKFbXt%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:01,551 - synapse.handlers.sync - 1385 - INFO - GET-7735 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !zYYkCNMSqumPBKFbXt:uplink.mit.edu
2023-07-27 17:04:01,553 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-74 - Unexpectedly did not have cached prev group for $JKtiGhOGa9367NR1xEJ6I1tI0qnCawiFhXQwkFKurek
2023-07-27 17:04:01,607 - synapse.access.http.8008 - 461 - INFO - GET-7735 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.911sec/0.001sec (0.009sec, 0.005sec) (0.032sec/0.022sec/15) 1305B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43148_6637_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:01,615 - synapse.http.server - 117 - INFO - POST-7738 - <XForwardedForRequest at 0x7f70afdac1c0 method='POST' uri='/_matrix/client/r0/rooms/!vyzBURRQkceiXIYvWN%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:01,616 - synapse.access.http.8008 - 461 - INFO - POST-7738 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.027sec/0.000sec (0.005sec, 0.000sec) (0.008sec/0.009sec/7) 80B 429 "POST /_matrix/client/r0/rooms/!vyzBURRQkceiXIYvWN%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:01,766 - synapse.access.http.8008 - 461 - INFO - GET-7739 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.004sec/0.000sec (0.000sec, 0.000sec) (0.003sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:01,926 - synapse.access.http.8008 - 461 - INFO - GET-7741 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:06,769 - synapse.access.http.8008 - 461 - INFO - POST-7742 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.028sec/0.001sec (0.004sec, 0.000sec) (0.001sec/0.003sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!vyzBURRQkceiXIYvWN%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:06,779 - synapse.handlers.sync - 1385 - INFO - GET-7740 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !vyzBURRQkceiXIYvWN:uplink.mit.edu
2023-07-27 17:04:06,781 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-75 - Unexpectedly did not have cached prev group for $ica55QgAGBrJjpqTb0hImrkY6HGKxevH9goTSzXkCug
2023-07-27 17:04:06,841 - synapse.http.server - 117 - INFO - POST-7743 - <XForwardedForRequest at 0x7f70afd7c4f0 method='POST' uri='/_matrix/client/r0/rooms/!qRiTQVxCwbtCAobTLA%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:06,842 - synapse.access.http.8008 - 461 - INFO - POST-7743 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.058sec/0.001sec (0.016sec, 0.001sec) (0.027sec/0.020sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!qRiTQVxCwbtCAobTLA%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:06,848 - synapse.access.http.8008 - 461 - INFO - GET-7740 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 5.085sec/0.000sec (0.007sec, 0.001sec) (0.049sec/0.020sec/15) 1451B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43149_6637_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:07,693 - synapse.access.http.8008 - 461 - INFO - GET-7745 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:08,748 - synapse.access.http.8008 - 461 - INFO - GET-7746 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:11,496 - synapse.access.http.8008 - 461 - INFO - POST-7747 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.034sec/0.001sec (0.011sec, 0.002sec) (0.001sec/0.006sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!qRiTQVxCwbtCAobTLA%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:11,506 - synapse.handlers.sync - 1385 - INFO - GET-7744 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !qRiTQVxCwbtCAobTLA:uplink.mit.edu
2023-07-27 17:04:11,508 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-76 - Unexpectedly did not have cached prev group for $GseAU2nq2E_nqsXlO2fUCDdHxvuoo3h5ydIty47VgOQ
2023-07-27 17:04:11,537 - synapse.http.client - 414 - INFO - as-sender-zephyr-22 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111386?access_token=<redacted>: 200
2023-07-27 17:04:11,573 - synapse.http.server - 117 - INFO - POST-7748 - <XForwardedForRequest at 0x7f70afdac9d0 method='POST' uri='/_matrix/client/r0/rooms/!NXWfjrzTmlTVBGmfKh%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:11,574 - synapse.access.http.8008 - 461 - INFO - POST-7748 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.062sec/0.001sec (0.009sec, 0.004sec) (0.034sec/0.015sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!NXWfjrzTmlTVBGmfKh%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:11,588 - synapse.access.http.8008 - 461 - INFO - GET-7744 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.294sec/0.000sec (0.017sec, 0.000sec) (0.051sec/0.030sec/15) 1446B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43150_6637_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:12,028 - synapse.access.http.8008 - 461 - INFO - GET-7749 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.006sec/0.001sec (0.001sec, 0.000sec) (0.004sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:12,029 - synapse.access.http.8008 - 461 - INFO - GET-7750 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.006sec/0.001sec (0.001sec, 0.000sec) (0.004sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:16,290 - synapse.api.auth.internal - 275 - WARNING - GET-7752 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:04:16,290 - synapse.http.server - 117 - INFO - GET-7752 - <XForwardedForRequest at 0x7f70afd9d4b0 method='GET' uri='/_matrix/client/v3/account/whoami' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:04:16,291 - synapse.access.http.8008 - 461 - INFO - GET-7752 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/account/whoami HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:04:16,368 - synapse.api.auth.internal - 275 - WARNING - GET-7753 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:04:16,368 - synapse.http.server - 117 - INFO - GET-7753 - <XForwardedForRequest at 0x7f70afd9f490 method='GET' uri='/_matrix/client/v3/joined_rooms' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:04:16,369 - synapse.access.http.8008 - 461 - INFO - GET-7753 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/joined_rooms HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:04:16,506 - synapse.access.http.8008 - 461 - INFO - POST-7754 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.057sec/0.001sec (0.005sec, 0.000sec) (0.001sec/0.009sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!NXWfjrzTmlTVBGmfKh%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:16,515 - synapse.handlers.sync - 1385 - INFO - GET-7751 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !NXWfjrzTmlTVBGmfKh:uplink.mit.edu
2023-07-27 17:04:16,517 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-77 - Unexpectedly did not have cached prev group for $IzuQYLpNtR76Hsl_JaPgRfoeK6jeYB6-72Am_C6zMYM
2023-07-27 17:04:16,527 - synapse.storage.databases.main.event_push_actions - 1306 - INFO - rotate_notifs-1821 - Rotating notifications
2023-07-27 17:04:16,535 - synapse.storage.databases.main.event_push_actions - 1510 - INFO - rotate_notifs-1821 - Rotating notifications up to: 43152
2023-07-27 17:04:16,535 - synapse.storage.databases.main.event_push_actions - 1596 - INFO - rotate_notifs-1821 - Rotating notifications, handling 0 rows
2023-07-27 17:04:16,547 - synapse.storage.databases.main.event_push_actions - 1684 - INFO - rotate_notifs-1821 - Rotating notifications, deleted 0 push actions
2023-07-27 17:04:16,573 - synapse.handlers.presence - 795 - INFO - persist_presence_changes-909 - Persisting 1 unpersisted presence updates
2023-07-27 17:04:16,583 - synapse.util.caches.lrucache - 210 - INFO - LruCache._expire_old_entries-1821 - Dropped 0 items from caches
2023-07-27 17:04:16,588 - synapse.http.server - 117 - INFO - POST-7755 - <XForwardedForRequest at 0x7f70afdb99f0 method='POST' uri='/_matrix/client/r0/rooms/!YRfPprpYCxNdFsnTLW%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:16,589 - synapse.access.http.8008 - 461 - INFO - POST-7755 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.052sec/0.001sec (0.009sec, 0.003sec) (0.025sec/0.016sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!YRfPprpYCxNdFsnTLW%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:16,594 - synapse.access.http.8008 - 461 - INFO - GET-7751 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.571sec/0.001sec (0.012sec, 0.005sec) (0.053sec/0.025sec/16) 1468B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43151_6637_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:16,793 - synapse.access.http.8008 - 461 - INFO - GET-7757 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.003sec/0.000sec (0.001sec, 0.000sec) (0.001sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:16,893 - synapse.access.http.8008 - 461 - INFO - GET-7758 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:21,552 - synapse.access.http.8008 - 461 - INFO - POST-7759 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.030sec/0.001sec (0.002sec, 0.003sec) (0.001sec/0.005sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!YRfPprpYCxNdFsnTLW%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:21,562 - synapse.handlers.sync - 1385 - INFO - GET-7756 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !YRfPprpYCxNdFsnTLW:uplink.mit.edu
2023-07-27 17:04:21,563 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-78 - Unexpectedly did not have cached prev group for $K0BDLlOeK-7rb98yHLCXglLrD28YQS0z0kq-AlwgviA
2023-07-27 17:04:21,589 - synapse.http.client - 414 - INFO - as-sender-zephyr-23 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111391?access_token=<redacted>: 200
2023-07-27 17:04:21,628 - synapse.http.server - 117 - INFO - POST-7760 - <XForwardedForRequest at 0x7f70afd106d0 method='POST' uri='/_matrix/client/r0/rooms/!HyhGtCEmovaqReTPxJ%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:21,630 - synapse.access.http.8008 - 461 - INFO - POST-7760 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.049sec/0.001sec (0.007sec, 0.003sec) (0.024sec/0.014sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!HyhGtCEmovaqReTPxJ%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:21,632 - synapse.access.http.8008 - 461 - INFO - GET-7756 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.843sec/0.000sec (0.012sec, 0.003sec) (0.047sec/0.024sec/15) 1371B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43152_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:21,814 - synapse.access.http.8008 - 461 - INFO - GET-7761 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:22,024 - synapse.access.http.8008 - 461 - INFO - GET-7763 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:26,599 - synapse.access.http.8008 - 461 - INFO - POST-7764 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.034sec/0.001sec (0.006sec, 0.000sec) (0.001sec/0.008sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!HyhGtCEmovaqReTPxJ%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:26,608 - synapse.handlers.sync - 1385 - INFO - GET-7762 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !HyhGtCEmovaqReTPxJ:uplink.mit.edu
2023-07-27 17:04:26,610 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-79 - Unexpectedly did not have cached prev group for $-dhAeRj5TGIU7_7n9783hRXAFpgFT0hdUysaTgGPrHc
2023-07-27 17:04:26,663 - synapse.http.server - 117 - INFO - POST-7765 - <XForwardedForRequest at 0x7f70afd69420 method='POST' uri='/_matrix/client/r0/rooms/!ACHLtqjhRBzvOsdkyx%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:26,664 - synapse.access.http.8008 - 461 - INFO - POST-7765 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.046sec/0.000sec (0.014sec, 0.000sec) (0.021sec/0.014sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!ACHLtqjhRBzvOsdkyx%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:26,668 - synapse.access.http.8008 - 461 - INFO - GET-7762 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.688sec/0.001sec (0.005sec, 0.002sec) (0.041sec/0.018sec/14) 1305B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43153_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:26,690 - synapse.access.http.8008 - 461 - INFO - GET-7766 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:26,890 - synapse.access.http.8008 - 461 - INFO - GET-7768 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:26,939 - synapse.access.http.8008 - 461 - INFO - GET-7769 - 192.168.122.1 - 8008 - {None} Processed request: 0.000sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 311B 200 "GET /_matrix/key/v2/server HTTP/1.1" "Synapse/1.84.1" [0 dbevts]
2023-07-27 17:04:27,228 - synapse.api.auth.internal - 275 - WARNING - GET-7770 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:04:27,230 - synapse.http.server - 117 - INFO - GET-7770 - <XForwardedForRequest at 0x7f70afdaea70 method='GET' uri='/_matrix/client/v3/account/whoami' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:04:27,231 - synapse.access.http.8008 - 461 - INFO - GET-7770 - 192.168.122.1 - 8008 - {None} Processed request: 0.002sec/0.000sec (0.002sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/account/whoami HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:04:27,314 - synapse.api.auth.internal - 275 - WARNING - GET-7771 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:04:27,315 - synapse.http.server - 117 - INFO - GET-7771 - <XForwardedForRequest at 0x7f70afdad570 method='GET' uri='/_matrix/client/v3/joined_rooms' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:04:27,315 - synapse.access.http.8008 - 461 - INFO - GET-7771 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.001sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/joined_rooms HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:04:27,878 - synapse.metrics._gc - 120 - INFO - sentinel - Collecting gc 1
2023-07-27 17:04:31,510 - synapse.access.http.8008 - 461 - INFO - POST-7772 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.033sec/0.001sec (0.006sec, 0.000sec) (0.001sec/0.007sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!ACHLtqjhRBzvOsdkyx%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:31,519 - synapse.handlers.sync - 1385 - INFO - GET-7767 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !ACHLtqjhRBzvOsdkyx:uplink.mit.edu
2023-07-27 17:04:31,521 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-80 - Unexpectedly did not have cached prev group for $SjdIQsgfPHUhFL452ZO-9BhFyKGoyzXBiv1ckffM1L8
2023-07-27 17:04:31,548 - synapse.http.client - 414 - INFO - as-sender-zephyr-24 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111396?access_token=<redacted>: 200
2023-07-27 17:04:31,590 - synapse.access.http.8008 - 461 - INFO - GET-7767 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.806sec/0.002sec (0.008sec, 0.001sec) (0.049sec/0.018sec/15) 1440B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43154_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:31,602 - synapse.http.server - 117 - INFO - POST-7773 - <XForwardedForRequest at 0x7f70afd8ab30 method='POST' uri='/_matrix/client/r0/rooms/!yvTMxhjiaoNMWwRQes%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:31,603 - synapse.access.http.8008 - 461 - INFO - POST-7773 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.042sec/0.000sec (0.016sec, 0.003sec) (0.007sec/0.022sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!yvTMxhjiaoNMWwRQes%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:31,678 - synapse.access.http.8008 - 461 - INFO - GET-7774 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:31,857 - synapse.access.http.8008 - 461 - INFO - GET-7776 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:36,570 - synapse.access.http.8008 - 461 - INFO - POST-7777 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.031sec/0.001sec (0.010sec, 0.000sec) (0.001sec/0.007sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!yvTMxhjiaoNMWwRQes%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:36,580 - synapse.handlers.sync - 1385 - INFO - GET-7775 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !yvTMxhjiaoNMWwRQes:uplink.mit.edu
2023-07-27 17:04:36,582 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-81 - Unexpectedly did not have cached prev group for $ISVki5tY1LgYz1Top209bAOOlNvx13APdhzHaMU46Qs
2023-07-27 17:04:36,657 - synapse.http.server - 117 - INFO - POST-7778 - <XForwardedForRequest at 0x7f70afd9e4a0 method='POST' uri='/_matrix/client/r0/rooms/!vtESqnFlaeNyspyITK%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:36,658 - synapse.access.http.8008 - 461 - INFO - POST-7778 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.063sec/0.001sec (0.019sec, 0.000sec) (0.028sec/0.023sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!vtESqnFlaeNyspyITK%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:36,662 - synapse.access.http.8008 - 461 - INFO - GET-7775 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.906sec/0.001sec (0.021sec, 0.002sec) (0.048sec/0.034sec/15) 1307B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43155_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:36,803 - synapse.access.http.8008 - 461 - INFO - GET-7780 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.003sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:36,900 - synapse.access.http.8008 - 461 - INFO - GET-7781 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:38,303 - synapse.api.auth.internal - 275 - WARNING - GET-7782 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:04:38,303 - synapse.http.server - 117 - INFO - GET-7782 - <XForwardedForRequest at 0x7f70afdebe20 method='GET' uri='/_matrix/client/v3/account/whoami' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:04:38,304 - synapse.access.http.8008 - 461 - INFO - GET-7782 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.001sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/account/whoami HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:04:38,386 - synapse.api.auth.internal - 275 - WARNING - GET-7783 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:04:38,386 - synapse.http.server - 117 - INFO - GET-7783 - <XForwardedForRequest at 0x7f70afde9690 method='GET' uri='/_matrix/client/v3/joined_rooms' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:04:38,387 - synapse.access.http.8008 - 461 - INFO - GET-7783 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/joined_rooms HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:04:41,514 - synapse.access.http.8008 - 461 - INFO - POST-7784 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.032sec/0.001sec (0.009sec, 0.000sec) (0.001sec/0.007sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!vtESqnFlaeNyspyITK%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:41,522 - synapse.handlers.sync - 1385 - INFO - GET-7779 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !vtESqnFlaeNyspyITK:uplink.mit.edu
2023-07-27 17:04:41,524 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-82 - Unexpectedly did not have cached prev group for $kprGVreInn4B8f0J_OP0QqKEJikMngQOrBT6LDw6vXg
2023-07-27 17:04:41,543 - synapse.federation.sender.transaction_manager - 121 - INFO - federation_transaction_transmission_loop-122 - TX [matrix.mit.edu] {1690422796708} Sending transaction [1690422796708], (PDUs: 1, EDUs: 0)
2023-07-27 17:04:41,559 - synapse.http.client - 414 - INFO - as-sender-zephyr-25 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111401?access_token=<redacted>: 200
2023-07-27 17:04:41,615 - synapse.http.server - 117 - INFO - POST-7785 - <XForwardedForRequest at 0x7f70afd69e10 method='POST' uri='/_matrix/client/r0/rooms/!ISiZmEukFqXvUxyXhK%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:41,616 - synapse.access.http.8008 - 461 - INFO - POST-7785 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.075sec/0.001sec (0.009sec, 0.009sec) (0.041sec/0.019sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!ISiZmEukFqXvUxyXhK%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:41,620 - synapse.access.http.8008 - 461 - INFO - GET-7779 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.821sec/0.000sec (0.019sec, 0.005sec) (0.058sec/0.036sec/15) 1409B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43156_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:41,785 - synapse.access.http.8008 - 461 - INFO - GET-7787 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.003sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:41,939 - synapse.access.http.8008 - 461 - INFO - GET-7788 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:42,328 - synapse.http.matrixfederationclient - 346 - INFO - federation_transaction_transmission_loop-122 - {PUT-O-151} [matrix.mit.edu] Completed request: 200 OK in 0.79 secs, got 60 bytes - PUT matrix-federation://matrix.mit.edu/_matrix/federation/v1/send/1690422796708
2023-07-27 17:04:42,328 - synapse.federation.sender.transaction_manager - 174 - INFO - federation_transaction_transmission_loop-122 - TX [matrix.mit.edu] {1690422796708} got 200 response
2023-07-27 17:04:46,518 - synapse.storage.databases.main.event_push_actions - 1306 - INFO - rotate_notifs-1822 - Rotating notifications
2023-07-27 17:04:46,518 - synapse.storage.databases.main.event_push_actions - 1510 - INFO - rotate_notifs-1822 - Rotating notifications up to: 43157
2023-07-27 17:04:46,519 - synapse.storage.databases.main.event_push_actions - 1596 - INFO - rotate_notifs-1822 - Rotating notifications, handling 0 rows
2023-07-27 17:04:46,521 - synapse.storage.databases.main.event_push_actions - 1684 - INFO - rotate_notifs-1822 - Rotating notifications, deleted 0 push actions
2023-07-27 17:04:46,582 - synapse.util.caches.lrucache - 210 - INFO - LruCache._expire_old_entries-1822 - Dropped 0 items from caches
2023-07-27 17:04:47,127 - synapse.access.http.8008 - 461 - INFO - POST-7789 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.031sec/0.001sec (0.007sec, 0.000sec) (0.001sec/0.006sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!ISiZmEukFqXvUxyXhK%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:47,136 - synapse.handlers.sync - 1385 - INFO - GET-7786 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !ISiZmEukFqXvUxyXhK:uplink.mit.edu
2023-07-27 17:04:47,138 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-83 - Unexpectedly did not have cached prev group for $UCYgM-pHVmRtL6Bf4aw2LbB-bsJ-GQEZTGk-KvF32Xs
2023-07-27 17:04:47,207 - synapse.http.server - 117 - INFO - POST-7790 - <XForwardedForRequest at 0x7f70afd8a9b0 method='POST' uri='/_matrix/client/r0/rooms/!UaOZmycyiTocnRsrMs%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:47,208 - synapse.access.http.8008 - 461 - INFO - POST-7790 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.054sec/0.001sec (0.014sec, 0.000sec) (0.025sec/0.016sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!UaOZmycyiTocnRsrMs%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:47,213 - synapse.access.http.8008 - 461 - INFO - GET-7786 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 5.432sec/0.001sec (0.014sec, 0.004sec) (0.046sec/0.028sec/15) 1372B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43157_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:47,300 - synapse.access.http.8008 - 461 - INFO - GET-7791 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:49,163 - synapse.access.http.8008 - 461 - INFO - GET-7793 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:52,122 - synapse.access.http.8008 - 461 - INFO - POST-7794 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.031sec/0.001sec (0.007sec, 0.000sec) (0.001sec/0.005sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!UaOZmycyiTocnRsrMs%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:52,132 - synapse.handlers.sync - 1385 - INFO - GET-7792 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !UaOZmycyiTocnRsrMs:uplink.mit.edu
2023-07-27 17:04:52,134 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-84 - Unexpectedly did not have cached prev group for $broOWWjHLtX2L_2RLpHcVFMCHr2yzqRd8YhThsNbyOk
2023-07-27 17:04:52,162 - synapse.http.client - 414 - INFO - as-sender-zephyr-26 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111406?access_token=<redacted>: 200
2023-07-27 17:04:52,208 - synapse.http.server - 117 - INFO - POST-7795 - <XForwardedForRequest at 0x7f70afd117b0 method='POST' uri='/_matrix/client/r0/rooms/!eOobElgVYCBmPzmrgY%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:52,209 - synapse.access.http.8008 - 461 - INFO - POST-7795 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.062sec/0.001sec (0.018sec, 0.003sec) (0.029sec/0.021sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!eOobElgVYCBmPzmrgY%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:52,213 - synapse.access.http.8008 - 461 - INFO - GET-7792 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.900sec/0.000sec (0.011sec, 0.000sec) (0.053sec/0.026sec/15) 1368B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43158_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:52,262 - synapse.access.http.8008 - 461 - INFO - GET-7796 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:55,169 - synapse.access.http.8008 - 461 - INFO - GET-7798 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.001sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:57,192 - synapse.access.http.8008 - 461 - INFO - POST-7799 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.032sec/0.001sec (0.005sec, 0.000sec) (0.001sec/0.007sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!eOobElgVYCBmPzmrgY%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:57,201 - synapse.handlers.sync - 1385 - INFO - GET-7797 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !eOobElgVYCBmPzmrgY:uplink.mit.edu
2023-07-27 17:04:57,203 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-85 - Unexpectedly did not have cached prev group for $KBf9E0OWOjcP2uGyx4MIlaNL-cgriXeJTOoZwjI3UgU
2023-07-27 17:04:57,231 - synapse.http.client - 414 - INFO - as-sender-zephyr-27 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111409?access_token=<redacted>: 200
2023-07-27 17:04:57,263 - synapse.http.server - 117 - INFO - POST-7800 - <XForwardedForRequest at 0x7f70afc07820 method='POST' uri='/_matrix/client/r0/rooms/!KrIgJUtyHnWUMJitEJ%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:04:57,264 - synapse.access.http.8008 - 461 - INFO - POST-7800 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.058sec/0.001sec (0.008sec, 0.001sec) (0.034sec/0.013sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!KrIgJUtyHnWUMJitEJ%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:57,278 - synapse.access.http.8008 - 461 - INFO - GET-7797 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.970sec/0.001sec (0.013sec, 0.008sec) (0.044sec/0.032sec/15) 1307B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43159_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:04:57,331 - synapse.access.http.8008 - 461 - INFO - GET-7801 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:00,171 - synapse.access.http.8008 - 461 - INFO - GET-7803 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:02,203 - synapse.access.http.8008 - 461 - INFO - POST-7804 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.034sec/0.001sec (0.008sec, 0.000sec) (0.001sec/0.008sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!KrIgJUtyHnWUMJitEJ%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:02,212 - synapse.handlers.sync - 1385 - INFO - GET-7802 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !KrIgJUtyHnWUMJitEJ:uplink.mit.edu
2023-07-27 17:05:02,214 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-86 - Unexpectedly did not have cached prev group for $uTR1PBd8gQe3nJPqZH3Ix3wv90Q1GEWe-fv3ydLVo7E
2023-07-27 17:05:02,242 - synapse.http.client - 414 - INFO - as-sender-zephyr-28 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111412?access_token=<redacted>: 200
2023-07-27 17:05:02,278 - synapse.http.server - 117 - INFO - POST-7805 - <XForwardedForRequest at 0x7f70afdbadd0 method='POST' uri='/_matrix/client/r0/rooms/!ZFyzrWzwjBGkGUIYJt%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:02,280 - synapse.access.http.8008 - 461 - INFO - POST-7805 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.057sec/0.002sec (0.012sec, 0.003sec) (0.027sec/0.019sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!ZFyzrWzwjBGkGUIYJt%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:02,286 - synapse.access.http.8008 - 461 - INFO - GET-7802 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.941sec/0.000sec (0.006sec, 0.004sec) (0.052sec/0.020sec/15) 1369B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43160_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:02,302 - synapse.access.http.8008 - 461 - INFO - GET-7806 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:04,252 - synapse.access.http.8008 - 461 - INFO - GET-7808 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:06,509 - synapse.access.http.8008 - 461 - INFO - POST-7809 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.059sec/0.001sec (0.008sec, 0.000sec) (0.001sec/0.011sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!ZFyzrWzwjBGkGUIYJt%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:06,520 - synapse.handlers.sync - 1385 - INFO - GET-7807 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !ZFyzrWzwjBGkGUIYJt:uplink.mit.edu
2023-07-27 17:05:06,522 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-87 - Unexpectedly did not have cached prev group for $CQ9aVy7uYnF9u5yMbUFxHsxb8vJo8nuKgOyPdt6GdAs
2023-07-27 17:05:06,549 - synapse.http.client - 414 - INFO - as-sender-zephyr-29 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111415?access_token=<redacted>: 200
2023-07-27 17:05:06,578 - synapse.metrics._gc - 120 - INFO - sentinel - Collecting gc 1
2023-07-27 17:05:06,608 - synapse.http.server - 117 - INFO - POST-7810 - <XForwardedForRequest at 0x7f70afdbb4c0 method='POST' uri='/_matrix/client/r0/rooms/!dIDCndQIimxEuzUKOa%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:06,610 - synapse.access.http.8008 - 461 - INFO - POST-7810 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.080sec/0.001sec (0.024sec, 0.000sec) (0.036sec/0.028sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!dIDCndQIimxEuzUKOa%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:06,620 - synapse.access.http.8008 - 461 - INFO - GET-7807 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.284sec/0.000sec (0.016sec, 0.002sec) (0.069sec/0.028sec/14) 1307B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43161_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:06,763 - synapse.access.http.8008 - 461 - INFO - GET-7811 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:06,892 - synapse.access.http.8008 - 461 - INFO - GET-7813 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:09,909 - synapse.api.auth.internal - 275 - WARNING - GET-7814 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:05:09,910 - synapse.http.server - 117 - INFO - GET-7814 - <XForwardedForRequest at 0x7f70afd68040 method='GET' uri='/_matrix/client/v3/account/whoami' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:05:09,911 - synapse.access.http.8008 - 461 - INFO - GET-7814 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.001sec (0.002sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/account/whoami HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:05:09,994 - synapse.api.auth.internal - 275 - WARNING - GET-7815 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:05:09,995 - synapse.http.server - 117 - INFO - GET-7815 - <XForwardedForRequest at 0x7f70afd68160 method='GET' uri='/_matrix/client/v3/joined_rooms' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:05:09,996 - synapse.access.http.8008 - 461 - INFO - GET-7815 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/joined_rooms HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:05:11,549 - synapse.access.http.8008 - 461 - INFO - POST-7816 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.033sec/0.001sec (0.011sec, 0.000sec) (0.001sec/0.007sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!dIDCndQIimxEuzUKOa%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:11,559 - synapse.handlers.sync - 1385 - INFO - GET-7812 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !dIDCndQIimxEuzUKOa:uplink.mit.edu
2023-07-27 17:05:11,561 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-88 - Unexpectedly did not have cached prev group for $WC0TXlLcQXp3eRTgnrylYSBBAwitdASPQWsxVsLkWAk
2023-07-27 17:05:11,612 - synapse.access.http.8008 - 461 - INFO - GET-7812 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.801sec/0.001sec (0.011sec, 0.004sec) (0.030sec/0.022sec/14) 1369B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43162_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:11,635 - synapse.http.server - 117 - INFO - POST-7817 - <XForwardedForRequest at 0x7f70afef8c40 method='POST' uri='/_matrix/client/r0/rooms/!ZgKtWdJyJmcmurJkCM%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:11,635 - synapse.access.http.8008 - 461 - INFO - POST-7817 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.020sec/0.000sec (0.006sec, 0.000sec) (0.002sec/0.008sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!ZgKtWdJyJmcmurJkCM%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:11,733 - synapse.access.http.8008 - 461 - INFO - GET-7818 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:11,836 - synapse.access.http.8008 - 461 - INFO - GET-7820 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:16,518 - synapse.access.http.8008 - 461 - INFO - POST-7821 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.030sec/0.001sec (0.006sec, 0.000sec) (0.001sec/0.004sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!ZgKtWdJyJmcmurJkCM%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:16,524 - synapse.storage.databases.main.event_push_actions - 1306 - INFO - rotate_notifs-1823 - Rotating notifications
2023-07-27 17:05:16,528 - synapse.handlers.sync - 1385 - INFO - GET-7819 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !ZgKtWdJyJmcmurJkCM:uplink.mit.edu
2023-07-27 17:05:16,529 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-89 - Unexpectedly did not have cached prev group for $SFNk7WGtCGscmo8i6k2tMh9aq4o2z0zMeN9ekaYY6yA
2023-07-27 17:05:16,532 - synapse.storage.databases.main.event_push_actions - 1510 - INFO - rotate_notifs-1823 - Rotating notifications up to: 43164
2023-07-27 17:05:16,533 - synapse.storage.databases.main.event_push_actions - 1596 - INFO - rotate_notifs-1823 - Rotating notifications, handling 0 rows
2023-07-27 17:05:16,550 - synapse.storage.databases.main.event_push_actions - 1684 - INFO - rotate_notifs-1823 - Rotating notifications, deleted 0 push actions
2023-07-27 17:05:16,573 - synapse.handlers.presence - 795 - INFO - persist_presence_changes-910 - Persisting 1 unpersisted presence updates
2023-07-27 17:05:16,582 - synapse.util.caches.lrucache - 210 - INFO - LruCache._expire_old_entries-1823 - Dropped 0 items from caches
2023-07-27 17:05:16,594 - synapse.http.server - 117 - INFO - POST-7822 - <XForwardedForRequest at 0x7f70afd89c30 method='POST' uri='/_matrix/client/r0/rooms/!hyKiICkVaqBMKbSNPL%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:16,597 - synapse.access.http.8008 - 461 - INFO - POST-7822 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.064sec/0.001sec (0.012sec, 0.000sec) (0.034sec/0.016sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!hyKiICkVaqBMKbSNPL%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:16,604 - synapse.access.http.8008 - 461 - INFO - GET-7819 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.819sec/0.000sec (0.016sec, 0.000sec) (0.056sec/0.020sec/15) 1468B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43163_6638_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:16,695 - synapse.access.http.8008 - 461 - INFO - GET-7823 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:16,888 - synapse.access.http.8008 - 461 - INFO - GET-7825 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:20,657 - synapse.api.auth.internal - 275 - WARNING - GET-7826 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:05:20,657 - synapse.http.server - 117 - INFO - GET-7826 - <XForwardedForRequest at 0x7f70afdbbf10 method='GET' uri='/_matrix/client/v3/account/whoami' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:05:20,658 - synapse.access.http.8008 - 461 - INFO - GET-7826 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.001sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/account/whoami HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:05:20,734 - synapse.api.auth.internal - 275 - WARNING - GET-7827 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:05:20,734 - synapse.http.server - 117 - INFO - GET-7827 - <XForwardedForRequest at 0x7f70afc07490 method='GET' uri='/_matrix/client/v3/joined_rooms' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:05:20,735 - synapse.access.http.8008 - 461 - INFO - GET-7827 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/joined_rooms HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:05:21,481 - synapse.access.http.8008 - 461 - INFO - POST-7828 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.032sec/0.001sec (0.006sec, 0.000sec) (0.001sec/0.006sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!hyKiICkVaqBMKbSNPL%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:21,490 - synapse.handlers.sync - 1385 - INFO - GET-7824 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !hyKiICkVaqBMKbSNPL:uplink.mit.edu
2023-07-27 17:05:21,492 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-90 - Unexpectedly did not have cached prev group for $vIuHoxy_QcBgMFmiHBe9M-H5okVUeullAXpV-dihu_w
2023-07-27 17:05:21,521 - synapse.http.client - 414 - INFO - as-sender-zephyr-30 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111422?access_token=<redacted>: 200
2023-07-27 17:05:21,559 - synapse.http.server - 117 - INFO - POST-7829 - <XForwardedForRequest at 0x7f70afd9f040 method='POST' uri='/_matrix/client/r0/rooms/!mrRoNbZzRfSaxcsxcz%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:21,560 - synapse.access.http.8008 - 461 - INFO - POST-7829 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.060sec/0.001sec (0.013sec, 0.001sec) (0.036sec/0.013sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!mrRoNbZzRfSaxcsxcz%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:21,565 - synapse.access.http.8008 - 461 - INFO - GET-7824 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.822sec/0.000sec (0.013sec, 0.001sec) (0.048sec/0.025sec/15) 1371B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43164_6639_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:21,587 - synapse.access.http.8008 - 461 - INFO - GET-7830 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:21,791 - synapse.access.http.8008 - 461 - INFO - GET-7832 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:26,717 - synapse.access.http.8008 - 461 - INFO - POST-7833 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.030sec/0.001sec (0.006sec, 0.000sec) (0.001sec/0.003sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!mrRoNbZzRfSaxcsxcz%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:26,727 - synapse.handlers.sync - 1385 - INFO - GET-7831 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !mrRoNbZzRfSaxcsxcz:uplink.mit.edu
2023-07-27 17:05:26,729 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-91 - Unexpectedly did not have cached prev group for $vpaiB7Pm6v6peahRpGnXrysixbYVmcot78nJvvLiedU
2023-07-27 17:05:26,766 - synapse.access.http.8008 - 461 - INFO - GET-7831 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 5.095sec/0.001sec (0.004sec, 0.003sec) (0.030sec/0.009sec/14) 1305B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43165_6639_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:26,784 - synapse.http.server - 117 - INFO - POST-7834 - <XForwardedForRequest at 0x7f70dc3dd2a0 method='POST' uri='/_matrix/client/r0/rooms/!uyikmAfyvPXYOybhsH%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:26,785 - synapse.access.http.8008 - 461 - INFO - POST-7834 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.021sec/0.000sec (0.003sec, 0.001sec) (0.002sec/0.009sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!uyikmAfyvPXYOybhsH%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:26,792 - synapse.access.http.8008 - 461 - INFO - GET-7835 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:28,763 - synapse.access.http.8008 - 461 - INFO - GET-7837 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:31,725 - synapse.access.http.8008 - 461 - INFO - POST-7838 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.029sec/0.001sec (0.008sec, 0.001sec) (0.001sec/0.004sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!uyikmAfyvPXYOybhsH%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:31,734 - synapse.handlers.sync - 1385 - INFO - GET-7836 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !uyikmAfyvPXYOybhsH:uplink.mit.edu
2023-07-27 17:05:31,736 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-92 - Unexpectedly did not have cached prev group for $qlyodnITV2vaeTTVD-z7BJnUBd_g_eIvPZaDrjSVmEc
2023-07-27 17:05:31,773 - synapse.access.http.8008 - 461 - INFO - GET-7836 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.961sec/0.000sec (0.006sec, 0.001sec) (0.029sec/0.009sec/14) 1305B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43166_6639_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:32,134 - synapse.access.http.8008 - 461 - INFO - GET-7840 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.004sec/0.001sec (0.000sec, 0.000sec) (0.001sec/0.002sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:32,155 - synapse.http.server - 117 - INFO - POST-7839 - <XForwardedForRequest at 0x7f70afdba0e0 method='POST' uri='/_matrix/client/r0/rooms/!xChUwUYKZzJYbovoZa%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:32,156 - synapse.access.http.8008 - 461 - INFO - POST-7839 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.028sec/0.000sec (0.003sec, 0.004sec) (0.002sec/0.014sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!xChUwUYKZzJYbovoZa%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:34,768 - synapse.access.http.8008 - 461 - INFO - GET-7842 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:36,788 - synapse.access.http.8008 - 461 - INFO - POST-7843 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.029sec/0.001sec (0.004sec, 0.000sec) (0.001sec/0.004sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!xChUwUYKZzJYbovoZa%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:36,796 - synapse.handlers.sync - 1385 - INFO - GET-7841 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !xChUwUYKZzJYbovoZa:uplink.mit.edu
2023-07-27 17:05:36,798 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-93 - Unexpectedly did not have cached prev group for $wHQdWrE1gRv6jEpp4Lnj_Re820Z7pO6mQBUhBnudB7I
2023-07-27 17:05:36,835 - synapse.access.http.8008 - 461 - INFO - GET-7841 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.687sec/0.000sec (0.005sec, 0.000sec) (0.029sec/0.012sec/14) 1305B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43167_6639_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:36,893 - synapse.http.server - 117 - INFO - POST-7844 - <XForwardedForRequest at 0x7f70afd89630 method='POST' uri='/_matrix/client/r0/rooms/!TPgUmivBoGAmAKMYfo%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:36,893 - synapse.access.http.8008 - 461 - INFO - POST-7844 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.021sec/0.000sec (0.008sec, 0.000sec) (0.002sec/0.009sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!TPgUmivBoGAmAKMYfo%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:37,047 - synapse.access.http.8008 - 461 - INFO - GET-7845 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:38,387 - synapse.federation.transport.server.federation - 105 - INFO - PUT-7847 - Received txn 1689885634295 from exozy.me. (PDUs: 0, EDUs: 1)
2023-07-27 17:05:38,397 - synapse.access.http.8008 - 461 - INFO - PUT-7847 - 192.168.122.1 - 8008 - {exozy.me} Processed request: 0.011sec/0.000sec (0.001sec, 0.003sec) (0.002sec/0.004sec/3) 11B 200 "PUT /_matrix/federation/v1/send/1689885634295 HTTP/1.1" "Synapse/1.88.0" [0 dbevts]
2023-07-27 17:05:38,978 - synapse.metrics._gc - 120 - INFO - sentinel - Collecting gc 1
2023-07-27 17:05:39,078 - synapse.metrics._gc - 120 - INFO - sentinel - Collecting gc 2
2023-07-27 17:05:39,796 - synapse.access.http.8008 - 461 - INFO - GET-7848 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:41,807 - synapse.access.http.8008 - 461 - INFO - POST-7849 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.034sec/0.001sec (0.004sec, 0.000sec) (0.001sec/0.008sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!TPgUmivBoGAmAKMYfo%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:41,814 - synapse.handlers.sync - 1385 - INFO - GET-7846 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !TPgUmivBoGAmAKMYfo:uplink.mit.edu
2023-07-27 17:05:41,816 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-94 - Unexpectedly did not have cached prev group for $E0qmf8VC9sx-lq17IHV3PXA5deWi_cRCMsg_1BiQ8Hc
2023-07-27 17:05:41,879 - synapse.http.server - 117 - INFO - POST-7850 - <XForwardedForRequest at 0x7f70dc375150 method='POST' uri='/_matrix/client/r0/rooms/!ItejMUQOtaEVZliXfz%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:41,879 - synapse.access.http.8008 - 461 - INFO - POST-7850 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.053sec/0.000sec (0.015sec, 0.000sec) (0.023sec/0.018sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!ItejMUQOtaEVZliXfz%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:41,886 - synapse.access.http.8008 - 461 - INFO - GET-7846 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.798sec/0.001sec (0.009sec, 0.000sec) (0.046sec/0.020sec/16) 1370B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43168_6639_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:41,990 - synapse.access.http.8008 - 461 - INFO - GET-7852 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.003sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:42,104 - synapse.api.auth.internal - 275 - WARNING - GET-7853 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:05:42,105 - synapse.http.server - 117 - INFO - GET-7853 - <XForwardedForRequest at 0x7f70dc218250 method='GET' uri='/_matrix/client/v3/account/whoami' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:05:42,105 - synapse.access.http.8008 - 461 - INFO - GET-7853 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/account/whoami HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:05:42,189 - synapse.api.auth.internal - 275 - WARNING - GET-7854 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:05:42,189 - synapse.http.server - 117 - INFO - GET-7854 - <XForwardedForRequest at 0x7f70afdea2c0 method='GET' uri='/_matrix/client/v3/joined_rooms' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:05:42,190 - synapse.access.http.8008 - 461 - INFO - GET-7854 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/joined_rooms HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:05:42,775 - synapse.access.http.8008 - 461 - INFO - GET-7855 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:46,518 - synapse.storage.databases.main.event_push_actions - 1306 - INFO - rotate_notifs-1824 - Rotating notifications
2023-07-27 17:05:46,518 - synapse.storage.databases.main.event_push_actions - 1510 - INFO - rotate_notifs-1824 - Rotating notifications up to: 43169
2023-07-27 17:05:46,518 - synapse.storage.databases.main.event_push_actions - 1596 - INFO - rotate_notifs-1824 - Rotating notifications, handling 0 rows
2023-07-27 17:05:46,521 - synapse.storage.databases.main.event_push_actions - 1684 - INFO - rotate_notifs-1824 - Rotating notifications, deleted 0 push actions
2023-07-27 17:05:46,582 - synapse.util.caches.lrucache - 210 - INFO - LruCache._expire_old_entries-1824 - Dropped 0 items from caches
2023-07-27 17:05:46,825 - synapse.access.http.8008 - 461 - INFO - POST-7856 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.030sec/0.001sec (0.011sec, 0.000sec) (0.001sec/0.006sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!ItejMUQOtaEVZliXfz%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:46,834 - synapse.handlers.sync - 1385 - INFO - GET-7851 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !ItejMUQOtaEVZliXfz:uplink.mit.edu
2023-07-27 17:05:46,835 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-95 - Unexpectedly did not have cached prev group for $mA3LUwux1JP773rQRVlBZ9BDakHs97loVzgZp-On9M0
2023-07-27 17:05:46,881 - synapse.access.http.8008 - 461 - INFO - GET-7851 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.893sec/0.002sec (0.011sec, 0.001sec) (0.028sec/0.019sec/14) 1305B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43169_6640_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:46,913 - synapse.access.http.8008 - 461 - INFO - GET-7857 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:47,186 - synapse.http.server - 117 - INFO - POST-7859 - <XForwardedForRequest at 0x7f70dc3dff70 method='POST' uri='/_matrix/client/r0/rooms/!HwErvyGOAoTCRQgzXa%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:47,186 - synapse.access.http.8008 - 461 - INFO - POST-7859 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.023sec/0.000sec (0.005sec, 0.000sec) (0.002sec/0.010sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!HwErvyGOAoTCRQgzXa%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:48,782 - synapse.access.http.8008 - 461 - INFO - GET-7860 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:51,831 - synapse.access.http.8008 - 461 - INFO - POST-7861 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.029sec/0.001sec (0.003sec, 0.000sec) (0.001sec/0.004sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!HwErvyGOAoTCRQgzXa%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:51,841 - synapse.handlers.sync - 1385 - INFO - GET-7858 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !HwErvyGOAoTCRQgzXa:uplink.mit.edu
2023-07-27 17:05:51,842 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-96 - Unexpectedly did not have cached prev group for $3611XcnakMM365CBrvjRznQmBmDhcn6Gdp_qAiHNJB0
2023-07-27 17:05:51,871 - synapse.http.client - 414 - INFO - as-sender-zephyr-31 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111435?access_token=<redacted>: 200
2023-07-27 17:05:51,915 - synapse.http.server - 117 - INFO - POST-7862 - <XForwardedForRequest at 0x7f70afc04970 method='POST' uri='/_matrix/client/r0/rooms/!IBnLSPncfteCZanJya%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:51,917 - synapse.access.http.8008 - 461 - INFO - POST-7862 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.068sec/0.001sec (0.006sec, 0.005sec) (0.042sec/0.014sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!IBnLSPncfteCZanJya%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:51,925 - synapse.access.http.8008 - 461 - INFO - GET-7858 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.938sec/0.001sec (0.009sec, 0.013sec) (0.049sec/0.034sec/15) 1785B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43170_6640_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:51,976 - synapse.access.http.8008 - 461 - INFO - GET-7863 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:53,802 - synapse.access.http.8008 - 461 - INFO - GET-7865 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:57,253 - synapse.access.http.8008 - 461 - INFO - POST-7866 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.057sec/0.001sec (0.006sec, 0.002sec) (0.001sec/0.008sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!IBnLSPncfteCZanJya%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:57,263 - synapse.handlers.sync - 1385 - INFO - GET-7864 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !IBnLSPncfteCZanJya:uplink.mit.edu
2023-07-27 17:05:57,265 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-97 - Unexpectedly did not have cached prev group for $zFboCBQp1kwpJQGu9JHZwS5ru6EsWIhc5aL9Vwswj98
2023-07-27 17:05:57,316 - synapse.access.http.8008 - 461 - INFO - GET-7864 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 5.311sec/0.002sec (0.009sec, 0.002sec) (0.034sec/0.017sec/14) 1307B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43171_6640_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:57,325 - synapse.http.server - 117 - INFO - POST-7867 - <XForwardedForRequest at 0x7f70aff3f5e0 method='POST' uri='/_matrix/client/r0/rooms/!xxdxLoWfJPcaiUNhau%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:05:57,326 - synapse.access.http.8008 - 461 - INFO - POST-7867 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.033sec/0.000sec (0.010sec, 0.001sec) (0.008sec/0.014sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!xxdxLoWfJPcaiUNhau%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:57,349 - synapse.access.http.8008 - 461 - INFO - GET-7868 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:05:59,820 - synapse.access.http.8008 - 461 - INFO - GET-7870 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:02,204 - synapse.access.http.8008 - 461 - INFO - POST-7871 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.034sec/0.001sec (0.005sec, 0.000sec) (0.001sec/0.008sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!xxdxLoWfJPcaiUNhau%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:02,213 - synapse.handlers.sync - 1385 - INFO - GET-7869 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !xxdxLoWfJPcaiUNhau:uplink.mit.edu
2023-07-27 17:06:02,214 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-98 - Unexpectedly did not have cached prev group for $-zdxth_w6lTjT4-v7M-XTMyPDyKzy6yr40xUwkLZ9lU
2023-07-27 17:06:02,253 - synapse.access.http.8008 - 461 - INFO - GET-7869 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.877sec/0.000sec (0.009sec, 0.001sec) (0.028sec/0.012sec/14) 1305B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43172_6640_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:02,318 - synapse.http.server - 117 - INFO - POST-7872 - <XForwardedForRequest at 0x7f70afd68e80 method='POST' uri='/_matrix/client/r0/rooms/!DGoBuOczGYSLemrFuW%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:06:02,318 - synapse.access.http.8008 - 461 - INFO - POST-7872 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.025sec/0.000sec (0.001sec, 0.008sec) (0.002sec/0.012sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!DGoBuOczGYSLemrFuW%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:02,441 - synapse.access.http.8008 - 461 - INFO - GET-7873 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.004sec/0.000sec (0.000sec, 0.000sec) (0.002sec/0.001sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:02,521 - synapse.access.http.8008 - 461 - INFO - GET-7875 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:03,081 - synapse.api.auth.internal - 275 - WARNING - GET-7876 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:06:03,082 - synapse.http.server - 117 - INFO - GET-7876 - <XForwardedForRequest at 0x7f70afc070a0 method='GET' uri='/_matrix/client/v3/account/whoami' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:06:03,083 - synapse.access.http.8008 - 461 - INFO - GET-7876 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/account/whoami HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:06:03,165 - synapse.api.auth.internal - 275 - WARNING - GET-7877 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:06:03,165 - synapse.http.server - 117 - INFO - GET-7877 - <XForwardedForRequest at 0x7f70afdb8940 method='GET' uri='/_matrix/client/v3/joined_rooms' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:06:03,166 - synapse.access.http.8008 - 461 - INFO - GET-7877 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.001sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/joined_rooms HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:06:06,577 - synapse.access.http.8008 - 461 - INFO - POST-7878 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.031sec/0.001sec (0.009sec, 0.000sec) (0.001sec/0.005sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!DGoBuOczGYSLemrFuW%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:06,587 - synapse.handlers.sync - 1385 - INFO - GET-7874 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !DGoBuOczGYSLemrFuW:uplink.mit.edu
2023-07-27 17:06:06,589 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-99 - Unexpectedly did not have cached prev group for $uI6WjxndwRa80korbwofWYQdBVHA3PwgdAcTEm2cMX4
2023-07-27 17:06:06,648 - synapse.http.server - 117 - INFO - POST-7879 - <XForwardedForRequest at 0x7f70dc374ca0 method='POST' uri='/_matrix/client/r0/rooms/!oJxgTUfKSDJhfkPerj%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:06:06,649 - synapse.access.http.8008 - 461 - INFO - POST-7879 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.050sec/0.001sec (0.009sec, 0.004sec) (0.026sec/0.013sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!oJxgTUfKSDJhfkPerj%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:06,654 - synapse.access.http.8008 - 461 - INFO - GET-7874 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.217sec/0.000sec (0.010sec, 0.005sec) (0.040sec/0.027sec/15) 1446B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43173_6640_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:06,674 - synapse.access.http.8008 - 461 - INFO - GET-7880 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:06,880 - synapse.access.http.8008 - 461 - INFO - GET-7882 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:07,678 - synapse.metrics._gc - 120 - INFO - sentinel - Collecting gc 1
2023-07-27 17:06:12,216 - synapse.access.http.8008 - 461 - INFO - POST-7883 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.030sec/0.001sec (0.004sec, 0.000sec) (0.001sec/0.004sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!oJxgTUfKSDJhfkPerj%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:12,225 - synapse.handlers.sync - 1385 - INFO - GET-7881 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !oJxgTUfKSDJhfkPerj:uplink.mit.edu
2023-07-27 17:06:12,227 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-100 - Unexpectedly did not have cached prev group for $aylcAcPV8oqcBREyDksRy7NK7LBZGHIbr_xklTb1aEk
2023-07-27 17:06:12,291 - synapse.http.server - 117 - INFO - POST-7884 - <XForwardedForRequest at 0x7f70afd118d0 method='POST' uri='/_matrix/client/r0/rooms/!EgQehnZOiUHsWdBOsJ%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:06:12,292 - synapse.access.http.8008 - 461 - INFO - POST-7884 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.055sec/0.001sec (0.014sec, 0.006sec) (0.021sec/0.023sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!EgQehnZOiUHsWdBOsJ%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:12,296 - synapse.access.http.8008 - 461 - INFO - GET-7881 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 5.566sec/0.001sec (0.007sec, 0.004sec) (0.051sec/0.020sec/14) 1307B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43174_6640_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:12,328 - synapse.access.http.8008 - 461 - INFO - GET-7885 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:13,720 - synapse.api.auth.internal - 275 - WARNING - GET-7887 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:06:13,720 - synapse.http.server - 117 - INFO - GET-7887 - <XForwardedForRequest at 0x7f70afdad8d0 method='GET' uri='/_matrix/client/v3/account/whoami' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:06:13,721 - synapse.access.http.8008 - 461 - INFO - GET-7887 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/account/whoami HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:06:13,803 - synapse.api.auth.internal - 275 - WARNING - GET-7888 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:06:13,804 - synapse.http.server - 117 - INFO - GET-7888 - <XForwardedForRequest at 0x7f70afef9120 method='GET' uri='/_matrix/client/v3/joined_rooms' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:06:13,804 - synapse.access.http.8008 - 461 - INFO - GET-7888 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.001sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/joined_rooms HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:06:15,010 - synapse.access.http.8008 - 461 - INFO - GET-7889 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:16,496 - synapse.access.http.8008 - 461 - INFO - POST-7890 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.032sec/0.001sec (0.010sec, 0.000sec) (0.001sec/0.006sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!EgQehnZOiUHsWdBOsJ%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:16,505 - synapse.handlers.sync - 1385 - INFO - GET-7886 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !EgQehnZOiUHsWdBOsJ:uplink.mit.edu
2023-07-27 17:06:16,507 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-101 - Unexpectedly did not have cached prev group for $3ZeR16iwxu5Ssnx5_pzJZAv66aH6Asn1GoAKpSmBfYo
2023-07-27 17:06:16,523 - synapse.storage.databases.main.event_push_actions - 1306 - INFO - rotate_notifs-1825 - Rotating notifications
2023-07-27 17:06:16,528 - synapse.storage.databases.main.event_push_actions - 1510 - INFO - rotate_notifs-1825 - Rotating notifications up to: 43176
2023-07-27 17:06:16,529 - synapse.storage.databases.main.event_push_actions - 1596 - INFO - rotate_notifs-1825 - Rotating notifications, handling 0 rows
2023-07-27 17:06:16,536 - synapse.storage.databases.main.event_push_actions - 1684 - INFO - rotate_notifs-1825 - Rotating notifications, deleted 0 push actions
2023-07-27 17:06:16,574 - synapse.handlers.presence - 795 - INFO - persist_presence_changes-911 - Persisting 1 unpersisted presence updates
2023-07-27 17:06:16,582 - synapse.util.caches.lrucache - 210 - INFO - LruCache._expire_old_entries-1825 - Dropped 0 items from caches
2023-07-27 17:06:16,591 - synapse.access.http.8008 - 461 - INFO - GET-7886 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 4.200sec/0.001sec (0.023sec, 0.002sec) (0.056sec/0.028sec/14) 1307B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43175_6640_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:16,593 - synapse.http.server - 117 - INFO - POST-7891 - <XForwardedForRequest at 0x7f70afec2b90 method='POST' uri='/_matrix/client/r0/rooms/!VgKBORbFvkolXomiBc%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:06:16,593 - synapse.access.http.8008 - 461 - INFO - POST-7891 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.067sec/0.000sec (0.014sec, 0.001sec) (0.033sec/0.022sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!VgKBORbFvkolXomiBc%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:16,664 - synapse.access.http.8008 - 461 - INFO - GET-7892 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.001sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:16,781 - synapse.access.http.8008 - 461 - INFO - GET-7893 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.004sec/0.000sec (0.001sec, 0.000sec) (0.001sec/0.000sec/2) 397B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43176_6640_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:16,838 - synapse.access.http.8008 - 461 - INFO - GET-7895 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:21,876 - synapse.access.http.8008 - 461 - INFO - POST-7896 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.031sec/0.001sec (0.012sec, 0.000sec) (0.001sec/0.006sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!VgKBORbFvkolXomiBc%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:21,886 - synapse.handlers.sync - 1385 - INFO - GET-7894 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !VgKBORbFvkolXomiBc:uplink.mit.edu
2023-07-27 17:06:21,888 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-102 - Unexpectedly did not have cached prev group for $3y_34XEAMYjQlgO61WFBv542sVH89wTmw9S2amw8mQk
2023-07-27 17:06:21,916 - synapse.http.client - 414 - INFO - as-sender-zephyr-32 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111448?access_token=<redacted>: 200
2023-07-27 17:06:21,951 - synapse.http.server - 117 - INFO - POST-7897 - <XForwardedForRequest at 0x7f70afdb9ba0 method='POST' uri='/_matrix/client/r0/rooms/!vQzAYACbBqYdODiqrs%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:06:21,952 - synapse.access.http.8008 - 461 - INFO - POST-7897 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.059sec/0.001sec (0.007sec, 0.003sec) (0.032sec/0.015sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!vQzAYACbBqYdODiqrs%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:21,964 - synapse.access.http.8008 - 461 - INFO - GET-7894 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 5.157sec/0.000sec (0.020sec, 0.000sec) (0.049sec/0.029sec/15) 1808B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43176_6641_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:22,003 - synapse.access.http.8008 - 461 - INFO - GET-7898 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:23,902 - synapse.access.http.8008 - 461 - INFO - GET-7900 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:26,951 - synapse.access.http.8008 - 461 - INFO - POST-7901 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.033sec/0.001sec (0.005sec, 0.000sec) (0.001sec/0.004sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!vQzAYACbBqYdODiqrs%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:26,961 - synapse.handlers.sync - 1385 - INFO - GET-7899 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !vQzAYACbBqYdODiqrs:uplink.mit.edu
2023-07-27 17:06:26,963 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-103 - Unexpectedly did not have cached prev group for $EhZESuYfvClvGXRZAp7MWikf6X73OLCJpjyg1VRrm4E
2023-07-27 17:06:26,991 - synapse.http.client - 414 - INFO - as-sender-zephyr-33 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111451?access_token=<redacted>: 200
2023-07-27 17:06:27,037 - synapse.access.http.8008 - 461 - INFO - GET-7899 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 5.009sec/0.001sec (0.016sec, 0.001sec) (0.054sec/0.020sec/15) 1687B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43177_6641_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:27,038 - synapse.http.server - 117 - INFO - POST-7902 - <XForwardedForRequest at 0x7f70afdf1b70 method='POST' uri='/_matrix/client/r0/rooms/!cNfAnoOGLFCnBPedQw%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:06:27,038 - synapse.access.http.8008 - 461 - INFO - POST-7902 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.060sec/0.000sec (0.010sec, 0.000sec) (0.019sec/0.019sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!cNfAnoOGLFCnBPedQw%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [3 dbevts]
2023-07-27 17:06:27,093 - synapse.access.http.8008 - 461 - INFO - GET-7903 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:29,998 - synapse.access.http.8008 - 461 - INFO - GET-7905 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.001sec, 0.000sec) (0.001sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:32,042 - synapse.access.http.8008 - 461 - INFO - POST-7906 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.036sec/0.001sec (0.010sec, 0.000sec) (0.001sec/0.009sec/3) 2B 200 "POST /_matrix/client/r0/rooms/!cNfAnoOGLFCnBPedQw%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:32,051 - synapse.handlers.sync - 1385 - INFO - GET-7904 - User membership change between getting rooms and current token: @rgabriel:uplink.mit.edu leave !cNfAnoOGLFCnBPedQw:uplink.mit.edu
2023-07-27 17:06:32,062 - synapse.federation.sender - 586 - INFO - process_event_queue_for_federation-104 - Unexpectedly did not have cached prev group for $ek6D6UztZen7ZOxC3ay8D4mGwgl_deJMP2jgB9cZjeI
2023-07-27 17:06:32,084 - synapse.http.client - 414 - INFO - as-sender-zephyr-34 - Received response to PUT http://localhost:29781/_matrix/app/v1/transactions/111454?access_token=<redacted>: 200
2023-07-27 17:06:32,126 - synapse.http.server - 117 - INFO - POST-7907 - <XForwardedForRequest at 0x7f70bcd5c7f0 method='POST' uri='/_matrix/client/r0/rooms/!YmmQeUqkvWIhBICOeY%3Auplink.mit.edu/leave' clientproto='HTTP/1.1' site='8008'> SynapseError: 429 - Too Many Requests
2023-07-27 17:06:32,127 - synapse.access.http.8008 - 461 - INFO - POST-7907 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.066sec/0.001sec (0.014sec, 0.006sec) (0.035sec/0.018sec/8) 80B 429 "POST /_matrix/client/r0/rooms/!YmmQeUqkvWIhBICOeY%3Auplink.mit.edu/leave HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:32,133 - synapse.access.http.8008 - 461 - INFO - GET-7904 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 5.039sec/0.000sec (0.012sec, 0.001sec) (0.054sec/0.024sec/15) 1307B 200 "GET /_matrix/client/r0/sync?filter=0&timeout=30000&since=s43178_6641_0_225_333_7_144_186_0_2 HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [1 dbevts]
2023-07-27 17:06:32,211 - synapse.access.http.8008 - 461 - INFO - GET-7908 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.004sec/0.000sec (0.000sec, 0.000sec) (0.003sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:33,995 - synapse.access.http.8008 - 461 - INFO - GET-7910 - 192.168.122.1 - 8008 - {@rgabriel:uplink.mit.edu} Processed request: 0.002sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/2) 46B 200 "GET /_matrix/client/r0/profile/%40rgabriel%3Auplink.mit.edu HTTP/1.1" "Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/115.0" [0 dbevts]
2023-07-27 17:06:34,412 - synapse.api.auth.internal - 275 - WARNING - GET-7911 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.
2023-07-27 17:06:34,413 - synapse.http.server - 117 - INFO - GET-7911 - <XForwardedForRequest at 0x7f70bcf00a00 method='GET' uri='/_matrix/client/v3/account/whoami' clientproto='HTTP/1.1' site='8008'> SynapseError: 401 - Invalid access token passed.
2023-07-27 17:06:34,414 - synapse.access.http.8008 - 461 - INFO - GET-7911 - 192.168.122.1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 88B 401 "GET /_matrix/client/v3/account/whoami HTTP/1.1" "-" [0 dbevts]
2023-07-27 17:06:34,498 - synapse.api.auth.internal - 275 - WARNING - GET-7912 - Invalid access token in auth: <class 'pymacaroons.exceptions.MacaroonDeserializationException'> cannot determine data format of binary-encoded macaroon.

@seychelles111

This comment was marked as off-topic.

@seychelles111

This comment was marked as off-topic.

@gabrc52
Copy link
Contributor Author

gabrc52 commented Jul 29, 2023

Yes, it's not a public homeserver. Do you need it to debug?

@MatMaul MatMaul self-assigned this Jul 31, 2023
@DMRobertson
Copy link
Contributor

(Note that seychelles111 is not a member of the Synapse core team).

@seychelles111

This comment was marked as off-topic.

@DMRobertson
Copy link
Contributor

  • we should confirm which rate limiter gets hit and why. (Probably the generic one for creating events)

  • can we get Synapse's logs to at least show you which rate limiter you hit?

#16135 should help with this.

@seychelles111

This comment was marked as spam.

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
A-Config Configuration, or the documentation thereof A-Leave Leaving a room O-Occasional Affects or can be seen by some users regularly or most users rarely S-Minor Blocks non-critical functionality, workarounds exist. T-Enhancement New features, changes in functionality, improvements in performance, or user-facing enhancements.
Projects
None yet
Development

No branches or pull requests

4 participants