Skip site navigation (1)Skip section navigation (2)
Date:      Wed, 11 Sep 2019 12:54:58 +0000
From:      bugzilla-noreply@freebsd.org
To:        ports-bugs@FreeBSD.org
Subject:   [Bug 240498] net-im/py-matrix-synapse: Cannot start service
Message-ID:  <bug-240498-7788-kAFJXr322N@https.bugs.freebsd.org/bugzilla/>
In-Reply-To: <bug-240498-7788@https.bugs.freebsd.org/bugzilla/>
References:  <bug-240498-7788@https.bugs.freebsd.org/bugzilla/>

next in thread | previous in thread | raw e-mail | index | archive | help
https://bugs.freebsd.org/bugzilla/show_bug.cgi?id=3D240498

--- Comment #4 from IPTRACE <arkadiusz.majewski@iptrace.pl> ---
# tail -150 /log/app/matrix-synapse/homeserver.log

2019-09-11 14:52:34,818 - root - 226 - WARNING - None - ***** STARTING SERV=
ER
*****
2019-09-11 14:52:34,849 - synapse.util.versionstring - 85 - INFO - None -
Failed to check for git repository: [Errno 2] No such file or directory: 'g=
it':
'git'
2019-09-11 14:52:34,851 - root - 227 - WARNING - None - Server
/usr/local/lib/python3.6/site-packages/synapse/app/homeserver.py version 1.=
2.1
2019-09-11 14:52:34,851 - root - 228 - INFO - None - Server hostname: my.do=
main
2019-09-11 14:52:34,852 - twisted - 254 - INFO - None - Redirected
stdout/stderr to logs
2019-09-11 14:52:34,885 - synapse.util.versionstring - 85 - INFO - None -
Failed to check for git repository: [Errno 2] No such file or directory: 'g=
it':
'git'
2019-09-11 14:52:34,887 - synapse.app.homeserver - 359 - INFO - None -
Preparing database: sqlite3...
2019-09-11 14:52:34,893 - synapse.storage.prepare_database - 242 - INFO - N=
one
- Upgrading schema to v55
2019-09-11 14:52:34,894 - synapse.app.homeserver - 377 - INFO - None - Data=
base
prepared in sqlite3.
2019-09-11 14:52:34,895 - synapse.server - 233 - INFO - None - Setting up.
2019-09-11 14:52:34,914 - synapse.storage.event_push_actions - 500 - INFO -
None - Searching for stream ordering 1 month ago
2019-09-11 14:52:34,915 - synapse.storage.event_push_actions - 505 - INFO -
None - Found stream ordering 1 month ago: it's 0
2019-09-11 14:52:34,915 - synapse.storage.event_push_actions - 507 - INFO -
None - Searching for stream ordering 1 day ago
2019-09-11 14:52:34,916 - synapse.storage.event_push_actions - 512 - INFO -
None - Found stream ordering 1 day ago: it's 0
2019-09-11 14:52:34,919 - synapse.server - 237 - INFO - None - Finished set=
ting
up.
2019-09-11 14:52:34,963 - synapse.app.homeserver - 245 - WARNING -  - Start=
ing
daemon.
2019-09-11 14:52:34,966 - synapse.app.homeserver - 106 - INFO -  - Running
2019-09-11 14:52:34,966 - synapse.app.homeserver - 30 - INFO -  - Set file
limit to: 57870
2019-09-11 14:52:34,981 - synapse.federation.federation_server - 814 - INFO=
 -=20
- Registering federation query handler for 'profile'
2019-09-11 14:52:34,983 - synapse.push.pusher - 41 - INFO -  - email enable
notifs: False
2019-09-11 14:52:34,988 - synapse.federation.federation_server - 814 - INFO=
 -=20
- Registering federation query handler for 'directory'
2019-09-11 14:52:34,990 - synapse.handlers.auth - 84 - INFO -  - Extra
password_providers: []
2019-09-11 14:52:34,992 - synapse.federation.federation_server - 796 - INFO=
 -=20
- Registering federation EDU handler for 'm.device_list_update'
2019-09-11 14:52:34,992 - synapse.federation.federation_server - 814 - INFO=
 -=20
- Registering federation query handler for 'user_devices'
2019-09-11 14:52:35,030 - synapse.federation.federation_server - 796 - INFO=
 -=20
- Registering federation EDU handler for 'm.presence'
2019-09-11 14:52:35,040 - synapse.federation.federation_server - 796 - INFO=
 -=20
- Registering federation EDU handler for 'm.typing'
2019-09-11 14:52:35,054 - synapse.handlers.deactivate_account - 149 - INFO -
user_parter_loop-0 - Starting user parter
2019-09-11 14:52:35,058 - synapse.federation.federation_server - 796 - INFO=
 -=20
