Hello,
I can confirm this is happening as well, the journal output is slightly
more informative than the deluged log:
Sep 04 18:32:06 testvm deluged[2132]: Unhandled error in Deferred:
Sep 04 18:32:06 testvm deluged[2132]: Traceback (most recent call last):
Sep 04 18:32:06 testvm deluged[2132]: File
"/usr/lib/python3/dist-packages/deluge/core/daemon_entry.py", line 112,
in run_daemon
Sep 04 18:32:06 testvm deluged[2132]: daemon = Daemon(
Sep 04 18:32:06 testvm deluged[2132]: File
"/usr/lib/python3/dist-packages/deluge/core/daemon.py", line 94, in
__init__
Sep 04 18:32:06 testvm deluged[2132]: log.info('Deluge daemon %s',
get_version())
Sep 04 18:32:06 testvm deluged[2132]: File
"/usr/lib/python3/dist-packages/twisted/internet/defer.py", line 1613,
in unwindGenerator
Sep 04 18:32:06 testvm deluged[2132]: return
_cancellableInlineCallbacks(gen)
Sep 04 18:32:06 testvm deluged[2132]: File
"/usr/lib/python3/dist-packages/twisted/internet/defer.py", line 1529,
in _cancellableInlineCallbacks
Sep 04 18:32:06 testvm deluged[2132]: _inlineCallbacks(None, g,
status)
Sep 04 18:32:06 testvm deluged[2132]: --- <exception caught here> ---
Sep 04 18:32:06 testvm deluged[2132]: File
"/usr/lib/python3/dist-packages/twisted/internet/defer.py", line 1418,
in _inlineCallbacks
Sep 04 18:32:06 testvm deluged[2132]: result = g.send(result)
Sep 04 18:32:06 testvm deluged[2132]: File
"/usr/lib/python3/dist-packages/deluge/log.py", line 69, in info
Sep 04 18:32:06 testvm deluged[2132]: yield
LoggingLoggerClass.info(self, msg, *args, **kwargs)
Sep 04 18:32:06 testvm deluged[2132]: File
"/usr/lib/python3.9/logging/__init__.py", line 1442, in info
Sep 04 18:32:06 testvm deluged[2132]: self._log(INFO, msg, args,
**kwargs)
Sep 04 18:32:06 testvm deluged[2132]: File
"/usr/lib/python3.9/logging/__init__.py", line 1573, in _log
Sep 04 18:32:06 testvm deluged[2132]: fn, lno, func, sinfo =
self.findCaller(stack_info, stacklevel)
Sep 04 18:32:06 testvm deluged[2132]: builtins.TypeError: findCaller()
takes from 1 to 2 positional arguments but 3 were given
Sep 04 18:32:06 testvm deluged[2132]: Temporarily disabling observer
LegacyLogObserverWrapper(<bound method TwistedLoggingObserver.emit of
<deluge.log.TwistedLoggingObserver object at 0x7fef143b84c0>>) due to
exception: [Failure instance: Traceback: <class 'TypeError'>:
findCaller() t
akes from 1 to 2 positional arguments but 3 were given
Sep 04 18:32:06 testvm deluged[2132]:
/usr/lib/python3/dist-packages/pkg_resources/_vendor/pyparsing.py:646:__getattr__
Sep 04 18:32:06 testvm deluged[2132]:
/usr/lib/python3/dist-packages/twisted/internet/defer.py:962:__del__
Sep 04 18:32:06 testvm deluged[2132]:
/usr/lib/python3/dist-packages/twisted/logger/_logger.py:190:failure
Sep 04 18:32:06 testvm deluged[2132]:
/usr/lib/python3/dist-packages/twisted/logger/_logger.py:144:emit
Sep 04 18:32:06 testvm deluged[2132]: --- <exception caught here> ---
Sep 04 18:32:06 testvm deluged[2132]:
/usr/lib/python3/dist-packages/twisted/logger/_observer.py:131:__call__
Sep 04 18:32:06 testvm deluged[2132]:
/usr/lib/python3/dist-packages/twisted/logger/_legacy.py:93:__call__
Sep 04 18:32:06 testvm deluged[2132]:
/usr/lib/python3/dist-packages/deluge/log.py:204:emit
Sep 04 18:32:06 testvm deluged[2132]:
/usr/lib/python3.9/logging/__init__.py:1489:critical
Sep 04 18:32:06 testvm deluged[2132]:
/usr/lib/python3.9/logging/__init__.py:1573:_log
Sep 04 18:32:06 testvm deluged[2132]: ]
It seems like it's bumping into something like this:
https://stackoverflow.com/questions/60859773/flask-logging-error-typeerror-findcaller-takes-from-1-to-2-position-argument.
If you fix that error (i.e. you patch
/usr/lib/python3/dist-packages/deluge/log.py:89 to be: `def
findCaller(self, stack_info=False, stackLevel=1):` then that will fix
the deluged logging which then means that /var/log/deluged/daemon.log
will be full of actually useful information; that said I think this is
actually an upstream issue with deluged, rather than a Debian problem.
On this test VM I have here, it looks like the error I get is:
```
9:25:43 [INFO ][deluge.configmanager:52 ] Setting config directory
to: /var/lib/deluged/config
19:25:44 [INFO ][deluge.core.daemon :94 ] Deluge daemon
2.0.3
19:25:44 [INFO ][deluge.core.core :339 ] Successfully
loaded session.state: /var/lib/deluged/config/session.state
19:25:44 [WARNING ][deluge.core.core :337 ] Unable to load
/var/lib/deluged/config/session.state.bak: [Errno 2] No such file or
directory: '/var/lib/deluged/config/session.state.bak'
19:25:44 [INFO ][deluge.core.alertmanager :148 ] Alert Queue
Size set to 10000
19:25:44 [INFO ][deluge.core.rpcserver :402 ] Starting
DelugeRPC server localhost:58846
19:25:44 [ERROR ][deluge.core.daemon_entry :132 ] Unable to start
deluged: [('SSL routines', 'SSL_CTX_use_certificate', 'ee key too
small')]
19:25:44 [INFO ][deluge.core.daemon_entry :137 ] Exiting...
```
So presumably there are 2 problems; this one may be a debian issue.
-Rob
