CedarMadness

Members
  • Posts

    3
  • Joined

  • Last visited

Everything posted by CedarMadness

  1. I removed tun-mtu and tried with mssfix at various values as well as leaving it off and still no luck. It's almost always exactly 30 seconds for it to restart, but sometimes it lasts 1 minute or 1:30. Here is the first part of my log below: Created by... ___. .__ .__ \_ |__ |__| ____ | |__ ____ ___ ___ | __ \| |/ \| | \_/ __ \\ \/ / | \_\ \ | | \ Y \ ___/ > < |___ /__|___| /___| /\___ >__/\_ \ \/ \/ \/ \/ \/ https://hub.docker.com/u/binhex/ 2018-02-19 11:30:03.142687 [info] System information Linux 68e037700d87 4.2.8 #1 SMP Tue Feb 13 01:43:29 CST 2018 x86_64 GNU/Linux 2018-02-19 11:30:03.231792 [info] PUID defined as '911' 2018-02-19 11:30:03.350080 [info] PGID defined as '1000' 2018-02-19 11:30:03.437476 [info] UMASK defined as '000' 2018-02-19 11:30:03.505382 [info] Permissions already set for volume mappings 2018-02-19 11:30:03.588286 [info] VPN_ENABLED defined as 'yes' 2018-02-19 11:30:03.872091 [info] OpenVPN config file (ovpn extension) is located at /config/openvpn/AirVPN_US-Pennsylvania_Metallah_UDP-443.ovpn dos2unix: converting file /config/openvpn/AirVPN_US-Pennsylvania_Metallah_UDP-443.ovpn to Unix format... 2018-02-19 11:30:03.947324 [info] VPN remote line defined as '199.19.94.12 443' 2018-02-19 11:30:04.012750 [info] VPN_REMOTE defined as '199.19.94.12' 2018-02-19 11:30:04.078367 [info] VPN_PORT defined as '443' 2018-02-19 11:30:04.150602 [info] VPN_PROTOCOL defined as 'udp' 2018-02-19 11:30:04.220178 [info] VPN_DEVICE_TYPE defined as 'tun0' 2018-02-19 11:30:04.289099 [info] VPN_PROV defined as 'airvpn' 2018-02-19 11:30:04.358946 [info] LAN_NETWORK defined as '10.11.12.0/24' 2018-02-19 11:30:04.431508 [info] NAME_SERVERS defined as '8.8.8.8,37.235.1.174,37.235.1.177,209.244.0.3,64.6.64.6,4.2.2.2,8.8.4.4,208.67.222.222,209.222.18.218' 2018-02-19 11:30:04.498166 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS) 2018-02-19 11:30:04.570766 [info] ENABLE_PRIVOXY defined as 'NO' 2018-02-19 11:30:04.639653 [info] Setting permissions on files/folders inside container... 2018-02-19 11:30:04.736066 [info] Starting Supervisor... 2018-02-19 11:30:05,323 CRIT Set uid to user 0 2018-02-19 11:30:05,323 INFO Included extra file "/etc/supervisor/conf.d/delugevpn.conf" during parsing 2018-02-19 11:30:05,333 INFO supervisord started with pid 6 2018-02-19 11:30:06,336 INFO spawned: 'start-script' with pid 117 2018-02-19 11:30:06,338 INFO spawned: 'deluge-script' with pid 118 2018-02-19 11:30:06,341 INFO spawned: 'deluge-web-script' with pid 119 2018-02-19 11:30:06,344 INFO spawned: 'privoxy-script' with pid 120 2018-02-19 11:30:06,345 INFO reaped unknown pid 7 2018-02-19 11:30:06,354 DEBG 'deluge-script' stdout output: [info] Deluge config file already exists, skipping copy 2018-02-19 11:30:06,355 INFO success: start-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs) 2018-02-19 11:30:06,355 INFO success: deluge-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs) 2018-02-19 11:30:06,355 INFO success: deluge-web-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs) 2018-02-19 11:30:06,355 INFO success: privoxy-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs) 2018-02-19 11:30:06,356 DEBG 'deluge-script' stdout output: [info] VPN is enabled, checking VPN tunnel local ip is valid 2018-02-19 11:30:06,356 DEBG 'deluge-script' stdout output: [debug] Waiting for valid IP address from tunnel... 2018-02-19 11:30:06,360 DEBG 'privoxy-script' stdout output: [info] Privoxy set to disabled 2018-02-19 11:30:06,361 DEBG fd 22 closed, stopped monitoring <POutputDispatcher at 140379986381280 for <Subprocess at 140379995430712 with name privoxy-script in state RUNNING> (stdout)> 2018-02-19 11:30:06,361 DEBG fd 26 closed, stopped monitoring <POutputDispatcher at 140379986381712 for <Subprocess at 140379995430712 with name privoxy-script in state RUNNING> (stderr)> 2018-02-19 11:30:06,362 INFO exited: privoxy-script (exit status 0; expected) 2018-02-19 11:30:06,362 DEBG received SIGCLD indicating a child quit 2018-02-19 11:30:06,400 DEBG 'start-script' stdout output: [info] VPN is enabled, beginning configuration of VPN [debug] Environment variables defined as follows BASH=/bin/bash BASHOPTS=cmdhist:complete_fullquote:extquote:force_fignore:hostcomplete:interactive_comments:progcomp:promptvars:sourcepath BASH_ALIASES=() BASH_ARGC=() BASH_ARGV=() BASH_CMDS=() 2018-02-19 11:30:06,401 DEBG 'start-script' stdout output: BASH_LINENO=([0]="0") BASH_SOURCE=([0]="/root/start.sh") BASH_VERSINFO=([0]="4" [1]="4" [2]="12" [3]="1" [4]="release" [5]="x86_64-unknown-linux-gnu") BASH_VERSION='4.4.12(1)-release' DEBUG=true DIRSTACK=() ENABLE_PRIVOXY=NO EUID=0 GROUPS=() HOME=/home/nobody HOSTNAME=68e037700d87 HOSTTYPE=x86_64 IFS=$' \t\n' LANG=en_GB.UTF-8 LAN_NETWORK=10.11.12.0/24 MACHTYPE=x86_64-unknown-linux-gnu NAME_SERVERS=8.8.8.8,37.235.1.174,37.235.1.177,209.244.0.3,64.6.64.6,4.2.2.2,8.8.4.4,208.67.222.222,209.222.18.218 OPTERR=1 OPTIND=1 OSTYPE=linux-gnu PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin PGID=1000 PIPESTATUS=([0]="0") PPID=6 PS4='+ ' PUID=911 PWD=/ SHELL=/bin/bash SHELLOPTS=braceexpand:hashall:interactive-comments SHLVL=1 SUPERVISOR_ENABLED=1 SUPERVISOR_GROUP_NAME=start-script SUPERVISOR_PROCESS_NAME=start-script TERM=xterm UID=0 UMASK=000 VPN_CONFIG=/config/openvpn/AirVPN_US-Pennsylvania_Metallah_UDP-443.ovpn VPN_DEVICE_TYPE=tun0 VPN_ENABLED=yes VPN_OPTIONS= VPN_PORT=443 VPN_PROTOCOL=udp VPN_PROV=airvpn VPN_REMOTE=199.19.94.12 _='[debug] Environment variables defined as follows' vpn_remote_line='199.19.94.12 443' [debug] Directory listing of files in /config/openvpn as follows 2018-02-19 11:30:06,420 DEBG 'start-script' stdout output: total 7564 drwxrwxr-x+ 4 nobody users 4096 Feb 19 11:30 . drwxrwxr-x+ 7 nobody users 4096 Feb 19 11:30 .. drwxrwxr-x+ 2 nobody users 4096 Feb 16 14:02 airvpn -rwxrwxr-x+ 1 nobody users 9155 Feb 19 11:30 AirVPN_US-Pennsylvania_Metallah_UDP-443.ovpn drwxrwxr-x+ 2 nobody users 491520 Feb 16 12:24 configs -rwxrwxr-x+ 1 nobody users 7212234 Feb 16 15:56 openvpn.log 2018-02-19 11:30:06,470 DEBG 'start-script' stdout output: [debug] Contents of ovpn file /config/openvpn/AirVPN_US-Pennsylvania_Metallah_UDP-443.ovpn as follows... 2018-02-19 11:30:06,473 DEBG 'start-script' stdout output: remote 199.19.94.12 443 # -------------------------------------------------------- # Air VPN | https://airvpn.org | Friday 16th of February 2018 07:43:22 PM # OpenVPN Client Configuration # AirVPN_US-Pennsylvania_Metallah_UDP-443 # -------------------------------------------------------- client dev tun proto udp resolv-retry infinite remote-random nobind #mssfix 1400 persist-key ping 15 ping-restart 60 explicit-exit-notify 3 remote-cert-tls server #mute 10000 comp-lzo no verb 3 pull fast-io cipher AES-256-CBC key-direction 1 <ca> -----BEGIN CERTIFICATE----- ... -----END CERTIFICATE----- </ca> <cert> -----BEGIN CERTIFICATE----- ... -----END CERTIFICATE----- </cert> <key> -----BEGIN PRIVATE KEY----- ... -----END PRIVATE KEY----- </key> <tls-auth> # # 2048 bit OpenVPN static key # -----BEGIN OpenVPN Static key V1----- ... -----END OpenVPN Static key V1----- </tls-auth> 2018-02-19 11:30:06,516 DEBG 'start-script' stdout output: [info] Default route for container is 10.0.3.1 2018-02-19 11:30:06,522 DEBG 'start-script' stdout output: [info] Adding 8.8.8.8 to /etc/resolv.conf 2018-02-19 11:30:06,529 DEBG 'start-script' stdout output: [info] Adding 37.235.1.174 to /etc/resolv.conf 2018-02-19 11:30:06,535 DEBG 'start-script' stdout output: [info] Adding 37.235.1.177 to /etc/resolv.conf 2018-02-19 11:30:06,542 DEBG 'start-script' stdout output: [info] Adding 209.244.0.3 to /etc/resolv.conf 2018-02-19 11:30:06,548 DEBG 'start-script' stdout output: [info] Adding 64.6.64.6 to /etc/resolv.conf 2018-02-19 11:30:06,555 DEBG 'start-script' stdout output: [info] Adding 4.2.2.2 to /etc/resolv.conf 2018-02-19 11:30:06,561 DEBG 'start-script' stdout output: [info] Adding 8.8.4.4 to /etc/resolv.conf 2018-02-19 11:30:06,567 DEBG 'start-script' stdout output: [info] Adding 208.67.222.222 to /etc/resolv.conf 2018-02-19 11:30:06,574 DEBG 'start-script' stdout output: [info] Adding 209.222.18.218 to /etc/resolv.conf 2018-02-19 11:30:06,579 DEBG 'start-script' stdout output: 199.19.94.12 2018-02-19 11:30:06,606 DEBG 'start-script' stdout output: [info] Attempting to load iptable_mangle module... 2018-02-19 11:30:06,609 DEBG 'start-script' stderr output: modprobe: FATAL: Module iptable_mangle not found in directory /lib/modules/4.2.8 2018-02-19 11:30:06,610 DEBG 'start-script' stdout output: [warn] Unable to load iptable_mangle module using modprobe, trying insmod... 2018-02-19 11:30:06,616 DEBG 'start-script' stderr output: insmod: ERROR: could not load module /lib/modules/iptable_mangle.ko: No such file or directory 2018-02-19 11:30:06,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 iptable_mangle' [info] Synology users: Please attempt to load the module by executing the following on your host: 'insmod /lib/modules/iptable_mangle.ko' [debug] Show name servers defined for container 2018-02-19 11:30:06,618 DEBG 'start-script' stdout output: nameserver 8.8.8.8 nameserver 37.235.1.174 nameserver 37.235.1.177 nameserver 209.244.0.3 nameserver 64.6.64.6 nameserver 4.2.2.2 nameserver 8.8.4.4 nameserver 208.67.222.222 nameserver 209.222.18.218 2018-02-19 11:30:06,619 DEBG 'start-script' stdout output: [debug] Show name resolution for VPN endpoint 199.19.94.12 2018-02-19 11:30:06,656 DEBG 'start-script' stdout output: ;; ->>HEADER<<- opcode: QUERY, rcode: NXDOMAIN, id: 43339 ;; flags: qr rd ra ; QUERY: 1, ANSWER: 0, AUTHORITY: 1, ADDITIONAL: 0 ;; QUESTION SECTION: ;; 199.19.94.12. IN A ;; ANSWER SECTION: ;; AUTHORITY SECTION: . 86381 IN SOA a.root-servers.net. nstld.verisign-grs.com. 2018021900 1800 900 604800 86400 ;; ADDITIONAL SECTION: ;; Query time: 29 msec ;; SERVER: 8.8.8.8 ;; WHEN: Mon Feb 19 11:30:06 2018 ;; MSG SIZE rcvd: 105 2018-02-19 11:30:06,657 DEBG 'start-script' stdout output: [debug] Show contents of hosts file 2018-02-19 11:30:06,658 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 10.0.3.6 68e037700d87 2018-02-19 11:30:06,666 DEBG 'start-script' stdout output: [info] Adding 10.11.12.0/24 as route via docker eth0 2018-02-19 11:30:06,668 DEBG 'start-script' stdout output: [info] ip route defined as follows... -------------------- 2018-02-19 11:30:06,670 DEBG 'start-script' stdout output: default via 10.0.3.1 dev eth0 10.0.3.0/24 dev eth0 proto kernel scope link src 10.0.3.6 10.11.12.0/24 via 10.0.3.1 dev eth0 2018-02-19 11:30:06,670 DEBG 'start-script' stdout output: -------------------- [debug] Modules currently loaded for kernel 2018-02-19 11:30:06,675 DEBG 'start-script' stdout output: Module Size Used by xfrm4_tunnel 1993 0 ipcomp 2203 0 esp4 6849 0 ah4 6547 0 xfrm4_mode_transport 1418 0 xfrm4_mode_tunnel 1936 0 xfrm4_mode_beet 1979 0 af_key 25926 0 xfrm_ipcomp 3868 1 ipcomp nf_nat_redirect 1267 0 l2tp_ppp 15362 0 l2tp_eth 3984 0 l2tp_netlink 9274 2 l2tp_eth,l2tp_ppp l2tp_debugfs 4346 0 l2tp_ip 6053 0 l2tp_core 18070 5 l2tp_eth,l2tp_ppp,l2tp_debugfs,l2tp_ip,l2tp_netlink ppp_deflate 3874 0 ppp_async 6850 0 ppp_mppe 6187 0 bsd_comp 5226 0 pppoe 9430 0 pppox 1810 2 l2tp_ppp,pppoe ppp_generic 18543 7 l2tp_ppp,pppoe,pppox,bsd_comp,ppp_mppe,ppp_async,ppp_deflate slhc 5235 1 ppp_generic tunnel4 2325 1 xfrm4_tunnel vhost_scsi 21520 0 target_core_mod 392179 1 vhost_scsi vhost_net 10228 0 vhost 19160 2 vhost_net,vhost_scsi macvtap 10877 1 vhost_net macvlan 13168 1 macvtap tun 18961 1 vhost_net vringh 8642 0 virtio_scsi 10385 0 virtio_pci 14007 0 virtio_net 21315 0 virtio_mmio 5142 0 virtio_console 17572 0 virtio_blk 8736 0 virtio_balloon 5479 0 virtio_rng 2527 0 virtio_ring 7299 8 virtio_blk,virtio_net,virtio_pci,virtio_rng,virtio_balloon,virtio_console,virtio_mmio,virtio_scsi virtio 5273 8 virtio_blk,virtio_net,virtio_pci,virtio_rng,virtio_balloon,virtio_console,virtio_mmio,virtio_scsi kvm_intel 145614 0 kvm 307775 1 kvm_intel xt_nat 1977 0 rfcomm 50263 0 iscsi_tcp 8942 0 libiscsi_tcp 12474 1 iscsi_tcp libiscsi 38257 2 libiscsi_tcp,iscsi_tcp scsi_transport_iscsi 66567 3 iscsi_tcp,libiscsi fbdisk 20441 0 ipt_MASQUERADE 1533 4 iptable_nat 1959 1 nf_nat_masquerade_ipv4 1865 1 ipt_MASQUERADE nf_nat_ipv4 5147 1 iptable_nat nf_nat 11914 4 nf_nat_redirect,nf_nat_ipv4,xt_nat,nf_nat_masquerade_ipv4 xt_policy 2522 0 xt_mark 1317 0 bonding 114377 0 br_netfilter 13236 0 bridge 80620 1 br_netfilter stp 1693 1 bridge 8021q 17067 0 ipv6 311235 2 bridge,l2tp_core uvcvideo 73142 0 videobuf2_vmalloc 5094 1 uvcvideo videobuf2_memops 2215 1 videobuf2_vmalloc videobuf2_core 33372 1 uvcvideo snd_usb_caiaq 39170 0 snd_usb_audio 139029 0 snd_usbmidi_lib 20836 1 snd_usb_audio snd_seq_midi 5478 0 snd_rawmidi 18725 3 snd_usb_caiaq,snd_usbmidi_lib,snd_seq_midi fnotify 27085 2 isofs 31658 0 sp5100_tco 5952 0 iTCO_wdt 5700 1 vfio_pci 27672 0 vfio_virqfd 2165 1 vfio_pci vfio_iommu_type1 8294 0 vfio 14991 2 vfio_iommu_type1,vfio_pci udf 77803 1 x53B_pins 3884 0 ufsd 652732 0 jnl 27383 1 ufsd pl2303 11696 0 usbserial 28885 1 pl2303 qm2_i2c 3281 6 intel_ips 11476 0 drbd 331130 4 flashcache 140446 1 dm_tier_hro_algo 14663 1 dm_thin_pool 157769 21 target_core_mod,dm_tier_hro_algo dm_bio_prison 4372 1 dm_thin_pool dm_persistent_data 48048 1 dm_thin_pool hal_netlink 4853 0 k10temp 3253 0 coretemp 6044 0 tn40xx 1078570 0 r8152 52531 0 usb 2018-02-19 11:30:06,677 DEBG 'start-script' stdout output: net 25199 0 mii 4035 2 r8152,usbnet igb 162846 0 e1000e 198727 0 mpt3sas 275732 0 mpt2sas 169679 0 scsi_transport_sas 24764 2 mpt2sas,mpt3sas raid_class 3572 2 mpt2sas,mpt3sas usb_storage 49870 1 xhci_pci 4650 0 xhci_hcd 134237 1 xhci_pci usblp 12346 0 uhci_hcd 32595 0 ehci_pci 4359 0 ehci_hcd 60621 1 ehci_pci 2018-02-19 11:30:06,693 DEBG 'start-script' stdout output: [debug] Docker interface defined as eth0 2018-02-19 11:30:06,700 DEBG 'start-script' stdout output: [debug] Docker IP defined as 10.0.3.6 2018-02-19 11:30:06,707 DEBG 'start-script' stdout output: [debug] Docker netmask defined as 255.255.255.0 2018-02-19 11:30:06,727 DEBG 'start-script' stdout output: [info] Docker network defined as 10.0.3.0/24 2018-02-19 11:30:06,832 DEBG 'start-script' stdout output: [info] iptables defined as follows... -------------------- 2018-02-19 11:30:06,836 DEBG 'start-script' stdout output: -P INPUT DROP -P FORWARD ACCEPT -P OUTPUT DROP -A INPUT -i tun0 -j ACCEPT -A INPUT -s 10.0.3.0/24 -d 10.0.3.0/24 -j ACCEPT -A INPUT -i eth0 -p udp -m udp --sport 443 -j ACCEPT -A INPUT -i eth0 -p tcp -m tcp --dport 8112 -j ACCEPT -A INPUT -i eth0 -p tcp -m tcp --sport 8112 -j ACCEPT -A INPUT -s 10.11.12.0/24 -i eth0 -p tcp -m tcp --dport 58846 -j ACCEPT -A INPUT -p icmp -m icmp --icmp-type 0 -j ACCEPT -A INPUT -i lo -j ACCEPT -A OUTPUT -o tun0 -j ACCEPT -A OUTPUT -s 10.0.3.0/24 -d 10.0.3.0/24 -j ACCEPT -A OUTPUT -o eth0 -p udp -m udp --dport 443 -j ACCEPT -A OUTPUT -o eth0 -p tcp -m tcp --dport 8112 -j ACCEPT -A OUTPUT -o eth0 -p tcp -m tcp --sport 8112 -j ACCEPT -A OUTPUT -d 10.11.12.0/24 -o eth0 -p tcp -m tcp --sport 58846 -j ACCEPT -A OUTPUT -p icmp -m icmp --icmp-type 8 -j ACCEPT -A OUTPUT -o lo -j ACCEPT 2018-02-19 11:30:06,837 DEBG 'start-script' stdout output: -------------------- 2018-02-19 11:30:06,838 DEBG 'start-script' stdout output: [debug] OpenVPN command line:- /usr/bin/openvpn --cd /config/openvpn --config '/config/openvpn/AirVPN_US-Pennsylvania_Metallah_UDP-443.ovpn' --daemon --reneg-sec 0 --mute-replay-warnings --auth-nocache --setenv VPN_PROV 'airvpn' --setenv DEBUG 'true' --setenv VPN_DEVICE_TYPE 'tun0' --setenv VPN_REMOTE '199.19.94.12' --script-security 2 --up /root/openvpnup.sh --up-delay --up-restart --writepid /root/openvpn.pid --remap-usr1 SIGHUP --log-append /dev/stdout --pull-filter ignore 'up' --pull-filter ignore 'down' --pull-filter ignore 'route-ipv6' --pull-filter ignore 'ifconfig-ipv6' --pull-filter ignore 'tun-ipv6' --pull-filter ignore 'persist-tun' --pull-filter ignore 'reneg-sec' [info] Starting OpenVPN... 2018-02-19 11:30:06,852 DEBG 'start-script' stdout output: Mon Feb 19 11:30:06 2018 OpenVPN 2.4.4 x86_64-unknown-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Sep 26 2017 Mon Feb 19 11:30:06 2018 library versions: OpenSSL 1.1.0f 25 May 2017, LZO 2.10 2018-02-19 11:30:06,854 DEBG 'start-script' stdout output: [info] OpenVPN started Mon Feb 19 11:30:06 2018 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts [debug] Waiting for valid IP address from tunnel... 2018-02-19 11:30:06,854 DEBG 'start-script' stdout output: Mon Feb 19 11:30:06 2018 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication Mon Feb 19 11:30:06 2018 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication Mon Feb 19 11:30:06 2018 TCP/UDP: Preserving recently used remote address: [AF_INET]199.19.94.12:443 Mon Feb 19 11:30:06 2018 Socket Buffers: R=[1048576->1048576] S=[1048576->1048576] Mon Feb 19 11:30:06 2018 UDP link local: (not bound) Mon Feb 19 11:30:06 2018 UDP link remote: [AF_INET]199.19.94.12:443 2018-02-19 11:30:06,884 DEBG 'start-script' stdout output: Mon Feb 19 11:30:06 2018 TLS: Initial packet from [AF_INET]199.19.94.12:443, sid=131d3c23 7399b3ec 2018-02-19 11:30:06,937 DEBG 'start-script' stdout output: Mon Feb 19 11:30:06 2018 VERIFY OK: depth=1, C=IT, ST=IT, L=Perugia, O=airvpn.org, CN=airvpn.org CA, [email protected] 2018-02-19 11:30:06,937 DEBG 'start-script' stdout output: Mon Feb 19 11:30:06 2018 VERIFY KU OK Mon Feb 19 11:30:06 2018 Validating certificate extended key usage 2018-02-19 11:30:06,937 DEBG 'start-script' stdout output: Mon Feb 19 11:30:06 2018 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication Mon Feb 19 11:30:06 2018 VERIFY EKU OK Mon Feb 19 11:30:06 2018 VERIFY OK: depth=0, C=IT, ST=IT, L=Perugia, O=airvpn.org, CN=Almach, [email protected] 2018-02-19 11:30:07,084 DEBG 'start-script' stdout output: Mon Feb 19 11:30:07 2018 Control Channel: TLSv1.2, cipher TLSv1.2 ECDHE-RSA-AES256-GCM-SHA384, 4096 bit RSA Mon Feb 19 11:30:07 2018 [Almach] Peer Connection Initiated with [AF_INET]199.19.94.12:443 2018-02-19 11:30:08,160 DEBG 'start-script' stdout output: Mon Feb 19 11:30:08 2018 SENT CONTROL [Almach]: 'PUSH_REQUEST' (status=1) 2018-02-19 11:30:08,190 DEBG 'start-script' stdout output: Mon Feb 19 11:30:08 2018 PUSH: Received control message: 'PUSH_REPLY,comp-lzo no,redirect-gateway def1 bypass-dhcp,dhcp-option DNS 10.4.0.1,route-gateway 10.4.0.1,topology subnet,ping 10,ping-restart 60,ifconfig 10.4.19.176 255.255.0.0,peer-id 4,cipher AES-256-GCM' Mon Feb 19 11:30:08 2018 OPTIONS IMPORT: timers and/or timeouts modified Mon Feb 19 11:30:08 2018 OPTIONS IMPORT: compression parms modified Mon Feb 19 11:30:08 2018 OPTIONS IMPORT: --ifconfig/up options modified Mon Feb 19 11:30:08 2018 OPTIONS IMPORT: route options modified Mon Feb 19 11:30:08 2018 OPTIONS IMPORT: route-related options modified Mon Feb 19 11:30:08 2018 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified 2018-02-19 11:30:08,191 DEBG 'start-script' stdout output: Mon Feb 19 11:30:08 2018 OPTIONS IMPORT: peer-id set Mon Feb 19 11:30:08 2018 OPTIONS IMPORT: adjusting link_mtu to 1625 Mon Feb 19 11:30:08 2018 OPTIONS IMPORT: data channel crypto options modified Mon Feb 19 11:30:08 2018 Data Channel: using negotiated cipher 'AES-256-GCM' Mon Feb 19 11:30:08 2018 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key Mon Feb 19 11:30:08 2018 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key Mon Feb 19 11:30:08 2018 ROUTE_GATEWAY 10.0.3.1/255.255.255.0 IFACE=eth0 HWADDR=02:42:0a:00:03:06 Mon Feb 19 11:30:08 2018 TUN/TAP device tun0 opened Mon Feb 19 11:30:08 2018 TUN/TAP TX queue length set to 100 Mon Feb 19 11:30:08 2018 do_ifconfig, tt->did_ifconfig_ipv6_setup=0 2018-02-19 11:30:08,191 DEBG 'start-script' stdout output: Mon Feb 19 11:30:08 2018 /usr/bin/ip link set dev tun0 up mtu 1500 2018-02-19 11:30:08,194 DEBG 'start-script' stdout output: Mon Feb 19 11:30:08 2018 /usr/bin/ip addr add dev tun0 10.4.19.176/16 broadcast 10.4.255.255 2018-02-19 11:30:08,197 DEBG 'start-script' stdout output: Mon Feb 19 11:30:08 2018 /root/openvpnup.sh tun0 1500 1553 10.4.19.176 255.255.0.0 init 2018-02-19 11:30:08,202 DEBG 'start-script' stdout output: Mon Feb 19 11:30:08 2018 /usr/bin/ip route add 199.19.94.12/32 via 10.0.3.1 2018-02-19 11:30:08,208 DEBG 'start-script' stdout output: [debug] Waiting for valid IP address from tunnel... 2018-02-19 11:30:08,210 DEBG 'start-script' stdout output: Mon Feb 19 11:30:08 2018 /usr/bin/ip route add 0.0.0.0/1 via 10.4.0.1 2018-02-19 11:30:08,213 DEBG 'start-script' stdout output: Mon Feb 19 11:30:08 2018 /usr/bin/ip route add 128.0.0.0/1 via 10.4.0.1 2018-02-19 11:30:08,214 DEBG 'start-script' stdout output: [debug] Waiting for valid IP address from tunnel... 2018-02-19 11:30:08,217 DEBG 'start-script' stdout output: Mon Feb 19 11:30:08 2018 Initialization Sequence Completed 2018-02-19 11:30:08,236 DEBG 'start-script' stdout output: [debug] Valid IP address from tunnel acquired '10.4.19.176' 2018-02-19 11:30:08,334 DEBG 'deluge-script' stdout output: [debug] Valid IP address from tunnel acquired '10.4.19.176' 2018-02-19 11:30:08,343 DEBG 'start-script' stdout output: [debug] Valid IP address from tunnel acquired '10.4.19.176' [debug] Attempting to get external IP using Name Server 'ns1.google.com'... 2018-02-19 11:30:08,344 DEBG 'start-script' stdout output: [debug] Valid IP address from tunnel acquired '10.4.19.176' 2018-02-19 11:30:08,345 DEBG 'start-script' stdout output: [debug] VPN provider airvpn is != pia, skipping incoming port detection 2018-02-19 11:30:08,346 DEBG 'deluge-script' stdout output: [info] Deluge not running [info] Deluge listening interface IP 0.0.0.0 and VPN provider IP 10.4.19.176 different, marking for reconfigure [info] Attempting to start Deluge... 2018-02-19 11:30:08,489 DEBG 'start-script' stdout output: [info] Successfully retrieved external IP address 199.19.94.16 2018-02-19 11:30:08,702 DEBG 'deluge-script' stdout output: [info] Deluge listening interface currently defined as 10.4.19.176 [info] Deluge listening interface will be changed to 10.4.19.176 [info] Saving changes to Deluge config file /config/core.conf... 2018-02-19 11:30:09,080 DEBG 'deluge-script' stdout output: [info] Deluge started [debug] VPN incoming port is [debug] VPN IP is 10.4.19.176 2018-02-19 11:30:09,080 DEBG 'deluge-script' stdout output: [debug] Deluge incoming port is [debug] Deluge IP is 10.4.19.176 2018-02-19 11:30:09,823 DEBG 'deluge-web-script' stdout output: [info] Starting Deluge webui... 2018-02-19 11:30:38,251 DEBG 'start-script' stdout output: [warn] OpenVPN process terminated, restarting OpenVPN...
  2. I'm still unable to keep a connection alive for more than 30 seconds. I've tried all sorts of servers and options and nothing seems to work. Using the AirVPN desktop client everything works fine, however, so I don't think it's my ISP messing with things. Everything starts up fine, then the following repeats in my log over and over again: 2018-02-16 16:07:23,708 DEBG 'start-script' stdout output: [warn] OpenVPN process terminated, restarting OpenVPN... 2018-02-16 16:07:23,719 DEBG 'start-script' stdout output: Fri Feb 16 16:07:23 2018 OpenVPN 2.4.4 x86_64-unknown-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Sep 26 2017 Fri Feb 16 16:07:23 2018 library versions: OpenSSL 1.1.0f 25 May 2017, LZO 2.10 2018-02-16 16:07:23,720 DEBG 'start-script' stdout output: [info] OpenVPN restarted 2018-02-16 16:07:23,723 DEBG 'start-script' stdout output: Fri Feb 16 16:07:23 2018 WARNING: --ping should normally be used with --ping-restart or --ping-exit Fri Feb 16 16:07:23 2018 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts 2018-02-16 16:07:23,724 DEBG 'start-script' stdout output: Fri Feb 16 16:07:23 2018 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication Fri Feb 16 16:07:23 2018 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication 2018-02-16 16:07:23,724 DEBG 'start-script' stdout output: Fri Feb 16 16:07:23 2018 TCP/UDP: Preserving recently used remote address: [AF_INET]199.19.94.12:443 Fri Feb 16 16:07:23 2018 Socket Buffers: R=[1048576->1048576] S=[1048576->1048576] Fri Feb 16 16:07:23 2018 UDP link local: (not bound) Fri Feb 16 16:07:23 2018 UDP link remote: [AF_INET]199.19.94.12:443 2018-02-16 16:07:23,756 DEBG 'start-script' stdout output: Fri Feb 16 16:07:23 2018 TLS: Initial packet from [AF_INET]199.19.94.12:443, sid=ecdeed21 230df73d 2018-02-16 16:07:23,815 DEBG 'start-script' stdout output: Fri Feb 16 16:07:23 2018 VERIFY OK: depth=1, C=IT, ST=IT, L=Perugia, O=airvpn.org, CN=airvpn.org CA, [email protected] 2018-02-16 16:07:23,816 DEBG 'start-script' stdout output: Fri Feb 16 16:07:23 2018 VERIFY KU OK Fri Feb 16 16:07:23 2018 Validating certificate extended key usage Fri Feb 16 16:07:23 2018 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication 2018-02-16 16:07:23,816 DEBG 'start-script' stdout output: Fri Feb 16 16:07:23 2018 VERIFY EKU OK Fri Feb 16 16:07:23 2018 VERIFY OK: depth=0, C=IT, ST=IT, L=Perugia, O=airvpn.org, CN=Almach, [email protected] 2018-02-16 16:07:23,967 DEBG 'start-script' stdout output: Fri Feb 16 16:07:23 2018 WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1590', remote='link-mtu 1558' Fri Feb 16 16:07:23 2018 WARNING: 'tun-mtu' is used inconsistently, local='tun-mtu 1532', remote='tun-mtu 1500' 2018-02-16 16:07:23,967 DEBG 'start-script' stdout output: Fri Feb 16 16:07:23 2018 Control Channel: TLSv1.2, cipher TLSv1.2 ECDHE-RSA-AES256-GCM-SHA384, 4096 bit RSA Fri Feb 16 16:07:23 2018 [Almach] Peer Connection Initiated with [AF_INET]199.19.94.12:443 2018-02-16 16:07:24,374 DEBG 'deluge-script' stdout output: [debug] Waiting for valid IP address from tunnel... 2018-02-16 16:07:25,050 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 SENT CONTROL [Almach]: 'PUSH_REQUEST' (status=1) 2018-02-16 16:07:25,080 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 PUSH: Received control message: 'PUSH_REPLY,comp-lzo no,redirect-gateway def1 bypass-dhcp,dhcp-option DNS 10.4.0.1,route-gateway 10.4.0.1,topology subnet,ping 10,ping-restart 60,ifconfig 10.4.19.176 255.255.0.0,peer-id 34,cipher AES-256-GCM' 2018-02-16 16:07:25,081 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 OPTIONS IMPORT: timers and/or timeouts modified Fri Feb 16 16:07:25 2018 OPTIONS IMPORT: compression parms modified Fri Feb 16 16:07:25 2018 OPTIONS IMPORT: --ifconfig/up options modified Fri Feb 16 16:07:25 2018 OPTIONS IMPORT: route options modified Fri Feb 16 16:07:25 2018 OPTIONS IMPORT: route-related options modified Fri Feb 16 16:07:25 2018 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified Fri Feb 16 16:07:25 2018 OPTIONS IMPORT: peer-id set Fri Feb 16 16:07:25 2018 OPTIONS IMPORT: adjusting link_mtu to 1657 Fri Feb 16 16:07:25 2018 OPTIONS IMPORT: data channel crypto options modified Fri Feb 16 16:07:25 2018 Data Channel: using negotiated cipher 'AES-256-GCM' Fri Feb 16 16:07:25 2018 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key Fri Feb 16 16:07:25 2018 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key 2018-02-16 16:07:25,081 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 ROUTE_GATEWAY 10.0.3.1/255.255.255.0 IFACE=eth0 HWADDR=02:42:0a:00:03:06 2018-02-16 16:07:25,082 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 TUN/TAP device tun0 opened Fri Feb 16 16:07:25 2018 TUN/TAP TX queue length set to 100 Fri Feb 16 16:07:25 2018 do_ifconfig, tt->did_ifconfig_ipv6_setup=0 Fri Feb 16 16:07:25 2018 /usr/bin/ip link set dev tun0 up mtu 1500 2018-02-16 16:07:25,084 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 /usr/bin/ip addr add dev tun0 10.4.19.176/16 broadcast 10.4.255.255 2018-02-16 16:07:25,086 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 /root/openvpnup.sh tun0 1500 1585 10.4.19.176 255.255.0.0 init 2018-02-16 16:07:25,090 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 /usr/bin/ip route add 199.19.94.12/32 via 10.0.3.1 2018-02-16 16:07:25,092 DEBG 'start-script' stdout output: RTNETLINK answers: File exists 2018-02-16 16:07:25,093 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 ERROR: Linux route add command failed: external program exited with error status: 2 Fri Feb 16 16:07:25 2018 /usr/bin/ip route add 0.0.0.0/1 via 10.4.0.1 2018-02-16 16:07:25,094 DEBG 'start-script' stdout output: [debug] Waiting for valid IP address from tunnel... 2018-02-16 16:07:25,096 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 /usr/bin/ip route add 128.0.0.0/1 via 10.4.0.1 2018-02-16 16:07:25,097 DEBG 'start-script' stdout output: [debug] Waiting for valid IP address from tunnel... 2018-02-16 16:07:25,098 DEBG 'start-script' stdout output: Fri Feb 16 16:07:25 2018 Initialization Sequence Completed 2018-02-16 16:07:25,127 DEBG 'deluge-script' stdout output: [debug] Valid IP address from tunnel acquired '10.4.19.176' 2018-02-16 16:07:25,134 DEBG 'deluge-script' stdout output: [debug] VPN incoming port is [debug] VPN IP is 10.4.19.176 [debug] Deluge incoming port is [debug] Deluge IP is 10.4.19.176 2018-02-16 16:07:25,214 DEBG 'start-script' stdout output: [debug] Valid IP address from tunnel acquired '10.4.19.176' 2018-02-16 16:07:25,215 DEBG 'start-script' stdout output: [debug] VPN provider airvpn is != pia, skipping incoming port detection 2018-02-16 16:07:25,216 DEBG 'start-script' stdout output: [debug] Valid IP address from tunnel acquired '10.4.19.176' 2018-02-16 16:07:25,216 DEBG 'start-script' stdout output: [debug] Attempting to get external IP using Name Server 'ns1.google.com'... 2018-02-16 16:07:25,364 DEBG 'start-script' stdout output: [info] Successfully retrieved external IP address 199.19.94.16 2018-02-16 16:07:53,728 DEBG 'start-script' stdout output: [warn] OpenVPN process terminated, restarting OpenVPN...
  3. My connection to AirVPN keeps dropping every 30 seconds as in the screenshot: Log file: supervisord.log I'm running on a QNAP in docker, and it happens every server I try, with any port configuration. Any ideas on what might be causing it?