Web interface refuses to come up using delugevpn deocker

General support for problems installing or using Deluge
Post Reply
defaria
New User
New User
Posts: 4
Joined: Sat Jun 12, 2021 6:43 pm

Web interface refuses to come up using delugevpn deocker

Post by defaria »

I was working with the deluge container trying to address the problem where torrents were not being removed by AddRemovePlus and saw that the mapping for the volume was not the same between deluge and sonarr so I changed it so that both deluge and sonarr use the same paths. Restarted the docker container and I cannot browse to 192.168.0.251:8112 (that's the IP address of my Synology NAS where the docker container is running). I can't get deluge to come up enough to access via the WebUI.

Here are the contents of deluge-web.log

Code: Select all

12:26:00.981 [INFO    ][deluge.configmanager:52  ] Setting config directory to: /config
12:26:00.996 [INFO    ][deluge.ui.ui:70  ] Deluge ui 2.0.4.dev56
12:26:00.996 [INFO    ][deluge.ui.ui:72  ] Starting web ui..
12:26:02.297 [WARNING ][deluge.i18n.util           :83  ] IOError when loading translations: [Errno 2] No translation file found for doma
in: 'deluge'
12:26:02.323 [INFO    ][deluge.i18n.util           :102 ] Setting up translations from /usr/lib/python3.9/site-packages/deluge/i18n
12:26:02.359 [INFO    ][deluge.ui.web.server       :735 ] Starting webui server at PID 642
12:26:02.361 [INFO    ][twisted                    :147 ] Site starting on 8112
12:26:02.361 [INFO    ][twisted                    :147 ] Starting factory <twisted.web.server.Site object at 0x7f43daf4fe50>
12:26:02.362 [INFO    ][deluge.ui.web.server       :750 ] Serving at http://0.0.0.0:8112/
12:26:02.408 [INFO    ][deluge.pluginmanagerbase   :189 ] Plugin AutoRemovePlus enabled...
supervisord.log

Code: Select all

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

2022-04-02 12:24:20.451035 [info] System information Linux delugevpntest 4.4.180+ #42218 SMP Mon Oct 18 19:17:56 CST 2021 x86_64 GNU/Linux
2022-04-02 12:24:20.536795 [info] OS_ARCH defined as 'x86-64'
2022-04-02 12:24:20.692705 [info] PUID defined as '1000'
2022-04-02 12:24:20.950167 [info] PGID defined as '1000'
2022-04-02 12:24:21.385028 [warn] UMASK not defined (via -e UMASK), defaulting to '000'
2022-04-02 12:24:21.471462 [info] Permissions already set for volume mappings
2022-04-02 12:24:21.681796 [info] Deleting files in /tmp (non recursive)...
2022-04-02 12:24:21.913447 [warn] VPN_ENABLED not defined,(via -e VPN_ENABLED), defaulting to 'yes'
2022-04-02 12:24:22.083527 [info] VPN_CLIENT defined as 'openvpn'
2022-04-02 12:24:22.213596 [info] VPN_PROV defined as 'pia'
2022-04-02 12:24:22.771651 [info] OpenVPN config file (ovpn extension) is located at /config/openvpn/Netherlands.ovpn
2022-04-02 12:24:22.931201 [info] VPN remote server(s) defined as 'nl-amsterdam.privacy.network,'
2022-04-02 12:24:22.992086 [info] VPN remote port(s) defined as '1198,'
2022-04-02 12:24:23.053344 [info] VPN remote protcol(s) defined as 'udp,'
2022-04-02 12:24:23.122758 [info] VPN_DEVICE_TYPE defined as 'tun0'
2022-04-02 12:24:23.189879 [info] VPN_OPTIONS not defined (via -e VPN_OPTIONS)
2022-04-02 12:24:23.258056 [info] LAN_NETWORK defined as '192.168.0.0/24'
2022-04-02 12:24:23.327874 [warn] NAME_SERVERS not defined (via -e NAME_SERVERS), defaulting to name servers defined in readme.md
2022-04-02 12:24:23.396644 [info] VPN_USER defined as 'p6153273'
2022-04-02 12:24:23.464880 [info] VPN_PASS defined as '39wB!WS%na%8xMCf'
2022-04-02 12:24:23.534485 [info] STRICT_PORT_FORWARD defined as 'yes'
2022-04-02 12:24:23.603280 [info] ENABLE_PRIVOXY defined as 'yes'
2022-04-02 12:24:23.676440 [info] VPN_INPUT_PORTS not defined (via -e VPN_INPUT_PORTS), skipping allow for custom incoming ports
2022-04-02 12:24:23.743479 [info] VPN_OUTPUT_PORTS not defined (via -e VPN_OUTPUT_PORTS), skipping allow for custom outgoing ports
2022-04-02 12:24:23.830517 [info] DELUGE_DAEMON_LOG_LEVEL not defined,(via -e DELUGE_DAEMON_LOG_LEVEL), defaulting to 'info'
2022-04-02 12:24:23.919272 [info] DELUGE_WEB_LOG_LEVEL not defined,(via -e DELUGE_WEB_LOG_LEVEL), defaulting to 'info'
2022-04-02 12:24:23.988474 [info] Starting Supervisor...
2022-04-02 12:24:24,826 INFO Included extra file "/etc/supervisor/conf.d/delugevpn.conf" during parsing
2022-04-02 12:24:24,826 INFO Set uid to user 0 succeeded
2022-04-02 12:24:24,836 INFO supervisord started with pid 6
2022-04-02 12:24:25,839 INFO spawned: 'shutdown-script' with pid 179
2022-04-02 12:24:25,842 INFO spawned: 'start-script' with pid 180
2022-04-02 12:24:25,845 INFO spawned: 'watchdog-script' with pid 181
2022-04-02 12:24:25,846 INFO reaped unknown pid 7 (exit status 0)
2022-04-02 12:24:25,896 DEBG 'start-script' stdout output:
[info] VPN is enabled, beginning configuration of VPN

2022-04-02 12:24:25,897 INFO success: shutdown-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2022-04-02 12:24:25,898 INFO success: start-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2022-04-02 12:24:25,898 INFO success: watchdog-script entered RUNNING state, process has stayed up for > than 0 seconds (startsecs)
2022-04-02 12:24:25,915 DEBG 'start-script' stdout output:
[warn] Password contains characters which could cause authentication issues, please consider changing this if possible

2022-04-02 12:24:25,998 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.222 to /etc/resolv.conf

2022-04-02 12:24:26,005 DEBG 'start-script' stdout output:
[info] Adding 84.200.69.80 to /etc/resolv.conf

2022-04-02 12:24:26,012 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.174 to /etc/resolv.conf

2022-04-02 12:24:26,019 DEBG 'start-script' stdout output:
[info] Adding 1.1.1.1 to /etc/resolv.conf

2022-04-02 12:24:26,025 DEBG 'start-script' stdout output:
[info] Adding 209.222.18.218 to /etc/resolv.conf

2022-04-02 12:24:26,032 DEBG 'start-script' stdout output:
[info] Adding 37.235.1.177 to /etc/resolv.conf

2022-04-02 12:24:26,039 DEBG 'start-script' stdout output:
[info] Adding 84.200.70.40 to /etc/resolv.conf

2022-04-02 12:24:26,045 DEBG 'start-script' stdout output:
[info] Adding 1.0.0.1 to /etc/resolv.conf

2022-04-02 12:24:26,444 DEBG 'start-script' stdout output:
[info] Default route for container is 172.17.0.1

2022-04-02 12:24:26,483 DEBG 'start-script' stdout output:
[info] Docker network defined as    172.17.0.0/16

2022-04-02 12:24:26,490 DEBG 'start-script' stdout output:
[info] Adding 192.168.0.0/24 as route via docker eth0

2022-04-02 12:24:26,493 DEBG 'start-script' stdout output:
[info] ip route defined as follows...
--------------------

2022-04-02 12:24:26,495 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.12 
192.168.0.0/24 via 172.17.0.1 dev eth0 
broadcast 127.0.0.0 dev lo table local proto kernel scope link src 127.0.0.1 
local 127.0.0.0/8 dev lo table local proto kernel scope host src 127.0.0.1 
local 127.0.0.1 dev lo table local proto kernel scope host src 127.0.0.1 
broadcast 127.255.255.255 dev lo table local proto kernel scope link src 127.0.0.1 
broadcast 172.17.0.0 dev eth0 table local proto kernel scope link src 172.17.0.12 
local 172.17.0.12 dev eth0 table local proto kernel scope host src 172.17.0.12 
broadcast 172.17.255.255 dev eth0 table local proto kernel scope link src 172.17.0.12 

2022-04-02 12:24:26,496 DEBG 'start-script' stdout output:
unreachable default dev lo proto kernel metric 4294967295 error 4294967195 pref medium
unreachable default dev lo proto kernel metric 4294967295 error 4294967195 pref medium

2022-04-02 12:24:26,497 DEBG 'start-script' stdout output:
--------------------

2022-04-02 12:24:26,507 DEBG 'start-script' stdout output:
iptable_mangle          1656  0
ip_tables              14011  5 iptable_filter,iptable_mangle,iptable_nat
x_tables               17138  19 ip6table_filter,xt_ipvs,xt_iprange,xt_mark,xt_recent,ip_tables,xt_tcpudp,ipt_MASQUERADE,xt_limit,xt_state,xt_conntrack,xt_LOG,xt_nat,xt_multiport,iptable_filter,xt_REDIRECT,iptable_mangle,ip6_tables,xt_addrtype

2022-04-02 12:24:26,508 DEBG 'start-script' stdout output:
[info] iptable_mangle support detected, adding fwmark for tables

2022-04-02 12:24:26,833 DEBG 'start-script' stdout output:
[info] iptables defined as follows...
--------------------

2022-04-02 12:24:26,837 DEBG 'start-script' stdout output:
-P INPUT DROP
-P FORWARD DROP
-P OUTPUT DROP
-A INPUT -s 172.17.0.0/16 -d 172.17.0.0/16 -j ACCEPT
-A INPUT -s 181.214.206.126/32 -i eth0 -j ACCEPT
-A INPUT -s 212.102.34.139/32 -i eth0 -j ACCEPT
-A INPUT -s 212.102.34.138/32 -i eth0 -j ACCEPT
-A INPUT -i eth0 -p tcp -m tcp --dport 8112 -j ACCEPT
-A INPUT -i eth0 -p udp -m udp --dport 8112 -j ACCEPT
-A INPUT -s 192.168.0.0/24 -d 172.17.0.0/16 -i eth0 -p tcp -m tcp --dport 58846 -j ACCEPT
-A INPUT -s 192.168.0.0/24 -d 172.17.0.0/16 -i eth0 -p tcp -m tcp --dport 8118 -j ACCEPT
-A INPUT -p icmp -m icmp --icmp-type 0 -j ACCEPT
-A INPUT -i lo -j ACCEPT
-A INPUT -i tun0 -j ACCEPT
-A OUTPUT -s 172.17.0.0/16 -d 172.17.0.0/16 -j ACCEPT
-A OUTPUT -d 181.214.206.126/32 -o eth0 -j ACCEPT
-A OUTPUT -d 212.102.34.139/32 -o eth0 -j ACCEPT
-A OUTPUT -d 212.102.34.138/32 -o eth0 -j ACCEPT
-A OUTPUT -o eth0 -p tcp -m tcp --sport 8112 -j ACCEPT
-A OUTPUT -o eth0 -p udp -m udp --sport 8112 -j ACCEPT
-A OUTPUT -s 172.17.0.0/16 -d 192.168.0.0/24 -o eth0 -p tcp -m tcp --sport 58846 -j ACCEPT
-A OUTPUT -s 172.17.0.0/16 -d 192.168.0.0/24 -o eth0 -p tcp -m tcp --sport 8118 -j ACCEPT
-A OUTPUT -p icmp -m icmp --icmp-type 8 -j ACCEPT
-A OUTPUT -o lo -j ACCEPT
-A OUTPUT -o tun0 -j ACCEPT

2022-04-02 12:24:26,840 DEBG 'start-script' stdout output:
--------------------

2022-04-02 12:24:26,841 DEBG 'start-script' stdout output:
[info] Starting OpenVPN (non daemonised)...

2022-04-02 12:24:26,870 DEBG 'start-script' stdout output:
2022-04-02 12:24:26 WARNING: Compression for receiving enabled. Compression has been used in the past to break encryption. Sent packets are not compressed unless "allow-compression yes" is also set.
2022-04-02 12:24:26 DEPRECATED OPTION: --cipher set to 'aes-128-cbc' but missing in --data-ciphers (AES-256-GCM:AES-128-GCM). Future OpenVPN version will ignore --cipher for cipher negotiations. Add 'aes-128-cbc' to --data-ciphers or change --cipher 'aes-128-cbc' to --data-ciphers-fallback 'aes-128-cbc' to silence this warning.

2022-04-02 12:24:26,871 DEBG 'start-script' stdout output:
2022-04-02 12:24:26 WARNING: file 'credentials.conf' is group or others accessible
2022-04-02 12:24:26 OpenVPN 2.5.1 [git:makepkg/f186691b32e68362+] x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Feb 24 2021
2022-04-02 12:24:26 library versions: OpenSSL 1.1.1k  25 Mar 2021, LZO 2.10
2022-04-02 12:24:26 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts

2022-04-02 12:24:26,872 DEBG 'start-script' stdout output:
2022-04-02 12:24:26 CRL: loaded 1 CRLs from file -----BEGIN X509 CRL-----
MIICWDCCAUAwDQYJKoZIhvcNAQENBQAwgegxCzAJBgNVBAYTAlVTMQswCQYDVQQI
EwJDQTETMBEGA1UEBxMKTG9zQW5nZWxlczEgMB4GA1UEChMXUHJpdmF0ZSBJbnRl
cm5ldCBBY2Nlc3MxIDAeBgNVBAsTF1ByaXZhdGUgSW50ZXJuZXQgQWNjZXNzMSAw
HgYDVQQDExdQcml2YXRlIEludGVybmV0IEFjY2VzczEgMB4GA1UEKRMXUHJpdmF0
ZSBJbnRlcm5ldCBBY2Nlc3MxLzAtBgkqhkiG9w0BCQEWIHNlY3VyZUBwcml2YXRl
aW50ZXJuZXRhY2Nlc3MuY29tFw0xNjA3MDgxOTAwNDZaFw0zNjA3MDMxOTAwNDZa
MCYwEQIBARcMMTYwNzA4MTkwMDQ2MBECAQYXDDE2MDcwODE5MDA0NjANBgkqhkiG
9w0BAQ0FAAOCAQEAQZo9X97ci8EcPYu/uK2HB152OZbeZCINmYyluLDOdcSvg6B5
jI+ffKN3laDvczsG6CxmY3jNyc79XVpEYUnq4rT3FfveW1+Ralf+Vf38HdpwB8EW
B4hZlQ205+21CALLvZvR8HcPxC9KEnev1mU46wkTiov0EKc+EdRxkj5yMgv0V2Re
ze7AP+NQ9ykvDScH4eYCsmufNpIjBLhpLE2cuZZXBLcPhuRzVoU3l7A9lvzG9mjA
5YijHJGHNjlWFqyrn1CfYS6koa4TGEPngBoAziWRbDGdhEgJABHrpoaFYaL61zqy
MR6jC0K2ps9qyZAN74LEBedEfK7tBOzWMwr58A==
-----END X509 CRL-----

2022-04-02 12:24:26 TCP/UDP: Preserving recently used remote address: [AF_INET]212.102.34.138:1198
2022-04-02 12:24:26 UDP link local: (not bound)
2022-04-02 12:24:26 UDP link remote: [AF_INET]212.102.34.138:1198

2022-04-02 12:24:27,473 DEBG 'start-script' stdout output:
2022-04-02 12:24:27 [amsterdam402] Peer Connection Initiated with [AF_INET]212.102.34.138:1198

2022-04-02 12:24:27,641 DEBG 'start-script' stdout output:
2022-04-02 12:24:27 TUN/TAP device tun0 opened
2022-04-02 12:24:27 net_iface_mtu_set: mtu 1500 for tun0
2022-04-02 12:24:27 net_iface_up: set tun0 up
2022-04-02 12:24:27 net_addr_v4_add: 10.9.112.183/24 dev tun0

2022-04-02 12:24:27,641 DEBG 'start-script' stdout output:
2022-04-02 12:24:27 /root/openvpnup.sh tun0 1500 1553 10.9.112.183 255.255.255.0 init

2022-04-02 12:24:27,646 DEBG 'start-script' stdout output:
2022-04-02 12:24:27 Initialization Sequence Completed

2022-04-02 12:24:45,288 DEBG 'start-script' stdout output:
[info] Attempting to get external IP using 'http://checkip.amazonaws.com'...

2022-04-02 12:24:51,575 DEBG 'start-script' stdout output:
[info] Successfully retrieved external IP address 212.102.34.138

2022-04-02 12:24:51,654 DEBG 'start-script' stdout output:
[info] Script started to assign incoming port

2022-04-02 12:24:51,654 DEBG 'start-script' stdout output:
[info] Port forwarding is enabled
[info] Checking endpoint 'nl-amsterdam.privacy.network' is port forward enabled...

2022-04-02 12:24:57,855 DEBG 'start-script' stdout output:
[info] PIA endpoint 'nl-amsterdam.privacy.network' is in the list of endpoints that support port forwarding
[info] List of PIA endpoints that support port forwarding:-
[info] bangladesh.privacy.network
[info] za.privacy.network
[info] man.privacy.network
[info] al.privacy.network
[info] tr.privacy.network
[info] ad.privacy.network
[info] saudiarabia.privacy.network
[info] hk.privacy.network
[info] fi-2.privacy.network
[info] ae.privacy.network
[info] poland.privacy.network
[info] aus-melbourne.privacy.network
[info] slovenia.privacy.network
[info] ca-montreal.privacy.network
[info] philippines.privacy.network
[info] hungary.privacy.network
[info] gr.privacy.network
[info] italy.privacy.network
[info] ca-toronto.privacy.network
[info] vietnam.privacy.network
[info] liechtenstein.privacy.network
[info] macau.privacy.network
[info] es-valencia.privacy.network
[info] france.privacy.network
[info] kazakhstan.privacy.network
[info] ireland.privacy.network
[info] fi.privacy.network
[info] kualalumpur.privacy.network
[info] austria.privacy.network
[info] china.privacy.network
[info] venezuela.privacy.network
[info] monaco.privacy.network
[info] sweden-2.privacy.network
[info] israel.privacy.network
[info] yerevan.privacy.network
[info] panama.privacy.network
[info] ca-vancouver.privacy.network
[info] lt.privacy.network
[info] cyprus.privacy.network
[info] de-berlin.privacy.network
[info] denmark-2.privacy.network
[info] santiago.privacy.network
[info] lv.privacy.network
[info] denmark.privacy.network
[info] uk-manchester.privacy.network
[info] uk-2.privacy.network
[info] mexico.privacy.network
[info] ar.privacy.network
[info] srilanka.privacy.network
[info] cambodia.privacy.network
[info] nl-amsterdam.privacy.network
[info] uk-southampton.privacy.network
[info] spain.privacy.network
[info] ee.privacy.network
[info] greenland.privacy.network
[info] swiss.privacy.network
[info] br.privacy.network
[info] sweden.privacy.network
[info] brussels.privacy.network
[info] in.privacy.network
[info] sg.privacy.network
[info] mongolia.privacy.network
[info] bogota.privacy.network
[info] zagreb.privacy.network
[info] montenegro.privacy.network
[info] de-frankfurt.privacy.network
[info] morocco.privacy.network
[info] czech.privacy.network
[info] pt.privacy.network
[info] is.privacy.network
[info] nz.privacy.network
[info] japan-2.privacy.network
[info] aus-perth.privacy.network
[info] ca-ontario.privacy.network
[info] lu.privacy.network
[info] sofia.privacy.network
[info] bahamas.privacy.network
[info] rs.privacy.network
[info] dz.privacy.network
[info] egypt.privacy.network
[info] md.privacy.network
[info] ro.privacy.network
[info] au-sydney.privacy.network
[info] italy-2.privacy.network
[info] mk.privacy.network
[info] japan.privacy.network
[info] no.privacy.network
[info] qatar.privacy.network
[info] jakarta.privacy.network
[info] sanjose.privacy.network

2022-04-02 12:24:57,856 DEBG 'start-script' stdout output:
[info] ua.privacy.network
[info] uk-london.privacy.network
[info] malta.privacy.network
[info] taiwan.privacy.network
[info] sk.privacy.network
[info] nigeria.privacy.network
[info] ba.privacy.network
[info] georgia.privacy.network

2022-04-02 12:25:06,696 DEBG 'start-script' stdout output:
[info] Successfully assigned and bound incoming port '37952'

2022-04-02 12:25:21,377 DEBG 'watchdog-script' stdout output:
[info] Deluge listening interface IP 0.0.0.0 and VPN provider IP 10.9.112.183 different, marking for reconfigure

2022-04-02 12:25:21,605 DEBG 'watchdog-script' stdout output:
[info] Deluge not running

2022-04-02 12:25:21,614 DEBG 'watchdog-script' stdout output:
[info] Deluge Web UI not running

2022-04-02 12:25:21,631 DEBG 'watchdog-script' stdout output:
[info] Privoxy not running
[info] Deluge incoming port 6890 and VPN incoming port 37952 different, marking for reconfigure

2022-04-02 12:25:21,643 DEBG 'watchdog-script' stdout output:
[info] Attempting to start Deluge...
[info] Removing deluge pid file (if it exists)...

2022-04-02 12:25:24,521 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'listen_interface' currently has a value of '10.37.112.236'
[info] Deluge key 'listen_interface' will have a new value '10.9.112.183'
[info] Writing changes to Deluge config file '/config/core.conf'...

2022-04-02 12:25:25,112 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'outgoing_interface' currently has a value of 'tun0'
[info] Deluge key 'outgoing_interface' will have a new value 'tun0'
[info] Writing changes to Deluge config file '/config/core.conf'...

2022-04-02 12:25:25,791 DEBG 'watchdog-script' stdout output:
[info] Deluge key 'default_daemon' currently has a value of 'cfce24192fab4b0a9127a0e49f8635e4'
[info] Deluge key 'default_daemon' will have a new value 'cfce24192fab4b0a9127a0e49f8635e4'
[info] Writing changes to Deluge config file '/config/web.conf'...

2022-04-02 12:25:27,232 DEBG 'watchdog-script' stdout output:
[info] Deluge process started
[info] Waiting for Deluge process to start listening on port 58846...

2022-04-02 12:25:27,937 DEBG 'watchdog-script' stdout output:
[info] Deluge process listening on port 58846

2022-04-02 12:25:38,413 DEBG 'watchdog-script' stdout output:
Setting "random_port" to: False
Configuration value successfully updated.

2022-04-02 12:25:48,917 DEBG 'watchdog-script' stdout output:
Setting "listen_ports" to: (37952, 37952)
Configuration value successfully updated.

2022-04-02 12:25:59,913 DEBG 'watchdog-script' stdout output:
[info] No torrents with state 'Error' found

2022-04-02 12:25:59,914 DEBG 'watchdog-script' stdout output:
[info] Starting Deluge Web UI...
[info] Deluge Web UI started

2022-04-02 12:25:59,938 DEBG 'watchdog-script' stdout output:
[info] Attempting to start Privoxy...

2022-04-02 12:26:00,980 DEBG 'watchdog-script' stdout output:
[info] Privoxy process started
[info] Waiting for Privoxy process to start listening on port 8118...

2022-04-02 12:26:00,990 DEBG 'watchdog-script' stdout output:
[info] Privoxy process listening on port 8118

2022-04-02 12:27:46,104 WARN received SIGTERM indicating exit request
2022-04-02 12:27:46,104 DEBG killing watchdog-script (pid 181) with signal SIGTERM
2022-04-02 12:27:46,104 INFO waiting for shutdown-script, start-script, watchdog-script to die
2022-04-02 12:27:47,106 DEBG fd 16 closed, stopped monitoring <POutputDispatcher at 140125420487824 for <Subprocess at 140125420486816 with name watchdog-script in state STOPPING> (stdout)>
2022-04-02 12:27:47,106 DEBG fd 20 closed, stopped monitoring <POutputDispatcher at 140125420487872 for <Subprocess at 140125420486816 with name watchdog-script in state STOPPING> (stderr)>
2022-04-02 12:27:47,107 INFO stopped: watchdog-script (terminated by SIGTERM)
2022-04-02 12:27:47,107 DEBG received SIGCHLD indicating a child quit
2022-04-02 12:27:47,107 DEBG killing start-script (pid 180) with signal SIGTERM
2022-04-02 12:27:48,109 DEBG fd 11 closed, stopped monitoring <POutputDispatcher at 140125420374720 for <Subprocess at 140125420374048 with name start-script in state STOPPING> (stdout)>
2022-04-02 12:27:48,109 DEBG fd 15 closed, stopped monitoring <POutputDispatcher at 140125420487296 for <Subprocess at 140125420374048 with name start-script in state STOPPING> (stderr)>
2022-04-02 12:27:48,109 INFO stopped: start-script (terminated by SIGTERM)
2022-04-02 12:27:48,109 DEBG received SIGCHLD indicating a child quit
2022-04-02 12:27:48,110 DEBG killing shutdown-script (pid 179) with signal SIGTERM
2022-04-02 12:27:48,110 DEBG 'shutdown-script' stdout output:
[info] Initialising shutdown of process(es) '^deluge-web,^/usr/bin/python /usr/bin/deluged' ...

2022-04-02 12:27:49,112 DEBG fd 8 closed, stopped monitoring <POutputDispatcher at 140125420726448 for <Subprocess at 140125420727264 with name shutdown-script in state STOPPING> (stdout)>
2022-04-02 12:27:49,112 DEBG fd 10 closed, stopped monitoring <POutputDispatcher at 140125420486960 for <Subprocess at 140125420727264 with name shutdown-script in state STOPPING> (stderr)>
2022-04-02 12:27:49,112 INFO stopped: shutdown-script (exit status 0)
2022-04-02 12:27:49,113 DEBG received SIGCHLD indicating a child quit
Finally deluged.log

Code: Select all

12:25:27.194 [INFO    ][deluge.configmanager:52  ] Setting config directory to: /config
12:25:27.772 [INFO    ][deluge.core.daemon            :94  ] Deluge daemon 2.0.4.dev56
12:25:27.798 [INFO    ][deluge.core.core              :340 ] Successfully loaded session.state: /config/session.state
12:25:27.806 [INFO    ][deluge.core.core              :340 ] Successfully loaded session.state: /config/session.state.bak
12:25:27.808 [INFO    ][deluge.core.alertmanager      :148 ] Alert Queue Size set to 10000
12:25:27.849 [INFO    ][deluge.core.rpcserver         :402 ] Starting DelugeRPC server localhost:58846
12:25:27.879 [INFO    ][twisted                       :147 ] Factory (TLS) starting on 58846
12:25:27.880 [INFO    ][twisted                       :147 ] Starting factory <twisted.internet.protocol.Factory object at 0x7f1fcda32cd0>
12:25:27.883 [INFO    ][deluge.core.daemon            :154 ] Deluge daemon starting...
12:25:28.235 [INFO    ][autoremoveplus.core           :153 ] Server config: Sonarr: enabled=True,key=7da0feea419b407d8f820a58d4ba9159, Radarr: enabled=True, key=df82f300cd63428c8a1d5e1f109dff25, Lidarr: enabled=False, key=, Server: url=
12:25:28.236 [INFO    ][autoremoveplus.mediaserver    :119 ] Endpoint of sonarr is /sonarr/api/v3
12:25:28.236 [INFO    ][autoremoveplus.mediaserver    :119 ] Endpoint of lidarr is /lidarr/api/v1
12:25:28.236 [INFO    ][autoremoveplus.mediaserver    :119 ] Endpoint of radarr is /radarr/api
12:25:28.237 [INFO    ][deluge.pluginmanagerbase      :189 ] Plugin AutoRemovePlus enabled...
12:25:28.237 [INFO    ][deluge.core.authmanager       :228 ] Opening auth for load: /config/auth
12:25:28.238 [INFO    ][deluge.core.authmanager       :236 ] Successfully loaded auth: /config/auth
12:25:28.240 [INFO    ][deluge.core.torrentmanager    :813 ] Loading torrent state: /config/state/torrents.state
12:25:28.255 [INFO    ][deluge.core.torrentmanager    :829 ] Successfully loaded /config/state/torrents.state
12:25:28.256 [INFO    ][deluge.core.torrentmanager    :1076] Opening torrents.fastresume for load: /config/state/torrents.fastresume
12:25:28.259 [INFO    ][deluge.core.torrentmanager    :1087] Successfully loaded torrents.fastresume: /config/state/torrents.fastresume
12:25:28.541 [INFO    ][deluge.core.torrentmanager    :675 ] Torrent Blue.Bloods.S12E17.720p.WEB.h264-GOSSIP[eztv.re].mkv from user "localclient" loaded
12:25:28.542 [ERROR   ][deluge.core.torrentmanager    :1517] on_alert_fastresume_rejected: Blue.Bloods.S12E17.720p.WEB.h264-GOSSIP[eztv.re].mkv fast resume rejected. file_stat(/data/complete/Blue.Bloods.S12E17.720p.WEB.h264-GOSSIP[eztv.re].mkv): mismatching file size
12:25:28.547 [INFO    ][deluge.core.torrentmanager    :675 ] Torrent Blue.Bloods.S12E09.PROPER.720p.HDTV.x264-SYNCOPY[rarbg] from user "localclient" loaded
12:25:28.548 [INFO    ][deluge.core.torrentmanager    :892 ] Finished loading 2 torrents in 0:00:00.308789
12:25:28.808 [ERROR   ][deluge.core.torrentmanager    :1517] on_alert_fastresume_rejected: Blue.Bloods.S12E09.PROPER.720p.HDTV.x264-SYNCOPY[rarbg] fast resume rejected. file_stat(/data/incomplete/Blue.Bloods.S12E09.PROPER.720p.HDTV.x264-SYNCOPY[rarbg]/blue.bloods.s12e09.proper.720p.hdtv.x264-syncopy.mkv): mismatching file size
12:25:33.236 [INFO    ][autoremoveplus.core           :173 ] check interval loop starting
12:25:33.246 [INFO    ][autoremoveplus.core           :295 ] AutoRemovePlus: check do_remove
12:25:33.264 [INFO    ][autoremoveplus.mediaserver    :140 ] Sending GET request to /sonarr/api/v3/queue?page=1
12:25:33.301 [ERROR   ][autoremoveplus.core           :333 ] Error reading config: "Error Connecting to server: Invalid URL '/sonarr/api/v3/queue?page=1': No schema supplied. Perhaps you meant http:///sonarr/api/v3/queue?page=1?"
12:25:38.355 [INFO    ][deluge.core.rpcserver         :171 ] Deluge Client connection made from: 127.0.0.1:47624
12:25:38.413 [INFO    ][deluge.core.rpcserver         :197 ] Deluge client disconnected: Connection to the other side was lost in a non-clean fashion: Connection lost.
12:25:48.886 [INFO    ][deluge.core.rpcserver         :171 ] Deluge Client connection made from: 127.0.0.1:47778
12:25:48.920 [INFO    ][deluge.core.rpcserver         :197 ] Deluge client disconnected: Connection to the other side was lost in a non-clean fashion: Connection lost.
12:25:59.108 [INFO    ][deluge.core.torrentmanager    :1634] on_alert_external_ip: 212.102.34.138
12:25:59.586 [INFO    ][deluge.core.rpcserver         :171 ] Deluge Client connection made from: 127.0.0.1:47940
12:25:59.719 [INFO    ][deluge.core.rpcserver         :197 ] Deluge client disconnected: Connection to the other side was lost in a non-clean fashion: Connection lost.
12:26:02.365 [INFO    ][deluge.core.rpcserver         :171 ] Deluge Client connection made from: 127.0.0.1:47974
12:26:09.247 [INFO    ][autoremoveplus.core           :295 ] AutoRemovePlus: check do_remove
12:26:09.248 [INFO    ][autoremoveplus.mediaserver    :140 ] Sending GET request to /sonarr/api/v3/queue?page=1
12:26:09.249 [ERROR   ][autoremoveplus.core           :333 ] Error reading config: "Error Connecting to server: Invalid URL '/sonarr/api/v3/queue?page=1': No schema supplied. Perhaps you meant http:///sonarr/api/v3/queue?page=1?"
12:26:45.246 [INFO    ][autoremoveplus.core           :295 ] AutoRemovePlus: check do_remove
12:26:45.247 [INFO    ][autoremoveplus.mediaserver    :140 ] Sending GET request to /sonarr/api/v3/queue?page=1
12:26:45.248 [ERROR   ][autoremoveplus.core           :333 ] Error reading config: "Error Connecting to server: Invalid URL '/sonarr/api/v3/queue?page=1': No schema supplied. Perhaps you meant http:///sonarr/api/v3/queue?page=1?"
12:27:21.247 [INFO    ][autoremoveplus.core           :295 ] AutoRemovePlus: check do_remove
12:27:21.247 [INFO    ][autoremoveplus.mediaserver    :140 ] Sending GET request to /sonarr/api/v3/queue?page=1
12:27:21.248 [ERROR   ][autoremoveplus.core           :333 ] Error reading config: "Error Connecting to server: Invalid URL '/sonarr/api/v3/queue?page=1': No schema supplied. Perhaps you meant http:///sonarr/api/v3/queue?page=1?"
12:27:48.129 [INFO    ][twisted                       :147 ] Received SIGTERM, shutting down.
12:27:48.130 [INFO    ][deluge.core.daemon            :176 ] Deluge daemon shutting down, waiting for components to shutdown...
12:27:48.149 [INFO    ][deluge.core.core              :308 ] Saving the session.state at: /config/session.state
12:27:48.216 [INFO    ][deluge.core.rpcserver         :197 ] Deluge client disconnected: Connection to the other side was lost in a non-clean fashion: Connection lost.
12:27:48.236 [INFO    ][twisted                       :147 ] Received SIGTERM, shutting down.
12:27:48.257 [CRITICAL][twisted                       :147 ] Unhandled Error
Traceback (most recent call last):
  File "/usr/lib/python3.9/site-packages/deluge/core/daemon_entry.py", line 122, in run_daemon
    daemon.start()
  File "/usr/lib/python3.9/site-packages/deluge/core/daemon.py", line 164, in start
    reactor.run()
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 1423, in run
    self.mainLoop()
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 1433, in mainLoop
    reactorBaseSelf.runUntilCurrent()
--- <exception caught here> ---
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 972, in runUntilCurrent
    f(*a, **kw)
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 711, in stop
    raise error.ReactorNotRunning("Can't stop reactor that isn't running.")
twisted.internet.error.ReactorNotRunning: Can't stop reactor that isn't running.

12:27:48.340 [INFO    ][twisted                       :147 ] Received SIGTERM, shutting down.
12:27:48.341 [CRITICAL][twisted                       :147 ] Unhandled Error
Traceback (most recent call last):
  File "/usr/lib/python3.9/site-packages/deluge/core/daemon_entry.py", line 122, in run_daemon
    daemon.start()
  File "/usr/lib/python3.9/site-packages/deluge/core/daemon.py", line 164, in start
    reactor.run()
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 1423, in run
    self.mainLoop()
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 1433, in mainLoop
    reactorBaseSelf.runUntilCurrent()
--- <exception caught here> ---
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 972, in runUntilCurrent
    f(*a, **kw)
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 711, in stop
    raise error.ReactorNotRunning("Can't stop reactor that isn't running.")
twisted.internet.error.ReactorNotRunning: Can't stop reactor that isn't running.

12:27:48.442 [INFO    ][twisted                       :147 ] Received SIGTERM, shutting down.
12:27:48.443 [CRITICAL][twisted                       :147 ] Unhandled Error
Traceback (most recent call last):
  File "/usr/lib/python3.9/site-packages/deluge/core/daemon_entry.py", line 122, in run_daemon
    daemon.start()
  File "/usr/lib/python3.9/site-packages/deluge/core/daemon.py", line 164, in start
    reactor.run()
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 1423, in run
    self.mainLoop()
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 1433, in mainLoop
    reactorBaseSelf.runUntilCurrent()
--- <exception caught here> ---
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 972, in runUntilCurrent
    f(*a, **kw)
  File "/usr/lib/python3.9/site-packages/twisted/internet/base.py", line 711, in stop
    raise error.ReactorNotRunning("Can't stop reactor that isn't running.")
twisted.internet.error.ReactorNotRunning: Can't stop reactor that isn't running.

12:27:48.473 [INFO    ][deluge.pluginmanagerbase      :250 ] Plugin AutoRemovePlus disabled...
12:27:48.474 [INFO    ][twisted                       :147 ] (TLS Port 58846 Closed)
12:27:48.474 [INFO    ][twisted                       :147 ] Stopping factory <twisted.internet.protocol.Factory object at 0x7f1fcda32cd0>
12:27:48.475 [INFO    ][twisted                       :147 ] Main loop terminated.
12:27:48.476 [INFO    ][deluge.core.daemon            :168 ] Deluge daemon shutdown successfully
12:27:48.476 [INFO    ][deluge.core.daemon_entry      :137 ] Exiting...
Post Reply