Hi all,
Since yesterday, I cannot login to the Deluge WebGUI anymore. I don't remember doing anything special. I restarted the docker but it did not help.
Here is the log (I don't see any obvious error). Any Idea ?
Many thanks !
G
2019-11-14 01:05:36,353 WARN received SIGTERM indicating exit request
2019-11-14 01:05:36,354 DEBG killing watchdog-script (pid 144) with signal SIGTERM
2019-11-14 01:05:36,354 INFO waiting for start-script, watchdog-script to die
2019-11-14 01:05:37,355 DEBG fd 16 closed, stopped monitoring <POutputDispatcher at 22528258681544 for <Subprocess at 22528259310312 with name watchdog-script in state STOPPING> (stdout)>
2019-11-14 01:05:37,355 DEBG fd 20 closed, stopped monitoring <POutputDispatcher at 22528258681712 for <Subprocess at 22528259310312 with name watchdog-script in state STOPPING> (stderr)>
2019-11-14 01:05:37,355 INFO stopped: watchdog-script (terminated by SIGTERM)
2019-11-14 01:05:37,355 DEBG received SIGCHLD indicating a child quit
2019-11-14 01:05:37,355 DEBG killing start-script (pid 143) with signal SIGTERM
2019-11-14 01:05:38,357 DEBG fd 11 closed, stopped monitoring <POutputDispatcher at 22528258681152 for <Subprocess at 22528259215200 with name start-script in state STOPPING> (stdout)>
2019-11-14 01:05:38,357 DEBG fd 15 closed, stopped monitoring <POutputDispatcher at 22528258681320 for <Subprocess at 22528259215200 with name start-script in state STOPPING> (stderr)>
2019-11-14 01:05:38,357 INFO stopped: start-script (terminated by SIGTERM)
2019-11-14 01:05:38,357 DEBG received SIGCHLD indicating a child quit
Created by...
___. .__ .__
\_ |__ |__| ____ | |__ ____ ___ ___
| __ \| |/ \| | \_/ __ \\ \/ /
| \_\ \ | | \ Y \ ___/ > <
|___ /__|___| /___| /\___ >__/\_ \
\/ \/ \/ \/ \/
https://hub.docker.com/u/binhex/
2019-11-14 01:05:44.724363 [info] System information Linux 09c127340e4c 4.19.56-Unraid #1 SMP Tue Jun 25 10:19:34 PDT 2019 x86_64 GNU/Linux
2019-11-14 01:05:44.751494 [info] PUID defined as '99'
2019-11-14 01:05:44.787506 [info] PGID defined as '100'
2019-11-14 01:05:44.846095 [info] UMASK defined as '000'
2019-11-14 01:05:44.869266 [info] Permissions already set for volume mappings
2019-11-14 01:05:44.906574 [info] DELUGE_DAEMON_LOG_LEVEL defined as 'info'
2019-11-14 01:05:44.935647 [info] DELUGE_WEB_LOG_LEVEL defined as 'info'
2019-11-14 01:05:44.967666 [info] VPN_ENABLED defined as 'yes'
2019-11-14 01:05:45.007881 [info] OpenVPN config file (ovpn extension) is located at /config/openvpn/TorGuard.port-forwared.IPs.ovpn
dos2unix: converting file /config/openvpn/port-forwared.IPs.ovpn to Unix format...
2019-11-14 01:05:45.048223 [info] VPN remote line defined as 'remote [IP REDACTED] 1912'
2019-11-14 01:05:45.074104 [info] VPN_REMOTE defined as '[IP REDACTED]'
2019-11-14 01:05:45.101580 [info] VPN_PORT defined as '1912'
2019-11-14 01:05:45.128008 [info] VPN_PROTOCOL defined as 'udp'
2019-11-14 01:05:45.158134 [info] VPN_DEVICE_TYPE defined as 'tun0'
2019-11-14 01:05:45.189768 [info] VPN_PROV defined as 'custom'
2019-11-14 01:05:45.225091 [info] LAN_NETWORK defined as '192.168.1.0/24'
2019-11-14 01:05:45.262771 [info] NAME_SERVERS defined as '[IP REDACTED],[IP REDACTED],[IP REDACTED],[IP REDACTED],[IP REDACTED]'
2019-11-14 01:05:45.303731 [info] VPN_USER defined as 'XXX'
2019-11-14 01:05:45.331435 [info] VPN_PASS defined as 'XXX'
2019-11-14 01:05:45.356103 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS)
2019-11-14 01:05:45.380372 [info] ENABLE_PRIVOXY defined as 'no'
2019-11-14 01:05:45.406197 [info] Starting Supervisor...
2019-11-14 01:05:45,613 INFO Included extra file "/etc/supervisor/conf.d/delugevpn.conf" during parsing
2019-11-14 01:05:45,613 INFO Set uid to user 0 succeeded
2019-11-14 01:05:45,616 INFO supervisord started with pid 7
2019-11-14 01:05:46,618 INFO spawned: 'privoxy-script' with pid 142
2019-11-14 01:05:46,620 INFO spawned: 'start-script' with pid 143
2019-11-14 01:05:46,622 INFO spawned: 'watchdog-script' with pid 144
2019-11-14 01:05:46,622 INFO reaped unknown pid 8
2019-11-14 01:05:46,629 DEBG fd 8 closed, stopped monitoring <POutputDispatcher at 23094013354672 for <Subprocess at 23094013250136 with name privoxy-script in state STARTING> (stdout)>
2019-11-14 01:05:46,629 DEBG fd 10 closed, stopped monitoring <POutputDispatcher at 23094012725232 for <Subprocess at 23094013250136 with name privoxy-script in state STARTING> (stderr)>
2019-11-14 01:05:46,629 INFO success: privoxy-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2019-11-14 01:05:46,630 INFO success: start-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2019-11-14 01:05:46,630 INFO success: watchdog-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2019-11-14 01:05:46,630 INFO exited: privoxy-script (exit status 0; expected)
2019-11-14 01:05:46,630 DEBG received SIGCHLD indicating a child quit
2019-11-14 01:05:46,631 DEBG 'start-script' stdout output:
[info] VPN is enabled, beginning configuration of VPN
2019-11-14 01:05:46,632 DEBG 'watchdog-script' stderr output:
dos2unix: converting file /config/core.conf to Unix format...
2019-11-14 01:05:46,637 DEBG 'start-script' stdout output:
[warn] Username contains characters which could cause authentication issues, please consider changing this if possible
2019-11-14 01:05:46,641 DEBG 'start-script' stdout output:
[warn] Password contains characters which could cause authentication issues, please consider changing this if possible
2019-11-14 01:05:46,700 DEBG 'start-script' stdout output:
[info] Default route for container is 172.17.0.1
2019-11-14 01:05:46,703 DEBG 'start-script' stdout output:
[info] Adding [IP REDACTED] to /etc/resolv.conf
2019-11-14 01:05:46,705 DEBG 'start-script' stdout output:
[info] Adding [IP REDACTED] to /etc/resolv.conf
2019-11-14 01:05:46,708 DEBG 'start-script' stdout output:
[info] Adding [IP REDACTED] to /etc/resolv.conf
2019-11-14 01:05:46,711 DEBG 'start-script' stdout output:
[info] Adding [IP REDACTED] to /etc/resolv.conf
2019-11-14 01:05:46,713 DEBG 'start-script' stdout output:
[info] Adding [IP REDACTED] to /etc/resolv.conf
2019-11-14 01:05:46,716 DEBG 'start-script' stdout output:
[IP REDACTED]
2019-11-14 01:05:46,755 DEBG 'start-script' stdout output:
[info] Docker network defined as 172.17.0.0/16
2019-11-14 01:05:46,760 DEBG 'start-script' stdout output:
[info] Adding 192.168.1.0/24 as route via docker eth0
2019-11-14 01:05:46,761 DEBG 'start-script' stdout output:
[info] ip route defined as follows...
--------------------
2019-11-14 01:05:46,762 DEBG 'start-script' stdout output:
default via 172.17.0.1 dev eth0
172.17.0.0/16 dev eth0 proto kernel scope link src 172.17.0.2
192.168.1.0/24 via 172.17.0.1 dev eth0
2019-11-14 01:05:46,762 DEBG 'start-script' stdout output:
--------------------
2019-11-14 01:05:46,764 DEBG 'start-script' stdout output:
iptable_mangle 16384 5
ip_tables 24576 12 iptable_filter,iptable_nat,iptable_mangle
2019-11-14 01:05:46,765 DEBG 'start-script' stdout output:
[info] iptable_mangle support detected, adding fwmark for tables
2019-11-14 01:05:46,821 DEBG 'start-script' stdout output:
[info] iptables defined as follows...
--------------------
2019-11-14 01:05:46,823 DEBG 'start-script' stdout output:
-P INPUT DROP
-P FORWARD ACCEPT
-P OUTPUT DROP
-A INPUT -i tun0 -j ACCEPT
-A INPUT -s 172.17.0.0/16 -d 172.17.0.0/16 -j ACCEPT
-A INPUT -i eth0 -p udp -m udp --sport 1912 -j ACCEPT
-A INPUT -i eth0 -p tcp -m tcp --dport 8112 -j ACCEPT
-A INPUT -i eth0 -p tcp -m tcp --sport 8112 -j ACCEPT
-A INPUT -s 192.168.1.0/24 -i eth0 -p tcp -m tcp --dport 58846 -j ACCEPT
-A INPUT -p icmp -m icmp --icmp-type 0 -j ACCEPT
-A INPUT -i lo -j ACCEPT
-A OUTPUT -o tun0 -j ACCEPT
-A OUTPUT -s 172.17.0.0/16 -d 172.17.0.0/16 -j ACCEPT
-A OUTPUT -o eth0 -p udp -m udp --dport 1912 -j ACCEPT
-A OUTPUT -o eth0 -p tcp -m tcp --dport 8112 -j ACCEPT
-A OUTPUT -o eth0 -p tcp -m tcp --sport 8112 -j ACCEPT
-A OUTPUT -d 192.168.1.0/24 -o eth0 -p tcp -m tcp --sport 58846 -j ACCEPT
-A OUTPUT -p icmp -m icmp --icmp-type 8 -j ACCEPT
-A OUTPUT -o lo -j ACCEPT
2019-11-14 01:05:46,823 DEBG 'start-script' stdout output:
--------------------
2019-11-14 01:05:46,824 DEBG 'start-script' stdout output:
[info] Starting OpenVPN...
2019-11-14 01:05:46,853 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:46 2019 WARNING: file 'credentials.conf' is group or others accessible
Thu Nov 14 01:05:46 2019 OpenVPN 2.4.7 [git:makepkg/2b8aec62d5db2c17+] x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Feb 19 2019
Thu Nov 14 01:05:46 2019 library versions: OpenSSL 1.1.1b 26 Feb 2019, LZO 2.10
2019-11-14 01:05:46,854 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:46 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2019-11-14 01:05:46,854 DEBG 'start-script' stdout output:
[info] OpenVPN started
2019-11-14 01:05:46,854 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:46 2019 Outgoing Control Channel Authentication: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Nov 14 01:05:46 2019 Incoming Control Channel Authentication: Using 256 bit message hash 'SHA256' for HMAC authentication
2019-11-14 01:05:46,855 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:46 2019 TCP/UDP: Preserving recently used remote address: [AF_INET][IP REDACTED]:1912
Thu Nov 14 01:05:46 2019 Socket Buffers: R=[212992->786432] S=[212992->786432]
Thu Nov 14 01:05:46 2019 UDP link local: (not bound)
Thu Nov 14 01:05:46 2019 UDP link remote: [AF_INET][IP REDACTED]:1912
2019-11-14 01:05:46,873 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:46 2019 TLS: Initial packet from [AF_INET][IP REDACTED]:1912, sid=fd606e88 b4a0a141
2019-11-14 01:05:46,923 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:46 2019 VERIFY OK: depth=1, CN=TG-VPN-CA
2019-11-14 01:05:46,923 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:46 2019 VERIFY KU OK
Thu Nov 14 01:05:46 2019 Validating certificate extended key usage
Thu Nov 14 01:05:46 2019 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Thu Nov 14 01:05:46 2019 VERIFY EKU OK
Thu Nov 14 01:05:46 2019 VERIFY OK: depth=0, CN=server
2019-11-14 01:05:48,641 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:48 2019 WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1582', remote='link-mtu 1569'
Thu Nov 14 01:05:48 2019 WARNING: 'tun-mtu' is used inconsistently, local='tun-mtu 1532', remote='tun-mtu 1500'
Thu Nov 14 01:05:48 2019 WARNING: 'comp-lzo' is present in local config but missing in remote config, local='comp-lzo'
Thu Nov 14 01:05:48 2019 WARNING: 'cipher' is used inconsistently, local='cipher AES-256-GCM', remote='cipher AES-128-CBC'
Thu Nov 14 01:05:48 2019 WARNING: 'auth' is used inconsistently, local='auth [null-digest]', remote='auth SHA256'
Thu Nov 14 01:05:48 2019 WARNING: 'keysize' is used inconsistently, local='keysize 256', remote='keysize 128'
2019-11-14 01:05:48,642 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:48 2019 Control Channel: TLSv1.2, cipher TLSv1.2 DHE-RSA-AES256-GCM-SHA384, 2048 bit RSA
Thu Nov 14 01:05:48 2019 [server] Peer Connection Initiated with [AF_INET][IP REDACTED]:1912
2019-11-14 01:05:49,891 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 SENT CONTROL [server]: 'PUSH_REQUEST' (status=1)
2019-11-14 01:05:49,909 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 PUSH: Received control message: 'PUSH_REPLY,redirect-gateway def1,dhcp-option DNS [IP REDACTED],dhcp-option DNS 1.0.0.1,sndbuf 524288,rcvbuf 524288,route [IP REDACTED],topology net30,ping 5,ping-restart 30,compress,ifconfig [IP REDACTED] [IP REDACTED],peer-id 12'
2019-11-14 01:05:49,910 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 OPTIONS IMPORT: timers and/or timeouts modified
Thu Nov 14 01:05:49 2019 OPTIONS IMPORT: compression parms modified
Thu Nov 14 01:05:49 2019 OPTIONS IMPORT: --sndbuf/--rcvbuf options modified
Thu Nov 14 01:05:49 2019 Socket Buffers: R=[786432->1048576] S=[786432->1048576]
Thu Nov 14 01:05:49 2019 OPTIONS IMPORT: --ifconfig/up options modified
Thu Nov 14 01:05:49 2019 OPTIONS IMPORT: route options modified
Thu Nov 14 01:05:49 2019 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Thu Nov 14 01:05:49 2019 OPTIONS IMPORT: peer-id set
Thu Nov 14 01:05:49 2019 OPTIONS IMPORT: adjusting link_mtu to 1657
Thu Nov 14 01:05:49 2019 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Thu Nov 14 01:05:49 2019 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Thu Nov 14 01:05:49 2019 ROUTE_GATEWAY 172.17.0.1/255.255.0.0 IFACE=eth0 HWADDR=XXX
2019-11-14 01:05:49,910 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 TUN/TAP device tun0 opened
Thu Nov 14 01:05:49 2019 TUN/TAP TX queue length set to 100
Thu Nov 14 01:05:49 2019 /usr/bin/ip link set dev tun0 up mtu 1500
2019-11-14 01:05:49,911 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 /usr/bin/ip addr add dev tun0 local [IP REDACTED] peer [IP REDACTED]
2019-11-14 01:05:49,912 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 /root/openvpnup.sh tun0 1500 1585 [IP REDACTED] [IP REDACTED] init
2019-11-14 01:05:49,914 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 /usr/bin/ip route add [IP REDACTED]/32 via 172.17.0.1
2019-11-14 01:05:49,917 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 /usr/bin/ip route add 0.0.0.0/1 via [IP REDACTED]
2019-11-14 01:05:49,918 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 /usr/bin/ip route add 128.0.0.0/1 via [IP REDACTED]
2019-11-14 01:05:49,920 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 /usr/bin/ip route add [IP REDACTED]/32 via [IP REDACTED]
2019-11-14 01:05:49,921 DEBG 'start-script' stdout output:
Thu Nov 14 01:05:49 2019 Initialization Sequence Completed
2019-11-14 01:05:49,966 DEBG 'watchdog-script' stdout output:
[info] Deluge listening interface IP 0.0.0.0 and VPN provider IP [IP REDACTED] different, marking for reconfigure
2019-11-14 01:05:49,971 DEBG 'watchdog-script' stdout output:
[info] Deluge not running
2019-11-14 01:05:49,974 DEBG 'watchdog-script' stdout output:
[info] Deluge Web UI not running
2019-11-14 01:05:50,106 DEBG 'start-script' stdout output:
[info] Successfully retrieved external IP address [IP REDACTED]
2019-11-14 01:05:50,176 DEBG 'watchdog-script' stdout output:
[info] Attempting to start Deluge...
[info] Removing deluge pid file (if it exists)...
2019-11-14 01:05:50,453 DEBG 'watchdog-script' stdout output:
[info] Deluge listening interface currently defined as 0.0.0.0
[info] Deluge listening interface will be changed to 0.0.0.0
[info] Saving changes to Deluge config file /config/core.conf...
2019-11-14 01:05:50,753 DEBG 'watchdog-script' stdout output:
[info] Deluge process started
[info] Waiting for Deluge process to start listening on port 58846...
2019-11-14 01:05:51,015 DEBG 'watchdog-script' stderr output:
Unhandled error in Deferred:
2019-11-14 01:05:51,017 DEBG 'watchdog-script' stderr output:
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/deluge/main.py", line 241, in start_daemon
Daemon(options, args)
File "/usr/lib/python2.7/site-packages/deluge/core/daemon.py", line 170, in __init__
component.start("PreferencesManager")
File "/usr/lib/python2.7/site-packages/deluge/component.py", line 296, in start
deferreds.append(self.components[name]._component_start())
File "/usr/lib/python2.7/site-packages/deluge/component.py", line 124, in _component_start
d = maybeDeferred(self.start)
--- <exception caught here> ---
File "/usr/lib/python2.7/site-packages/twisted/internet/defer.py", line 151, in maybeDeferred
result = f(*args, **kw)
File "/usr/lib/python2.7/site-packages/deluge/core/preferencesmanager.py", line 186, in start
self._on_set_encryption)
File "/usr/lib/python2.7/site-packages/deluge/config.py", line 319, in register_set_function
function(key, self.__config[key])
File "/usr/lib/python2.7/site-packages/deluge/core/preferencesmanager.py", line 362, in _on_set_encryption
lt.enc_policy(self.config["enc_out_policy"])
exceptions.OverflowError: can't convert negative value to unsigned