binhex / arch-delugevpn

Docker build script for Arch Linux base with Deluge, Privoxy and OpenVPN
GNU General Public License v3.0
696 stars 112 forks source link

(Mullvad, Synology Docker) Network error #334

Closed djnetics closed 2 years ago

djnetics commented 2 years ago

Latest version via Synology Docker.

After updating to DSM 7.1.1-42962 Update 1, arch-delugevpn stopped working. Before updating the DSM, I was using Arch-DelugeVPN version 1.3.15_2_g38d7b7cdf-1-15 Went on to update to latest version of arch-delugevpn and even tried deleting and reinstalling the whole thing.

Kept getting this error: "Error: error sending query: Could not send or receive, because of network error"

Have tried different NAME_SERVERS IP addresses and it still having this issue.

Extracted the debug info from supervisord.log:

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

2022-10-18 18:33:32.925131 [info] System information Linux binhex-arch-delugevpn1 3.10.108 #42962 SMP Tue Sep 20 22:34:21 CST 2022 x86_64 GNU/Linux 2022-10-18 18:33:33.011392 [info] OS_ARCH defined as 'x86-64' 2022-10-18 18:33:33.097737 [info] PUID defined as '1041' 2022-10-18 18:33:33.190313 [info] PGID defined as '100' 2022-10-18 18:33:33.396888 [info] UMASK defined as '000' 2022-10-18 18:33:33.478399 [info] Permissions already set for '/config' 2022-10-18 18:33:33.562307 [info] Deleting files in /tmp (non recursive)... 2022-10-18 18:33:33.658471 [info] VPN_ENABLED defined as 'yes' 2022-10-18 18:33:33.742656 [info] VPN_CLIENT defined as 'openvpn' 2022-10-18 18:33:33.825186 [info] VPN_PROV defined as 'custom' 2022-10-18 18:33:33.917177 [info] OpenVPN config file (ovpn extension) is located at /config/openvpn/mullvad_hk_hkg.ovpn 2022-10-18 18:33:34.222759 [info] VPN remote server(s) defined as '209.58.184.146,103.125.233.33,209.58.185.186,89.45.6.66,103.125.233.48,209.58.185.53,' 2022-10-18 18:33:34.298493 [info] VPN remote port(s) defined as '1300,1300,1300,1300,1300,1300,' 2022-10-18 18:33:34.378441 [info] VPN remote protcol(s) defined as 'udp,udp,udp,udp,udp,udp,' 2022-10-18 18:33:34.467890 [info] VPN_DEVICE_TYPE defined as 'tun0' 2022-10-18 18:33:34.553325 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS) 2022-10-18 18:33:34.637357 [info] LAN_NETWORK defined as '192.168.1.0/24' 2022-10-18 18:33:34.719711 [info] NAME_SERVERS defined as '1.1.1.1,1.0.0.1' 2022-10-18 18:33:34.801689 [info] VPN_USER defined as 2022-10-18 18:33:34.884842 [info] VPN_PASS defined as 2022-10-18 18:33:34.967156 [warn] ENABLE_PRIVOXY not defined (via -e ENABLE_PRIVOXY), defaulting to 'no' 2022-10-18 18:33:35.056652 [info] VPN_INPUT_PORTS not defined (via -e VPN_INPUT_PORTS), skipping allow for custom incoming ports 2022-10-18 18:33:35.139978 [info] VPN_OUTPUT_PORTS not defined (via -e VPN_OUTPUT_PORTS), skipping allow for custom outgoing ports 2022-10-18 18:33:35.223579 [info] DELUGE_DAEMON_LOG_LEVEL not defined,(via -e DELUGE_DAEMON_LOG_LEVEL), defaulting to 'info' 2022-10-18 18:33:35.306193 [info] DELUGE_WEB_LOG_LEVEL not defined,(via -e DELUGE_WEB_LOG_LEVEL), defaulting to 'info' 2022-10-18 18:33:35.388824 [info] Starting Supervisor... 2022-10-18 18:33:36,069 INFO Included extra file "/etc/supervisor/conf.d/delugevpn.conf" during parsing 2022-10-18 18:33:36,069 INFO Set uid to user 0 succeeded 2022-10-18 18:33:36,076 INFO supervisord started with pid 7 2022-10-18 18:33:37,079 INFO spawned: 'start-script' with pid 254 2022-10-18 18:33:37,084 INFO spawned: 'watchdog-script' with pid 255 2022-10-18 18:33:37,085 INFO reaped unknown pid 8 (exit status 0) 2022-10-18 18:33:37,098 DEBG 'start-script' stdout output: [info] VPN is enabled, beginning configuration of VPN

