blacksad
Members-
Posts
2 -
Joined
-
Last visited
Recent Profile Visitors
The recent visitors block is disabled and is not being shown to other users.
blacksad's Achievements
Noob (1/14)
0
Reputation
-
Hello, Thank you all for your answers. permissions issues, probably due to incorrect umask, 0022 is not valid, set it back to 000, or if you want to be a little tighter set it to 002 Thanks binhex. It did the trick. With 000 as UMASK and rights relaxed an config and data folders it works. I will rationalized that later. I still have a little issue. There is a 5 minutes delay during the initialization, just after ENABLE_AUTODL_IRSSI step (which is disabled). There is no specific output. Do you have any idea of what is blocking ? 2019-02-06 22:38:29.978641 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS) 2019-02-06 22:38:29.998500 [info] STRICT_PORT_FORWARD defined as 'yes' 2019-02-06 22:38:30.017873 [info] ENABLE_PRIVOXY defined as 'no' 2019-02-06 22:38:30.037296 [info] ENABLE_FLOOD defined as 'no' 2019-02-06 22:38:30.056538 [info] ENABLE_AUTODL_IRSSI defined as 'no' 2019-02-06 22:43:31.103989 [info] Starting Supervisor... 2019-02-06 22:43:31,285 INFO Included extra file "/etc/supervisor/conf.d/rtorrent.conf" during parsing 2019-02-06 22:43:31,285 INFO Set uid to user 0 succeeded I attached the full log if it helps. Thanks for your support. 20190206_rtorrentvpn_log.txt
-
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.