- Registering federation EDU handler for 'm.receipt'
2019-09-11 14:52:35,062 - synapse.federation.federation_server - 814 - INFO=
 -=20
- Registering federation query handler for 'client_keys'
2019-09-11 14:52:35,065 - synapse.federation.federation_server - 796 - INFO=
 -=20
- Registering federation EDU handler for 'm.direct_to_device'
2019-09-11 14:52:35,106 - synapse.util.versionstring - 85 - INFO -  - Faile=
d to
check for git repository: [Errno 2] No such file or directory: 'git': 'git'
2019-09-11 14:52:35,132 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.ClientRestResource object at 0x805deda90> to path
b'/_matrix/client/api/v1'
2019-09-11 14:52:35,132 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.ClientRestResource object at 0x805deda90> to path
b'/_matrix/client/r0'
2019-09-11 14:52:35,133 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.ClientRestResource object at 0x805deda90> to path
b'/_matrix/client/unstable'
2019-09-11 14:52:35,133 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.ClientRestResource object at 0x805deda90> to path
b'/_matrix/client/v2_alpha'
2019-09-11 14:52:35,133 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.ClientRestResource object at 0x805deda90> to path
b'/_matrix/client/versions'
2019-09-11 14:52:35,134 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.well_known.WellKnownResource object at 0x805f94588>=
 to
path b'/.well-known/matrix/client'
2019-09-11 14:52:35,134 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.admin.AdminRestResource object at 0x805f94748> to p=
ath
b'/_synapse/admin'
2019-09-11 14:52:35,134 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching FilePath('/usr/local/lib/python3.6/site-packages/synapse/static')=
 to
path b'/_matrix/static'
2019-09-11 14:52:35,135 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource
object at 0x805e48d30> to path b'/_matrix/media/r0'
2019-09-11 14:52:35,135 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource
object at 0x805e48d30> to path b'/_matrix/media/v1'
2019-09-11 14:52:35,135 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.media.v0.content_repository.ContentRepoResource obj=
ect
at 0x805f94f60> to path b'/_matrix/content'
2019-09-11 14:52:35,136 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.federation.transport.server.TransportLayerServer object =
at
0x805f6dfd0> to path b'/_matrix/federation'
2019-09-11 14:52:35,136 - synapse.util.httpresourcetree - 46 - INFO -  -
Attaching <synapse.rest.key.v2.KeyApiV2Resource object at 0x805fa3b00> to p=
ath
b'/_matrix/key/v2'
2019-09-11 14:52:35,142 - twisted - 254 - ERROR -  - Traceback (most recent
call last):
2019-09-11 14:52:35,142 - twisted - 254 - ERROR -  -   File
"/usr/local/lib/python3.6/site-packages/twisted/internet/tcp.py", line 1361=
, in
startListening
2019-09-11 14:52:35,143 - twisted - 254 - ERROR -  -     skt.bind(addr)
2019-09-11 14:52:35,143 - twisted - 254 - ERROR -  - PermissionError: [Errno
13] Permission denied
2019-09-11 14:52:35,144 - twisted - 254 - ERROR -  -
2019-09-11 14:52:35,144 - twisted - 254 - ERROR -  - During handling of the
above exception, another exception occurred:
2019-09-11 14:52:35,144 - twisted - 254 - ERROR -  -
2019-09-11 14:52:35,145 - twisted - 254 - ERROR -  - Traceback (most recent
call last):
2019-09-11 14:52:35,145 - twisted - 254 - ERROR -  -   File
"/usr/local/lib/python3.6/site-packages/synapse/app/_base.py", line 259, in
start
2019-09-11 14:52:35,145 - twisted - 254 - ERROR -  -=20=20=20=20
hs.start_listening(listeners)
2019-09-11 14:52:35,146 - twisted - 254 - ERROR -  -   File
"/usr/local/lib/python3.6/site-packages/synapse/app/homeserver.py", line 26=
6,
in start_listening
2019-09-11 14:52:35,146 - twisted - 254 - ERROR -  -=20=20=20=20
self._listening_services.extend(self._listener_http(config, listener))
2019-09-11 14:52:35,147 - twisted - 254 - ERROR -  -   File
"/usr/local/lib/python3.6/site-packages/synapse/app/homeserver.py", line 15=
2,
in _listener_http
2019-09-11 14:52:35,147 - twisted - 254 - ERROR -  -=20=20=20=20
reactor=3Dself.get_reactor(),
2019-09-11 14:52:35,147 - twisted - 254 - ERROR -  -   File
"/usr/local/lib/python3.6/site-packages/synapse/app/_base.py", line 171, in
listen_tcp
2019-09-11 14:52:35,148 - twisted - 254 - ERROR -  -     check_bind_error(e,
address, bind_addresses)
2019-09-11 14:52:35,148 - twisted - 254 - ERROR -  -   File
"/usr/local/lib/python3.6/site-packages/synapse/app/__init__.py", line 49, =
in
check_bind_error
2019-09-11 14:52:35,149 - twisted - 254 - ERROR -  -     raise e
2019-09-11 14:52:35,149 - twisted - 254 - ERROR -  -   File
"/usr/local/lib/python3.6/site-packages/synapse/app/_base.py", line 169, in
listen_tcp
2019-09-11 14:52:35,149 - twisted - 254 - ERROR -  -=20=20=20=20
r.append(reactor.listenTCP(port, factory, backlog, address))
2019-09-11 14:52:35,150 - twisted - 254 - ERROR -  -   File
"/usr/local/lib/python3.6/site-packages/twisted/internet/posixbase.py", line
495, in listenTCP
2019-09-11 14:52:35,150 - twisted - 254 - ERROR -  -     p.startListening()
2019-09-11 14:52:35,150 - twisted - 254 - ERROR -  -   File
"/usr/local/lib/python3.6/site-packages/twisted/internet/tcp.py", line 1363=
, in
startListening
2019-09-11 14:52:35,151 - twisted - 254 - ERROR -  -     raise
CannotListenError(self.interface, self.port, le)
2019-09-11 14:52:35,151 - twisted - 254 - ERROR -  -
twisted.internet.error.CannotListenError: Couldn't listen on 127.0.0.1:80:
[Errno 13] Permission denied.
2019-09-11 14:52:35,154 - synapse.handlers.deactivate_account - 159 - INFO -
user_parter_loop-0 - User parter finished: stopping
2019-09-11 14:52:35,157 - synapse.handlers.presence - 236 - INFO -
presence.on_shutdown-0 - Performing _on_shutdown. Persisting 0 unpersisted
changes
2019-09-11 14:52:35,160 - synapse.handlers.presence - 247 - INFO -
presence.on_shutdown-0 - Finished _on_shutdown
2019-09-11 14:52:35,241 - twisted - 254 - CRITICAL -  - Unhandled error in
Deferred:
2019-09-11 14:52:35,241 - twisted - 254 - CRITICAL -  -
Traceback (most recent call last):
  File "/usr/local/lib/python3.6/site-packages/twisted/internet/tcp.py", li=
