[solved] utserver will not download magnet metadata

[dingo]

EDIT: Since utserver appears to be dead in the water, i moved to deluged/yarss2 thin client setup, works fine now.

I installed utserver (300B (27079) 2012-04-17 19:51:48 -0700) on my Debian box (Linux xxxxxx 3.2.0-3-686-pae #1 SMP Mon Jul 23 03:50:34 UTC 2012 i686 GNU/Linux). The box serves as the NAT for local network.

Everything appeared to work fine until i got the first magnet link from one of my RSS feeds, basically what is happening is the metadata for the torrent never get retrieved from the scraper or DHT, it just sits indefinitely, and the log is filling up with banstick messages:

[23:34:19]  Banned XXX.XXX.185.179:443 until forever
[23:34:25]  BAN peer: Responsible for '<...Torrent Name Here...>' metadata not being loaded!

at this point, i thought maybe i forgot about a port so i did "lsof -nP|grep utserver.*LISTEN":

utserver  17491          utorrent    5u     IPv4 4592201      0t0      TCP *:14441 (LISTEN)
utserver  17491          utorrent    6u     IPv6 4592202      0t0      TCP *:14441 (LISTEN)
utserver  17491          utorrent    7u     IPv4 4592203      0t0      TCP 127.0.0.1:10000 (LISTEN)
utserver  17491          utorrent   10u     IPv4 4592208      0t0      TCP *:8080 (LISTEN)
utserver  17491          utorrent   11u     IPv6 4592209      0t0      TCP *:8080 (LISTEN)

i check iptables config:

# uTorrent Incomming port
$IPT -A INPUT  -p tcp -i $WAN --dport 14441 -j ACCEPT
$IPT -A INPUT  -p udp -i $WAN --dport 14441 -j ACCEPT

# Drop anything heading for uTorrent WebUI from WAN
$IPT -A INPUT -p tcp -i $WAN --dport 8080 -j DROP

Relevant output from "iptables -L -v -n":

Chain INPUT (policy DROP 0 packets, 0 bytes)
 pkts bytes target     prot opt in     out     source               destination
17480 1254K ACCEPT     tcp  --  eth0   *       0.0.0.0/0            0.0.0.0/0            tcp dpt:14441
20287 2502K ACCEPT     udp  --  eth0   *       0.0.0.0/0            0.0.0.0/0            udp dpt:14441
    0     0 DROP       tcp  --  eth0   *       0.0.0.0/0            0.0.0.0/0            tcp dpt:8080

So far everything seems fine, telnet <wan ip> 14441 connects, t1shopper online portscan shows 14441 accessible, 8080 is not accessible from wan interface and works fine from within the lan. IP tables show traffic on the 14441 port, no dropped incomming packets.

i added "logmask: 2147483647" in my utserver.conf file to get more verbose log, then i dropped "rcf.utmr" in my requests directory to reload the conf file, after that log started filling up nicely.

i've picked up about 10-15 ip's that got banned at random and grep for any activity from them, they all look pretty similar in the log:

[00:12:58]  Incoming connection from XXX.XXX.128.140:53392
[00:12:58]  XXX.XXX.128.140:53392: Disconnect: No such torrent: XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
[00:13:00]  Incoming connection from XXX.XXX.128.140:23391
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [uTorrent/2.2.0.0 (0.0)]: Handshake completed
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 516
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 81
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 284
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 542
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 417
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 121
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 660
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 34
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 131
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 415
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 506
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 568
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 652
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 210
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 266
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 541
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 92
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 605
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 224
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 494
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 98
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 627
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 100
[00:13:01]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Got Have 507
[00:13:03]  Banned XXX.XXX.128.140:23391 until forever
[00:13:03]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): [µTorrent 2.2 (0.0)]: Disconnect: Banned
[00:13:51]  Incoming connection from XXX.XXX.128.140:53411
[00:13:51]  XXX.XXX.128.140:53411(...Torrent Name Here...): Disconnect: Banned
[00:13:53]  Incoming connection from XXX.XXX.128.140:23391
[00:13:54]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): Disconnect: Banned
[00:14:45]  Incoming connection from XXX.XXX.128.140:23391
[00:14:46]  Incoming connection from XXX.XXX.128.140:53419
[00:14:46]  XXX.XXX.128.140:53419(...Torrent Name Here...): Disconnect: Banned
[00:14:46]  XXX.XXX.128.140:23391 [uTP](...Torrent Name Here...): Disconnect: Banned

i googled the hash from the 2nd line of the log excerpt and all the log entries for this peer refer to the same torrent.

I'm pretty much at my wits end,

here is my utserver.conf:

ut_webui_port: 8080
ut_webui_dir: /home/utorrent/utserver/webui

admin_name: xxxxxx
admin_password: xxxxxx

token_auth_enable: true

dir_active: /downloads/[in_progress]
dir_completed: /downloads/[complete]
dir_torrent_files: /home/utorrent/utserver/torrents
dir_temp_files: /home/utorrent/utserver/temp

dir_autoload: /home/utorrent/utserver/torrents/dropbox
dir_autoload_delete:  true

dir_request: /home/utorrent/utserver/requests

upnp: false
natpmp: false

lsd: true
dht: true
pex: true

logmask: 2147483647

preferred_interface: eth0
bind_port: 14441

max_ul_rate: 120
max_ul_rate_seed: 120

conns_per_torrent: 150
max_total_connections: 500

max_dl_rate: -1

seed_ratio: 150
seed_time: 7200

i start the utserver with this script:

#!/bin/sh
cd /home/xxxxxxxx/utserver

./utserver -settingspath ./settings -configfile ./utserver.conf -logfile ./logs/utserver.log -pidfile ./utserver.pid -daemon

one thing that i noticed is that all the magnet downloads drop torrent file in the settingspath, when i copied those torrents from that folder to my auto_add folder, it said torrent already loaded.

downloads started with a .torrent file instead of magnet, work just fine.

well thats it, anyone got any ideas?

Thanks