[Support] binhex - DelugeVPN

robobub · January 16, 2020

I have an issue where the vpn goes down but the watchdog doesn't detect it. I've attached by supervisord.log with debug turned on. From looking at watchdog.sh it seems that I still technically have a vpn connection (port, ip address), it's just I can no longer access anything (e.g. pinging google or anything else doesn't work).

Since I know it's likely just my VPN provider is poor and has a unique failure case, what's the best way to add / modify a script to the docker (e.g. checking ping and then touching /tmp/portclosed) to check this yet still get any updates from binhex's upstream docker?

I could do this externally with a user.script and docker exec -d, but I'm not seeing a way around having to run this regularly and checking if the script is running as there's no way to synchronize with docker starting the container or restarting it.

20200116_vpn_notresponding_supervisord.zip

Edited January 17, 2020 by robobub

LumberJackGeek · January 17, 2020

Hey so I randomly have a handful of torrents that are at, or even ABOVE 100%, and Deluge still has these items listed under "Downloading" and hasn't moved them to the Completed folder:

image.png.fe2c61825b1be5d9db8ef9035d63ad9e.png

wgstarks · January 18, 2020

On 1/16/2020 at 8:37 AM, jebusfreek666 said:

Sorry for being dumb, but i can not find the egg file on that page. I am never sure how to get it from there. I used to get it clicking through the link in deluge under "more plugins". It went to a forum with a direct download.

If you haven't found an egg yet try this- https://github.com/springjools/deluge-autoremoveplus/releases/tag/0.6.10

This should be the latest version of the branch still under development (AFAIK).

Mogo · January 18, 2020

Been having this error for awhile (a few months actually). Can't seem to find any solutions, however, I've seen similar questions posted. Almost all torrents will now say Tracker Status: Error: Permission Denied. If the same torrent is loaded into another application it works fine. The odd torrent does work. The container path is /data with a host path of /mnt/user/d and in the actual application it has /data/c and /data/i for complete and incomplete respectively. Other that regular os/docker updates the settings haven't been changed. Any ideas? Thanks.

Sonyswarm · January 19, 2020

Hello,

I just started having this issue where it will not connect to any tracker, and no torrents work. The UI shows a Error: Authorative host not found; then shortly there after it says Error: Connection refused. I have attached picutures and logs of the error.

I have tried re-downloading the the open vpn config/cert files, uninstalling and re-installing the docker but still the same issue. Noticed this about a week ago.


Created by...
___. .__ .__
\_ |__ |__| ____ | |__ ____ ___ ___
| __ \| |/ \| | \_/ __ \\ \/ /
| \_\ \ | | \ Y \ ___/ > <
|___ /__|___| /___| /\___ >__/\_ \
\/ \/ \/ \/ \/
https://hub.docker.com/u/binhex/

2020-01-18 21:38:07.714950 [info] System information Linux f2ed88915b73 4.19.88-Unraid #1 SMP Mon Dec 9 11:16:40 PST 2019 x86_64 GNU/Linux
2020-01-18 21:38:07.758980 [info] PUID defined as '99'
2020-01-18 21:38:07.907210 [info] PGID defined as '100'
2020-01-18 21:38:08.141160 [info] UMASK defined as '000'
2020-01-18 21:38:08.170928 [info] Permissions already set for volume mappings
2020-01-18 21:38:08.217063 [info] DELUGE_DAEMON_LOG_LEVEL defined as 'info'
2020-01-18 21:38:08.249767 [info] DELUGE_WEB_LOG_LEVEL defined as 'info'
2020-01-18 21:38:08.285385 [info] VPN_ENABLED defined as 'yes'
2020-01-18 21:38:08.332381 [info] OpenVPN config file (ovpn extension) is located at /config/openvpn/CA Toronto.ovpn
2020-01-18 21:38:08.418733 [info] VPN remote line defined as 'remote ca-toronto.privateinternetaccess.com 1198'
2020-01-18 21:38:08.455975 [info] VPN_REMOTE defined as 'ca-toronto.privateinternetaccess.com'
2020-01-18 21:38:08.490549 [info] VPN_PORT defined as '1198'
2020-01-18 21:38:08.528158 [info] VPN_PROTOCOL defined as 'udp'
2020-01-18 21:38:08.558831 [info] VPN_DEVICE_TYPE defined as 'tun0'
2020-01-18 21:38:08.585099 [info] VPN_PROV defined as 'pia'
2020-01-18 21:38:08.611793 [info] LAN_NETWORK defined as '192.168.1.0/24'
2020-01-18 21:38:08.638098 [info] NAME_SERVERS defined as '84.200.69.80,37.235.1.174,1.1.1.1,209.222.18.218,37.235.1.177,84.200.70.40'
2020-01-18 21:38:08.670693 [info] VPN_USER defined as 'myuid'
2020-01-18 21:38:08.702973 [info] VPN_PASS defined as 'mypwd'
2020-01-18 21:38:08.735579 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS)
2020-01-18 21:38:08.763552 [info] STRICT_PORT_FORWARD defined as 'yes'
2020-01-18 21:38:08.790918 [info] ENABLE_PRIVOXY defined as 'yes'
2020-01-18 21:38:08.841284 [info] Deleting files in /tmp (non recursive)...
2020-01-18 21:38:08.867187 [info] Starting Supervisor...
2020-01-18 21:38:09,104 INFO Included extra file "/etc/supervisor/conf.d/delugevpn.conf" during parsing
2020-01-18 21:38:09,104 INFO Set uid to user 0 succeeded
2020-01-18 21:38:09,107 INFO supervisord started with pid 6
2020-01-18 21:38:10,109 INFO spawned: 'start-script' with pid 168
2020-01-18 21:38:10,111 INFO spawned: 'watchdog-script' with pid 169
2020-01-18 21:38:10,112 INFO reaped unknown pid 7
2020-01-18 21:38:10,117 DEBG 'start-script' stdout output:
[info] VPN is enabled, beginning configuration of VPN

2020-01-18 21:38:10,117 INFO success: start-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2020-01-18 21:38:10,117 INFO success: watchdog-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2020-01-18 21:38:10,217 DEBG 'start-script' stdout output:
[info] Default route for container is 172.17.0.1

2020-01-18 21:38:10,222 DEBG 'start-script' stdout output:
[info] Adding 84.200.69.80 to /etc/resolv.conf

2020-01-18 21:38:10,226 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.174 to /etc/resolv.conf

2020-01-18 21:38:10,230 DEBG 'start-script' stdout output:
[info] Adding 1.1.1.1 to /etc/resolv.conf

2020-01-18 21:38:10,234 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.218 to /etc/resolv.conf

2020-01-18 21:38:10,238 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.177 to /etc/resolv.conf

2020-01-18 21:38:10,241 DEBG 'start-script' stdout output:
[info] Adding 84.200.70.40 to /etc/resolv.conf

2020-01-18 21:38:10,430 DEBG 'start-script' stdout output:
[info] Docker network defined as 172.17.0.0/16

2020-01-18 21:38:10,434 DEBG 'start-script' stdout output:
[info] Adding 192.168.1.0/24 as route via docker eth0

2020-01-18 21:38:10,436 DEBG 'start-script' stdout output:
[info] ip route defined as follows...
--------------------

2020-01-18 21:38:10,437 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

2020-01-18 21:38:10,437 DEBG 'start-script' stdout output:
--------------------

2020-01-18 21:38:10,440 DEBG 'start-script' stdout output:
iptable_mangle 16384 1
ip_tables 24576 3 iptable_filter,iptable_nat,iptable_mangle

2020-01-18 21:38:10,441 DEBG 'start-script' stdout output:
[info] iptable_mangle support detected, adding fwmark for tables

2020-01-18 21:38:10,503 DEBG 'start-script' stdout output:
[info] iptables defined as follows...
--------------------

2020-01-18 21:38:10,505 DEBG 'start-script' stdout output:
-P INPUT DROP
-P FORWARD DROP
-P OUTPUT DROP
-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 1198 -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 -s 192.168.1.0/24 -d 172.17.0.0/16 -i eth0 -p tcp -j ACCEPT
-A INPUT -p icmp -m icmp --icmp-type 0 -j ACCEPT
-A INPUT -i lo -j ACCEPT
-A INPUT -i 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 1198 -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 -s 172.17.0.0/16 -d 192.168.1.0/24 -o eth0 -p tcp -j ACCEPT
-A OUTPUT -p icmp -m icmp --icmp-type 8 -j ACCEPT
-A OUTPUT -o lo -j ACCEPT
-A OUTPUT -o tun0 -j ACCEPT

2020-01-18 21:38:10,506 DEBG 'start-script' stdout output:
--------------------

2020-01-18 21:38:10,507 DEBG 'start-script' stdout output:
[info] Starting OpenVPN...

2020-01-18 21:38:10,527 DEBG 'start-script' stdout output:
Sat Jan 18 21:38:10 2020 WARNING: file 'credentials.conf' is group or others accessible
Sat Jan 18 21:38:10 2020 OpenVPN 2.4.8 [git:makepkg/3976acda9bf10b5e+] x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Oct 30 2019
Sat Jan 18 21:38:10 2020 library versions: OpenSSL 1.1.1d 10 Sep 2019, LZO 2.10

2020-01-18 21:38:10,527 DEBG 'start-script' stdout output:
[info] OpenVPN started

2020-01-18 21:38:10,530 DEBG 'start-script' stdout output:
Sat Jan 18 21:38:10 2020 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts

2020-01-18 21:38:10,533 DEBG 'start-script' stdout output:
Sat Jan 18 21:38:10 2020 TCP/UDP: Preserving recently used remote address: [AF_INET]172.98.67.91:1198
Sat Jan 18 21:38:10 2020 UDP link local: (not bound)
Sat Jan 18 21:38:10 2020 UDP link remote: [AF_INET]172.98.67.91:1198

2020-01-18 21:38:10,661 DEBG 'start-script' stdout output:
Sat Jan 18 21:38:10 2020 [5f36d0df1d243d7664c708226b428943] Peer Connection Initiated with [AF_INET]172.98.67.91:1198

2020-01-18 21:38:11,817 DEBG 'start-script' stdout output:
Sat Jan 18 21:38:11 2020 TUN/TAP device tun0 opened
Sat Jan 18 21:38:11 2020 /usr/bin/ip link set dev tun0 up mtu 1500

2020-01-18 21:38:11,819 DEBG 'start-script' stdout output:
Sat Jan 18 21:38:11 2020 /usr/bin/ip addr add dev tun0 local 10.46.11.6 peer 10.46.11.5

2020-01-18 21:38:11,820 DEBG 'start-script' stdout output:
Sat Jan 18 21:38:11 2020 /root/openvpnup.sh tun0 1500 1558 10.46.11.6 10.46.11.5 init

2020-01-18 21:38:11,828 DEBG 'start-script' stdout output:
Sat Jan 18 21:38:11 2020 Initialization Sequence Completed

2020-01-18 21:38:11,953 DEBG 'start-script' stdout output:
[info] Port forwarding is enabled
[info] Checking endpoint 'ca-toronto.privateinternetaccess.com' is port forward enabled...

2020-01-18 21:38:11,962 DEBG 'start-script' stdout output:
[info] Attempting to curl https://www.privateinternetaccess.com/vpninfo/servers?version=82...

2020-01-18 21:38:13,279 DEBG 'start-script' stdout output:
[info] Curl successful for https://www.privateinternetaccess.com/vpninfo/servers?version=82, response code 200

2020-01-18 21:38:13,348 DEBG 'start-script' stdout output:
[info] PIA endpoint 'ca-toronto.privateinternetaccess.com' is in the list of endpoints that support port forwarding

2020-01-18 21:38:13,348 DEBG 'start-script' stdout output:
[info] List of PIA endpoints that support port forwarding:-
[info] ca-toronto.privateinternetaccess.com
[info] ca-montreal.privateinternetaccess.com
[info] ca-vancouver.privateinternetaccess.com

2020-01-18 21:38:13,348 DEBG 'start-script' stdout output:
[info] de-berlin.privateinternetaccess.com
[info] de-frankfurt.privateinternetaccess.com
[info] sweden.privateinternetaccess.com
[info] swiss.privateinternetaccess.com
[info] france.privateinternetaccess.com
[info] czech.privateinternetaccess.com
[info] spain.privateinternetaccess.com
[info] ro.privateinternetaccess.com
[info] israel.privateinternetaccess.com
[info] Attempting to get dynamically assigned port...

2020-01-18 21:38:13,363 DEBG 'start-script' stdout output:
[info] Attempting to curl http://209.222.18.222:2000/?client_id=d94e048d5fcaf9a0a06475e9c8d4217df176eac3b6a1ef5c9e2839060925dcf9...

2020-01-18 21:38:14,059 DEBG 'start-script' stdout output:
[info] Curl successful for http://209.222.18.222:2000/?client_id=d94e048d5fcaf9a0a06475e9c8d4217df176eac3b6a1ef5c9e2839060925dcf9, response code 200

2020-01-18 21:38:14,089 DEBG 'start-script' stdout output:
[info] Successfully assigned incoming port 24582

2020-01-18 21:38:14,090 DEBG 'start-script' stdout output:
[info] Checking we can resolve name 'www.google.com' to address...

2020-01-18 21:38:14,119 DEBG 'start-script' stdout output:
[info] DNS operational, we can resolve name 'www.google.com' to address '172.217.164.228'

2020-01-18 21:38:14,121 DEBG 'start-script' stdout output:
[info] Attempting to get external IP using Name Server 'ns1.google.com'...

2020-01-18 21:38:14,521 DEBG 'start-script' stdout output:
[info] Successfully retrieved external IP address 172.98.67.91

2020-01-18 21:38:14,987 DEBG 'watchdog-script' stdout output:
[info] Deluge listening interface IP 0.0.0.0 and VPN provider IP 10.46.11.6 different, marking for reconfigure

2020-01-18 21:38:14,992 DEBG 'watchdog-script' stdout output:
[info] Deluge not running

2020-01-18 21:38:14,996 DEBG 'watchdog-script' stdout output:
[info] Deluge Web UI not running

2020-01-18 21:38:14,999 DEBG 'watchdog-script' stdout output:
[info] Privoxy not running