ne
1361, in startListening
    skt.bind(addr)
PermissionError: [Errno 13] Permission denied

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/local/lib/python3.6/site-packages/synapse/app/_base.py", line =
259,
in start
    hs.start_listening(listeners)
  File "/usr/local/lib/python3.6/site-packages/synapse/app/homeserver.py", =
line
266, in start_listening
    self._listening_services.extend(self._listener_http(config, listener))
  File "/usr/local/lib/python3.6/site-packages/synapse/app/homeserver.py", =
line
152, in _listener_http
    reactor=3Dself.get_reactor(),
  File "/usr/local/lib/python3.6/site-packages/synapse/app/_base.py", line =
171,
in listen_tcp
    check_bind_error(e, address, bind_addresses)
  File "/usr/local/lib/python3.6/site-packages/synapse/app/__init__.py", li=
ne
49, in check_bind_error
    raise e
  File "/usr/local/lib/python3.6/site-packages/synapse/app/_base.py", line =
169,
in listen_tcp
    r.append(reactor.listenTCP(port, factory, backlog, address))
  File "/usr/local/lib/python3.6/site-packages/twisted/internet/posixbase.p=
y",
line 495, in listenTCP
    p.startListening()
  File "/usr/local/lib/python3.6/site-packages/twisted/internet/tcp.py", li=
ne
1363, in startListening
    raise CannotListenError(self.interface, self.port, le)
twisted.internet.error.CannotListenError: Couldn't listen on 127.0.0.1:80:
[Errno 13] Permission denied.

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/local/lib/python3.6/site-packages/twisted/internet/defer.py", =
line
1418, in _inlineCallbacks
    result =3D g.send(result)
  File "/usr/local/lib/python3.6/site-packages/synapse/app/homeserver.py", =
line
435, in start
    _base.start(hs, config.listeners)
  File "/usr/local/lib/python3.6/site-packages/synapse/app/_base.py", line =
268,
in start
    sys.exit(1)
SystemExit: 1
2019-09-11 14:52:35,258 - twisted - 254 - INFO -  - Main loop terminated.
2019-09-11 14:52:35,259 - synapse.app.homeserver - 70 - WARNING -  - Stoppi=
ng
daemon.

--=20
You are receiving this mail because:
You are the assignee for the bug.=



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?bug-240498-7788-kAFJXr322N>