Hello,
I try to make rtorrentvpn running.
My VPN is connected with an open port, but rtorrent doesn't look listening port 5000.
Here is my log with debug enabled:
blacksad@CHRONOS:~$ sudo docker run \
-v /root/docker/data:/data \
-v /root/docker/config:/config \
> --cap-add=NET_ADMIN \
> -p 9080:9080 \
> -p 9443:9443 \
> -p 8118:8118 \
> -p 3000:3000 \
> --name=rtorrentvpn \
-e LAN_NETWORK=192.168.2.0/24 \
> -v /root/docker/data:/data \
> -v /root/docker/config:/config \
> -v /etc/localtime:/etc/localtime:ro \
> -e VPN_ENABLED=yes \
> -e VPN_USER=pwd \
> -e VPN_PASS=user \
> -e VPN_PROV=pia \
> -e STRICT_PORT_FORWARD=yes \
> -e ENABLE_PRIVOXY=no \
> -e ENABLE_FLOOD=no \
> -e ENABLE_AUTODL_IRSSI=no \
> -e LAN_NETWORK=192.168.2.0/24 \
> -e 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 \
> -e DEBUG=true \
> -e PHP_TZ=UTC \
> -e UMASK=0022 \
> -e PUID=1000 \
> -e PGID=1000 \
> binhex/arch-rtorrentvpn
Created by...
___. .__ .__
\_ |__ |__| ____ | |__ ____ ___ ___
| __ \| |/ \| | \_/ __ \\ \/ /
| \_\ \ | | \ Y \ ___/ > <
|___ /__|___| /___| /\___ >__/\_ \
\/ \/ \/ \/ \/
https://hub.docker.com/u/binhex/
2019-02-05 20:16:26.221588 [info] System information Linux ec09e4a50cc4 4.15.0-45-generic #48-Ubuntu SMP Tue Jan 29 16:28:13 UTC 2019 x86_64 GNU/Linux
2019-02-05 20:16:26.241275 [info] PUID defined as '1000'
2019-02-05 20:16:26.363886 [info] PGID defined as '1000'
2019-02-05 20:16:26.418681 [info] UMASK defined as '0022'
2019-02-05 20:16:26.437622 [info] Permissions already set for volume mappings
2019-02-05 20:16:26.459599 [info] VPN_ENABLED defined as 'yes'
2019-02-05 20:16:26.481506 [info] OpenVPN config file (ovpn extension) is located at /config/openvpn/CA Montreal.ovpn
dos2unix: converting file /config/openvpn/CA Montreal.ovpn to Unix format...
2019-02-05 20:16:26.509750 [info] VPN remote line defined as 'remote ca-montreal.privateinternetaccess.com 1198'
2019-02-05 20:16:26.528986 [info] VPN_REMOTE defined as 'ca-montreal.privateinternetaccess.com'
2019-02-05 20:16:26.548609 [info] VPN_PORT defined as '1198'
2019-02-05 20:16:26.570129 [info] VPN_PROTOCOL defined as 'udp'
2019-02-05 20:16:26.589802 [info] VPN_DEVICE_TYPE defined as 'tun0'
2019-02-05 20:16:26.608919 [info] VPN_PROV defined as 'pia'
2019-02-05 20:16:26.628019 [info] LAN_NETWORK defined as '192.168.2.0/24'
2019-02-05 20:16:26.647444 [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'
2019-02-05 20:16:26.668127 [info] VPN_USER defined as 'pwd'
2019-02-05 20:16:26.687321 [info] VPN_PASS defined as 'user'
2019-02-05 20:16:26.706802 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS)
2019-02-05 20:16:26.726158 [info] STRICT_PORT_FORWARD defined as 'yes'
2019-02-05 20:16:26.745838 [info] ENABLE_PRIVOXY defined as 'no'
2019-02-05 20:16:26.765190 [info] ENABLE_FLOOD defined as 'no'
2019-02-05 20:16:26.784347 [info] ENABLE_AUTODL_IRSSI defined as 'no'
2019-02-05 20:21:28.088216 [info] Starting Supervisor...
2019-02-05 20:21:28,263 INFO Included extra file "/etc/supervisor/conf.d/rtorrent.conf" during parsing
2019-02-05 20:21:28,263 INFO Set uid to user 0 succeeded
2019-02-05 20:21:28,264 INFO supervisord started with pid 8
2019-02-05 20:21:29,267 INFO spawned: 'start-script' with pid 153
2019-02-05 20:21:29,267 INFO spawned: 'rutorrent-script' with pid 154
2019-02-05 20:21:29,268 INFO spawned: 'flood-script' with pid 155
2019-02-05 20:21:29,268 INFO spawned: 'irssi-script' with pid 156
2019-02-05 20:21:29,269 INFO spawned: 'watchdog-script' with pid 157
2019-02-05 20:21:29,270 INFO spawned: 'privoxy-script' with pid 158
2019-02-05 20:21:29,270 INFO reaped unknown pid 9
2019-02-05 20:21:29,271 DEBG 'start-script' stdout output:
[info] VPN is enabled, beginning configuration of VPN
[debug] Environment variables defined as follows
BASH=/bin/bash
BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:hostcomplete:interactive_comments:pro gcomp: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]="0" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu")
BASH_VERSION='5.0.0(1)-release'
DEBUG=true
DIRSTACK=()
ENABLE_AUTODL_IRSSI=no
ENABLE_FLOOD=no
ENABLE_PRIVOXY=no
EUID=0
GROUPS=()
HOME=/home/nobody
HOSTNAME=ec09e4a50cc4
HOSTTYPE=x86_64
IFS=$' \t\n'
LANG=en_GB.UTF-8
LAN_NETWORK=192.168.2.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=1000
PHP_TZ=UTC
PIPESTATUS=([0]="0")
PPID=8
PS4='+ '
PUID=1000
PWD=/
SHELL=/bin/bash
SHELLOPTS=braceexpand:hashall:interactive-comments
SHLVL=1
STRICT_PORT_FORWARD=yes
SUPERVISOR_ENABLED=1
SUPERVISOR_GROUP_NAME=start-script
SUPERVISOR_PROCESS_NAME=start-script
TERM=xterm
UID=0
UMASK=0022
VPN_CONFIG='/config/openvpn/CA Montreal.ovpn'
VPN_DEVICE_TYPE=tun0
VPN_ENABLED=yes
VPN_OPTIONS=
VPN_PASS=user
VPN_PORT=1198
VPN_PROTOCOL=udp
VPN_PROV=pia
VPN_REMOTE=ca-montreal.privateinternetaccess.com
VPN_USER=pwd
_='[debug] Environment variables defined as follows'
2019-02-05 20:21:29,271 INFO success: start-script entered RUNNING state, process has stayed up for > than 0 seconds (startse cs)
2019-02-05 20:21:29,271 INFO success: rutorrent-script entered RUNNING state, process has stayed up for > than 0 seconds (sta rtsecs)
2019-02-05 20:21:29,272 INFO success: flood-script entered RUNNING state, process has stayed up for > than 0 seconds (startse cs)
2019-02-05 20:21:29,272 INFO success: irssi-script entered RUNNING state, process has stayed up for > than 0 seconds (startse cs)
2019-02-05 20:21:29,272 INFO success: watchdog-script entered RUNNING state, process has stayed up for > than 0 seconds (star tsecs)
2019-02-05 20:21:29,272 INFO success: privoxy-script entered RUNNING state, process has stayed up for > than 0 seconds (start secs)
2019-02-05 20:21:29,272 DEBG 'start-script' stdout output:
[debug] Directory listing of files in /config/openvpn as follows
2019-02-05 20:21:29,272 DEBG 'flood-script' stdout output:
[info] Flood not enabled, skipping starting Flood Web UI
2019-02-05 20:21:29,272 DEBG fd 17 closed, stopped monitoring <POutputDispatcher at 140529256460160 for <Subprocess at 140529 256458216 with name flood-script in state RUNNING> (stdout)>
2019-02-05 20:21:29,273 DEBG fd 21 closed, stopped monitoring <POutputDispatcher at 140529254973664 for <Subprocess at 140529 256458216 with name flood-script in state RUNNING> (stderr)>
2019-02-05 20:21:29,273 INFO exited: flood-script (exit status 0; expected)
2019-02-05 20:21:29,273 DEBG received SIGCLD indicating a child quit
2019-02-05 20:21:29,273 DEBG 'irssi-script' stdout output:
[info] Autodl-irssi not enabled, skipping startup
2019-02-05 20:21:29,273 DEBG fd 26 closed, stopped monitoring <POutputDispatcher at 140529254974240 for <Subprocess at 140529 256458360 with name irssi-script in state RUNNING> (stderr)>
2019-02-05 20:21:29,273 DEBG fd 22 closed, stopped monitoring <POutputDispatcher at 140529254973952 for <Subprocess at 140529 256458360 with name irssi-script in state RUNNING> (stdout)>
2019-02-05 20:21:29,273 INFO exited: irssi-script (exit status 0; expected)
2019-02-05 20:21:29,273 DEBG received SIGCLD indicating a child quit
2019-02-05 20:21:29,274 DEBG 'start-script' stdout output:
total 24
drwxrwxr-x 2 nobody users 4096 Feb 5 20:16 .
drwxrwxr-x 3 root root 4096 Feb 4 22:29 ..
-rwxrwxr-x 1 nobody users 3174 Feb 5 20:16 CA Montreal.ovpn
-rwxrwxr-x 1 nobody users 2025 Feb 5 19:00 ca.rsa.2048.crt
-rwxrwxr-x 1 nobody users 20 Feb 5 19:42 credentials.conf
-rwxrwxr-x 1 nobody users 869 Feb 5 19:00 crl.rsa.2048.pem
2019-02-05 20:21:29,275 DEBG 'watchdog-script' stdout output:
[info] rTorrent config file doesnt exist, copying default to /config/rtorrent/config/...
2019-02-05 20:21:29,275 DEBG 'watchdog-script' stderr output:
mkdir: cannot create directory ‘/config/rtorrent’
2019-02-05 20:21:29,275 DEBG 'watchdog-script' stderr output:
: Permission denied
2019-02-05 20:21:29,277 DEBG 'watchdog-script' stderr output:
sed: can't read /config/rtorrent/config/rtorrent.rc: No such file or directory
2019-02-05 20:21:29,277 DEBG 'privoxy-script' stdout output:
[info] Privoxy set to disabled
2019-02-05 20:21:29,277 DEBG fd 32 closed, stopped monitoring <POutputDispatcher at 140529254975320 for <Subprocess at 140529 256458504 with name privoxy-script in state RUNNING> (stdout)>
2019-02-05 20:21:29,278 DEBG fd 36 closed, stopped monitoring <POutputDispatcher at 140529254975752 for <Subprocess at 140529 256458504 with name privoxy-script in state RUNNING> (stderr)>
2019-02-05 20:21:29,278 INFO exited: privoxy-script (exit status 0; expected)
2019-02-05 20:21:29,278 DEBG received SIGCLD indicating a child quit
2019-02-05 20:21:29,279 DEBG 'watchdog-script' stderr output:
sed: can't read /config/rtorrent/config/rtorrent.rc: No such file or directory
2019-02-05 20:21:29,281 DEBG 'watchdog-script' stderr output:
sed: can't read /config/rtorrent/config/rtorrent.rc: No such file or directory
2019-02-05 20:21:29,283 DEBG 'watchdog-script' stderr output:
sed: can't read /config/rtorrent/config/rtorrent.rc: No such file or directory
2019-02-05 20:21:29,285 DEBG 'watchdog-script' stderr output:
sed: can't read /config/rtorrent/config/rtorrent.rc: No such file or directory
2019-02-05 20:21:29,285 DEBG 'watchdog-script' stderr output:
dos2unix: /config/rtorrent/config/rtorrent.rc: No such file or directory
dos2unix:
2019-02-05 20:21:29,285 DEBG 'watchdog-script' stderr output:
Skipping /config/rtorrent/config/rtorrent.rc, not a regular file.
2019-02-05 20:21:29,286 DEBG 'watchdog-script' stdout output:
[debug] Waiting for valid IP address from tunnel...
2019-02-05 20:21:29,300 DEBG 'start-script' stdout output:
[debug] Contents of ovpn file /config/openvpn/CA Montreal.ovpn as follows...
2019-02-05 20:21:29,301 DEBG 'start-script' stdout output:
remote ca-montreal.privateinternetaccess.com 1198
client
dev tun
proto udp
resolv-retry infinite
nobind
persist-key
cipher aes-128-cbc
auth sha1
tls-client
remote-cert-tls server
auth-user-pass credentials.conf
compress
verb 1
<crl-verify>
-----BEGIN X509 CRL-----
MIICWDCCAUAwDQYJKoZIhvcNAQENBQAwgegxCzAJBgNVBAYTAlVTMQswCQYDVQQI
EwJDQTETMBEGA1UEBxMKTG9zQW5nZWxlczEgMB4GA1UEChMXUHJpdmF0ZSBJbnRl
cm5ldCBBY2Nlc3MxIDAeBgNVBAsTF1ByaXZhdGUgSW50ZXJuZXQgQWNjZXNzMSAw
HgYDVQQDExdQcml2YXRlIEludGVybmV0IEFjY2VzczEgMB4GA1UEKRMXUHJpdmF0
ZSBJbnRlcm5ldCBBY2Nlc3MxLzAtBgkqhkiG9w0BCQEWIHNlY3VyZUBwcml2YXRl
aW50ZXJuZXRhY2Nlc3MuY29tFw0xNjA3MDgxOTAwNDZaFw0zNjA3MDMxOTAwNDZa
MCYwEQIBARcMMTYwNzA4MTkwMDQ2MBECAQYXDDE2MDcwODE5MDA0NjANBgkqhkiG
jI+ffKN3laDvczsG6CxmY3jNyc79XVpEYUnq4rT3FfveW1+Ralf+Vf38HdpwB8EW
B4hZlQ205+21CALLvZvR8HcPxC9KEnev1mU46wkTiov0EKc+EdRxkj5yMgv0V2Re
ze7AP+NQ9ykvDScH4eYCsmufNpIjBLhpLE2cuZZXBLcPhuRzVoU3l7A9lvzG9mjA
5YijHJGHNjlWFqyrn1CfYS6koa4TGEPngBoAziWRbDGdhEgJABHrpoaFYaL61zqy
MR6jC0K2ps9qyZAN74LEBedEfK7tBOzWMwr58A==
-----END X509 CRL-----
</crl-verify>
<ca>
-----BEGIN CERTIFICATE-----
MIIFqzCCBJOgAwIBAgIJAKZ7D5Yv87qDMA0GCSqGSIb3DQEBDQUAMIHoMQswCQYD
VQQGEwJVUzELMAkGA1UECBMCQ0ExEzARBgNVBAcTCkxvc0FuZ2VsZXMxIDAeBgNV
BAoTF1ByaXZhdGUgSW50ZXJuZXQgQWNjZXNzMSAwHgYDVQQLExdQcml2YXRlIElu
dGVybmV0IEFjY2VzczEgMB4GA1UEAxMXUHJpdmF0ZSBJbnRlcm5ldCBBY2Nlc3Mx
IDAeBgNVBCkTF1ByaXZhdGUgSW50ZXJuZXQgQWNjZXNzMS8wLQYJKoZIhvcNAQkB
FiBzZWN1cmVAcHJpdmF0ZWludGVybmV0YWNjZXNzLmNvbTAeFw0xNDA0MTcxNzM1
MThaFw0zNDA0MTIxNzM1MThaMIHoMQswCQYDVQQGEwJVUzELMAkGA1UECBMCQ0Ex
EzARBgNVBAcTCkxvc0FuZ2VsZXMxIDAeBgNVBAoTF1ByaXZhdGUgSW50ZXJuZXQg
QWNjZXNzMSAwHgYDVQQLExdQcml2YXRlIEludGVybmV0IEFjY2VzczEgMB4GA1UE
AxMXUHJpdmF0ZSBJbnRlcm5ldCBBY2Nlc3MxIDAeBgNVBCkTF1ByaXZhdGUgSW50
ZXJuZXQgQWNjZXNzMS8wLQYJKoZIhvcNAQkBFiBzZWN1cmVAcHJpdmF0ZWludGVy
bmV0YWNjZXNzLmNvbTCCASIwDQYJKoZIhvcNAQEBBQADggEPADCCAQoCggEBAPXD
lH/P/CnFPdBWlLp5ze3HRBCc/Avgr5CdMRkEsySL5GHBZsx6w2cayQ2EcRhVTwWp
cdldeNO+pPr9rIgPrtXqT4SWViTQRBeGM8CDxAyTopTsobjSiYZCF9Ta1gunl0G/
8Vfp+SXfYCC+ZzWvP+L1pFhPRqzQQ8k+wMZIovObK1s+nlwPaLyayzw9a8sUnvWB
/5rGPdIYnQWPgoNlLN9HpSmsAcw2z8DXI9pIxbr74cb3/HSfuYGOLkRqrOk6h4RC
OfuWoTrZup1uEOn+fw8CAwEAAaOCAVQwggFQMB0GA1UdDgQWBBQv63nQ/pJAt5tL
y8VJcbHe22ZOsjCCAR8GA1UdIwSCARYwggESgBQv63nQ/pJAt5tLy8VJcbHe22ZO
sqGB7qSB6zCB6DELMAkGA1UEBhMCVVMxCzAJBgNVBAgTAkNBMRMwEQYDVQQHEwpM
b3NBbmdlbGVzMSAwHgYDVQQKExdQcml2YXRlIEludGVybmV0IEFjY2VzczEgMB4G
A1UECxMXUHJpdmF0ZSBJbnRlcm5ldCBBY2Nlc3MxIDAeBgNVBAMTF1ByaXZhdGUg
SW50ZXJuZXQgQWNjZXNzMSAwHgYDVQQpExdQcml2YXRlIEludGVybmV0IEFjY2Vz
czEvMC0GCSqGSIb3DQEJARYgc2VjdXJlQHByaXZhdGVpbnRlcm5ldGFjY2Vzcy5j
b22CCQCmew+WL/O6gzAMBgNVHRMEBTADAQH/MA0GCSqGSIb3DQEBDQUAA4IBAQAn
a5PgrtxfwTumD4+3/SYvwoD66cB8IcK//h1mCzAduU8KgUXocLx7QgJWo9lnZ8xU
ryXvWab2usg4fqk7FPi00bED4f4qVQFVfGfPZIH9QQ7/48bPM9RyfzImZWUCenK3
7pdw4Bvgoys2rHLHbGen7f28knT2j/cbMxd78tQc20TIObGjo8+ISTRclSTRBtyC
GohseKYpTS9himFERpUgNtefvYHbn70mIOzfOJFTVqfrptf9jXa9N8Mpy3ayfodz
1wiqdteqFXkTYoSDctgKMiZ6GdocK9nMroQipIQtpnwd4yBDWIyC6Bvlkrq5TQUt
YDQ8z9v+DMO6iwyIDRiU
-----END CERTIFICATE-----
</ca>
disable-occ
2019-02-05 20:21:29,308 DEBG 'start-script' stdout output:
[info] Default route for container is 172.17.0.1
2019-02-05 20:21:29,310 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.222 to /etc/resolv.conf
2019-02-05 20:21:29,312 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.174 to /etc/resolv.conf
2019-02-05 20:21:29,315 DEBG 'start-script' stdout output:
[info] Adding 1.1.1.1 to /etc/resolv.conf
2019-02-05 20:21:29,317 DEBG 'start-script' stdout output:
[info] Adding 8.8.8.8 to /etc/resolv.conf
2019-02-05 20:21:29,319 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.218 to /etc/resolv.conf
2019-02-05 20:21:29,321 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.177 to /etc/resolv.conf
2019-02-05 20:21:29,324 DEBG 'start-script' stdout output:
[info] Adding 1.0.0.1 to /etc/resolv.conf
2019-02-05 20:21:29,326 DEBG 'start-script' stdout output:
[info] Adding 8.8.4.4 to /etc/resolv.conf
2019-02-05 20:21:44,609 DEBG 'start-script' stdout output:
[info] Attempting to load tun kernel module...
2019-02-05 20:21:44,609 DEBG 'start-script' stderr output:
modprobe: FATAL: Module tun not found in directory /lib/modules/4.15.0-45-generic
2019-02-05 20:21:44,610 DEBG 'start-script' stdout output:
[warn] Unable to load tun kernel module using modprobe, trying insmod...
2019-02-05 20:21:44,610 DEBG 'start-script' stderr output:
insmod: ERROR: could not load module /lib/modules/tun.ko: No such file or directory
2019-02-05 20:21:44,610 DEBG 'start-script' stdout output:
[warn] Unable to load tun kernel module, assuming its dynamically loaded
2019-02-05 20:21:44,616 DEBG 'start-script' stdout output:
[info] Attempting to load iptable_mangle module...
2019-02-05 20:21:44,616 DEBG 'start-script' stderr output:
modprobe: FATAL: Module iptable_mangle not found in directory /lib/modules/4.15.0-45-generic
2019-02-05 20:21:44,616 DEBG 'start-script' stdout output:
[warn] Unable to load iptable_mangle module using modprobe, trying insmod...
2019-02-05 20:21:44,617 DEBG 'start-script' stderr output:
insmod: ERROR: could not load module /lib/modules/iptable_mangle.ko: No such file or directory
2019-02-05 20:21:44,617 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 iptabl e_mangle'
[info] Synology users: Please attempt to load the module by executing the following on your host: 'insmod /lib/modules/iptabl e_mangle.ko'
[debug] Show name servers defined for container
2019-02-05 20:21:44,617 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
2019-02-05 20:21:44,617 DEBG 'start-script' stdout output:
[debug] Show name resolution for VPN endpoint ca-montreal.privateinternetaccess.com
2019-02-05 20:21:44,744 DEBG 'start-script' stdout output:
;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 8487
;; flags: qr rd ra ; QUERY: 1, ANSWER: 13, AUTHORITY: 4, ADDITIONAL: 0
;; QUESTION SECTION:
;; ca-montreal.privateinternetaccess.com. IN A
;; ANSWER SECTION:
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.138
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.165
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.140
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.146
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.170
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.182
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.185
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.188
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.153
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.132
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.151
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.163
ca-montreal.privateinternetaccess.com. 300 IN A 199.229.249.164
;; AUTHORITY SECTION:
privateinternetaccess.com. 86400 IN NS ns2.p28.dynect.net.
privateinternetaccess.com. 86400 IN NS ns4.p28.dynect.net.
privateinternetaccess.com. 86400 IN NS ns3.p28.dynect.net.
privateinternetaccess.com. 86400 IN NS ns1.p28.dynect.net.
;; ADDITIONAL SECTION:
;; Query time: 125 msec
;; SERVER: 209.222.18.218
;; WHEN: Tue Feb 5 20:21:44 2019
;; MSG SIZE rcvd: 349
2019-02-05 20:21:44,744 DEBG 'start-script' stdout output:
[debug] Show contents of hosts file
2019-02-05 20:21:44,745 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.4 ec09e4a50cc4
199.229.249.138 ca-montreal.privateinternetaccess.com
2019-02-05 20:21:44,747 DEBG 'start-script' stdout output:
[debug] Docker interface defined as eth0
2019-02-05 20:21:44,750 DEBG 'start-script' stdout output:
[debug] Docker IP defined as 172.17.0.4
2019-02-05 20:21:44,752 DEBG 'start-script' stdout output:
[debug] Docker netmask defined as 255.255.0.0
2019-02-05 20:21:44,757 DEBG 'start-script' stdout output:
[info] Docker network defined as 172.17.0.0/16
2019-02-05 20:21:44,760 DEBG 'start-script' stdout output:
[info] Adding 192.168.2.0/24 as route via docker eth0
2019-02-05 20:21:44,761 DEBG 'start-script' stdout output:
[info] ip route defined as follows...
--------------------
2019-02-05 20:21:44,761 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.4
192.168.2.0/24 via 172.17.0.1 dev eth0
2019-02-05 20:21:44,762 DEBG 'start-script' stdout output:
--------------------
[debug] Modules currently loaded for kernel
2019-02-05 20:21:44,763 DEBG 'start-script' stdout output:
Module Size Used by
xt_nat 16384 28
xt_tcpudp 16384 60
veth 16384 0
ipt_MASQUERADE 16384 20
nf_nat_masquerade_ipv4 16384 1 ipt_MASQUERADE
nf_conntrack_netlink 40960 0
nfnetlink 16384 2 nf_conntrack_netlink
xfrm_user 32768 1
xfrm_algo 16384 1 xfrm_user
iptable_nat 16384 4
nf_conntrack_ipv4 16384 51
nf_defrag_ipv4 16384 1 nf_conntrack_ipv4
nf_nat_ipv4 16384 1 iptable_nat
xt_addrtype 16384 4
iptable_filter 16384 1
xt_conntrack 16384 2
nf_nat 32768 3 nf_nat_masquerade_ipv4,nf_nat_ipv4,xt_nat
nf_conntrack 131072 8 xt_conntrack,nf_nat_masquerade_ipv4,nf_conntrack_ipv4,nf_nat,ipt_MASQUERADE,nf_nat_ipv4,xt_na t,nf_conntrack_netlink
libcrc32c 16384 2 nf_conntrack,nf_nat
br_netfilter 24576 0
bridge 151552 1 br_netfilter
stp 16384 1 bridge
llc 16384 2 bridge,stp
overlay 77824 4
aufs 241664 0
snd_hda_codec_realtek 106496 1
snd_hda_codec_generic 73728 1 snd_hda_codec_realtek
edac_mce_amd 28672 0
snd_hda_codec_hdmi 49152 1
snd_hda_intel 40960 0
snd_hda_codec 126976 4 snd_hda_codec_generic,snd_hda_codec_hdmi,snd_hda_intel,snd_hda_codec_realtek
kvm 598016 0
snd_hda_core 81920 5 snd_hda_codec_generic,snd_hda_codec_hdmi,snd_hda_intel,snd_hda_codec,snd_hda_codec_realtek
snd_hwdep 20480 1 snd_hda_codec
snd_pcm 98304 4 snd_hda_codec_hdmi,snd_hda_intel,snd_hda_codec,snd_hda_core
irqbypass 16384 1 kvm
snd_timer 32768 1 snd_pcm
crct10dif_pclmul 16384 0
snd 81920 8 snd_hda_codec_generic,snd_hda_codec_hdmi,snd_hwdep,snd_hda_intel,snd_hda_codec,snd_hda_codec_ realtek,snd_timer,snd_pcm
crc32_pclmul 16384 0
soundcore 16384 1 snd
joydev 24576 0
input_leds 16384 0
ccp 73728 0
ghash_clmulni_intel 16384 0
pcbc 16384 0
aesni_intel 188416 0
eeepc_wmi 16384 0
aes_x86_64 20480 1 aesni_intel
asus_wmi 28672 1 eeepc_wmi
crypto_simd 16384 1 aesni_intel
shpchp 36864 0
sparse_keymap 16384 1 asus_wmi
glue_helper 16384 1 aesni_intel
k10temp 16384 0
video 45056 1 asus_wmi
cryptd 24576 3 crypto_simd,ghash_clmulni_intel,aesni_intel
wmi_bmof 16384 0
mac_hid 16384 0
sch_fq_codel 20480 3
ip_tables 28672 2 iptable_filter,iptable_nat
x_tables 40960 7 xt_conntrack,iptable_filter,xt_tcpudp,ipt_MASQUERADE,xt_addrtype,xt_nat,ip_tables
autofs4 40960 2
hid_generic 16384 0
usbhid 49152 0
hid 118784 2 usbhid,hid_generic
amdkfd 180224 1
amd_iommu_v2 20480 1 amdkfd
amdgpu 2703360 1
chash 16384 1 amdgpu
ttm 106496 1 amdgpu
drm_kms_helper 172032 1 amdgpu
syscopyarea 16384 1 drm_kms_helper
sysfillrect 16384 1 drm_kms_helper
sysimgblt 16384 1 drm_kms_helper
fb_sys_fops 16384 1 drm_kms_helper
igb 217088 0
drm 401408 4 drm_kms_helper,amdgpu,ttm
dca 16384 1 igb
i2c_piix4 24576 0
ahci 40960 1
i2c_algo_bit 16384 2 igb,amdgpu
ptp 20480 1 igb
libahci 32768 1 ahci
pps_core 20480 1 ptp
wmi 24576 2 asus_wmi,wmi_bmof
gpio_amdpt 16384 0
gpio_generic 20480 1 gpio_amdpt
2019-02-05 20:21:44,861 DEBG 'start-script' stdout output:
[info] iptables defined as follows...
--------------------
2019-02-05 20:21:44,862 DEBG 'start-script' stdout output:
-P INPUT DROP
-P FORWARD ACCEPT
-P OUTPUT DROP
-A INPUT -i tun0 -j ACCEPT
-A INPUT -s 172.17.0.0/16 -d 172.17.0.0/16 -j ACCEPT
-A INPUT -i eth0 -p udp -m udp --sport 1198 -j ACCEPT
-A INPUT -i eth0 -p tcp -m tcp --dport 9080 -j ACCEPT
-A INPUT -i eth0 -p tcp -m tcp --sport 9080 -j ACCEPT
-A INPUT -i eth0 -p tcp -m tcp --dport 9443 -j ACCEPT
-A INPUT -i eth0 -p tcp -m tcp --sport 9443 -j ACCEPT
-A INPUT -s 192.168.2.0/24 -i eth0 -p tcp -m tcp --dport 5000 -j ACCEPT
-A INPUT -p icmp -m icmp --icmp-type 0 -j ACCEPT
-A INPUT -i lo -j ACCEPT
-A OUTPUT -o tun0 -j ACCEPT
-A OUTPUT -s 172.17.0.0/16 -d 172.17.0.0/16 -j ACCEPT
-A OUTPUT -o eth0 -p udp -m udp --dport 1198 -j ACCEPT
-A OUTPUT -o eth0 -p tcp -m tcp --dport 9080 -j ACCEPT
-A OUTPUT -o eth0 -p tcp -m tcp --sport 9080 -j ACCEPT
-A OUTPUT -o eth0 -p tcp -m tcp --dport 9443 -j ACCEPT
-A OUTPUT -o eth0 -p tcp -m tcp --sport 9443 -j ACCEPT
-A OUTPUT -d 192.168.2.0/24 -o eth0 -p tcp -m tcp --sport 5000 -j ACCEPT
-A OUTPUT -p icmp -m icmp --icmp-type 8 -j ACCEPT
-A OUTPUT -o lo -j ACCEPT
2019-02-05 20:21:44,862 DEBG 'start-script' stdout output:
--------------------
2019-02-05 20:21:44,862 DEBG 'start-script' stdout output:
[debug] OpenVPN command line:- /usr/bin/openvpn --daemon --reneg-sec 0 --mute-replay-warnings --auth-nocache --setenv VPN_PRO V 'pia' --setenv DEBUG 'true' --setenv VPN_DEVICE_TYPE 'tun0' --setenv VPN_REMOTE 'ca-montreal.privateinternetaccess.com' --s cript-security 2 --up /root/openvpnup.sh --up-delay --up-restart --writepid /root/openvpn.pid --remap-usr1 SIGHUP --log-appen d /dev/stdout --pull-filter ignore 'up' --pull-filter ignore 'down' --pull-filter ignore 'route-ipv6' --pull-filter ignore 'i fconfig-ipv6' --pull-filter ignore 'tun-ipv6' --pull-filter ignore 'persist-tun' --pull-filter ignore 'reneg-sec' --remote 19 9.229.249.138 1198 udp --remote 199.229.249.164 1198 udp --remote 199.229.249.163 1198 udp --remote 199.229.249.146 1198 udp --remote 199.229.249.187 1198 udp --remote 199.229.249.143 1198 udp --remote 199.229.249.173 1198 udp --remote 199.229.249.18 5 1198 udp --remote 199.229.249.177 1198 udp --remote 199.229.249.132 1198 udp --remote 199.229.249.142 1198 udp --remote 199 .229.249.165 1198 udp --remote 199.229.249.181 1198 udp --remote-random --keepalive 10 60 --setenv STRICT_PORT_FORWARD 'yes' --disable-occ --auth-user-pass credentials.conf --cd /config/openvpn --config '/config/openvpn/CA Montreal.ovpn'
2019-02-05 20:21:44,863 DEBG 'start-script' stdout output:
[info] Starting OpenVPN...
2019-02-05 20:21:44,867 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:44 2019 WARNING: file 'credentials.conf' is group or others accessible
2019-02-05 20:21:44,868 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:44 2019 OpenVPN 2.4.6 x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] b uilt on Apr 24 2018
Tue Feb 5 20:21:44 2019 library versions: OpenSSL 1.1.1a 20 Nov 2018, LZO 2.10
2019-02-05 20:21:44,868 DEBG 'start-script' stdout output:
[info] OpenVPN started
2019-02-05 20:21:44,868 DEBG 'start-script' stdout output:
[debug] Waiting for valid IP address from tunnel...
2019-02-05 20:21:44,868 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:44 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined script s
2019-02-05 20:21:44,868 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:44 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]199.229.249.181:1198
Tue Feb 5 20:21:44 2019 UDP link local: (not bound)
Tue Feb 5 20:21:44 2019 UDP link remote: [AF_INET]199.229.249.181:1198
2019-02-05 20:21:45,099 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:45 2019 [559bec350bd6509591e00595c47663b8] Peer Connection Initiated with [AF_INET]199.229.249.181:1198
2019-02-05 20:21:46,231 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:46 2019 auth-token received, disabling auth-nocache for the authentication token
2019-02-05 20:21:46,232 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:46 2019 TUN/TAP device tun0 opened
2019-02-05 20:21:46,232 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:46 2019 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Tue Feb 5 20:21:46 2019 /usr/bin/ip link set dev tun0 up mtu 1500
2019-02-05 20:21:46,233 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:46 2019 /usr/bin/ip addr add dev tun0 local 10.12.10.10 peer 10.12.10.9
2019-02-05 20:21:46,233 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:46 2019 /root/openvpnup.sh tun0 1500 1558 10.12.10.10 10.12.10.9 init
2019-02-05 20:21:46,236 DEBG 'start-script' stdout output:
[debug] Waiting for valid IP address from tunnel...
2019-02-05 20:21:46,236 DEBG 'start-script' stdout output:
[debug] Waiting for valid IP address from tunnel...
2019-02-05 20:21:46,238 DEBG 'start-script' stdout output:
Tue Feb 5 20:21:46 2019 Initialization Sequence Completed
2019-02-05 20:21:46,272 DEBG 'start-script' stdout output:
[debug] Valid IP address from tunnel acquired '10.12.10.10'
2019-02-05 20:21:46,315 DEBG 'watchdog-script' stdout output:
[debug] Valid IP address from tunnel acquired '10.12.10.10'
[info] rTorrent listening interface IP 0.0.0.0 and VPN provider IP 10.12.10.10 different, marking for reconfigure
2019-02-05 20:21:46,318 DEBG 'watchdog-script' stdout output:
[info] rTorrent not running
2019-02-05 20:21:46,342 DEBG 'start-script' stdout output:
[debug] Valid IP address from tunnel acquired '10.12.10.10'
2019-02-05 20:21:46,343 DEBG 'start-script' stdout output:
[debug] Valid IP address from tunnel acquired '10.12.10.10'
2019-02-05 20:21:46,343 DEBG 'start-script' stdout output:
[debug] Port forwarding is enabled
[debug] Checking endpoint 'ca-montreal.privateinternetaccess.com' is port forward enabled...
2019-02-05 20:21:46,343 DEBG 'start-script' stdout output:
[debug] Attempting to get external IP using Name Server 'ns1.google.com'...
2019-02-05 20:21:46,345 DEBG 'start-script' stdout output:
[info] Attempting to curl https://www.privateinternetaccess.com/vpninfo/servers?version=82...
2019-02-05 20:21:46,562 DEBG 'start-script' stdout output:
[info] Successfully retrieved external IP address 199.229.249.181
2019-02-05 20:21:46,574 DEBG 'start-script' stdout output:
[info] Curl successful for https://www.privateinternetaccess.com/vpninfo/servers?version=82, response code 200
2019-02-05 20:21:46,619 DEBG 'start-script' stdout output:
[debug] PIA endpoint 'ca-montreal.privateinternetaccess.com' is in the list of endpoints that support port forwarding
2019-02-05 20:21:46,619 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
[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
[debug] Attempting to get dynamically assigned port...
2019-02-05 20:21:46,620 DEBG 'watchdog-script' stdout output:
[debug] External IP address from tunnel is '199.229.249.181'
2019-02-05 20:21:46,620 DEBG 'watchdog-script' stdout output:
[debug] Waiting for file '/home/nobody/vpn_incoming_port.txt' to be generated (contains PIA API generated incoming port numbe r)...
2019-02-05 20:21:46,622 DEBG 'start-script' stdout output:
[info] Attempting to curl http://209.222.18.222:2000/?client_id=f5aa2d5739129722f06cf56be6adeeda24fa2138f82244abd47f3...
2019-02-05 20:21:47,149 DEBG 'start-script' stdout output:
[info] Curl successful for http://209.222.18.222:2000/?client_id=f5aa2d5739129722f06cf56be6adeeda24fa2138f82244abd405ba37f3, response code 200
2019-02-05 20:21:47,171 DEBG 'start-script' stdout output:
[debug] Successfully assigned incoming port 56945
2019-02-05 20:21:47,621 DEBG 'watchdog-script' stdout output:
[debug] Incoming port for tunnel is '56945'
2019-02-05 20:21:47,621 DEBG 'watchdog-script' stdout output:
[info] rTorrent incoming port 49160 and VPN incoming port 56945 different, marking for reconfigure
[info] Removing any rTorrent session lock files left over from the previous run...
2019-02-05 20:21:47,622 DEBG 'watchdog-script' stdout output:
[info] Attempting to start rTorrent...
2019-02-05 20:21:47,623 DEBG 'watchdog-script' stdout output:
Script started, file is /home/nobody/typescript
2019-02-05 20:21:47,643 DEBG 'watchdog-script' stdout output:
Script done, file is /home/nobody/typescript
2019-02-05 20:21:47,646 DEBG 'watchdog-script' stdout output:
[debug] Waiting for rTorrent process to start...
2019-02-05 20:21:48,650 DEBG 'watchdog-script' stdout output:
[info] rTorrent process started
2019-02-05 20:21:48,652 DEBG 'watchdog-script' stdout output:
[info] Waiting for rTorrent process to start listening on port 5000...
I'm unable to indentify the issue.
If somebody can help, I'll be grateful.
Thanks for your help.