2020-01-18 21:38:15,000 DEBG 'watchdog-script' stdout output:
[info] Deluge incoming port 6890 and VPN incoming port 24582 different, marking for reconfigure

2020-01-18 21:38:15,000 DEBG 'watchdog-script' stdout output:
[info] Attempting to start Deluge...

2020-01-18 21:38:15,000 DEBG 'watchdog-script' stdout output:
[info] Removing deluge pid file (if it exists)...

2020-01-18 21:38:15,400 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'listen_interface' currently has a value of '10.2.11.10'
[info] Deluge key 'listen_interface' will have a new value '10.46.11.6'
[info] Writing changes to Deluge config file '/config/core.conf'...

2020-01-18 21:38:15,649 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'outgoing_interface' currently has a value of 'tun0'
[info] Deluge key 'outgoing_interface' will have a new value 'tun0'
[info] Writing changes to Deluge config file '/config/core.conf'...

2020-01-18 21:38:15,859 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'default_daemon' currently has a value of '43f602c0073a4c86a9433b9b76f85101'
[info] Deluge key 'default_daemon' will have a new value '43f602c0073a4c86a9433b9b76f85101'
[info] Writing changes to Deluge config file '/config/web.conf'...

2020-01-18 21:38:16,223 DEBG 'watchdog-script' stdout output:
[info] Deluge process started
[info] Waiting for Deluge process to start listening on port 58846...

2020-01-18 21:38:16,454 DEBG 'watchdog-script' stdout output:
[info] Deluge process listening on port 58846

2020-01-18 21:38:16,775 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-18 21:38:16,776 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

2020-01-18 21:38:19,124 DEBG 'watchdog-script' stdout output:
Setting "random_port" to: False
Configuration value successfully updated.

2020-01-18 21:38:19,537 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-18 21:38:19,538 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

2020-01-18 21:38:21,524 DEBG 'watchdog-script' stdout output:
Setting "listen_ports" to: (24582, 24582)
Configuration value successfully updated.

2020-01-18 21:38:21,951 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-18 21:38:21,952 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

2020-01-18 21:38:24,357 DEBG 'watchdog-script' stdout output:
[info] No torrents with state 'Error' found

2020-01-18 21:38:24,357 DEBG 'watchdog-script' stdout output:
[info] Starting Deluge Web UI...
[info] Deluge Web UI started

2020-01-18 21:38:24,365 DEBG 'watchdog-script' stdout output:
[info] Attempting to start Privoxy...

2020-01-18 21:38:24,653 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-18 21:38:24,653 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

2020-01-18 21:38:25,373 DEBG 'watchdog-script' stdout output:
[info] Privoxy process started
[info] Waiting for Privoxy process to start listening on port 8118...

2020-01-18 21:38:25,382 DEBG 'watchdog-script' stdout output:
[info] Privoxy process listening on port 8118

Edited January 19, 2020 by Sonyswarm
added logs

casperse · January 19, 2020

16 hours ago, wgstarks said:

If you haven't found an egg yet try this- https://github.com/springjools/deluge-autoremoveplus/releases/tag/0.6.10

This should be the latest version of the branch still under development (AFAIK).

Strange I cannot install this in Deluge nothing happens...

Update - Could it be this:

Plugin eggs have the Python version encoded in the filename and will only load in Deluge if the Python versions match.

Edited January 19, 2020 by casperse

wgstarks · January 19, 2020

4 hours ago, casperse said:

Strange I cannot install this in Deluge nothing happens...

How are you trying to install it? Some people have had some luck by dropping the file into the appdata plugins folder and restarting the container.

rmilyard · January 19, 2020

Woke up this morning and looks like Delugevpn not working. Any ideas what issue might be?

ErrorWarningSystemArrayLogin

Created by...
___. .__ .__
\_ |__ |__| ____ | |__ ____ ___ ___
| __ \| |/ \| | \_/ __ \\ \/ /
| \_\ \ | | \ Y \ ___/ > <
|___ /__|___| /___| /\___ >__/\_ \
\/ \/ \/ \/ \/
https://hub.docker.com/u/binhex/

2020-01-19 06:12:46.260092 [info] System information Linux 69113bac74f6 4.19.94-Unraid #1 SMP Thu Jan 9 08:20:36 PST 2020 x86_64 GNU/Linux
2020-01-19 06:12:46.280621 [info] PUID defined as '99'
2020-01-19 06:12:46.370744 [info] PGID defined as '100'
2020-01-19 06:12:46.517440 [info] UMASK defined as '000'
2020-01-19 06:12:46.536197 [info] Permissions already set for volume mappings
2020-01-19 06:12:46.557560 [info] DELUGE_DAEMON_LOG_LEVEL defined as 'info'
2020-01-19 06:12:46.576019 [info] DELUGE_WEB_LOG_LEVEL defined as 'info'
2020-01-19 06:12:46.594417 [info] VPN_ENABLED defined as 'yes'
2020-01-19 06:12:46.619170 [info] OpenVPN config file (ovpn extension) is located at /config/openvpn/TorGuard.USA-LOS.ANGELES.ovpn
2020-01-19 06:12:46.655603 [info] VPN remote line defined as 'remote la.west.usa.torguardvpnaccess.com 1912'
2020-01-19 06:12:46.674092 [info] VPN_REMOTE defined as 'la.west.usa.torguardvpnaccess.com'
2020-01-19 06:12:46.693179 [info] VPN_PORT defined as '1912'
2020-01-19 06:12:46.714962 [info] VPN_PROTOCOL defined as 'tcp-client'
2020-01-19 06:12:46.733751 [info] VPN_DEVICE_TYPE defined as 'tun0'
2020-01-19 06:12:46.752640 [info] VPN_PROV defined as 'custom'
2020-01-19 06:12:46.772267 [info] LAN_NETWORK defined as '10.0.1.0/24'
2020-01-19 06:12:46.791969 [info] NAME_SERVERS defined as '209.222.18.222,84.200.69.80,37.235.1.174,1.1.1.1,209.222.18.218,37.235.1.177,84.200.70.40,1.0.0.1'
2020-01-19 06:12:46.810965 [info] VPN_USER defined as '[email protected]'
2020-01-19 06:12:46.833035 [info] VPN_PASS defined as 'Prodos123!'
2020-01-19 06:12:46.854457 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS)
2020-01-19 06:12:46.874064 [info] ENABLE_PRIVOXY defined as 'yes'
2020-01-19 06:12:46.898671 [info] Deleting files in /tmp (non recursive)...
2020-01-19 06:12:46.916314 [info] Starting Supervisor...
2020-01-19 06:12:47,070 INFO Included extra file "/etc/supervisor/conf.d/delugevpn.conf" during parsing
2020-01-19 06:12:47,070 INFO Set uid to user 0 succeeded
2020-01-19 06:12:47,073 INFO supervisord started with pid 6
2020-01-19 06:12:48,075 INFO spawned: 'start-script' with pid 163
2020-01-19 06:12:48,075 INFO spawned: 'watchdog-script' with pid 164
2020-01-19 06:12:48,076 INFO reaped unknown pid 7
2020-01-19 06:12:48,080 DEBG 'start-script' stdout output:
[info] VPN is enabled, beginning configuration of VPN

2020-01-19 06:12:48,080 INFO success: start-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2020-01-19 06:12:48,080 INFO success: watchdog-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2020-01-19 06:12:48,084 DEBG 'start-script' stdout output:
[warn] Username contains characters which could cause authentication issues, please consider changing this if possible

2020-01-19 06:12:48,086 DEBG 'start-script' stdout output:
[warn] Password contains characters which could cause authentication issues, please consider changing this if possible

2020-01-19 06:12:48,117 DEBG 'start-script' stdout output:
[info] Default route for container is 172.17.0.1

2020-01-19 06:12:48,120 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.222 to /etc/resolv.conf

2020-01-19 06:12:48,122 DEBG 'start-script' stdout output:
[info] Adding 84.200.69.80 to /etc/resolv.conf

2020-01-19 06:12:48,124 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.174 to /etc/resolv.conf

2020-01-19 06:12:48,126 DEBG 'start-script' stdout output:
[info] Adding 1.1.1.1 to /etc/resolv.conf

2020-01-19 06:12:48,128 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.218 to /etc/resolv.conf

2020-01-19 06:12:48,130 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.177 to /etc/resolv.conf

2020-01-19 06:12:48,133 DEBG 'start-script' stdout output:
[info] Adding 84.200.70.40 to /etc/resolv.conf

2020-01-19 06:12:48,135 DEBG 'start-script' stdout output:
[info] Adding 1.0.0.1 to /etc/resolv.conf

2020-01-19 06:12:48,530 DEBG 'start-script' stdout output:
[info] Attempting to load iptable_mangle module...

2020-01-19 06:12:48,531 DEBG 'start-script' stderr output:
modprobe: FATAL: Module iptable_mangle not found in directory /lib/modules/4.19.94-Unraid

2020-01-19 06:12:48,531 DEBG 'start-script' stdout output:
[warn] Unable to load iptable_mangle module using modprobe, trying insmod...

2020-01-19 06:12:48,532 DEBG 'start-script' stderr output:
insmod: ERROR: could not load module /lib/modules/iptable_mangle.ko: No such file or directory

2020-01-19 06:12:48,532 DEBG 'start-script' stdout output:
[warn] Unable to load iptable_mangle module, you will not be able to connect to the applications Web UI or Privoxy outside of your LAN
[info] unRAID/Ubuntu users: Please attempt to load the module by executing the following on your host: '/sbin/modprobe iptable_mangle'
[info] Synology users: Please attempt to load the module by executing the following on your host: 'insmod /lib/modules/iptable_mangle.ko'

2020-01-19 06:12:48,543 DEBG 'start-script' stdout output:
[info] Docker network defined as 172.17.0.0/16

2020-01-19 06:12:48,546 DEBG 'start-script' stdout output:
[info] Adding 10.0.1.0/24 as route via docker eth0

2020-01-19 06:12:48,546 DEBG 'start-script' stdout output:
[info] ip route defined as follows...
--------------------

2020-01-19 06:12:48,547 DEBG 'start-script' stdout output:
default via 172.17.0.1 dev eth0
10.0.1.0/24 via 172.17.0.1 dev eth0
172.17.0.0/16 dev eth0 proto kernel scope link src 172.17.0.2

2020-01-19 06:12:48,547 DEBG 'start-script' stdout output:
--------------------

2020-01-19 06:12:48,574 DEBG 'start-script' stdout output:
[info] iptables defined as follows...
--------------------

2020-01-19 06:12:48,575 DEBG 'start-script' stdout output:
-P INPUT DROP
-P FORWARD DROP
-P OUTPUT DROP
-A INPUT -s 172.17.0.0/16 -d 172.17.0.0/16 -j ACCEPT
-A INPUT -i eth0 -p tcp -m tcp --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 10.0.1.0/24 -i eth0 -p tcp -m tcp --dport 58846 -j ACCEPT
-A INPUT -s 10.0.1.0/24 -d 172.17.0.0/16 -i eth0 -p tcp -j ACCEPT
-A INPUT -p icmp -m icmp --icmp-type 0 -j ACCEPT
-A INPUT -i lo -j ACCEPT
-A INPUT -i tun0 -j ACCEPT
-A OUTPUT -s 172.17.0.0/16 -d 172.17.0.0/16 -j ACCEPT
-A OUTPUT -o eth0 -p tcp -m tcp --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 10.0.1.0/24 -o eth0 -p tcp -m tcp --sport 58846 -j ACCEPT
-A OUTPUT -s 172.17.0.0/16 -d 10.0.1.0/24 -o eth0 -p tcp -j ACCEPT
-A OUTPUT -p icmp -m icmp --icmp-type 8 -j ACCEPT
-A OUTPUT -o lo -j ACCEPT
-A OUTPUT -o tun0 -j ACCEPT

2020-01-19 06:12:48,576 DEBG 'start-script' stdout output:
--------------------

2020-01-19 06:12:48,578 DEBG 'start-script' stdout output:
[info] Starting OpenVPN...

2020-01-19 06:12:48,588 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:48 2020 WARNING: file 'credentials.conf' is group or others accessible
Sun Jan 19 06:12:48 2020 OpenVPN 2.4.8 [git:makepkg/3976acda9bf10b5e+] x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Oct 30 2019
Sun Jan 19 06:12:48 2020 library versions: OpenSSL 1.1.1d 10 Sep 2019, LZO 2.10

2020-01-19 06:12:48,589 DEBG 'start-script' stdout output:
[info] OpenVPN started

2020-01-19 06:12:48,589 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:48 2020 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Sun Jan 19 06:12:48 2020 NOTE: --fast-io is disabled since we are not using UDP

2020-01-19 06:12:48,590 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:48 2020 Outgoing Control Channel Authentication: Using 256 bit message hash 'SHA256' for HMAC authentication
Sun Jan 19 06:12:48 2020 Incoming Control Channel Authentication: Using 256 bit message hash 'SHA256' for HMAC authentication

2020-01-19 06:12:48,591 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:48 2020 TCP/UDP: Preserving recently used remote address: [AF_INET]66.154.116.194:1912
Sun Jan 19 06:12:48 2020 Socket Buffers: R=[87380->786432] S=[65536->786432]
Sun Jan 19 06:12:48 2020 Attempting to establish TCP connection with [AF_INET]66.154.116.194:1912 [nonblock]

2020-01-19 06:12:49,591 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:49 2020 TCP connection established with [AF_INET]66.154.116.194:1912
Sun Jan 19 06:12:49 2020 TCP_CLIENT link local: (not bound)
Sun Jan 19 06:12:49 2020 TCP_CLIENT link remote: [AF_INET]66.154.116.194:1912

2020-01-19 06:12:49,612 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:49 2020 TLS: Initial packet from [AF_INET]66.154.116.194:1912, sid=f973ec9c 0ae5f3c5

2020-01-19 06:12:49,728 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:49 2020 VERIFY OK: depth=1, CN=TG-VPN-CA

2020-01-19 06:12:49,729 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:49 2020 VERIFY KU OK
Sun Jan 19 06:12:49 2020 Validating certificate extended key usage
Sun Jan 19 06:12:49 2020 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Sun Jan 19 06:12:49 2020 VERIFY EKU OK
Sun Jan 19 06:12:49 2020 VERIFY OK: depth=0, CN=server

