SynapseError: 401 - Invalid macaroon passed.

[damnms]

marks will be invisible in the report. -->

Description

When i connect to my homeserver with riot.im (mobile phone/tablet) or nheko (desktop), i get error messages in the synapse log and the clients are not able to send messages. But logging in, creating rooms etc. works

Steps to reproduce

• clean the old debian installation with: apt-get purge matrix-synapse-py3 && rm -rf /var/lib/matrix && rm -rf /etc/matrix
• apt-get install matrix-synapse-py3
• configure homeserver.yaml (point to real certificates, registration_shared_secret, ...)
• start synapse
• connect to the server and send a message to a room

2019-02-10 20:52:54,842 - root - 214 - WARNING - None- ***** STARTING SERVER *****
2019-02-10 20:52:54,865 - root - 217 - WARNING - None- Server /opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/app/homeserver.py version 0.99.0
2019-02-10 20:52:54,865 - root - 219 - INFO - None- Server hostname: matrix.mydomain.de
2019-02-10 20:52:54,887 - synapse.app.homeserver - 358 - INFO - None- Preparing database: sqlite3...
2019-02-10 20:52:54,891 - synapse.storage.prepare_database - 223 - INFO - None- Upgrading schema to v53
2019-02-10 20:52:54,891 - synapse.app.homeserver - 376 - INFO - None- Database prepared in sqlite3.
2019-02-10 20:52:54,892 - synapse.server - 211 - INFO - None- Setting up.
2019-02-10 20:52:54,901 - synapse.storage.event_push_actions - 471 - INFO - None- Searching for stream ordering 1 month ago
2019-02-10 20:52:54,902 - synapse.storage.event_push_actions - 477 - INFO - None- Found stream ordering 1 month ago: it's 0
2019-02-10 20:52:54,902 - synapse.storage.event_push_actions - 479 - INFO - None- Searching for stream ordering 1 day ago
2019-02-10 20:52:54,902 - synapse.storage.event_push_actions - 485 - INFO - None- Found stream ordering 1 day ago: it's 0
2019-02-10 20:52:54,904 - synapse.server - 215 - INFO - None- Finished setting up.
2019-02-10 20:52:54,905 - synapse.app.homeserver - 623 - INFO - None- Scheduling stats reporting for 3 hour intervals
2019-02-10 20:52:54,905 - synapse.app.homeserver - 86 - INFO - - Running
2019-02-10 20:52:54,905 - synapse.app.homeserver - 30 - INFO - - Set file limit to: 4096
2019-02-10 20:52:54,912 - synapse.federation.federation_server - 817 - INFO - - Registering federation query handler for 'profile'
2019-02-10 20:52:54,912 - synapse.push.pusher - 43 - INFO - - email enable notifs: False
2019-02-10 20:52:54,913 - synapse.handlers.auth - 81 - INFO - - Extra password_providers: []
2019-02-10 20:52:54,913 - synapse.federation.federation_server - 817 - INFO - - Registering federation query handler for 'directory'
2019-02-10 20:52:54,921 - synapse.federation.federation_server - 797 - INFO - - Registering federation EDU handler for 'm.presence'
2019-02-10 20:52:54,922 - synapse.federation.federation_server - 797 - INFO - - Registering federation EDU handler for 'm.presence_invite'
2019-02-10 20:52:54,922 - synapse.federation.federation_server - 797 - INFO - - Registering federation EDU handler for 'm.presence_accept'
2019-02-10 20:52:54,922 - synapse.federation.federation_server - 797 - INFO - - Registering federation EDU handler for 'm.presence_deny'
2019-02-10 20:52:54,922 - synapse.federation.federation_server - 797 - INFO - - Registering federation EDU handler for 'm.typing'
2019-02-10 20:52:54,923 - synapse.federation.federation_server - 797 - INFO - - Registering federation EDU handler for 'm.device_list_update'
2019-02-10 20:52:54,923 - synapse.federation.federation_server - 817 - INFO - - Registering federation query handler for 'user_devices'
2019-02-10 20:52:54,924 - synapse.handlers.deactivate_account - 134 - INFO - user_parter_loop-0- Starting user parter
2019-02-10 20:52:54,926 - synapse.federation.federation_server - 797 - INFO - - Registering federation EDU handler for 'm.receipt'
2019-02-10 20:52:54,927 - synapse.federation.federation_server - 817 - INFO - - Registering federation query handler for 'client_keys'
2019-02-10 20:52:54,927 - synapse.federation.federation_server - 797 - INFO - - Registering federation EDU handler for 'm.direct_to_device'
2019-02-10 20:52:54,937 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.rest.media.v0.content_repository.ContentRepoResource object at 0x7f82e22216d8> to path b'/_matrix/content'
2019-02-10 20:52:54,937 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <twisted.web.resource.EncodingResourceWrapper object at 0x7f82e2221b00> to path b'/_matrix/client/api/v1'
2019-02-10 20:52:54,937 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <twisted.web.resource.EncodingResourceWrapper object at 0x7f82e2221b00> to path b'/_matrix/client/v2_alpha'
2019-02-10 20:52:54,937 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.federation.transport.server.TransportLayerServer object at 0x7f82e21722e8> to path b'/_matrix/federation'
2019-02-10 20:52:54,938 - synapse.util.httpresourcetree - 46 - INFO - - Attaching FilePath('/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/static') to path b'/_matrix/static'
2019-02-10 20:52:54,938 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.rest.key.v2.KeyApiV2Resource object at 0x7f82e222c5c0> to path b'/_matrix/key/v2'
2019-02-10 20:52:54,938 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <twisted.web.resource.EncodingResourceWrapper object at 0x7f82e2221b00> to path b'/_matrix/client/versions'
2019-02-10 20:52:54,938 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.rest.well_known.WellKnownResource object at 0x7f82e222ceb8> to path b'/.well-known/matrix/client'
2019-02-10 20:52:54,938 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f82e2172278> to path b'/_matrix/media/v1'
2019-02-10 20:52:54,938 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <twisted.web.resource.EncodingResourceWrapper object at 0x7f82e2221b00> to path b'/_matrix/client/unstable'
2019-02-10 20:52:54,939 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f82e2172278> to path b'/_matrix/media/r0'
2019-02-10 20:52:54,939 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <twisted.web.resource.EncodingResourceWrapper object at 0x7f82e2221b00> to path b'/_matrix/client/r0'
2019-02-10 20:52:54,941 - twisted - 245 - INFO - - SynapseSite (TLS) starting on 8448
2019-02-10 20:52:54,941 - synapse.app - 47 - WARNING - - Failed to listen on 0.0.0.0, continuing because listening on [::]
2019-02-10 20:52:54,942 - synapse.app._base - 190 - INFO - - Synapse now listening on port 8448 (TLS)
2019-02-10 20:52:54,950 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.rest.media.v0.content_repository.ContentRepoResource object at 0x7f82e0075b70> to path b'/_matrix/content'
2019-02-10 20:52:54,950 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <twisted.web.resource.EncodingResourceWrapper object at 0x7f82e0075e10> to path b'/_matrix/client/api/v1'
2019-02-10 20:52:54,950 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <twisted.web.resource.EncodingResourceWrapper object at 0x7f82e0075e10> to path b'/_matrix/client/v2_alpha'
2019-02-10 20:52:54,951 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.federation.transport.server.TransportLayerServer object at 0x7f82e007ceb8> to path b'/_matrix/federation'
2019-02-10 20:52:54,951 - synapse.util.httpresourcetree - 46 - INFO - - Attaching FilePath('/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/static') to path b'/_matrix/static'
2019-02-10 20:52:54,951 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.rest.key.v2.KeyApiV2Resource object at 0x7f82e007c940> to path b'/_matrix/key/v2'
2019-02-10 20:52:54,951 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <twisted.web.resource.EncodingResourceWrapper object at 0x7f82e0075e10> to path b'/_matrix/client/versions'
2019-02-10 20:52:54,951 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.rest.well_known.WellKnownResource object at 0x7f82e222ce80> to path b'/.well-known/matrix/client'
2019-02-10 20:52:54,951 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f82e2172278> to path b'/_matrix/media/v1'
2019-02-10 20:52:54,952 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <twisted.web.resource.EncodingResourceWrapper object at 0x7f82e0075e10> to path b'/_matrix/client/unstable'
2019-02-10 20:52:54,952 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f82e2172278> to path b'/_matrix/media/r0'
2019-02-10 20:52:54,952 - synapse.util.httpresourcetree - 46 - INFO - - Attaching <twisted.web.resource.EncodingResourceWrapper object at 0x7f82e0075e10> to path b'/_matrix/client/r0'
2019-02-10 20:52:54,952 - twisted - 245 - INFO - - SynapseSite starting on 8008
2019-02-10 20:52:54,953 - synapse.app - 47 - WARNING - - Failed to listen on 0.0.0.0, continuing because listening on [::]
2019-02-10 20:52:54,953 - synapse.app._base - 162 - INFO - - Synapse now listening on TCP port 8008
2019-02-10 20:52:54,954 - synapse.storage.background_updates - 99 - INFO - background_updates-0- Starting background schema updates
2019-02-10 20:52:54,955 - synapse.handlers.deactivate_account - 144 - INFO - user_parter_loop-0- User parter finished: stopping
2019-02-10 20:52:54,957 - synapse.app.homeserver - 572 - INFO - - report_stats can use psutil
2019-02-10 20:52:54,957 - synapse.metrics - 374 - INFO - - Collecting gc 1
2019-02-10 20:52:54,999 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:52:55,000 - synapse.push.pusherpool - 201 - INFO - start_pushers-0- Starting 0 pushers
2019-02-10 20:52:55,000 - synapse.push.pusherpool - 204 - INFO - start_pushers-0- Started pushers
2019-02-10 20:52:55,956 - synapse.storage.background_updates - 113 - INFO - background_updates-0- No more background updates to do. Unscheduling background update task.
2019-02-10 20:52:56,178 - synapse.access.http.8008 - 233 - INFO - GET-0- ::1 - 8008 - Received request: GET /_matrix/client/r0/sync?timeout=0&filter=0&since=s65_136_0_16_20_1_1_21_1&set_presence=offline
2019-02-10 20:52:56,181 - synapse.http.server - 85 - INFO - GET-0- <SynapseRequest at 0x7f82e00807b8 method='GET' uri='/_matrix/client/r0/sync?timeout=0&filter=0&since=s65_136_0_16_20_1_1_21_1&set_presence=offline' clientproto='HTTP/1.1' site=8008> SynapseError: 401 - Invalid macaroon passed.
2019-02-10 20:52:56,182 - synapse.access.http.8008 - 302 - INFO - GET-0- ::1 - 8008 - {None} Processed request: 0.004sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/1) 80B 401 "GET /_matrix/client/r0/sync?timeout=0&filter=0&since=s65_136_0_16_20_1_1_21_1&set_presence=offline HTTP/1.1" "Riot.im/0.8.20 (Linux; U; Android 7.1.2; GT-I9300 Build/NJH47; Flavour GooglePlay; MatrixAndroidSDK 0.9.14)" [0 dbevts]
2019-02-10 20:52:56,182 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:52:59,923 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts
2019-02-10 20:53:02,114 - synapse.access.https.8448 - 233 - INFO - GET-1- ::1 - 8448 - Received request: GET /_matrix/client/r0/admin/register
2019-02-10 20:53:02,116 - synapse.access.https.8448 - 302 - INFO - GET-1- ::1 - 8448 - {None} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 123B 200 "GET /_matrix/client/r0/admin/register HTTP/1.1" "python-requests/2.21.0" [0 dbevts]
2019-02-10 20:53:02,129 - synapse.access.https.8448 - 233 - INFO - POST-2- ::1 - 8448 - Received request: POST /_matrix/client/r0/admin/register
2019-02-10 20:53:02,130 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:02,424 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:02,428 - synapse.handlers.auth - 542 - INFO - POST-2- Logging in user @slack:matrix.mydomain.de on device KZQLWPCDMP
2019-02-10 20:53:02,537 - synapse.access.https.8448 - 302 - INFO - POST-2- ::1 - 8448 - {None} Processed request: 0.407sec/0.001sec (0.280sec, 0.000sec) (0.003sec/0.114sec/10) 334B 200 "POST /_matrix/client/r0/admin/register HTTP/1.1" "python-requests/2.21.0" [0 dbevts]
2019-02-10 20:53:04,923 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts
2019-02-10 20:53:04,954 - synapse.storage.TIME - 271 - INFO - - Total database time: 0.002% {add_access_token_to_user(1): 0.001%, get_user_directory_stream_pos(1): 0.000%, register(1): 0.000%} {}
2019-02-10 20:53:08,726 - synapse.access.http.8008 - 233 - INFO - GET-3- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!FPnyUjQEHRQZaOHZRs:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:08,727 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:08,728 - synapse.access.http.8008 - 233 - INFO - GET-4- ::1 - 8008 - Received request: GET /_matrix/client/r0/joined_groups
2019-02-10 20:53:08,729 - synapse.http.server - 85 - INFO - GET-3- <SynapseRequest at 0x7f82e0080c50 method='GET' uri='/_matrix/client/r0/rooms/!FPnyUjQEHRQZaOHZRs:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008> SynapseError: 401 - Invalid macaroon passed.
2019-02-10 20:53:08,731 - synapse.http.server - 85 - INFO - GET-4- <SynapseRequest at 0x7f82e00a4e48 method='GET' uri='/_matrix/client/r0/joined_groups' clientproto='HTTP/1.1' site=8008> SynapseError: 401 - Invalid macaroon passed.
2019-02-10 20:53:08,731 - synapse.access.http.8008 - 302 - INFO - GET-3- ::1 - 8008 - {None} Processed request: 0.004sec/0.001sec (0.000sec, 0.000sec) (0.001sec/0.000sec/1) 80B 401 "GET /_matrix/client/r0/rooms/!FPnyUjQEHRQZaOHZRs:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:08,732 - synapse.access.http.8008 - 302 - INFO - GET-4- ::1 - 8008 - {None} Processed request: 0.003sec/0.001sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 80B 401 "GET /_matrix/client/r0/joined_groups HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:08,733 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:08,733 - synapse.access.http.8008 - 233 - INFO - GET-5- ::1 - 8008 - Received request: GET /_matrix/client/r0/profile/@test2:matrix.mydomain.de
2019-02-10 20:53:08,734 - synapse.access.http.8008 - 233 - INFO - GET-6- ::1 - 8008 - Received request: GET /_matrix/client/r0/sync?since=s65_134_0_16_20_1_1_21_1&timeout=30000
2019-02-10 20:53:08,736 - synapse.http.server - 85 - INFO - GET-6- <SynapseRequest at 0x7f82e00ad160 method='GET' uri='/_matrix/client/r0/sync?since=s65_134_0_16_20_1_1_21_1&timeout=30000' clientproto='HTTP/1.1' site=8008> SynapseError: 401 - Invalid macaroon passed.
2019-02-10 20:53:08,737 - synapse.access.http.8008 - 302 - INFO - GET-6- ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec (0.004sec, 0.000sec) (0.000sec/0.000sec/0) 80B 401 "GET /_matrix/client/r0/sync?since=s65_134_0_16_20_1_1_21_1&timeout=30000 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:08,737 - synapse.http.server - 85 - INFO - GET-5- <SynapseRequest at 0x7f82e2249f60 method='GET' uri='/_matrix/client/r0/profile/@test2:matrix.mydomain.de' clientproto='HTTP/1.1' site=8008> SynapseError: 404 - Profile was not found
2019-02-10 20:53:08,738 - synapse.access.http.8008 - 302 - INFO - GET-5- ::1 - 8008 - {None} Processed request: 0.004sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.002sec/1) 73B 404 "GET /_matrix/client/r0/profile/@test2:matrix.mydomain.de HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:09,923 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts
2019-02-10 20:53:14,923 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts
2019-02-10 20:53:14,954 - synapse.storage.TIME - 271 - INFO - - Total database time: 0.000% {get_profile_displayname(1): 0.000%, get_user_by_access_token(1): 0.000%, _update_client_ips_batch(2): 0.000%} {}
2019-02-10 20:53:17,294 - synapse.access.http.8008 - 233 - INFO - GET-7- ::1 - 8008 - Received request: GET /_matrix/client/versions
2019-02-10 20:53:17,295 - synapse.access.http.8008 - 302 - INFO - GET-7- ::1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 104B 200 "GET /_matrix/client/versions HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:17,296 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:18,603 - synapse.access.http.8008 - 233 - INFO - POST-8- ::1 - 8008 - Received request: POST /_matrix/client/r0/login
2019-02-10 20:53:18,603 - synapse.rest.client.v1.login - 167 - INFO - POST-8- Got login request with identifier: None, medium: None, address: None, user: 'slack'
2019-02-10 20:53:18,895 - synapse.handlers.auth - 542 - INFO - POST-8- Logging in user @slack:matrix.mydomain.de on device GVFEXIXNPR
2019-02-10 20:53:18,899 - synapse.access.http.8008 - 302 - INFO - POST-8- ::1 - 8008 - {None} Processed request: 0.296sec/0.001sec (0.280sec, 0.000sec) (0.001sec/0.006sec/5) 335B 200 "POST /_matrix/client/r0/login HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:19,054 - synapse.access.http.8008 - 233 - INFO - GET-9- ::1 - 8008 - Received request: GET /_matrix/client/r0/profile/@slack:matrix.mydomain.de
2019-02-10 20:53:19,055 - synapse.access.http.8008 - 233 - INFO - GET-10- ::1 - 8008 - Received request: GET /_matrix/client/r0/joined_groups
2019-02-10 20:53:19,056 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:19,059 - synapse.access.http.8008 - 302 - INFO - GET-9- ::1 - 8008 - {None} Processed request: 0.005sec/0.000sec (0.000sec, 0.004sec) (0.001sec/0.002sec/2) 44B 200 "GET /_matrix/client/r0/profile/@slack:matrix.mydomain.de HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:19,061 - synapse.access.http.8008 - 302 - INFO - GET-10- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.005sec/0.000sec (0.000sec, 0.000sec) (0.002sec/0.001sec/2) 34B 200 "GET /_matrix/client/r0/joined_groups HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:19,069 - synapse.access.http.8008 - 233 - INFO - GET-11- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:19,070 - synapse.access.http.8008 - 233 - INFO - GET-12- ::1 - 8008 - Received request: GET /_matrix/client/r0/sync?since=s52_124_0_14_18_1_1_16_1&timeout=30000
2019-02-10 20:53:19,072 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:19,076 - synapse.http.server - 112 - ERROR - GET-11- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82e00a8d30 method='GET' uri='/_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 654, in _runCallbacks
    current.result = callback(current.result, *args, **kw)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1475, in gotResult
    _inlineCallbacks(r, g, status)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v1/room.py", line 479, in on_GET
    event_filter=event_filter,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/pagination.py", line 182, in get_messages
    room_id=room_id
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/streams/events.py", line 81, in get_current_token_for_room
    yield self.sources["room"].get_current_key_for_room(room_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/storage/stream.py", line 446, in get_room_events_max_id
    defer.returnValue("t%d-%d" % (topo, token))
builtins.TypeError: %d format: a number is required, not NoneType
2019-02-10 20:53:19,077 - synapse.access.http.8008 - 302 - INFO - GET-11- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.008sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.002sec/1) 67B 500 "GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:19,086 - synapse.util.caches.response_cache - 151 - INFO - GET-12- [sync]: no cached result for [(@slack:matrix.mydomain.de, 30000, 's52_124_0_14_18_1_1_16_1', None, False, 'GVFEXIXNPR')], calculating new one
2019-02-10 20:53:19,087 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:19,193 - synapse.access.http.8008 - 233 - INFO - GET-13- ::1 - 8008 - Received request: GET /_matrix/client/r0/groups/world/profile
2019-02-10 20:53:19,196 - synapse.http.server - 112 - ERROR - GET-13- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82db7817f0 method='GET' uri='/_matrix/client/r0/groups/world/profile' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1613, in unwindGenerator
    return _cancellableInlineCallbacks(gen)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1529, in _cancellableInlineCallbacks
    _inlineCallbacks(None, g, status)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v2_alpha/groups.py", line 47, in on_GET
    requester_user_id,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/groups_local.py", line 34, in f
    if self.is_mine_id(group_id):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/server.py", line 231, in is_mine_id
    return string.split(":", 1)[1] == self.hostname
