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

Noob (1/14)

0

Reputation

  1. 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
  2. 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.