2022-10-18 18:33:37,098 INFO success: start-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs) 2022-10-18 18:33:37,099 INFO success: watchdog-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs) 2022-10-18 18:33:37,202 DEBG 'start-script' stdout output: [debug] Contents of ovpn file /config/openvpn/mullvad_hk_hkg.ovpn as follows...

2022-10-18 18:33:37,203 DEBG 'start-script' stdout output: client dev tun resolv-retry infinite nobind persist-key verb 3 remote-cert-tls server ping 10 ping-restart 60 sndbuf 524288 rcvbuf 524288 cipher AES-256-CBC tls-cipher TLS-DHE-RSA-WITH-AES-256-GCM-SHA384:TLS-DHE-RSA-WITH-AES-256-CBC-SHA proto udp auth-user-pass credentials.conf fast-io remote-random remote 209.58.184.146 1300 # hk-hkg-001 remote 103.125.233.33 1300 # hk-hkg-ovpn-201 remote 209.58.185.186 1300 # hk-hkg-003 remote 89.45.6.66 1300 # hk-hkg-102 remote 103.125.233.48 1300 # hk-hkg-ovpn-202 remote 209.58.185.53 1300 # hk-hkg-002

-----BEGIN CERTIFICATE----- MIIGIzCCBAugAwIBAgIJAK6BqXN9GHI0MA0GCSqGSIb3DQEBCwUAMIGfMQswCQYD VQQGEwJTRTERMA8GA1UECAwIR290YWxhbmQxEzARBgNVBAcMCkdvdGhlbmJ1cmcx FDASBgNVBAoMC0FtYWdpY29tIEFCMRAwDgYDVQQLDAdNdWxsdmFkMRswGQYDVQQD DBJNdWxsdmFkIFJvb3QgQ0EgdjIxIzAhBgkqhkiG9w0BCQEWFHNlY3VyaXR5QG11 bGx2YWQubmV0MB4XDTE4MTEwMjExMTYxMVoXDTI4MTAzMDExMTYxMVowgZ8xCzAJ BgNVBAYTAlNFMREwDwYDVQQIDAhHb3RhbGFuZDETMBEGA1UEBwwKR290aGVuYnVy ZzEUMBIGA1UECgwLQW1hZ2ljb20gQUIxEDAOBgNVBAsMB011bGx2YWQxGzAZBgNV BAMMEk11bGx2YWQgUm9vdCBDQSB2MjEjMCEGCSqGSIb3DQEJARYUc2VjdXJpdHlA bXVsbHZhZC5uZXQwggIiMA0GCSqGSIb3DQEBAQUAA4ICDwAwggIKAoICAQCifDn7 5E/Zdx1qsy31rMEzuvbTXqZVZp4bjWbmcyyXqvnayRUHHoovG+lzc+HDL3HJV+kj xKpCMkEVWwjY159lJbQbm8kkYntBBREdzRRjjJpTb6haf/NXeOtQJ9aVlCc4dM66 bEmyAoXkzXVZTQJ8h2FE55KVxHi5Sdy4XC5zm0wPa4DPDokNp1qm3A9Xicq3Hsfl LbMZRCAGuI+Jek6caHqiKjTHtujn6Gfxv2WsZ7SjerUAk+mvBo2sfKmB7octxG7y AOFFg7YsWL0AxddBWqgq5R/1WDJ9d1Cwun9WGRRQ1TLvzF1yABUerjjKrk89RCzY ISwsKcgJPscaDqZgO6RIruY/xjuTtrnZSv+FXs+Woxf87P+QgQd76LC0MstTnys+ AfTMuMPOLy9fMfEzs3LP0Nz6v5yjhX8ff7+3UUI3IcMxCvyxdTPClY5IvFdW7CCm mLNzakmx5GCItBWg/EIg1K1SG0jU9F8vlNZUqLKz42hWy/xB5C4QYQQ9ILdu4ara PnrXnmd1D1QKVwKQ1DpWhNbpBDfE776/4xXD/tGM5O0TImp1NXul8wYsDi8g+e0p xNgY3Pahnj1yfG75Yw82spZanUH0QSNoMVMWnmV2hXGsWqypRq0pH8mPeLzeKa82 gzsAZsouRD1k8wFlYA4z9HQFxqfcntTqXuwQcQIDAQABo2AwXjAdBgNVHQ4EFgQU faEyaBpGNzsqttiSMETq+X/GJ0YwHwYDVR0jBBgwFoAUfaEyaBpGNzsqttiSMETq +X/GJ0YwCwYDVR0PBAQDAgEGMA8GA1UdEwEB/wQFMAMBAf8wDQYJKoZIhvcNAQEL BQADggIBADH5izxu4V8Javal8EA4DxZxIHUsWCg5cuopB28PsyJYpyKipsBoI8+R XqbtrLLue4WQfNPZHLXlKi+A3GTrLdlnenYzXVipPd+n3vRZyofaB3Jtb03nirVW Ga8FG21Xy/f4rPqwcW54lxrnnh0SA0hwuZ+b2yAWESBXPxrzVQdTWCqoFI6/aRnN 8RyZn0LqRYoW7WDtKpLmfyvshBmmu4PCYSh/SYiFHgR9fsWzVcxdySDsmX8wXowu Ffp8V9sFhD4TsebAaplaICOuLUgj+Yin5QzgB0F9Ci3Zh6oWwl64SL/OxxQLpzMW zr0lrWsQrS3PgC4+6JC4IpTXX5eUqfSvHPtbRKK0yLnd9hYgvZUBvvZvUFR/3/fW +mpBHbZJBu9+/1uux46M4rJ2FeaJUf9PhYCPuUj63yu0Grn0DreVKK1SkD5V6qXN 0TmoxYyguhfsIPCpI1VsdaSWuNjJ+a/HIlKIU8vKp5iN/+6ZTPAg9Q7s3Ji+vfx/ AhFtQyTpIYNszVzNZyobvkiMUlK+eUKGlHVQp73y6MmGIlbBbyzpEoedNU4uFu57 mw4fYGHqYZmYqFaiNQv4tVrGkg6p+Ypyu1zOfIHF7eqlAOu/SyRTvZkt9VtSVEOV H7nDIGdrCC9U/g1Lqk8Td00Oj8xesyKzsG214Xd8m7/7GmJ7nXe5 -----END CERTIFICATE-----