builtins.IndexError: list index out of range
2019-02-10 20:53:19,197 - synapse.access.http.8008 - 302 - INFO - GET-13- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.003sec/0.000sec (0.004sec, 0.000sec) (0.000sec/0.000sec/0) 67B 500 "GET /_matrix/client/r0/groups/world/profile HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:19,199 - synapse.access.http.8008 - 233 - INFO - GET-14- ::1 - 8008 - Received request: GET /_matrix/client/r0/groups/world/rooms
2019-02-10 20:53:19,200 - synapse.http.server - 112 - ERROR - GET-14- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82db782630 method='GET' uri='/_matrix/client/r0/groups/world/rooms' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1613, in unwindGenerator
    return _cancellableInlineCallbacks(gen)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1529, in _cancellableInlineCallbacks
    _inlineCallbacks(None, g, status)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v2_alpha/groups.py", line 355, in on_GET
    result = yield self.groups_handler.get_rooms_in_group(group_id, requester_user_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/groups_local.py", line 34, in f
    if self.is_mine_id(group_id):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/server.py", line 231, in is_mine_id
    return string.split(":", 1)[1] == self.hostname
builtins.IndexError: list index out of range
2019-02-10 20:53:19,201 - synapse.access.http.8008 - 302 - INFO - GET-14- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.002sec/0.000sec (0.004sec, 0.000sec) (0.000sec/0.000sec/0) 67B 500 "GET /_matrix/client/r0/groups/world/rooms HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:19,202 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:19,202 - synapse.metrics - 374 - INFO - - Collecting gc 1
2019-02-10 20:53:19,923 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts
2019-02-10 20:53:21,074 - synapse.access.http.8008 - 233 - INFO - GET-15- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:21,076 - synapse.http.server - 112 - ERROR - GET-15- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82e0045400 method='GET' uri='/_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 654, in _runCallbacks
    current.result = callback(current.result, *args, **kw)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1475, in gotResult
    _inlineCallbacks(r, g, status)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v1/room.py", line 479, in on_GET
    event_filter=event_filter,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/pagination.py", line 182, in get_messages
    room_id=room_id
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/streams/events.py", line 81, in get_current_token_for_room
    yield self.sources["room"].get_current_key_for_room(room_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/storage/stream.py", line 446, in get_room_events_max_id
    defer.returnValue("t%d-%d" % (topo, token))
builtins.TypeError: %d format: a number is required, not NoneType
2019-02-10 20:53:21,077 - synapse.access.http.8008 - 302 - INFO - GET-15- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.003sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/1) 67B 500 "GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:23,090 - synapse.access.http.8008 - 233 - INFO - GET-16- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:23,092 - synapse.http.server - 112 - ERROR - GET-16- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82e0040550 method='GET' uri='/_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 654, in _runCallbacks
    current.result = callback(current.result, *args, **kw)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1475, in gotResult
    _inlineCallbacks(r, g, status)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v1/room.py", line 479, in on_GET
    event_filter=event_filter,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/pagination.py", line 182, in get_messages
    room_id=room_id
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/streams/events.py", line 81, in get_current_token_for_room
    yield self.sources["room"].get_current_key_for_room(room_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/storage/stream.py", line 446, in get_room_events_max_id
    defer.returnValue("t%d-%d" % (topo, token))
builtins.TypeError: %d format: a number is required, not NoneType
2019-02-10 20:53:23,093 - synapse.access.http.8008 - 302 - INFO - GET-16- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.003sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/1) 67B 500 "GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:23,093 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:24,923 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts
2019-02-10 20:53:24,955 - synapse.storage.TIME - 271 - INFO - - Total database time: 0.000% {_update_client_ips_batch(2): 0.000%, _get_max_topological_txn(3): 0.000%, store_device(1): 0.000%} {}
2019-02-10 20:53:25,101 - synapse.access.http.8008 - 233 - INFO - GET-17- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:25,104 - synapse.http.server - 112 - ERROR - GET-17- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82e21f92e8 method='GET' uri='/_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 654, in _runCallbacks
    current.result = callback(current.result, *args, **kw)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1475, in gotResult
    _inlineCallbacks(r, g, status)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v1/room.py", line 479, in on_GET
    event_filter=event_filter,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/pagination.py", line 182, in get_messages
    room_id=room_id
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/streams/events.py", line 81, in get_current_token_for_room
    yield self.sources["room"].get_current_key_for_room(room_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/storage/stream.py", line 446, in get_room_events_max_id
    defer.returnValue("t%d-%d" % (topo, token))
builtins.TypeError: %d format: a number is required, not NoneType
2019-02-10 20:53:25,105 - synapse.access.http.8008 - 302 - INFO - GET-17- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.003sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/1) 67B 500 "GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:27,113 - synapse.access.http.8008 - 233 - INFO - GET-18- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:27,116 - synapse.http.server - 112 - ERROR - GET-18- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82e0040d68 method='GET' uri='/_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 654, in _runCallbacks
    current.result = callback(current.result, *args, **kw)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1475, in gotResult
    _inlineCallbacks(r, g, status)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v1/room.py", line 479, in on_GET
    event_filter=event_filter,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/pagination.py", line 182, in get_messages
    room_id=room_id
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/streams/events.py", line 81, in get_current_token_for_room
    yield self.sources["room"].get_current_key_for_room(room_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/storage/stream.py", line 446, in get_room_events_max_id
    defer.returnValue("t%d-%d" % (topo, token))
builtins.TypeError: %d format: a number is required, not NoneType
2019-02-10 20:53:27,116 - synapse.access.http.8008 - 302 - INFO - GET-18- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.003sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/1) 67B 500 "GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:27,117 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:29,130 - synapse.access.http.8008 - 233 - INFO - GET-19- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:29,132 - synapse.http.server - 112 - ERROR - GET-19- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82e009f320 method='GET' uri='/_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 654, in _runCallbacks
    current.result = callback(current.result, *args, **kw)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1475, in gotResult
    _inlineCallbacks(r, g, status)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v1/room.py", line 479, in on_GET
    event_filter=event_filter,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/pagination.py", line 182, in get_messages
    room_id=room_id
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/streams/events.py", line 81, in get_current_token_for_room
    yield self.sources["room"].get_current_key_for_room(room_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/storage/stream.py", line 446, in get_room_events_max_id
    defer.returnValue("t%d-%d" % (topo, token))
builtins.TypeError: %d format: a number is required, not NoneType
2019-02-10 20:53:29,133 - synapse.access.http.8008 - 302 - INFO - GET-19- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.003sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/1) 67B 500 "GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:29,874 - synapse.access.http.8008 - 233 - INFO - POST-20- ::1 - 8008 - Received request: POST /_matrix/client/r0/logout
2019-02-10 20:53:29,881 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:29,886 - synapse.access.http.8008 - 302 - INFO - POST-20- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.012sec/0.000sec (0.004sec, 0.000sec) (0.001sec/0.006sec/5) 22B 200 "POST /_matrix/client/r0/logout HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:29,923 - synapse.handlers.presence - 350 - INFO - - Handling presence timeouts
2019-02-10 20:53:29,924 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts
2019-02-10 20:53:34,923 - synapse.handlers.presence - 350 - INFO - - Handling presence timeouts
2019-02-10 20:53:34,924 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts
2019-02-10 20:53:34,955 - synapse.storage.TIME - 271 - INFO - - Total database time: 0.000% {delete_device(1): 0.000%, user_delete_access_tokens(1): 0.000%, add_device_change_to_streams(1): 0.000%} {}
2019-02-10 20:53:39,923 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts
2019-02-10 20:53:39,924 - synapse.handlers.presence - 350 - INFO - - Handling presence timeouts
2019-02-10 20:53:40,985 - synapse.access.http.8008 - 233 - INFO - GET-21- ::1 - 8008 - Received request: GET /_matrix/client/versions
2019-02-10 20:53:40,986 - synapse.access.http.8008 - 302 - INFO - GET-21- ::1 - 8008 - {None} Processed request: 0.001sec/0.000sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 104B 200 "GET /_matrix/client/versions HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:42,122 - synapse.access.http.8008 - 233 - INFO - POST-22- ::1 - 8008 - Received request: POST /_matrix/client/r0/login
2019-02-10 20:53:42,122 - synapse.rest.client.v1.login - 167 - INFO - POST-22- Got login request with identifier: None, medium: None, address: None, user: 'slack'
2019-02-10 20:53:42,123 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:42,416 - synapse.handlers.auth - 542 - INFO - POST-22- Logging in user @slack:matrix.mydomain.de on device ZCTYQPMKCR
2019-02-10 20:53:42,420 - synapse.access.http.8008 - 302 - INFO - POST-22- ::1 - 8008 - {None} Processed request: 0.298sec/0.000sec (0.288sec, 0.000sec) (0.002sec/0.005sec/5) 335B 200 "POST /_matrix/client/r0/login HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:42,549 - synapse.access.http.8008 - 233 - INFO - GET-23- ::1 - 8008 - Received request: GET /_matrix/client/r0/profile/@slack:matrix.mydomain.de
2019-02-10 20:53:42,553 - synapse.access.http.8008 - 233 - INFO - GET-24- ::1 - 8008 - Received request: GET /_matrix/client/r0/joined_groups
2019-02-10 20:53:42,555 - synapse.access.http.8008 - 302 - INFO - GET-23- ::1 - 8008 - {None} Processed request: 0.005sec/0.000sec (0.004sec, 0.000sec) (0.002sec/0.002sec/2) 44B 200 "GET /_matrix/client/r0/profile/@slack:matrix.mydomain.de HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:42,557 - synapse.access.http.8008 - 302 - INFO - GET-24- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.004sec/0.000sec (0.004sec, 0.000sec) (0.001sec/0.001sec/2) 34B 200 "GET /_matrix/client/r0/joined_groups HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:42,577 - synapse.access.http.8008 - 233 - INFO - GET-25- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:42,579 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:42,580 - synapse.http.server - 112 - ERROR - GET-25- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82db7afcc0 method='GET' uri='/_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 654, in _runCallbacks
    current.result = callback(current.result, *args, **kw)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1475, in gotResult
    _inlineCallbacks(r, g, status)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v1/room.py", line 479, in on_GET
    event_filter=event_filter,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/pagination.py", line 182, in get_messages
    room_id=room_id
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/streams/events.py", line 81, in get_current_token_for_room
    yield self.sources["room"].get_current_key_for_room(room_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/storage/stream.py", line 446, in get_room_events_max_id
    defer.returnValue("t%d-%d" % (topo, token))
builtins.TypeError: %d format: a number is required, not NoneType
2019-02-10 20:53:42,581 - synapse.access.http.8008 - 302 - INFO - GET-25- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.003sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.000sec/1) 67B 500 "GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:42,585 - synapse.access.http.8008 - 233 - INFO - GET-26- ::1 - 8008 - Received request: GET /_matrix/client/r0/sync?since=s52_124_0_14_18_1_1_16_1&timeout=30000
2019-02-10 20:53:42,592 - synapse.util.caches.response_cache - 151 - INFO - GET-26- [sync]: no cached result for [(@slack:matrix.mydomain.de, 30000, 's52_124_0_14_18_1_1_16_1', None, False, 'ZCTYQPMKCR')], calculating new one
2019-02-10 20:53:42,685 - synapse.access.http.8008 - 233 - INFO - GET-27- ::1 - 8008 - Received request: GET /_matrix/client/r0/groups/world/rooms
2019-02-10 20:53:42,686 - synapse.http.server - 112 - ERROR - GET-27- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82db7b0470 method='GET' uri='/_matrix/client/r0/groups/world/rooms' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1613, in unwindGenerator
    return _cancellableInlineCallbacks(gen)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1529, in _cancellableInlineCallbacks
    _inlineCallbacks(None, g, status)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v2_alpha/groups.py", line 355, in on_GET
    result = yield self.groups_handler.get_rooms_in_group(group_id, requester_user_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/groups_local.py", line 34, in f
    if self.is_mine_id(group_id):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/server.py", line 231, in is_mine_id
    return string.split(":", 1)[1] == self.hostname
builtins.IndexError: list index out of range
2019-02-10 20:53:42,687 - synapse.access.http.8008 - 233 - INFO - GET-28- ::1 - 8008 - Received request: GET /_matrix/client/r0/groups/world/profile
2019-02-10 20:53:42,688 - synapse.http.server - 112 - ERROR - GET-28- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82db7b0550 method='GET' uri='/_matrix/client/r0/groups/world/profile' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1613, in unwindGenerator
    return _cancellableInlineCallbacks(gen)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1529, in _cancellableInlineCallbacks
    _inlineCallbacks(None, g, status)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v2_alpha/groups.py", line 47, in on_GET
    requester_user_id,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/groups_local.py", line 34, in f
    if self.is_mine_id(group_id):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/server.py", line 231, in is_mine_id
    return string.split(":", 1)[1] == self.hostname
builtins.IndexError: list index out of range
2019-02-10 20:53:42,689 - synapse.access.http.8008 - 302 - INFO - GET-27- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.002sec/0.002sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 67B 500 "GET /_matrix/client/r0/groups/world/rooms HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:42,689 - synapse.access.http.8008 - 302 - INFO - GET-28- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.001sec/0.001sec (0.000sec, 0.000sec) (0.000sec/0.000sec/0) 67B 500 "GET /_matrix/client/r0/groups/world/profile HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:42,689 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:44,585 - synapse.access.http.8008 - 233 - INFO - GET-29- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:44,588 - synapse.http.server - 112 - ERROR - GET-29- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82db7b0208 method='GET' uri='/_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 654, in _runCallbacks
    current.result = callback(current.result, *args, **kw)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1475, in gotResult
    _inlineCallbacks(r, g, status)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v1/room.py", line 479, in on_GET
    event_filter=event_filter,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/pagination.py", line 182, in get_messages
    room_id=room_id
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/streams/events.py", line 81, in get_current_token_for_room
    yield self.sources["room"].get_current_key_for_room(room_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/storage/stream.py", line 446, in get_room_events_max_id
    defer.returnValue("t%d-%d" % (topo, token))
builtins.TypeError: %d format: a number is required, not NoneType
2019-02-10 20:53:44,589 - synapse.access.http.8008 - 302 - INFO - GET-29- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.003sec/0.000sec (0.004sec, 0.000sec) (0.001sec/0.000sec/1) 67B 500 "GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:44,924 - synapse.handlers.presence - 350 - INFO - - Handling presence timeouts
2019-02-10 20:53:44,925 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts
2019-02-10 20:53:44,954 - synapse.storage.TIME - 271 - INFO - - Total database time: 0.000% {_update_client_ips_batch(2): 0.000%, store_device(1): 0.000%, add_device_change_to_streams(1): 0.000%} {}
2019-02-10 20:53:46,598 - synapse.access.http.8008 - 233 - INFO - GET-30- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:46,599 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:46,601 - synapse.http.server - 112 - ERROR - GET-30- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82db7ad828 method='GET' uri='/_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 654, in _runCallbacks
    current.result = callback(current.result, *args, **kw)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1475, in gotResult
    _inlineCallbacks(r, g, status)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v1/room.py", line 479, in on_GET
    event_filter=event_filter,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/pagination.py", line 182, in get_messages
    room_id=room_id
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/streams/events.py", line 81, in get_current_token_for_room
    yield self.sources["room"].get_current_key_for_room(room_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/storage/stream.py", line 446, in get_room_events_max_id
    defer.returnValue("t%d-%d" % (topo, token))
builtins.TypeError: %d format: a number is required, not NoneType
2019-02-10 20:53:46,602 - synapse.access.http.8008 - 302 - INFO - GET-30- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.004sec/0.000sec (0.000sec, 0.000sec) (0.001sec/0.001sec/1) 67B 500 "GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:48,609 - synapse.access.http.8008 - 233 - INFO - GET-31- ::1 - 8008 - Received request: GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30
2019-02-10 20:53:48,612 - synapse.http.server - 112 - ERROR - GET-31- Failed handle request via <function JsonResource._async_render at 0x7f82e3e56598>: <SynapseRequest at 0x7f82db7ad9e8 method='GET' uri='/_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30' clientproto='HTTP/1.1' site=8008>: Traceback (most recent call last):
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 654, in _runCallbacks
    current.result = callback(current.result, *args, **kw)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1475, in gotResult
    _inlineCallbacks(r, g, status)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
--- <exception caught here> ---
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 81, in wrapped_request_handler
    yield h(self, request)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/http/server.py", line 316, in _async_render
    callback_return = yield callback(request, **kwargs)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/rest/client/v1/room.py", line 479, in on_GET
    event_filter=event_filter,
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/handlers/pagination.py", line 182, in get_messages
    room_id=room_id
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1416, in _inlineCallbacks
    result = result.throwExceptionIntoGenerator(g)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/python/failure.py", line 491, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/streams/events.py", line 81, in get_current_token_for_room
    yield self.sources["room"].get_current_key_for_room(room_id)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
    result = g.send(result)
  File "/opt/venvs/matrix-synapse/lib/python3.5/site-packages/synapse/storage/stream.py", line 446, in get_room_events_max_id
    defer.returnValue("t%d-%d" % (topo, token))
builtins.TypeError: %d format: a number is required, not NoneType
2019-02-10 20:53:48,613 - synapse.access.http.8008 - 302 - INFO - GET-31- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 0.003sec/0.000sec (0.000sec, 0.004sec) (0.001sec/0.000sec/1) 67B 500 "GET /_matrix/client/r0/rooms/!DjVCLHcgyPEsrNcGYH:matrix.mydomain.de/messages?dir=b&limit=30 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:49,088 - synapse.handlers.sync - 906 - INFO - GET-12- Calculating sync response for @slack:matrix.mydomain.de between StreamToken(room_key='s52', presence_key='124', typing_key='0', receipt_key='14', account_data_key='18', push_rules_key='1', to_device_key='1', device_list_key='16', groups_key='1') and StreamToken(room_key='s1', presence_key=2, typing_key=0, receipt_key=1, account_data_key=1, push_rules_key=1, to_device_key=1, device_list_key=5, groups_key=1)
2019-02-10 20:53:49,091 - synapse.metrics - 374 - INFO - - Collecting gc 0
2019-02-10 20:53:49,095 - synapse.access.http.8008 - 302 - INFO - GET-12- ::1 - 8008 - {@slack:matrix.mydomain.de} Processed request: 30.024sec/0.000sec (0.004sec, 0.004sec) (0.003sec/0.005sec/5) 171B 200 "GET /_matrix/client/r0/sync?since=s52_124_0_14_18_1_1_16_1&timeout=30000 HTTP/1.1" "mtxclient v0.2.0" [0 dbevts]
2019-02-10 20:53:49,230 - synapse.access.http.8008 - 233 - INFO - GET-32- ::1 - 8008 - Received request: GET /_matrix/client/r0/sync?since=s1_2_0_1_1_1_1_5_1&timeout=30000
2019-02-10 20:53:49,232 - synapse.util.caches.response_cache - 151 - INFO - GET-32- [sync]: no cached result for [(@slack:matrix.mydomain.de, 30000, 's1_2_0_1_1_1_1_5_1', None, False, 'ZCTYQPMKCR')], calculating new one
2019-02-10 20:53:49,923 - synapse.handlers.presence - 350 - INFO - - Handling presence timeouts
2019-02-10 20:53:49,924 - synapse.handlers.typing - 89 - INFO - - Checking for typing timeouts

Version information

• Homeserver: another

If not matrix.org: private, sorry - can write that as private message

• Version: ii matrix-synapse-py3 0.99.0+stretch1 amd64

• Install method: apt-get install matrix-synapse

• Platform: debian 9, x64

  its a private server (vps), i do not know what kind of virtualisation the provider uses, but its not containerized - maybe some kind of kvm

[richvdh]

is this still a problem?

[damnms]

can't reproduce anymore, maybe it was also a configuration problem.

[damnms]

My bad, this problem still occurs with Nheko reborn. With riot, i do not see any error message. But riot is unable to send messages.

[richvdh]

With riot, i do not see any error message. But riot is unable to send messages.

I'm confused?

[damnms]

I found the problem. mod_security was blocking the PUT request. Riot does not throw an exception when the request got blocked and nheko just continued in trying to send the message, therefore the invalid macaroon. Can be closed i guess, after modifying the ruleset of mod_security, everything seems to work.

[richvdh]

Riot does not throw an exception when the request got blocked

this sounds like it might be a bug to raise against riot?