2020-01-19 06:12:49,985 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:49 2020 WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1584', remote='link-mtu 1571'
Sun Jan 19 06:12:49 2020 WARNING: 'tun-mtu' is used inconsistently, local='tun-mtu 1532', remote='tun-mtu 1500'
Sun Jan 19 06:12:49 2020 WARNING: 'comp-lzo' is present in local config but missing in remote config, local='comp-lzo'
Sun Jan 19 06:12:49 2020 WARNING: 'cipher' is used inconsistently, local='cipher AES-256-GCM', remote='cipher AES-128-CBC'
Sun Jan 19 06:12:49 2020 WARNING: 'auth' is used inconsistently, local='auth [null-digest]', remote='auth SHA256'
Sun Jan 19 06:12:49 2020 WARNING: 'keysize' is used inconsistently, local='keysize 256', remote='keysize 128'

2020-01-19 06:12:49,985 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:49 2020 Control Channel: TLSv1.2, cipher TLSv1.2 DHE-RSA-AES256-GCM-SHA384, 2048 bit RSA
Sun Jan 19 06:12:49 2020 [server] Peer Connection Initiated with [AF_INET]66.154.116.194:1912

2020-01-19 06:12:51,014 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 SENT CONTROL [server]: 'PUSH_REQUEST' (status=1)

2020-01-19 06:12:51,034 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 PUSH: Received control message: 'PUSH_REPLY,redirect-gateway def1,dhcp-option DNS 10.9.0.1,dhcp-option DNS 10.8.0.1,sndbuf 524288,rcvbuf 524288,route 10.34.0.1,topology net30,ping 5,ping-restart 30,socket-flags TCP_NODELAY,compress,ifconfig 10.34.0.6 10.34.0.5,peer-id 0'

2020-01-19 06:12:51,034 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 OPTIONS IMPORT: timers and/or timeouts modified
Sun Jan 19 06:12:51 2020 OPTIONS IMPORT: compression parms modified
Sun Jan 19 06:12:51 2020 OPTIONS IMPORT: --sndbuf/--rcvbuf options modified
Sun Jan 19 06:12:51 2020 Socket Buffers: R=[786432->1048576] S=[786432->1048576]
Sun Jan 19 06:12:51 2020 OPTIONS IMPORT: --socket-flags option modified
Sun Jan 19 06:12:51 2020 Socket flags: TCP_NODELAY=1 succeeded
Sun Jan 19 06:12:51 2020 OPTIONS IMPORT: --ifconfig/up options modified
Sun Jan 19 06:12:51 2020 OPTIONS IMPORT: route options modified
Sun Jan 19 06:12:51 2020 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Sun Jan 19 06:12:51 2020 OPTIONS IMPORT: peer-id set
Sun Jan 19 06:12:51 2020 OPTIONS IMPORT: adjusting link_mtu to 1659
Sun Jan 19 06:12:51 2020 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Sun Jan 19 06:12:51 2020 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Sun Jan 19 06:12:51 2020 ROUTE_GATEWAY 172.17.0.1/255.255.0.0 IFACE=eth0 HWADDR=02:42:ac:11:00:02

2020-01-19 06:12:51,035 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 TUN/TAP device tun0 opened
Sun Jan 19 06:12:51 2020 TUN/TAP TX queue length set to 100
Sun Jan 19 06:12:51 2020 /usr/bin/ip link set dev tun0 up mtu 1500

2020-01-19 06:12:51,035 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 /usr/bin/ip addr add dev tun0 local 10.34.0.6 peer 10.34.0.5

2020-01-19 06:12:51,038 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 /root/openvpnup.sh tun0 1500 1587 10.34.0.6 10.34.0.5 init

2020-01-19 06:12:51,040 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 /usr/bin/ip route add 66.154.116.194/32 via 172.17.0.1

2020-01-19 06:12:51,041 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 /usr/bin/ip route add 0.0.0.0/1 via 10.34.0.5

2020-01-19 06:12:51,042 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 /usr/bin/ip route add 128.0.0.0/1 via 10.34.0.5

2020-01-19 06:12:51,043 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 /usr/bin/ip route add 10.34.0.1/32 via 10.34.0.5

2020-01-19 06:12:51,044 DEBG 'start-script' stdout output:
Sun Jan 19 06:12:51 2020 Initialization Sequence Completed

2020-01-19 06:12:51,154 DEBG 'start-script' stdout output:
[info] Application does not require port forwarding or VPN provider is != pia, skipping incoming port assignment

2020-01-19 06:12:51,155 DEBG 'start-script' stdout output:
[info] Checking we can resolve name 'www.google.com' to address...

2020-01-19 06:12:51,341 DEBG 'start-script' stdout output:
[info] DNS operational, we can resolve name 'www.google.com' to address '216.239.34.117 216.239.38.117 216.239.32.117 216.239.36.117'

2020-01-19 06:12:51,343 DEBG 'start-script' stdout output:
[info] Attempting to get external IP using Name Server 'ns1.google.com'...

2020-01-19 06:12:51,749 DEBG 'start-script' stdout output:
[info] Successfully retrieved external IP address 66.154.116.194

2020-01-19 06:12:51,825 DEBG 'watchdog-script' stdout output:
[info] Deluge listening interface IP 0.0.0.0 and VPN provider IP 10.34.0.6 different, marking for reconfigure

2020-01-19 06:12:51,827 DEBG 'watchdog-script' stdout output:
[info] Deluge not running

2020-01-19 06:12:51,829 DEBG 'watchdog-script' stdout output:
[info] Deluge Web UI not running

2020-01-19 06:12:51,832 DEBG 'watchdog-script' stdout output:
[info] Privoxy not running

2020-01-19 06:12:51,832 DEBG 'watchdog-script' stdout output:
[info] Attempting to start Deluge...
[info] Removing deluge pid file (if it exists)...

2020-01-19 06:12:51,958 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'listen_interface' currently has a value of '10.34.0.6'
[info] Deluge key 'listen_interface' will have a new value '10.34.0.6'
[info] Writing changes to Deluge config file '/config/core.conf'...

2020-01-19 06:12:52,105 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'outgoing_interface' currently has a value of 'tun0'
[info] Deluge key 'outgoing_interface' will have a new value 'tun0'
[info] Writing changes to Deluge config file '/config/core.conf'...

2020-01-19 06:12:52,340 DEBG 'watchdog-script' stdout output:
[info] Deluge process started
[info] Waiting for Deluge process to start listening on port 58846...

2020-01-19 06:12:52,556 DEBG 'watchdog-script' stdout output:
[info] Deluge process listening on port 58846

2020-01-19 06:12:52,759 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-19 06:12:52,759 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

casperse · January 19, 2020

6 hours ago, wgstarks said:

How are you trying to install it? Some people have had some luck by dropping the file into the appdata plugins folder and restarting the container.

Thanks! dumping it in the plugin folder worked for me!

I found this other version especially made for this docker its listed: https://github.com/omaralvarez/deluge-autoremoveplus/pull/54

its listed as version AutoRemovePlus-0.6.6-py3.8 and the above link after instal is listed as

Version: 0.6.1 in Deluge? no matter I will experiement and see what works the other one seem to have integration to Sonarr and Radarr?

wgstarks · January 19, 2020

36 minutes ago, casperse said:

found this other version especially made for this docker its listed: https://github.com/omaralvarez/deluge-autoremoveplus/pull/54

I’m not sure but I believe that is just a pre-compiled version of the branch I linked previously.

37 minutes ago, casperse said:

no matter I will experiement and see what works

I would be interested in seeing what your results are. I know the one I linked is still being actively developed with new features being added that are now supported by Deluge v2.

skirven · January 20, 2020

Hi! I've encountered similar issues now as other users. What I'm seeing is where my VPN will ramp up when started, but drop off to nothing. I think this started when I restarted the container, but made no other changes. It seems to be refreshing the iptables? I ran the commands from the VPN doc around iptables and the kill switch, with no help

The setup:
Unraid Server: 6.8.0

DelugeVPN Build: binhex/arch-delugevpn:latest

VPN Provider: NordVPN

My log:

Created by...
___. .__ .__
\_ |__ |__| ____ | |__ ____ ___ ___
| __ \| |/ \| | \_/ __ \\ \/ /
| \_\ \ | | \ Y \ ___/ > <
|___ /__|___| /___| /\___ >__/\_ \
\/ \/ \/ \/ \/
https://hub.docker.com/u/binhex/