2022-10-18 18:33:37,220 DEBG 'start-script' stdout output: [debug] Environment variables defined as follows ADDITIONAL_PORTS= APPLICATION=deluge BASH=/bin/bash BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:hostcomplete:interactive_comments:progcomp:promptvars:sourcepath BASH_ALIASES=() BASH_ARGC=() BASH_ARGV=() BASH_CMDS=() BASH_LINENO=([0]="0") BASH_SOURCE=([0]="/root/start.sh") BASH_VERSINFO=([0]="5" [1]="1" [2]="16" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu") BASH_VERSION='5.1.16(1)-release' DEBUG=true

2022-10-18 18:33:37,221 DEBG 'start-script' stdout output: DELUGE_DAEMON_LOG_LEVEL=info DELUGE_WEB_LOG_LEVEL=info DIRSTACK=() ENABLE_PRIVOXY=no EUID=0 GROUPS=() HOME=/home/nobody HOSTNAME=binhex-arch-delugevpn1 HOSTTYPE=x86_64 IFS=$' \t\n' LANG=en_GB.UTF-8 LAN_NETWORK=192.168.1.0/24 MACHTYPE=x86_64-pc-linux-gnu NAME_SERVERS=1.1.1.1,1.0.0.1 OPTERR=1 OPTIND=1 OSTYPE=linux-gnu OS_ARCH=x86-64 PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin PGID=100 PIPESTATUS=([0]="0") PPID=7 PS4='+ ' PUID=1041 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 TZ=Asia/Hong_Kong UID=0 UMASK=000 VPN_CLIENT=openvpn VPN_CONFIG=/config/openvpn/mullvad_hk_hkg.ovpn VPN_DEVICE_TYPE=tun0 VPN_ENABLED=yes VPN_INPUT_PORTS= VPN_OPTIONS= VPN_OUTPUT_PORTS= VPN_PASS=m VPN_PROV=custom VPN_REMOTE_PORT=1300,1300,1300,1300,1300,1300, VPN_REMOTE_PROTOCOL=udp,udp,udp,udp,udp,udp, VPN_REMOTE_SERVER=209.58.184.146,103.125.233.33,209.58.185.186,89.45.6.66,103.125.233.48,209.58.185.53, VPNUSER=2951017737934255 ='[debug] Environment variables defined as follows' password_char_check= username_char_check= vpn_ping='ping 10' [debug] Directory listing of files in /config/openvpn/ as follows

2022-10-18 18:33:37,229 DEBG 'start-script' stdout output: total 28 drwxrwxr-x 1 nobody users 584 Oct 18 18:33 . drwxrwxr-x 1 nobody users 840 Oct 18 18:33 .. -rwxrwxr-x 1 nobody users 19 Oct 18 18:33 credentials.conf -rwxrwxr-x 1 nobody users 2743 Oct 18 18:33 mullvad_hk_hkg.ovpn

2022-10-18 18:33:37,231 DEBG 'start-script' stdout output: [debug] Contents of OpenVPN config file '/config/openvpn/mullvad_hk_hkg.ovpn' as follows...

2022-10-18 18:33:37,232 DEBG 'start-script' stdout output: client dev tun resolv-retry infinite nobind persist-key verb 3 remote-cert-tls server ping 10 ping-restart 60 sndbuf 524288 rcvbuf 524288 cipher AES-256-CBC tls-cipher TLS-DHE-RSA-WITH-AES-256-GCM-SHA384:TLS-DHE-RSA-WITH-AES-256-CBC-SHA proto udp auth-user-pass credentials.conf fast-io remote-random remote 209.58.184.146 1300 # hk-hkg-001 remote 103.125.233.33 1300 # hk-hkg-ovpn-201 remote 209.58.185.186 1300 # hk-hkg-003 remote 89.45.6.66 1300 # hk-hkg-102 remote 103.125.233.48 1300 # hk-hkg-ovpn-202 remote 209.58.185.53 1300 # hk-hkg-002

-----BEGIN CERTIFICATE----- MIIGIzCCBAugAwIBAgIJAK6BqXN9GHI0MA0GCSqGSIb3DQEBCwUAMIGfMQswCQYD VQQGEwJTRTERMA8GA1UECAwIR290YWxhbmQxEzARBgNVBAcMCkdvdGhlbmJ1cmcx FDASBgNVBAoMC0FtYWdpY29tIEFCMRAwDgYDVQQLDAdNdWxsdmFkMRswGQYDVQQD DBJNdWxsdmFkIFJvb3QgQ0EgdjIxIzAhBgkqhkiG9w0BCQEWFHNlY3VyaXR5QG11 bGx2YWQubmV0MB4XDTE4MTEwMjExMTYxMVoXDTI4MTAzMDExMTYxMVowgZ8xCzAJ BgNVBAYTAlNFMREwDwYDVQQIDAhHb3RhbGFuZDETMBEGA1UEBwwKR290aGVuYnVy ZzEUMBIGA1UECgwLQW1hZ2ljb20gQUIxEDAOBgNVBAsMB011bGx2YWQxGzAZBgNV BAMMEk11bGx2YWQgUm9vdCBDQSB2MjEjMCEGCSqGSIb3DQEJARYUc2VjdXJpdHlA bXVsbHZhZC5uZXQwggIiMA0GCSqGSIb3DQEBAQUAA4ICDwAwggIKAoICAQCifDn7 5E/Zdx1qsy31rMEzuvbTXqZVZp4bjWbmcyyXqvnayRUHHoovG+lzc+HDL3HJV+kj xKpCMkEVWwjY159lJbQbm8kkYntBBREdzRRjjJpTb6haf/NXeOtQJ9aVlCc4dM66 bEmyAoXkzXVZTQJ8h2FE55KVxHi5Sdy4XC5zm0wPa4DPDokNp1qm3A9Xicq3Hsfl LbMZRCAGuI+Jek6caHqiKjTHtujn6Gfxv2WsZ7SjerUAk+mvBo2sfKmB7octxG7y AOFFg7YsWL0AxddBWqgq5R/1WDJ9d1Cwun9WGRRQ1TLvzF1yABUerjjKrk89RCzY ISwsKcgJPscaDqZgO6RIruY/xjuTtrnZSv+FXs+Woxf87P+QgQd76LC0MstTnys+ AfTMuMPOLy9fMfEzs3LP0Nz6v5yjhX8ff7+3UUI3IcMxCvyxdTPClY5IvFdW7CCm mLNzakmx5GCItBWg/EIg1K1SG0jU9F8vlNZUqLKz42hWy/xB5C4QYQQ9ILdu4ara PnrXnmd1D1QKVwKQ1DpWhNbpBDfE776/4xXD/tGM5O0TImp1NXul8wYsDi8g+e0p xNgY3Pahnj1yfG75Yw82spZanUH0QSNoMVMWnmV2hXGsWqypRq0pH8mPeLzeKa82 gzsAZsouRD1k8wFlYA4z9HQFxqfcntTqXuwQcQIDAQABo2AwXjAdBgNVHQ4EFgQU faEyaBpGNzsqttiSMETq+X/GJ0YwHwYDVR0jBBgwFoAUfaEyaBpGNzsqttiSMETq +X/GJ0YwCwYDVR0PBAQDAgEGMA8GA1UdEwEB/wQFMAMBAf8wDQYJKoZIhvcNAQEL BQADggIBADH5izxu4V8Javal8EA4DxZxIHUsWCg5cuopB28PsyJYpyKipsBoI8+R XqbtrLLue4WQfNPZHLXlKi+A3GTrLdlnenYzXVipPd+n3vRZyofaB3Jtb03nirVW Ga8FG21Xy/f4rPqwcW54lxrnnh0SA0hwuZ+b2yAWESBXPxrzVQdTWCqoFI6/aRnN 8RyZn0LqRYoW7WDtKpLmfyvshBmmu4PCYSh/SYiFHgR9fsWzVcxdySDsmX8wXowu Ffp8V9sFhD4TsebAaplaICOuLUgj+Yin5QzgB0F9Ci3Zh6oWwl64SL/OxxQLpzMW zr0lrWsQrS3PgC4+6JC4IpTXX5eUqfSvHPtbRKK0yLnd9hYgvZUBvvZvUFR/3/fW +mpBHbZJBu9+/1uux46M4rJ2FeaJUf9PhYCPuUj63yu0Grn0DreVKK1SkD5V6qXN 0TmoxYyguhfsIPCpI1VsdaSWuNjJ+a/HIlKIU8vKp5iN/+6ZTPAg9Q7s3Ji+vfx/ AhFtQyTpIYNszVzNZyobvkiMUlK+eUKGlHVQp73y6MmGIlbBbyzpEoedNU4uFu57 mw4fYGHqYZmYqFaiNQv4tVrGkg6p+Ypyu1zOfIHF7eqlAOu/SyRTvZkt9VtSVEOV H7nDIGdrCC9U/g1Lqk8Td00Oj8xesyKzsG214Xd8m7/7GmJ7nXe5 -----END CERTIFICATE-----

2022-10-18 18:33:37,240 DEBG 'start-script' stdout output: [info] Adding 1.1.1.1 to /etc/resolv.conf

2022-10-18 18:33:37,248 DEBG 'start-script' stdout output: [info] Adding 1.0.0.1 to /etc/resolv.conf

2022-10-18 18:33:37,258 DEBG 'start-script' stdout output: 209.58.184.146

2022-10-18 18:33:37,269 DEBG 'start-script' stdout output: 103.125.233.33

2022-10-18 18:33:37,279 DEBG 'start-script' stdout output: 209.58.185.186

2022-10-18 18:33:37,289 DEBG 'start-script' stdout output: 89.45.6.66

2022-10-18 18:33:37,299 DEBG 'start-script' stdout output: 103.125.233.48

2022-10-18 18:33:37,309 DEBG 'start-script' stdout output: 209.58.185.53

2022-10-18 18:33:37,341 DEBG 'start-script' stdout output: [debug] Show name servers defined for container

2022-10-18 18:33:37,342 DEBG 'start-script' stdout output: nameserver 1.1.1.1 nameserver 1.0.0.1 [debug] Show name resolution for VPN endpoint 209.58.184.146

2022-10-18 18:34:07,369 DEBG 'start-script' stderr output: Error: error sending query: Could not send or receive, because of network error

2022-10-18 18:34:07,370 DEBG 'start-script' stdout output: [debug] Show name resolution for VPN endpoint 103.125.233.33

2022-10-18 18:34:14,379 WARN received SIGTERM indicating exit request 2022-10-18 18:34:14,379 DEBG killing watchdog-script (pid 255) with signal SIGTERM 2022-10-18 18:34:14,379 INFO waiting for start-script, watchdog-script to die 2022-10-18 18:34:14,380 DEBG fd 12 closed, stopped monitoring <POutputDispatcher at 140659514705856 for <Subprocess at 140659514705184 with name watchdog-script in state STOPPING> (stdout)> 2022-10-18 18:34:14,381 DEBG fd 16 closed, stopped monitoring <POutputDispatcher at 140659514705904 for <Subprocess at 140659514705184 with name watchdog-script in state STOPPING> (stderr)> 2022-10-18 18:34:14,381 INFO stopped: watchdog-script (exit status 143) 2022-10-18 18:34:14,381 DEBG received SIGCHLD indicating a child quit 2022-10-18 18:34:14,382 DEBG killing start-script (pid 254) with signal SIGTERM 2022-10-18 18:34:15,383 DEBG fd 9 closed, stopped monitoring <POutputDispatcher at 140659515847104 for <Subprocess at 140659515847008 with name start-script in state STOPPING> (stdout)> 2022-10-18 18:34:15,384 DEBG fd 11 closed, stopped monitoring <POutputDispatcher at 140659514705472 for <Subprocess at 140659515847008 with name start-script in state STOPPING> (stderr)> 2022-10-18 18:34:15,384 INFO stopped: start-script (terminated by SIGTERM) 2022-10-18 18:34:15,384 DEBG received SIGCHLD indicating a child quit

djnetics commented 2 years ago

Found the issue. My own firewall rules blocking some countries which are needed to make it work.