2020-01-19 19:44:41.138319 [info] System information Linux 05efa151a05d 4.19.88-Unraid #1 SMP Mon Dec 9 11:16:40 PST 2019 x86_64 GNU/Linux
2020-01-19 19:44:41.167481 [info] PUID defined as '99'
2020-01-19 19:44:41.199017 [info] PGID defined as '100'
2020-01-19 19:44:41.916397 [info] UMASK defined as '000'
2020-01-19 19:44:41.945043 [info] Permissions already set for volume mappings
2020-01-19 19:44:41.976941 [info] DELUGE_DAEMON_LOG_LEVEL not defined,(via -e DELUGE_DAEMON_LOG_LEVEL), defaulting to 'info'
2020-01-19 19:44:42.005902 [info] DELUGE_WEB_LOG_LEVEL not defined,(via -e DELUGE_WEB_LOG_LEVEL), defaulting to 'info'
2020-01-19 19:44:42.035031 [info] VPN_ENABLED defined as 'yes'
2020-01-19 19:44:42.067262 [info] OpenVPN config file (ovpn extension) is located at /config/openvpn/us3141.nordvpn.com.udp.ovpn
2020-01-19 19:44:42.115418 [info] VPN remote line defined as 'remote 196.247.50.35 1194'
2020-01-19 19:44:42.144350 [info] VPN_REMOTE defined as '196.247.50.35'
2020-01-19 19:44:42.173703 [info] VPN_PORT defined as '1194'
2020-01-19 19:44:42.205619 [info] VPN_PROTOCOL defined as 'udp'
2020-01-19 19:44:42.236283 [info] VPN_DEVICE_TYPE defined as 'tun0'
2020-01-19 19:44:42.265470 [info] VPN_PROV defined as 'custom'
2020-01-19 19:44:42.294607 [info] LAN_NETWORK defined as '192.168.1.0/24'
2020-01-19 19:44:42.323645 [info] NAME_SERVERS defined as '209.222.18.222,37.235.1.174,1.1.1.1,8.8.8.8,209.222.18.218,37.235.1.177,1.0.0.1,8.8.4.4'
2020-01-19 19:44:42.352796 [info] VPN_USER defined as '*****'
2020-01-19 19:44:42.381885 [info] VPN_PASS defined as '****'
2020-01-19 19:44:42.411762 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS)
2020-01-19 19:44:42.443510 [info] ENABLE_PRIVOXY defined as 'no'
2020-01-19 19:44:42.475901 [info] Deleting files in /tmp (non recursive)...
2020-01-19 19:44:42.504609 [info] Starting Supervisor...
2020-01-19 19:44:42,687 INFO Included extra file "/etc/supervisor/conf.d/delugevpn.conf" during parsing
2020-01-19 19:44:42,687 INFO Set uid to user 0 succeeded
2020-01-19 19:44:42,690 INFO supervisord started with pid 6
2020-01-19 19:44:43,692 INFO spawned: 'start-script' with pid 156
2020-01-19 19:44:43,693 INFO spawned: 'watchdog-script' with pid 157
2020-01-19 19:44:43,693 INFO reaped unknown pid 7
2020-01-19 19:44:43,698 DEBG 'start-script' stdout output:
[info] VPN is enabled, beginning configuration of VPN
[debug] Environment variables defined as follows
APPLICATION=deluge
BASH=/bin/bash
BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:hostcomplete:interactive_comments:progcomp:promptvars:sourcepath
BASH_ALIASES=()
BASH_ARGC=()
BASH_ARGV=()
BASH_CMDS=()
BASH_LINENO=([0]="0")
BASH_SOURCE=([0]="/root/start.sh")
BASH_VERSINFO=([0]="5" [1]="0" [2]="11" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu")
BASH_VERSION='5.0.11(1)-release'
DEBUG=true
DELUGE_DAEMON_LOG_LEVEL=info
DELUGE_WEB_LOG_LEVEL=info
DIRSTACK=()
ENABLE_PRIVOXY=no
EUID=0
GROUPS=()
HOME=/home/nobody
HOSTNAME=05efa151a05d
HOSTTYPE=x86_64
HOST_OS=Unraid
IFS=$' \t\n'
LANG=en_GB.UTF-8
LAN_NETWORK=192.168.1.0/24
MACHTYPE=x86_64-pc-linux-gnu
NAME_SERVERS=209.222.18.222,37.235.1.174,1.1.1.1,8.8.8.8,209.222.18.218,37.235.1.177,1.0.0.1,8.8.4.4
OPTERR=1
OPTIND=1
OSTYPE=linux-gnu
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
PGID=100
PIPESTATUS=([0]="0")
PPID=6
PS4='+ '
PUID=99
PWD=/
SHELL=/bin/bash
SHELLOPTS=braceexpand:hashall:interactive-comments
SHLVL=1
STRICT_PORT_FORWARD=no
SUPERVISOR_ENABLED=1
SUPERVISOR_GROUP_NAME=start-script
SUPERVISOR_PROCESS_NAME=start-script
TERM=xterm
TZ=America/New_York
UID=0

2020-01-19 19:44:43,698 INFO success: start-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2020-01-19 19:44:43,699 INFO success: watchdog-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2020-01-19 19:44:43,699 DEBG 'start-script' stdout output:
UMASK=000
VPN_CONFIG=/config/openvpn/us3141.nordvpn.com.udp.ovpn
VPN_DEVICE_TYPE=tun0
VPN_ENABLED=yes
VPN_OPTIONS=
VPN_PASS=Ly3Pi2#0zQ68
VPN_PORT=1194
VPN_PROTOCOL=udp
VPN_PROV=custom
VPN_REMOTE=196.247.50.35
VPN_USER=****
_='[debug] Environment variables defined as follows'
[debug] Directory listing of files in /config/openvpn as follows

2020-01-19 19:44:43,701 DEBG 'start-script' stdout output:
total 12
drwxrwxr-x 2 nobody users 65 Jan 19 19:44 .
drwxrwxr-x 8 nobody users 4096 Jan 19 19:44 ..
-rwxrwxr-x 1 nobody users 31 Jan 19 19:37 credentials.conf
-rwxrwxr-x 1 nobody users 2802 Jan 19 19:44 us3141.nordvpn.com.udp.ovpn

2020-01-19 19:44:43,707 DEBG 'start-script' stdout output:
[warn] Username contains characters which could cause authentication issues, please consider changing this if possible

2020-01-19 19:44:43,710 DEBG 'start-script' stdout output:
[warn] Password contains characters which could cause authentication issues, please consider changing this if possible

2020-01-19 19:44:43,732 DEBG 'start-script' stdout output:
[debug] Contents of ovpn file /config/openvpn/us3141.nordvpn.com.udp.ovpn as follows...

2020-01-19 19:44:43,732 DEBG 'start-script' stdout output:
remote 196.247.50.35 1194
client
dev tun
proto udp
resolv-retry infinite
remote-random
nobind
tun-mtu 1500
tun-mtu-extra 32
mssfix 1450
persist-key
ping 15
ping-restart 0
ping-timer-rem
comp-lzo no

remote-cert-tls server

auth-user-pass credentials.conf
verb 3
pull
fast-io
cipher AES-256-CBC
auth SHA512

<ca>
2020-01-19 19:44:43,741 DEBG 'start-script' stdout output:
[info] Default route for container is 172.17.0.1

2020-01-19 19:44:43,744 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.222 to /etc/resolv.conf

2020-01-19 19:44:43,747 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.174 to /etc/resolv.conf

2020-01-19 19:44:43,749 DEBG 'start-script' stdout output:
[info] Adding 1.1.1.1 to /etc/resolv.conf

2020-01-19 19:44:43,752 DEBG 'start-script' stdout output:
[info] Adding 8.8.8.8 to /etc/resolv.conf

2020-01-19 19:44:43,755 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.218 to /etc/resolv.conf

2020-01-19 19:44:43,758 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.177 to /etc/resolv.conf

2020-01-19 19:44:43,761 DEBG 'start-script' stdout output:
[info] Adding 1.0.0.1 to /etc/resolv.conf

2020-01-19 19:44:43,764 DEBG 'start-script' stdout output:
[info] Adding 8.8.4.4 to /etc/resolv.conf

2020-01-19 19:44:43,766 DEBG 'start-script' stdout output:
196.247.50.35

2020-01-19 19:44:43,776 DEBG 'start-script' stdout output:
[debug] Show name servers defined for container

2020-01-19 19:44:43,777 DEBG 'start-script' stdout output:
nameserver 209.222.18.222
nameserver 37.235.1.174
nameserver 1.1.1.1
nameserver 8.8.8.8
nameserver 209.222.18.218
nameserver 37.235.1.177
nameserver 1.0.0.1
nameserver 8.8.4.4

2020-01-19 19:44:43,777 DEBG 'start-script' stdout output:
[debug] Show name resolution for VPN endpoint 196.247.50.35

2020-01-19 19:44:43,785 DEBG 'start-script' stdout output:
;; ->>HEADER<<- opcode: QUERY, rcode: NXDOMAIN, id: 5733
;; flags: qr rd ra ; QUERY: 1, ANSWER: 0, AUTHORITY: 1, ADDITIONAL: 0
;; QUESTION SECTION:
;; 196.247.50.35. IN A

;; ANSWER SECTION:

;; AUTHORITY SECTION:
. 86354 IN SOA a.root-servers.net. nstld.verisign-grs.com. 2020011901 1800 900 604800 86400

;; ADDITIONAL SECTION:

;; Query time: 5 msec
;; SERVER: 8.8.8.8
;; WHEN: Sun Jan 19 19:44:43 2020
;; MSG SIZE rcvd: 106

2020-01-19 19:44:43,785 DEBG 'start-script' stdout output:
[debug] Show contents of hosts file

2020-01-19 19:44:43,785 DEBG 'start-script' stdout output:
127.0.0.1   localhost
::1   localhost ip6-localhost ip6-loopback
fe00::0   ip6-localnet
ff00::0   ip6-mcastprefix
ff02::1   ip6-allnodes
ff02::2   ip6-allrouters
172.17.0.2   05efa151a05d

2020-01-19 19:44:43,791 DEBG 'start-script' stdout output:
[debug] Docker interface defined as eth0

2020-01-19 19:44:43,795 DEBG 'start-script' stdout output:
[debug] Docker IP defined as 172.17.0.2

2020-01-19 19:44:43,798 DEBG 'start-script' stdout output:
[debug] Docker netmask defined as 255.255.0.0

2020-01-19 19:44:43,805 DEBG 'start-script' stdout output:
[info] Docker network defined as 172.17.0.0/16

2020-01-19 19:44:43,808 DEBG 'start-script' stdout output:
[info] Adding 192.168.1.0/24 as route via docker eth0

2020-01-19 19:44:43,809 DEBG 'start-script' stdout output:
[info] ip route defined as follows...
--------------------

2020-01-19 19:44:43,810 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

2020-01-19 19:44:43,810 DEBG 'start-script' stdout output:
--------------------
[debug] Modules currently loaded for kernel

2020-01-19 19:44:43,812 DEBG 'start-script' stdout output:
Module Size Used by
xt_nat 16384 5
xt_CHECKSUM 16384 1
ipt_REJECT 16384 2
ip6table_mangle 16384 1
ip6table_nat 16384 1
nf_nat_ipv6 16384 1 ip6table_nat
ip6table_filter 16384 1
ip6_tables 24576 3 ip6table_filter,ip6table_nat,ip6table_mangle
vhost_net 24576 1
tun 36864 4 vhost_net
vhost 32768 1 vhost_net
tap 20480 1 vhost_net
veth 20480 0
ipt_MASQUERADE 16384 9
iptable_filter 16384 2
iptable_nat 16384 1
nf_nat_ipv4 16384 2 ipt_MASQUERADE,iptable_nat
nf_nat 24576 3 nf_nat_ipv6,nf_nat_ipv4,xt_nat
xfs 663552 2
md_mod 49152 2
iptable_mangle 16384 2
ip_tables 24576 5 iptable_filter,iptable_nat,iptable_mangle
x86_pkg_temp_thermal 16384 0
intel_powerclamp 16384 0
coretemp 16384 0
kvm_intel 196608 3
kvm 380928 1 kvm_intel
crct10dif_pclmul 16384 0
crc32_pclmul 16384 0
crc32c_intel 24576 0
ghash_clmulni_intel 16384 0
pcbc 16384 0
aesni_intel 200704 0
aes_x86_64 20480 1 aesni_intel
crypto_simd 16384 1 aesni_intel
cryptd 20480 3 crypto_simd,ghash_clmulni_intel,aesni_intel
r8169 69632 0
glue_helper 16384 1 aesni_intel
i2c_i801 24576 0
i2c_core 40960 1 i2c_i801
intel_cstate 16384 0
intel_uncore 102400 0
wmi_bmof 16384 0
intel_rapl_perf 16384 0
realtek 20480 1
ahci 40960 3
libahci 28672 1 ahci
thermal 20480 0
video 40960 0
fan 16384 0
pcc_cpufreq 16384 0
button 16384 0
wmi 20480 1 wmi_bmof
backlight 16384 1 video

2020-01-19 19:44:43,816 DEBG 'start-script' stdout output:
iptable_mangle 16384 2
ip_tables 24576 5 iptable_filter,iptable_nat,iptable_mangle

2020-01-19 19:44:43,816 DEBG 'start-script' stdout output:
[info] iptable_mangle support detected, adding fwmark for tables

2020-01-19 19:44:43,845 DEBG 'start-script' stdout output:
[info] iptables defined as follows...
--------------------

2020-01-19 19:44:43,846 DEBG 'start-script' stdout output:
-P INPUT DROP
-P FORWARD DROP
-P OUTPUT DROP
-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 1194 -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 INPUT -i 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 1194 -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
-A OUTPUT -o tun0 -j ACCEPT

2020-01-19 19:44:43,847 DEBG 'start-script' stdout output:
--------------------

2020-01-19 19:44:43,848 DEBG 'start-script' stdout output:
[debug] OpenVPN command line:- /usr/bin/openvpn --daemon --reneg-sec 0 --mute-replay-warnings --auth-nocache --setenv VPN_PROV 'custom' --setenv DEBUG 'true' --setenv VPN_DEVICE_TYPE 'tun0' --setenv VPN_ENABLED 'yes' --setenv VPN_REMOTE '196.247.50.35' --setenv APPLICATION 'deluge' --script-security 2 --writepid /root/openvpn.pid --remap-usr1 SIGHUP --log-append /dev/stdout --pull-filter ignore 'up' --pull-filter ignore 'down' --pull-filter ignore 'route-ipv6' --pull-filter ignore 'ifconfig-ipv6' --pull-filter ignore 'tun-ipv6' --pull-filter ignore 'persist-tun' --pull-filter ignore 'reneg-sec' --up /root/openvpnup.sh --up-delay --up-restart --auth-user-pass credentials.conf --cd /config/openvpn --config '/config/openvpn/us3141.nordvpn.com.udp.ovpn'
[info] Starting OpenVPN...

2020-01-19 19:44:43,852 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:43 2020 WARNING: file 'credentials.conf' is group or others accessible
Sun Jan 19 19:44:43 2020 OpenVPN 2.4.8 [git:makepkg/3976acda9bf10b5e+] x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Oct 30 2019
Sun Jan 19 19:44:43 2020 library versions: OpenSSL 1.1.1d 10 Sep 2019, LZO 2.10

2020-01-19 19:44:43,853 DEBG 'start-script' stdout output:
[info] OpenVPN started
Sun Jan 19 19:44:43 2020 WARNING: --ping should normally be used with --ping-restart or --ping-exit
Sun Jan 19 19:44:43 2020 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts

2020-01-19 19:44:43,854 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:43 2020 Outgoing Control Channel Authentication: Using 512 bit message hash 'SHA512' for HMAC authentication
Sun Jan 19 19:44:43 2020 Incoming Control Channel Authentication: Using 512 bit message hash 'SHA512' for HMAC authentication

2020-01-19 19:44:43,854 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:43 2020 TCP/UDP: Preserving recently used remote address: [AF_INET]196.247.50.35:1194
Sun Jan 19 19:44:43 2020 Socket Buffers: R=[212992->212992] S=[212992->212992]
Sun Jan 19 19:44:43 2020 UDP link local: (not bound)
Sun Jan 19 19:44:43 2020 UDP link remote: [AF_INET]196.247.50.35:1194

2020-01-19 19:44:43,860 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:43 2020 TLS: Initial packet from [AF_INET]196.247.50.35:1194, sid=a08791cd ec919c53

2020-01-19 19:44:43,884 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:43 2020 VERIFY OK: depth=2, C=PA, O=NordVPN, CN=NordVPN Root CA

2020-01-19 19:44:43,885 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:43 2020 VERIFY OK: depth=1, C=PA, O=NordVPN, CN=NordVPN CA4

2020-01-19 19:44:43,885 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:43 2020 VERIFY KU OK
Sun Jan 19 19:44:43 2020 Validating certificate extended key usage
Sun Jan 19 19:44:43 2020 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Sun Jan 19 19:44:43 2020 VERIFY EKU OK
Sun Jan 19 19:44:43 2020 VERIFY OK: depth=0, CN=us3141.nordvpn.com

2020-01-19 19:44:44,012 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:44 2020 Control Channel: TLSv1.2, cipher TLSv1.2 ECDHE-RSA-AES256-GCM-SHA384, 4096 bit RSA
Sun Jan 19 19:44:44 2020 [us3141.nordvpn.com] Peer Connection Initiated with [AF_INET]196.247.50.35:1194

2020-01-19 19:44:45,245 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 SENT CONTROL [us3141.nordvpn.com]: 'PUSH_REQUEST' (status=1)

2020-01-19 19:44:45,252 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 PUSH: Received control message: 'PUSH_REPLY,redirect-gateway def1,dhcp-option DNS 103.86.96.100,dhcp-option DNS 103.86.99.100,sndbuf 524288,rcvbuf 524288,explicit-exit-notify,comp-lzo no,route-gateway 10.8.1.1,topology subnet,ping 60,ping-restart 180,ifconfig 10.8.1.30 255.255.255.0,peer-id 28,cipher AES-256-GCM'

2020-01-19 19:44:45,252 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: timers and/or timeouts modified
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: explicit notify parm(s) modified
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: compression parms modified
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: --sndbuf/--rcvbuf options modified
Sun Jan 19 19:44:45 2020 Socket Buffers: R=[212992->1048576] S=[212992->1048576]
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: --ifconfig/up options modified
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: route options modified
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: route-related options modified
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: peer-id set
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: adjusting link_mtu to 1657
Sun Jan 19 19:44:45 2020 OPTIONS IMPORT: data channel crypto options modified
Sun Jan 19 19:44:45 2020 Data Channel: using negotiated cipher 'AES-256-GCM'

2020-01-19 19:44:45,253 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Sun Jan 19 19:44:45 2020 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Sun Jan 19 19:44:45 2020 ROUTE_GATEWAY 172.17.0.1/255.255.0.0 IFACE=eth0 HWADDR=02:42:ac:11:00:02

2020-01-19 19:44:45,253 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 TUN/TAP device tun0 opened
Sun Jan 19 19:44:45 2020 TUN/TAP TX queue length set to 100

2020-01-19 19:44:45,253 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 /usr/bin/ip link set dev tun0 up mtu 1500

2020-01-19 19:44:45,254 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 /usr/bin/ip addr add dev tun0 10.8.1.30/24 broadcast 10.8.1.255

2020-01-19 19:44:45,255 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 /root/openvpnup.sh tun0 1500 1585 10.8.1.30 255.255.255.0 init

2020-01-19 19:44:45,256 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 /usr/bin/ip route add 196.247.50.35/32 via 172.17.0.1

2020-01-19 19:44:45,257 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 /usr/bin/ip route add 0.0.0.0/1 via 10.8.1.1

2020-01-19 19:44:45,258 DEBG 'start-script' stdout output:
[debug] Waiting for valid IP address from tunnel...

2020-01-19 19:44:45,258 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 /usr/bin/ip route add 128.0.0.0/1 via 10.8.1.1

2020-01-19 19:44:45,259 DEBG 'start-script' stdout output:
Sun Jan 19 19:44:45 2020 Initialization Sequence Completed

2020-01-19 19:44:45,369 DEBG 'start-script' stdout output:
[debug] Valid IP address from tunnel acquired '10.8.1.30'

2020-01-19 19:44:45,369 DEBG 'start-script' stdout output:
[info] Application does not require port forwarding or VPN provider is != pia, skipping incoming port assignment

2020-01-19 19:44:45,369 DEBG 'start-script' stdout output:
[info] Checking we can resolve name 'www.google.com' to address...

2020-01-19 19:44:45,378 DEBG 'start-script' stdout output:
[info] DNS operational, we can resolve name 'www.google.com' to address '108.177.122.103 108.177.122.104 108.177.122.105 108.177.122.106 108.177.122.147 108.177.122.99'

2020-01-19 19:44:45,379 DEBG 'start-script' stdout output:
[info] Attempting to get external IP using Name Server 'ns1.google.com'...

2020-01-19 19:44:45,440 DEBG 'start-script' stdout output:
[info] Successfully retrieved external IP address 196.247.50.36

2020-01-19 19:44:45,527 DEBG 'watchdog-script' stdout output:
[debug] Waiting for iptables chain policies to be in place...

2020-01-19 19:44:45,534 DEBG 'watchdog-script' stdout output:
[debug] iptables chain policies are in place
[info] Deluge listening interface IP 0.0.0.0 and VPN provider IP 10.8.1.30 different, marking for reconfigure

2020-01-19 19:44:45,537 DEBG 'watchdog-script' stdout output:
[info] Deluge not running

2020-01-19 19:44:45,540 DEBG 'watchdog-script' stdout output:
[info] Deluge Web UI not running

2020-01-19 19:44:45,540 DEBG 'watchdog-script' stdout output:
[info] Attempting to start Deluge...
[info] Removing deluge pid file (if it exists)...

2020-01-19 19:44:45,912 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'listen_interface' currently has a value of '10.8.1.17'
[info] Deluge key 'listen_interface' will have a new value '10.8.1.30'
[info] Writing changes to Deluge config file '/config/core.conf'...

2020-01-19 19:44:46,122 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'outgoing_interface' currently has a value of 'tun0'
[info] Deluge key 'outgoing_interface' will have a new value 'tun0'
[info] Writing changes to Deluge config file '/config/core.conf'...

2020-01-19 19:44:46,315 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'default_daemon' currently has a value of 'a51c36dae6ac95da1d50697a7e866896a7a15aa5'
[info] Deluge key 'default_daemon' will have a new value 'a51c36dae6ac95da1d50697a7e866896a7a15aa5'
[info] Writing changes to Deluge config file '/config/web.conf'...

2020-01-19 19:44:46,642 DEBG 'watchdog-script' stdout output:
[info] Deluge process started
[info] Waiting for Deluge process to start listening on port 58846...

2020-01-19 19:44:46,854 DEBG 'watchdog-script' stdout output:
[info] Deluge process listening on port 58846

2020-01-19 19:44:47,153 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-19 19:44:47,154 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

2020-01-19 19:44:49,588 DEBG 'watchdog-script' stdout output:
[info] No torrents with state 'Error' found

2020-01-19 19:44:49,588 DEBG 'watchdog-script' stdout output:
[info] Starting Deluge Web UI...

2020-01-19 19:44:49,588 DEBG 'watchdog-script' stdout output:
[info] Deluge Web UI started

2020-01-19 19:44:49,588 DEBG 'watchdog-script' stdout output:
[debug] VPN IP is 10.8.1.30
[debug] Deluge IP is 10.8.1.30

2020-01-19 19:44:49,887 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-19 19:44:49,888 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

2020-01-19 19:45:19,593 DEBG 'watchdog-script' stdout output:
-[debug] Waiting for iptables chain policies to be in place...

2020-01-19 19:45:19,600 DEBG 'watchdog-script' stdout output:
[debug] iptables chain policies are in place

2020-01-19 19:45:19,606 DEBG 'watchdog-script' stdout output:
[debug] VPN IP is 10.8.1.30
[debug] Deluge IP is 10.8.1.30

binhex · January 20, 2020

9 hours ago, skirven said:

What I'm seeing is where my VPN will ramp up when started, but drop off to nothing.

that is a symptom of the vpn connection dropping, so something is causing the tunnel to drop, i would check you have an up to date ovpn file, if so try a different vpn endpoint, if you are still having issues then check your isp connection is stable, if that looks ok then talk to your vpn provider about the drops, maybe look at switching vpn provider if no joy.

skirven · January 20, 2020

Aha! OK - I ended up replacing the ovpn file with another server (which I'd already tried). But I also removed the other config files, with the intent of the restart to rebuild them, and that did start it back working again.

Thanks!

Stephen

Sonyswarm · January 20, 2020

2 hours ago, skirven said:

Aha! OK - I ended up replacing the ovpn file with another server (which I'd already tried). But I also removed the other config files, with the intent of the restart to rebuild them, and that did start it back working again.

Thanks!

Stephen

What config files did you remove?

Also update as to my issue; I reverted to version 2.0.3-2-01 and I deluge is working albeit with some python errors in the logs. I removed it from the GUI and re-installed it but it seems the library files stay intact. Should I be concerned? Logs below:

self._log(WARNING, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given


2020-01-20 10:07:19,793 DEBG 'watchdog-script' stderr output:
Temporarily disabling observer LegacyLogObserverWrapper(<bound method TwistedLoggingObserver.emit of <deluge.log.TwistedLoggingObserver object at 0x14f9983e9400>>) due to exception: [Failure instance: Traceback: <class 'TypeError'>: findCaller() takes from 1 to 2 positional arguments but 3 were given
/usr/lib/python3.8/site-packages/twisted/python/failure.py:766:_safeReprVars
/usr/lib/python3.8/site-packages/twisted/internet/defer.py:962:__del__
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:190:failure
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:144:emit
--- <exception caught here> ---
/usr/lib/python3.8/site-packages/twisted/logger/_observer.py:131:__call__
/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py:93:__call__
/usr/lib/python3.8/site-packages/deluge/log.py:204:emit
/usr/lib/python3.8/logging/__init__.py:1481:critical
/usr/lib/python3.8/logging/__init__.py:1565:_log
]
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/twisted/python/failure.py", line 766, in _safeReprVars
return [(name, reflect.safe_repr(obj)) for (name, obj) in varsDictItems]
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 962, in __del__
log.failure(format,
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 190, in failure
self.emit(level, format, log_failure=failure, **kwargs)
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 144, in emit
self.observer(event)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/logger/_observer.py", line 131, in __call__
observer(event)
File "/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py", line 93, in __call__
self.legacyObserver(event)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 204, in emit
getattr(LoggingLoggerClass, event_dict['log_level'].name)(
File "/usr/lib/python3.8/logging/__init__.py", line 1481, in critical
self._log(CRITICAL, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given


2020-01-20 10:07:19,793 DEBG 'watchdog-script' stderr output:
Unhandled error in Deferred:

2020-01-20 10:07:19,794 DEBG 'watchdog-script' stderr output:

Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/twisted/internet/base.py", line 913, in runUntilCurrent
call.func(*call.args, **call.kw)
File "/usr/lib/python3.8/site-packages/deluge/core/torrentmanager.py", line 1614, in on_alert_performance
log.warning(
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1613, in unwindGenerator
return _cancellableInlineCallbacks(gen)
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1529, in _cancellableInlineCallbacks
_inlineCallbacks(None, g, status)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
result = g.send(result)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 73, in warning
yield LoggingLoggerClass.warning(self, msg, *args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1446, in warning
self._log(WARNING, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given


2020-01-20 10:07:19,794 DEBG 'watchdog-script' stderr output:
Temporarily disabling observer LegacyLogObserverWrapper(<bound method TwistedLoggingObserver.emit of <deluge.log.TwistedLoggingObserver object at 0x14f9983e9400>>) due to exception: [Failure instance: Traceback: <class 'TypeError'>: findCaller() takes from 1 to 2 positional arguments but 3 were given
/usr/lib/python3.8/site-packages/twisted/python/failure.py:766:_safeReprVars
/usr/lib/python3.8/site-packages/twisted/internet/defer.py:962:__del__
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:190:failure
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:144:emit
--- <exception caught here> ---
/usr/lib/python3.8/site-packages/twisted/logger/_observer.py:131:__call__
/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py:93:__call__
/usr/lib/python3.8/site-packages/deluge/log.py:204:emit
/usr/lib/python3.8/logging/__init__.py:1481:critical
/usr/lib/python3.8/logging/__init__.py:1565:_log
]
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/twisted/python/failure.py", line 766, in _safeReprVars
return [(name, reflect.safe_repr(obj)) for (name, obj) in varsDictItems]
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 962, in __del__
log.failure(format,
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 190, in failure
self.emit(level, format, log_failure=failure, **kwargs)
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 144, in emit
self.observer(event)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/logger/_observer.py", line 131, in __call__
observer(event)
File "/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py", line 93, in __call__
self.legacyObserver(event)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 204, in emit
getattr(LoggingLoggerClass, event_dict['log_level'].name)(
File "/usr/lib/python3.8/logging/__init__.py", line 1481, in critical
self._log(CRITICAL, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given


2020-01-20 10:07:19,794 DEBG 'watchdog-script' stderr output:
Unhandled error in Deferred:

2020-01-20 10:07:19,794 DEBG 'watchdog-script' stderr output:

Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/twisted/internet/base.py", line 913, in runUntilCurrent
call.func(*call.args, **call.kw)
File "/usr/lib/python3.8/site-packages/deluge/core/torrentmanager.py", line 1614, in on_alert_performance
log.warning(
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1613, in unwindGenerator
return _cancellableInlineCallbacks(gen)
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1529, in _cancellableInlineCallbacks
_inlineCallbacks(None, g, status)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
result = g.send(result)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 73, in warning
yield LoggingLoggerClass.warning(self, msg, *args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1446, in warning
self._log(WARNING, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given


2020-01-20 10:07:19,795 DEBG 'watchdog-script' stderr output:
Temporarily disabling observer LegacyLogObserverWrapper(<bound method TwistedLoggingObserver.emit of <deluge.log.TwistedLoggingObserver object at 0x14f9983e9400>>) due to exception: [Failure instance: Traceback: <class 'TypeError'>: findCaller() takes from 1 to 2 positional arguments but 3 were given
/usr/lib/python3.8/site-packages/twisted/python/failure.py:766:_safeReprVars
/usr/lib/python3.8/site-packages/twisted/internet/defer.py:962:__del__
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:190:failure
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:144:emit
--- <exception caught here> ---
/usr/lib/python3.8/site-packages/twisted/logger/_observer.py:131:__call__
/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py:93:__call__
/usr/lib/python3.8/site-packages/deluge/log.py:204:emit
/usr/lib/python3.8/logging/__init__.py:1481:critical
/usr/lib/python3.8/logging/__init__.py:1565:_log
]
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/twisted/python/failure.py", line 766, in _safeReprVars
return [(name, reflect.safe_repr(obj)) for (name, obj) in varsDictItems]
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 962, in __del__
log.failure(format,
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 190, in failure
self.emit(level, format, log_failure=failure, **kwargs)
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 144, in emit
self.observer(event)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/logger/_observer.py", line 131, in __call__
observer(event)
File "/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py", line 93, in __call__
self.legacyObserver(event)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 204, in emit
getattr(LoggingLoggerClass, event_dict['log_level'].name)(
File "/usr/lib/python3.8/logging/__init__.py", line 1481, in critical
self._log(CRITICAL, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given

Unhandled error in Deferred:

2020-01-20 10:07:19,795 DEBG 'watchdog-script' stderr output:

Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/twisted/internet/base.py", line 913, in runUntilCurrent
call.func(*call.args, **call.kw)
File "/usr/lib/python3.8/site-packages/deluge/core/torrentmanager.py", line 1614, in on_alert_performance
log.warning(
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1613, in unwindGenerator
return _cancellableInlineCallbacks(gen)
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1529, in _cancellableInlineCallbacks
_inlineCallbacks(None, g, status)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
result = g.send(result)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 73, in warning
yield LoggingLoggerClass.warning(self, msg, *args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1446, in warning
self._log(WARNING, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given


2020-01-20 10:07:19,796 DEBG 'watchdog-script' stderr output:
Temporarily disabling observer LegacyLogObserverWrapper(<bound method TwistedLoggingObserver.emit of <deluge.log.TwistedLoggingObserver object at 0x14f9983e9400>>) due to exception: [Failure instance: Traceback: <class 'TypeError'>: findCaller() takes from 1 to 2 positional arguments but 3 were given
/usr/lib/python3.8/site-packages/twisted/python/failure.py:766:_safeReprVars
/usr/lib/python3.8/site-packages/twisted/internet/defer.py:962:__del__
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:190:failure
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:144:emit
--- <exception caught here> ---
/usr/lib/python3.8/site-packages/twisted/logger/_observer.py:131:__call__
/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py:93:__call__
/usr/lib/python3.8/site-packages/deluge/log.py:204:emit
/usr/lib/python3.8/logging/__init__.py:1481:critical
/usr/lib/python3.8/logging/__init__.py:1565:_log
]
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/twisted/python/failure.py", line 766, in _safeReprVars
return [(name, reflect.safe_repr(obj)) for (name, obj) in varsDictItems]
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 962, in __del__
log.failure(format,
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 190, in failure
self.emit(level, format, log_failure=failure, **kwargs)
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 144, in emit
self.observer(event)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/logger/_observer.py", line 131, in __call__
observer(event)
File "/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py", line 93, in __call__
self.legacyObserver(event)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 204, in emit
getattr(LoggingLoggerClass, event_dict['log_level'].name)(
File "/usr/lib/python3.8/logging/__init__.py", line 1481, in critical
self._log(CRITICAL, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given


2020-01-20 10:09:41,975 DEBG 'watchdog-script' stderr output:
Unhandled error in Deferred:

2020-01-20 10:09:41,976 DEBG 'watchdog-script' stderr output:

Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/twisted/web/server.py", line 284, in render
body = resrc.render(self)
File "/usr/lib/python3.8/site-packages/deluge/ui/web/server.py", line 614, in render
log.warning(
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1613, in unwindGenerator
return _cancellableInlineCallbacks(gen)
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1529, in _cancellableInlineCallbacks
_inlineCallbacks(None, g, status)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
result = g.send(result)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 73, in warning
yield LoggingLoggerClass.warning(self, msg, *args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1446, in warning
self._log(WARNING, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given


2020-01-20 10:09:41,977 DEBG 'watchdog-script' stderr output:
Temporarily disabling observer LegacyLogObserverWrapper(<bound method TwistedLoggingObserver.emit of <deluge.log.TwistedLoggingObserver object at 0x15348f192fa0>>) due to exception: [Failure instance: Traceback: <class 'TypeError'>: findCaller() takes from 1 to 2 positional arguments but 3 were given
/usr/lib/python3.8/site-packages/pyparsing.py:856:<listcomp>
/usr/lib/python3.8/site-packages/twisted/internet/defer.py:962:__del__
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:190:failure
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:144:emit
--- <exception caught here> ---
/usr/lib/python3.8/site-packages/twisted/logger/_observer.py:131:__call__
/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py:93:__call__
/usr/lib/python3.8/site-packages/deluge/log.py:204:emit
/usr/lib/python3.8/logging/__init__.py:1481:critical
/usr/lib/python3.8/logging/__init__.py:1565:_log
]
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/pyparsing.py", line 856, in <listcomp>
for k, vlist in otheritems for v in vlist]
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 962, in __del__
log.failure(format,
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 190, in failure
self.emit(level, format, log_failure=failure, **kwargs)
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 144, in emit
self.observer(event)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/logger/_observer.py", line 131, in __call__
observer(event)
File "/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py", line 93, in __call__
self.legacyObserver(event)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 204, in emit
getattr(LoggingLoggerClass, event_dict['log_level'].name)(
File "/usr/lib/python3.8/logging/__init__.py", line 1481, in critical
self._log(CRITICAL, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given


2020-01-20 10:09:41,977 DEBG 'watchdog-script' stderr output:
Unhandled error in Deferred:

2020-01-20 10:09:41,978 DEBG 'watchdog-script' stderr output:

Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/twisted/web/server.py", line 284, in render
body = resrc.render(self)
File "/usr/lib/python3.8/site-packages/deluge/ui/web/server.py", line 626, in render
log.warning('WebUI falling back to "%s" mode.', script_type)
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1613, in unwindGenerator
return _cancellableInlineCallbacks(gen)
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1529, in _cancellableInlineCallbacks
_inlineCallbacks(None, g, status)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 1418, in _inlineCallbacks
result = g.send(result)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 73, in warning
yield LoggingLoggerClass.warning(self, msg, *args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1446, in warning
self._log(WARNING, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given


2020-01-20 10:09:41,978 DEBG 'watchdog-script' stderr output:
Temporarily disabling observer LegacyLogObserverWrapper(<bound method TwistedLoggingObserver.emit of <deluge.log.TwistedLoggingObserver object at 0x15348f192fa0>>) due to exception: [Failure instance: Traceback: <class 'TypeError'>: findCaller() takes from 1 to 2 positional arguments but 3 were given
/usr/lib/python3.8/site-packages/pyparsing.py:856:<listcomp>
/usr/lib/python3.8/site-packages/twisted/internet/defer.py:962:__del__
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:190:failure
/usr/lib/python3.8/site-packages/twisted/logger/_logger.py:144:emit
--- <exception caught here> ---
/usr/lib/python3.8/site-packages/twisted/logger/_observer.py:131:__call__
/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py:93:__call__
/usr/lib/python3.8/site-packages/deluge/log.py:204:emit
/usr/lib/python3.8/logging/__init__.py:1481:critical
/usr/lib/python3.8/logging/__init__.py:1565:_log
]
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/pyparsing.py", line 856, in <listcomp>
for k, vlist in otheritems for v in vlist]
File "/usr/lib/python3.8/site-packages/twisted/internet/defer.py", line 962, in __del__
log.failure(format,
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 190, in failure
self.emit(level, format, log_failure=failure, **kwargs)
File "/usr/lib/python3.8/site-packages/twisted/logger/_logger.py", line 144, in emit
self.observer(event)
--- <exception caught here> ---
File "/usr/lib/python3.8/site-packages/twisted/logger/_observer.py", line 131, in __call__
observer(event)
File "/usr/lib/python3.8/site-packages/twisted/logger/_legacy.py", line 93, in __call__
self.legacyObserver(event)
File "/usr/lib/python3.8/site-packages/deluge/log.py", line 204, in emit
getattr(LoggingLoggerClass, event_dict['log_level'].name)(
File "/usr/lib/python3.8/logging/__init__.py", line 1481, in critical
self._log(CRITICAL, msg, args, **kwargs)
File "/usr/lib/python3.8/logging/__init__.py", line 1565, in _log
fn, lno, func, sinfo = self.findCaller(stack_info, stacklevel)
builtins.TypeError: findCaller() takes from 1 to 2 positional arguments but 3 were given

Edited January 20, 2020 by Sonyswarm

rohlando · January 20, 2020

Currently I am new to the unraid binhex-delugevpn and started to setup the configuration. I am however having a hard time with getting this to work. I however may be not setting this up correctly.

One of my core requirements which I need to have is a custom VLAN configuration. I have setup Unraid to have VLAN support enabled along with adding the specific VLAN. I am also using a different OpenVPN configuration than AirVPN or PIA. The error I am getting is that the GUI will not connect. I have checked the configuration along with the Logs and I do see that Error in the log calling Error Sending Query: Could not send or receive, because of network error.

Any help would be great.

___. .__ .__
\_ |__ |__| ____ | |__ ____ ___ ___
| __ \| |/ \| | \_/ __ \\ \/ /
| \_\ \ | | \ Y \ ___/ > <
|___ /__|___| /___| /\___ >__/\_ \
\/ \/ \/ \/ \/
https://hub.docker.com/u/binhex/

2020-01-20 09:14:21.329401 [info] System information Linux 5a18ce5e2b16 4.19.94-Unraid #1 SMP Thu Jan 9 08:20:36 PST 2020 x86_64 GNU/Linux
2020-01-20 09:14:21.385468 [info] PUID defined as '99'
2020-01-20 09:14:21.445080 [info] PGID defined as '100'
2020-01-20 09:14:22.467884 [info] UMASK defined as '000'
2020-01-20 09:14:22.523812 [info] Permissions already set for volume mappings
2020-01-20 09:14:22.586296 [info] DELUGE_DAEMON_LOG_LEVEL defined as 'info'
2020-01-20 09:14:22.648026 [info] DELUGE_WEB_LOG_LEVEL defined as 'info'
2020-01-20 09:14:22.703476 [info] VPN_ENABLED defined as 'yes'
2020-01-20 09:14:22.777663 [info] OpenVPN config file (ovpn extension) is located at /config/openvpn/custom.ovpn
2020-01-20 09:14:22.883726 [info] VPN remote line defined as 'xxxxsitename portxxxx'
2020-01-20 09:14:22.940044 [info] VPN_REMOTE defined as 'xxxxsitenamexxxx'
2020-01-20 09:14:22.995681 [info] VPN_PORT defined as '1194'
2020-01-20 09:14:23.063316 [info] VPN_PROTOCOL defined as 'udp'
2020-01-20 09:14:23.121373 [info] VPN_DEVICE_TYPE defined as 'tun0'
2020-01-20 09:14:23.178801 [info] VPN_PROV defined as 'custom'
2020-01-20 09:14:23.235258 [info] LAN_NETWORK defined as '172.16.40.0/24'
2020-01-20 09:14:23.290911 [info] NAME_SERVERS defined as '209.222.18.222,84.200.69.80,37.235.1.174,1.1.1.1,209.222.18.218,37.235.1.177,84.200.70.40,1.0.0.1'
2020-01-20 09:14:23.351944 [info] VPN_USER defined as 'xxxxusernamexxxx'
2020-01-20 09:14:23.407993 [info] VPN_PASS defined as 'xxxxuserpasswordxxxx'
2020-01-20 09:14:23.466256 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS)
2020-01-20 09:14:23.523009 [info] ENABLE_PRIVOXY defined as 'yes'
2020-01-20 09:14:23.584489 [info] Deleting files in /tmp (non recursive)...
2020-01-20 09:14:23.637068 [info] Starting Supervisor...
2020-01-20 09:14:23,979 INFO Included extra file "/etc/supervisor/conf.d/delugevpn.conf" during parsing
2020-01-20 09:14:23,979 INFO Set uid to user 0 succeeded
2020-01-20 09:14:23,985 INFO supervisord started with pid 6
2020-01-20 09:14:24,988 INFO spawned: 'start-script' with pid 156
2020-01-20 09:14:24,991 INFO spawned: 'watchdog-script' with pid 157
2020-01-20 09:14:24,992 INFO reaped unknown pid 7
2020-01-20 09:14:25,003 DEBG 'start-script' stdout output:
[info] VPN is enabled, beginning configuration of VPN

2020-01-20 09:14:25,004 INFO success: start-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2020-01-20 09:14:25,004 INFO success: watchdog-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2020-01-20 09:14:25,143 DEBG 'start-script' stdout output:
[info] Default route for container is 172.16.40.1

2020-01-20 09:14:25,149 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.222 to /etc/resolv.conf

2020-01-20 09:14:25,155 DEBG 'start-script' stdout output:
[info] Adding 84.200.69.80 to /etc/resolv.conf

2020-01-20 09:14:25,161 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.174 to /etc/resolv.conf

2020-01-20 09:14:25,166 DEBG 'start-script' stdout output:
[info] Adding 1.1.1.1 to /etc/resolv.conf

2020-01-20 09:14:25,173 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.218 to /etc/resolv.conf

2020-01-20 09:14:25,179 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.177 to /etc/resolv.conf

2020-01-20 09:14:25,184 DEBG 'start-script' stdout output:
[info] Adding 84.200.70.40 to /etc/resolv.conf

2020-01-20 09:14:25,189 DEBG 'start-script' stdout output:
[info] Adding 1.0.0.1 to /etc/resolv.conf

2020-01-20 09:16:25,309 DEBG 'start-script' stderr output:
Error: error sending query: Could not send or receive, because of network error

binhex · January 20, 2020

8 minutes ago, rohlando said:

2020-01-20 09:14:23.235258 [info] LAN_NETWORK defined as '172.16.40.0/24'

lan_network should not be set to the same value as the docker custom bridge, it should be set to the network your clients are connected to, as in the machines you want to access the deluge web ui from, if you have multiple networks due to vlan's then define all of them using comma as a separator, e.g.:-

LAN_NETWORK=192.168.1.0/24,192.168.2.0/24

binhex · January 20, 2020

2 hours ago, Sonyswarm said:

Also update as to my issue; I reverted to version 2.0.3-2-01 and I deluge is working albeit with some python errors in the logs.

dont do this, there were some issues with python 3.8 and deluge, fixed in the later images, so i wouldnt roll back to that version, what exactly is your issue with the latest image?.

0x646f6f6d · January 20, 2020

I need some help. I've been hitting my head against a wall for the past few days on this.

Clean install of both the OS and docker. When I get your image up and rolling everything connects and appears to be working as intended, but I cannot connect to the webui. If I temporarily disable the firewall I can connect. Adding the port to the firewall doesn't help either.

Any help would be greatly appreciated!

Created by...
___.   .__       .__                   
\_ |__ |__| ____ |  |__   ____ ___  ___
 | __ \|  |/    \|  |  \_/ __ \\  \/  /
 | \_\ \  |   |  \   Y  \  ___/ >    < 
 |___  /__|___|  /___|  /\___  >__/\_ \
     \/        \/     \/     \/      \/
   https://hub.docker.com/u/binhex/

2020-01-20 10:49:58.984782 [info] System information Linux 145a52d24f35 3.10.0-1062.9.1.el7.x86_64 #1 SMP Fri Dec 6 15:49:49 UTC 2019 x86_64 GNU/Linux
2020-01-20 10:49:59.026084 [info] PUID defined as '1000'
2020-01-20 10:49:59.069343 [info] PGID defined as '1000'
2020-01-20 10:49:59.112489 [warn] UMASK not defined (via -e UMASK), defaulting to '000'
2020-01-20 10:49:59.152753 [info] Permissions already set for volume mappings
2020-01-20 10:49:59.197901 [info] DELUGE_DAEMON_LOG_LEVEL defined as 'info'
2020-01-20 10:49:59.238116 [info] DELUGE_WEB_LOG_LEVEL defined as 'info'
2020-01-20 10:49:59.278518 [info] VPN_ENABLED defined as 'yes'
2020-01-20 10:49:59.325048 [info] OpenVPN config file (ovpn extension) is located at /config/openvpn/no86.nordvpn.com.udp.ovpn
2020-01-20 10:49:59.396361 [info] VPN remote line defined as 'remote 37.120.149.19 1194'
2020-01-20 10:49:59.436827 [info] VPN_REMOTE defined as '37.120.149.19'
2020-01-20 10:49:59.477494 [info] VPN_PORT defined as '1194'
2020-01-20 10:49:59.521365 [info] VPN_PROTOCOL defined as 'udp'
2020-01-20 10:49:59.561927 [info] VPN_DEVICE_TYPE defined as 'tun0'
2020-01-20 10:49:59.602117 [info] VPN_PROV defined as 'custom'
2020-01-20 10:49:59.642444 [info] LAN_NETWORK defined as '69.195.142.27/32'
2020-01-20 10:49:59.682754 [info] NAME_SERVERS defined as '1.1.1.1'
2020-01-20 10:49:59.722878 [info] VPN_USER defined as '***'
2020-01-20 10:49:59.763357 [info] VPN_PASS defined as '***'
2020-01-20 10:49:59.803484 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS)
2020-01-20 10:49:59.843601 [info] ENABLE_PRIVOXY defined as 'no'
2020-01-20 10:49:59.890306 [info] Deleting files in /tmp (non recursive)...
2020-01-20 10:49:59.929552 [info] Starting Supervisor...
2020-01-20 10:50:00,183 INFO Included extra file "/etc/supervisor/conf.d/delugevpn.conf" during parsing
2020-01-20 10:50:00,183 INFO Set uid to user 0 succeeded
2020-01-20 10:50:00,187 INFO supervisord started with pid 6
2020-01-20 10:50:01,189 INFO spawned: 'start-script' with pid 156
2020-01-20 10:50:01,191 INFO spawned: 'watchdog-script' with pid 157
2020-01-20 10:50:01,191 INFO reaped unknown pid 7
2020-01-20 10:50:01,200 DEBG 'start-script' stdout output:
[info] VPN is enabled, beginning configuration of VPN
[debug] Environment variables defined as follows
APPLICATION=deluge
BASH=/bin/bash
BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:hostcomplete:interactive_comments:progcomp:promptvars:sourcepath

2020-01-20 10:50:01,200 INFO success: start-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2020-01-20 10:50:01,200 INFO success: watchdog-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2020-01-20 10:50:01,200 DEBG 'start-script' stdout output:
BASH_ALIASES=()
BASH_ARGC=()
BASH_ARGV=()
BASH_CMDS=()
BASH_LINENO=([0]="0")
BASH_SOURCE=([0]="/root/start.sh")
BASH_VERSINFO=([0]="5" [1]="0" [2]="11" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu")
BASH_VERSION='5.0.11(1)-release'
DEBUG=true
DELUGE_DAEMON_LOG_LEVEL=info
DELUGE_WEB_LOG_LEVEL=info
DIRSTACK=()
ENABLE_PRIVOXY=no
EUID=0
GROUPS=()
HOME=/home/nobody
HOSTNAME=145a52d24f35
HOSTTYPE=x86_64
IFS=$' \t\n'
LANG=en_GB.UTF-8
LAN_NETWORK=69.195.142.27/32
MACHTYPE=x86_64-pc-linux-gnu
NAME_SERVERS=1.1.1.1
OPTERR=1
OPTIND=1
OSTYPE=linux-gnu
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
PGID=1000
PIPESTATUS=([0]="0")
PPID=6
PS4='+ '
PUID=1000
PWD=/
SHELL=/bin/bash
SHELLOPTS=braceexpand:hashall:interactive-comments
SHLVL=1
SUPERVISOR_ENABLED=1
SUPERVISOR_GROUP_NAME=start-script
SUPERVISOR_PROCESS_NAME=start-script
TERM=xterm
UID=0
VPN_CONFIG=/config/openvpn/no86.nordvpn.com.udp.ovpn
VPN_DEVICE_TYPE=tun0
VPN_ENABLED=yes
VPN_OPTIONS=
VPN_PASS=***
VPN_PORT=1194
VPN_PROTOCOL=udp
VPN_PROV=custom
VPN_REMOTE=37.120.149.19
VPN_USER=***
_='[debug] Environment variables defined as follows'
[debug] Directory listing of files in /config/openvpn as follows

2020-01-20 10:50:01,204 DEBG 'start-script' stdout output:
total 12
drwxrwxr-x 2 nobody users   63 Jan 20 10:49 .
drwxrwxr-x 8 nobody users 4096 Jan 20 10:49 ..
-rwxrwxr-x 1 nobody users   67 Jan 19 21:57 credentials.conf
-rwxrwxr-x 1 nobody users 2802 Jan 20 10:49 no86.nordvpn.com.udp.ovpn

2020-01-20 10:50:01,213 DEBG 'start-script' stdout output:
[warn] Username contains characters which could cause authentication issues, please consider changing this if possible

2020-01-20 10:50:01,251 DEBG 'start-script' stdout output:
[debug] Contents of ovpn file /config/openvpn/no86.nordvpn.com.udp.ovpn as follows...

2020-01-20 10:50:01,252 DEBG 'start-script' stdout output:
remote 37.120.149.19 1194
client
dev tun
proto udp
resolv-retry infinite
remote-random
nobind
tun-mtu 1500
tun-mtu-extra 32
mssfix 1450
persist-key
ping 15
ping-restart 0
ping-timer-rem

***openVPN certificate contents here***

2020-01-20 10:50:01,266 DEBG 'start-script' stdout output:
[info] Default route for container is 172.17.0.1

2020-01-20 10:50:01,271 DEBG 'start-script' stdout output:
[info] Adding 1.1.1.1 to /etc/resolv.conf

2020-01-20 10:50:01,274 DEBG 'start-script' stdout output:
37.120.149.19

2020-01-20 10:50:01,299 DEBG 'start-script' stdout output:
[debug] Show name servers defined for container

2020-01-20 10:50:01,300 DEBG 'start-script' stdout output:
nameserver 1.1.1.1

2020-01-20 10:50:01,301 DEBG 'start-script' stdout output:
[debug] Show name resolution for VPN endpoint 37.120.149.19

2020-01-20 10:50:01,304 DEBG 'start-script' stdout output:
;; ->>HEADER<<- opcode: QUERY, rcode: NXDOMAIN, id: 60853
;; flags: qr rd ra ; QUERY: 1, ANSWER: 0, AUTHORITY: 1, ADDITIONAL: 0 
;; QUESTION SECTION:
;; 37.120.149.19.       IN      A

;; ANSWER SECTION:

;; AUTHORITY SECTION:
.       7692    IN      SOA     a.root-servers.net. nstld.verisign-grs.com. 2020012000 1800 900 604800 86400

;; ADDITIONAL SECTION:

;; Query time: 0 msec
;; SERVER: 1.1.1.1
;; WHEN: Mon Jan 20 10:50:01 2020
;; MSG SIZE  rcvd: 106

2020-01-20 10:50:01,304 DEBG 'start-script' stdout output:
[debug] Show contents of hosts file

2020-01-20 10:50:01,305 DEBG 'start-script' stdout output:
127.0.0.1       localhost
::1     localhost ip6-localhost ip6-loopback
fe00::0 ip6-localnet
ff00::0 ip6-mcastprefix
ff02::1 ip6-allnodes
ff02::2 ip6-allrouters
172.17.0.2      145a52d24f35

2020-01-20 10:50:01,310 DEBG 'start-script' stdout output:
[debug] Docker interface defined as eth0

2020-01-20 10:50:01,315 DEBG 'start-script' stdout output:
[debug] Docker IP defined as 172.17.0.2

2020-01-20 10:50:01,319 DEBG 'start-script' stdout output:
[debug] Docker netmask defined as 255.255.0.0

2020-01-20 10:50:01,329 DEBG 'start-script' stdout output:
[info] Docker network defined as    172.17.0.0/16

2020-01-20 10:50:01,334 DEBG 'start-script' stdout output:
[info] Adding 69.195.142.27/32 as route via docker eth0

2020-01-20 10:50:01,336 DEBG 'start-script' stdout output:
[info] ip route defined as follows...
--------------------

2020-01-20 10:50:01,338 DEBG 'start-script' stdout output:
default via 172.17.0.1 dev eth0 
69.195.142.27 via 172.17.0.1 dev eth0 
172.17.0.0/16 dev eth0 proto kernel scope link src 172.17.0.2 

2020-01-20 10:50:01,338 DEBG 'start-script' stdout output:
--------------------

2020-01-20 10:50:01,338 DEBG 'start-script' stdout output:
[debug] Modules currently loaded for kernel

2020-01-20 10:50:01,346 DEBG 'start-script' stdout output:
Module                  Size  Used by
xt_mark                12563  0
nf_conntrack_netlink    36354  0
xt_nat                 12681  7
xt_addrtype            12676  2
ipt_MASQUERADE         12678  8
nf_nat_masquerade_ipv4    13430  1 ipt_MASQUERADE
ip6t_rpfilter          12595  2
ip6t_REJECT            12625  2
nf_reject_ipv6         13717  1 ip6t_REJECT
nf_log_ipv6            12726  5
ipt_REJECT             12541  2
nf_reject_ipv4         13373  1 ipt_REJECT
nf_log_ipv4            12767  4
nf_log_common          13317  2 nf_log_ipv4,nf_log_ipv6
xt_LOG                 12690  9
ebtable_nat            12807  1
ebtable_broute         12731  1
ip6table_nat           12864  1
nf_nat_ipv6            14131  1 ip6table_nat
ip6table_mangle        12700  1
ip6table_security      12710  1
ip6table_raw           12683  1
iptable_nat            12875  1
nf_nat_ipv4            14115  1 iptable_nat
nf_nat                 26583  4 nf_nat_ipv4,nf_nat_ipv6,xt_nat,nf_nat_masquerade_ipv4
iptable_mangle         12695  1
iptable_security       12705  1
iptable_raw            12678  1
ebtable_filter         12827  1
ebtables               35009  3 ebtable_broute,ebtable_nat,ebtable_filter
tun                    32026  0
xt_owner               12534  0
veth                   13458  0
fuse                  100463  3
br_netfilter           22256  0
overlay                91659  5
xt_conntrack           12760  15
bridge                151336  2 br_netfilter,ebtable_broute
stp                    12976  1 bridge
llc                    14552  2 stp,bridge
nf_conntrack_ipv6      18935  8
nf_defrag_ipv6         35104  1 nf_conntrack_ipv6
nf_conntrack_ipv4      15053  9
nf_defrag_ipv4         12729  1 nf_conntrack_ipv4
nf_conntrack          139224  8 nf_nat,nf_nat_ipv4,nf_nat_ipv6,xt_conntrack,nf_nat_masquerade_ipv4,nf_conntrack_netlink,nf_conntrack_ipv4,nf_conntrack_ipv6
ip_set                 45799  0
nfnetlink              14519  3 ip_set,nf_conntrack_netlink
ip6table_filter        12815  1
ip6_tables             26912  5 ip6table_filter,ip6table_mangle,ip6table_security,ip6table_nat,ip6table_raw
iptable_filter         12810  1
vmw_vsock_vmci_transport    30577  1
vsock                  36526  2 vmw_vsock_vmci_transport
vfat                   17461  1
fat                    65950  1 vfat
ppdev                  17671  0
vmw_balloon            18094  0
sb_edac                32114  0
iosf_mbi               15582  0
crc32_pclmul           13133  0
ghash_clmulni_intel    13273  0
aesni_intel           189456  0
lrw                    13286  1 aesni_intel
gf128mul               15139  1 lrw
glue_helper            13990  1 aesni_intel
ablk_helper            13597  1 aesni_intel
cryptd                 21190  3 ghash_clmulni_intel,aesni_intel,ablk_helper
pcspkr                 12718  0
joydev                 17389  0
sg                     40719  0
vmw_vmci               67168  1 vmw_vsock_vmci_transport
i2c_piix4              22401  0
parport_pc             28205  0
tpm_crb                13639  0
parport                46395  2 ppdev,parport_pc
ip_tables              27126  5 iptable_security,iptable_filter,iptable_mangle,iptable_nat,iptable_raw
xfs                   993020  3
libcrc32c              12644  3 xfs,nf_nat,nf_conntrack
sr_mod                 22416  0
cdrom                  42556  1 sr_mod
ata_generic            12923  0
pata_acpi              13053  0
sd_mod                 46281  4
crc_t10dif             12912  1 sd_mod
crct10dif_generic      12647  0
vmwgfx                291993  1
drm_kms_helper        186531  1 vmwgfx
syscopyarea            12529  1 drm_kms_helper
sysfillrect            12701  1 drm_kms_helper
sysimgblt              12640  1 drm_kms_helper
fb_sys_fops            12703  1 drm_kms_helper
ttm                    96673  1 vmwgfx
ahci                   34056  0
drm                   456166  4 ttm,drm_kms_helper,vmwgfx
libahci                31992  1 ahci
ata_piix               35052  0
crct10dif_pclmul       14307  1
crct10dif_common       12595  3 crct10dif_pclmul,crct10dif_generic,crc_t10dif
nfit                   55479  0
crc
2020-01-20 10:50:01,347 DEBG 'start-script' stdout output:
32c_intel           22094  1
libata                243133  5 ahci,pata_acpi,libahci,ata_generic,ata_piix
serio_raw              13434  0
libnvdimm             155545  1 nfit
vmxnet3                58059  0
vmw_pvscsi             23130  3
drm_panel_orientation_quirks    17180  1 drm
dm_mirror              22289  0
dm_region_hash         20813  1 dm_mirror
dm_log                 18411  2 dm_region_hash,dm_mirror
dm_mod                124501  11 dm_log,dm_mirror

2020-01-20 10:50:01,356 DEBG 'start-script' stdout output:
iptable_mangle         12695  1
ip_tables              27126  5 iptable_security,iptable_filter,iptable_mangle,iptable_nat,iptable_raw

2020-01-20 10:50:01,357 DEBG 'start-script' stdout output:
[info] iptable_mangle support detected, adding fwmark for tables

2020-01-20 10:50:01,412 DEBG 'start-script' stdout output:
[info] iptables defined as follows...
--------------------

2020-01-20 10:50:01,414 DEBG 'start-script' stdout output:
-P INPUT DROP
-P FORWARD DROP
-P OUTPUT DROP
-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 1194 -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 69.195.142.27/32 -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 INPUT -i 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 1194 -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 69.195.142.27/32 -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
-A OUTPUT -o tun0 -j ACCEPT

2020-01-20 10:50:01,415 DEBG 'start-script' stdout output:
--------------------

2020-01-20 10:50:01,415 DEBG 'start-script' stdout output:
[debug] OpenVPN command line:- /usr/bin/openvpn --daemon --reneg-sec 0 --mute-replay-warnings --auth-nocache --setenv VPN_PROV 'custom' --setenv DEBUG 'true' --setenv VPN_DEVICE_TYPE 'tun0' --setenv VPN_ENABLED 'yes' --setenv VPN_REMOTE '37.120.149.19' --setenv APPLICATION 'deluge' --script-security 2 --writepid /root/openvpn.pid --remap-usr1 SIGHUP --log-append /dev/stdout --pull-filter ignore 'up' --pull-filter ignore 'down' --pull-filter ignore 'route-ipv6' --pull-filter ignore 'ifconfig-ipv6' --pull-filter ignore 'tun-ipv6' --pull-filter ignore 'persist-tun' --pull-filter ignore 'reneg-sec' --up /root/openvpnup.sh --up-delay --up-restart --auth-user-pass credentials.conf --cd /config/openvpn --config '/config/openvpn/no86.nordvpn.com.udp.ovpn'
[info] Starting OpenVPN...

2020-01-20 10:50:01,423 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 WARNING: file 'credentials.conf' is group or others accessible

2020-01-20 10:50:01,423 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 OpenVPN 2.4.8 [git:makepkg/3976acda9bf10b5e+] x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Oct 30 2019
Mon Jan 20 10:50:01 2020 library versions: OpenSSL 1.1.1d  10 Sep 2019, LZO 2.10

2020-01-20 10:50:01,424 DEBG 'start-script' stdout output:
[info] OpenVPN started

2020-01-20 10:50:01,424 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 WARNING: --ping should normally be used with --ping-restart or --ping-exit
Mon Jan 20 10:50:01 2020 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts

2020-01-20 10:50:01,427 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 Outgoing Control Channel Authentication: Using 512 bit message hash 'SHA512' for HMAC authentication
Mon Jan 20 10:50:01 2020 Incoming Control Channel Authentication: Using 512 bit message hash 'SHA512' for HMAC authentication

2020-01-20 10:50:01,427 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 TCP/UDP: Preserving recently used remote address: [AF_INET]37.120.149.19:1194

2020-01-20 10:50:01,427 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 Socket Buffers: R=[212992->212992] S=[212992->212992]
Mon Jan 20 10:50:01 2020 UDP link local: (not bound)
Mon Jan 20 10:50:01 2020 UDP link remote: [AF_INET]37.120.149.19:1194

2020-01-20 10:50:01,602 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 TLS: Initial packet from [AF_INET]37.120.149.19:1194, sid=50ab07b2 621973b8

2020-01-20 10:50:01,790 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 VERIFY OK: depth=2, C=PA, O=NordVPN, CN=NordVPN Root CA

2020-01-20 10:50:01,791 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 VERIFY OK: depth=1, C=PA, O=NordVPN, CN=NordVPN CA4

2020-01-20 10:50:01,791 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 VERIFY KU OK
Mon Jan 20 10:50:01 2020 Validating certificate extended key usage
Mon Jan 20 10:50:01 2020 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication

2020-01-20 10:50:01,791 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:01 2020 VERIFY EKU OK
Mon Jan 20 10:50:01 2020 VERIFY OK: depth=0, CN=no86.nordvpn.com

2020-01-20 10:50:18,592 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:18 2020 Control Channel: TLSv1.2, cipher TLSv1.2 ECDHE-RSA-AES256-GCM-SHA384, 4096 bit RSA
Mon Jan 20 10:50:18 2020 [no86.nordvpn.com] Peer Connection Initiated with [AF_INET]37.120.149.19:1194

2020-01-20 10:50:19,692 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:19 2020 SENT CONTROL [no86.nordvpn.com]: 'PUSH_REQUEST' (status=1)

2020-01-20 10:50:21,973 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:21 2020 PUSH: Received control message: 'PUSH_REPLY,redirect-gateway def1,dhcp-option DNS 103.86.96.100,dhcp-option DNS 103.86.99.100,sndbuf 524288,rcvbuf 524288,explicit-exit-notify,comp-lzo no,route-gateway 10.8.0.1,topology subnet,ping 60,ping-restart 180,ifconfig 10.8.0.14 255.255.255.0,peer-id 10,cipher AES-256-GCM'
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: timers and/or timeouts modified
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: explicit notify parm(s) modified
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: compression parms modified
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: --sndbuf/--rcvbuf options modified
Mon Jan 20 10:50:21 2020 Socket Buffers: R=[212992->425984] S=[212992->425984]
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: --ifconfig/up options modified
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: route options modified
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: route-related options modified
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: peer-id set
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: adjusting link_mtu to 1657
Mon Jan 20 10:50:21 2020 OPTIONS IMPORT: data channel crypto options modified
Mon Jan 20 10:50:21 2020 Data Channel: using negotiated cipher 'AES-256-GCM'
Mon Jan 20 10:50:21 2020 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Mon Jan 20 10:50:21 2020 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key

2020-01-20 10:50:21,973 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:21 2020 ROUTE_GATEWAY 172.17.0.1/255.255.0.0 IFACE=eth0 HWADDR=02:42:ac:11:00:02

2020-01-20 10:50:21,974 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:21 2020 TUN/TAP device tun0 opened
Mon Jan 20 10:50:21 2020 TUN/TAP TX queue length set to 100
Mon Jan 20 10:50:21 2020 /usr/bin/ip link set dev tun0 up mtu 1500

2020-01-20 10:50:21,976 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:21 2020 /usr/bin/ip addr add dev tun0 10.8.0.14/24 broadcast 10.8.0.255

2020-01-20 10:50:21,978 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:21 2020 /root/openvpnup.sh tun0 1500 1585 10.8.0.14 255.255.255.0 init

2020-01-20 10:50:21,981 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:21 2020 /usr/bin/ip route add 37.120.149.19/32 via 172.17.0.1

2020-01-20 10:50:21,983 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:21 2020 /usr/bin/ip route add 0.0.0.0/1 via 10.8.0.1

2020-01-20 10:50:21,983 DEBG 'start-script' stdout output:
[debug] Waiting for valid IP address from tunnel...

2020-01-20 10:50:21,984 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:21 2020 /usr/bin/ip route add 128.0.0.0/1 via 10.8.0.1

2020-01-20 10:50:21,986 DEBG 'start-script' stdout output:
Mon Jan 20 10:50:21 2020 Initialization Sequence Completed

2020-01-20 10:50:22,106 DEBG 'start-script' stdout output:
[debug] Valid IP address from tunnel acquired '10.8.0.14'
[info] Application does not require port forwarding or VPN provider is != pia, skipping incoming port assignment
[info] Checking we can resolve name 'www.google.com' to address...

2020-01-20 10:50:27,300 DEBG 'start-script' stdout output:
[info] DNS operational, we can resolve name 'www.google.com' to address '172.217.20.36'

2020-01-20 10:50:27,302 DEBG 'start-script' stdout output:
[info] Attempting to get external IP using Name Server 'ns1.google.com'...

2020-01-20 10:50:37,887 DEBG 'start-script' stdout output:
[info] Successfully retrieved external IP address 37.120.149.20

2020-01-20 10:50:37,981 DEBG 'watchdog-script' stdout output:
[debug] Waiting for iptables chain policies to be in place...

2020-01-20 10:50:37,992 DEBG 'watchdog-script' stdout output:
[debug] iptables chain policies are in place
[info] Deluge listening interface IP 0.0.0.0 and VPN provider IP 10.8.0.14 different, marking for reconfigure

2020-01-20 10:50:37,997 DEBG 'watchdog-script' stdout output:
[info] Deluge not running

2020-01-20 10:50:38,002 DEBG 'watchdog-script' stdout output:
[info] Deluge Web UI not running
[info] Attempting to start Deluge...
[info] Removing deluge pid file (if it exists)...

2020-01-20 10:50:38,449 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'listen_interface' currently has a value of '10.8.1.21'
[info] Deluge key 'listen_interface' will have a new value '10.8.0.14'
[info] Writing changes to Deluge config file '/config/core.conf'...

2020-01-20 10:50:38,730 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'outgoing_interface' currently has a value of 'tun0'
[info] Deluge key 'outgoing_interface' will have a new value 'tun0'
[info] Writing changes to Deluge config file '/config/core.conf'...

2020-01-20 10:50:39,022 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'default_daemon' currently has a value of '2b37d4aa4159450692f0b35dc2acbc61'
[info] Deluge key 'default_daemon' will have a new value '2b37d4aa4159450692f0b35dc2acbc61'
[info] Writing changes to Deluge config file '/config/web.conf'...

2020-01-20 10:50:39,483 DEBG 'watchdog-script' stdout output:
[info] Deluge process started
[info] Waiting for Deluge process to start listening on port 58846...

2020-01-20 10:50:39,705 DEBG 'watchdog-script' stdout output:
[info] Deluge process listening on port 58846

2020-01-20 10:50:40,122 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-20 10:50:40,122 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
  File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
    builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

2020-01-20 10:50:43,382 DEBG 'watchdog-script' stdout output:
[info] No torrents with state 'Error' found

2020-01-20 10:50:43,382 DEBG 'watchdog-script' stdout output:
[info] Starting Deluge Web UI...
[info] Deluge Web UI started

2020-01-20 10:50:43,383 DEBG 'watchdog-script' stdout output:
[debug] VPN IP is 10.8.0.14
[debug] Deluge IP is 10.8.0.14

2020-01-20 10:50:43,788 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-20 10:50:43,789 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
  File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
    builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

2020-01-20 10:51:13,391 DEBG 'watchdog-script' stdout output:
[debug] Waiting for iptables chain policies to be in place...

2020-01-20 10:51:13,403 DEBG 'watchdog-script' stdout output:
[debug] iptables chain policies are in place

2020-01-20 10:51:13,413 DEBG 'watchdog-script' stdout output:
[debug] VPN IP is 10.8.0.14
[debug] Deluge IP is 10.8.0.14

2020-01-20 10:51:43,421 DEBG 'watchdog-script' stdout output:
[debug] Waiting for iptables chain policies to be in place...

2020-01-20 10:51:43,433 DEBG 'watchdog-script' stdout output:
[debug] iptables chain policies are in place

2020-01-20 10:51:43,443 DEBG 'watchdog-script' stdout output:
[debug] VPN IP is 10.8.0.14
[debug] Deluge IP is 10.8.0.14

2020-01-20 10:52:13,451 DEBG 'watchdog-script' stdout output:
[debug] Waiting for iptables chain policies to be in place...

2020-01-20 10:52:13,462 DEBG 'watchdog-script' stdout output:
[debug] iptables chain policies are in place

2020-01-20 10:52:13,472 DEBG 'watchdog-script' stdout output:
[debug] VPN IP is 10.8.0.14
[debug] Deluge IP is 10.8.0.14

2020-01-20 10:52:43,480 DEBG 'watchdog-script' stdout output:
[debug] Waiting for iptables chain policies to be in place...

2020-01-20 10:52:43,492 DEBG 'watchdog-script' stdout output:
[debug] iptables chain policies are in place

2020-01-20 10:52:43,502 DEBG 'watchdog-script' stdout output:
[debug] VPN IP is 10.8.0.14
[debug] Deluge IP is 10.8.0.14

Edited January 20, 2020 by 0x646f6f6d
removing keys

trurl · January 20, 2020

8 minutes ago, 0x646f6f6d said:

Any help would be greatly appreciated!

I don't use this container but I assume posting your credential certificate and VPN key isn't a good idea. Maybe edit your post.

rohlando · January 20, 2020

Thanks for the information, I did change the LAN as noted below, however that did not seem to change the issue which I am still seeing. Still unable to open the WebUI and also showing the events in the log. Am I missing something?

2020-01-20 10:29:18,548 DEBG 'start-script' stderr output:
Error: error sending query: Could not send or receive, because of network error

2020-01-20 10:31:23,664 DEBG 'start-script' stderr output:
Error: error sending query: Could not send or receive, because of network error

2020-01-20 10:33:28,787 DEBG 'start-script' stderr output:
Error: error sending query: Could not send or receive, because of network error

image.png.95d8aad7423e2950b6c3a950433a130d.png

1 hour ago, binhex said:
lan_network should not be set to the same value as the docker custom bridge, it should be set to the network your clients are connected to, as in the machines you want to access the deluge web ui from, if you have multiple networks due to vlan's then define all of them using comma as a separator, e.g.:-
LAN_NETWORK=192.168.1.0/24,192.168.2.0/24

binhex · January 20, 2020

12 minutes ago, rohlando said:

Could not send or receive, because of network error

that is a dns lookup problem, you look to be blocking dns queries, check your host firewall/lan firewall, you need to permit port 53 outbound.

0x646f6f6d · January 20, 2020

56 minutes ago, trurl said:

I don't use this container but I assume posting your credential certificate and VPN key isn't a good idea. Maybe edit your post.

That was the contents of the publicly available nordVPN conf file, however, I've removed it as requested.

Guanyin · January 20, 2020

I keep getting this in my logs which crashes Deluge.

2020-01-20 18:45:01,825 DEBG 'watchdog-script' stdout output:
[info] Starting Deluge Web UI...

2020-01-20 18:45:01,825 DEBG 'watchdog-script' stdout output:
[info] Deluge Web UI started

2020-01-20 18:45:01,988 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-20 18:45:01,988 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

is there a known solution for this issue? I've seen it on this thread before but couldn't find a solution to the problem.

rohlando · January 20, 2020

On 1/20/2020 at 11:05 AM, binhex said:

that is a dns lookup problem, you look to be blocking dns queries, check your host firewall/lan firewall, you need to permit port 53 outbound.

Quote

Thanks, that was my bad on that one. I did forget my firewall rule, so thanks.

I still however can not get the GUI to open. What I have determined is that if I turn the VPN off, it works, when on it does not work. Does the custom VPN configuration need to be anythings specific?

Thank you, was able to get this corrected, was a typo on my part, sorry about that.

Edited January 25, 2020 by rohlando

no1home · January 20, 2020

2 hours ago, Guanyin said:

I keep getting this in my logs which crashes Deluge.

2020-01-20 18:45:01,825 DEBG 'watchdog-script' stdout output:
[info] Starting Deluge Web UI...

2020-01-20 18:45:01,825 DEBG 'watchdog-script' stdout output:
[info] Deluge Web UI started

2020-01-20 18:45:01,988 DEBG 'watchdog-script' stderr output:
Unable to initialize gettext/locale!

2020-01-20 18:45:01,988 DEBG 'watchdog-script' stderr output:
'ngettext'
Traceback (most recent call last):
File "/usr/lib/python3.8/site-packages/deluge/i18n/util.py", line 118, in setup_translation
builtins.__dict__['_n'] = builtins.__dict__['ngettext']
KeyError: 'ngettext'

is there a known solution for this issue? I've seen it on this thread before but couldn't find a solution to the problem.

I have this error as well but it seems to be pretty harmless. See the issue posted here Issue#151. Perhaps there is another error in your log causing deluge to crash for you.

[Support] binhex - DelugeVPN

Recommended Posts

Link to comment

Top Posters In This Topic

Popular Days

Top Posters In This Topic

Popular Days

Popular Posts

Ryanoc3ros

Dad_Rage

binhex

Posted Images

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Join the conversation