Help and Support
Ask a question, report a problem, request a feature...
<<  Back To Forum

UPNP port mapping error but all seems to be working fine

by Guest on 2016/04/22 09:22:09 AM    
Hi!

I've recently moved to a new ISP. Due to that fact I'm forced to use their modem/router (VOIP, fiber etc. - they don't support private hardware and don't provide configuration details so moving to your own device is virtually impossible). Tixati seems to be working fine. Incoming connections are listed as "Listening / OK", DHT is fine, transfers are fine. However I'm getting UPNP port mapping errors (full diagnostic log below):

[09:01:11]  NAT-PMP mapping TCP port 55363 on gateway 192.168.1.1 > socket error waiting for reply: Timed out (50434)
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > error searching: Timed out searching for commands (50178)
[09:01:33]  mapping TCP port 55363 on gateway 192.168.1.1 will be retried every 5 minutes

I've tried opening ports, fiddling with firewall etc. with no effect (ISP's router has a bit limited configuration panel, no logs access and so on so there is not much I can check, UPNP is on for sure). While Tixati seems to be working fine I'm wondering why I'm getting those errors. Any help welcome. Thanks!



 *** 2016-04-22 ***
[09:00:56]  listening on tcp:0.0.0.0:55363
[09:00:56]  listening on udp:0.0.0.0:55363
[09:00:56]  error listening on tcp:[::]:55363 : IPv6 address family not supported on this computer (ignoring)
[09:00:56]  error listening on udp:[::]:55363 : IPv6 address family not supported on this computer (ignoring)
[09:01:06]  NAT-PMP mapping TCP port 55363 on gateway 192.168.1.1 > Sending to 192.168.1.1:5351
 12  00020000.D843D843.00000382
[09:01:11]  NAT-PMP mapping TCP port 55363 on gateway 192.168.1.1 > socket error waiting for reply: Timed out (50434)
[09:01:11]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > searching for device:InternetGatewayDevice:1
[09:01:11]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > sending to 192.168.1.1:1900
M-SEARCH * HTTP/1.1
HOST: 192.168.1.1:1900
MAN: "ssdp:discover"
MX: 3
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1

[09:01:18]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > error searching: Timed out searching for commands (50178)
[09:01:18]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > retrying search with alternate parameters
[09:01:18]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > searching for service:WANIPConnection:1
[09:01:18]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > sending to 192.168.1.1:1900
M-SEARCH * HTTP/1.1
HOST: 192.168.1.1:1900
MAN: "ssdp:discover"
MX: 3
ST: urn:schemas-upnp-org:service:WANIPConnection:1

[09:01:25]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > error searching: Timed out searching for commands (50178)
[09:01:25]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > retrying search with alternate parameters again
[09:01:25]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > searching for service:WANPPPConnection:1
[09:01:25]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > sending to 192.168.1.1:1900
M-SEARCH * HTTP/1.1
HOST: 192.168.1.1:1900
MAN: "ssdp:discover"
MX: 3
ST: urn:schemas-upnp-org:service:WANPPPConnection:1

[09:01:32]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > error searching: Timed out searching for commands (50178)
[09:01:32]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > retrying search with more alternate parameters
[09:01:32]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > searching for device:InternetGatewayDevice:1
[09:01:32]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > sending to 192.168.1.1:1900
M-SEARCH * HTTP/1.1
HOST: 239.255.255.250:1900
MAN: "ssdp:discover"
MX: 3
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1

[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > got reply from: 192.168.1.1:56957
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=1800
DATE: Fri, 22 Apr 2016 07:00:23 GMT
EXT:
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: 457bd4b2-1dd2-11b2-8ca6-8db88d941aa9
SERVER: Unspecified, UPnP/1.0, SoftAtHome
X-User-Agent: redsonic
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1
USN: uuid:59767d25-7c1e-3528-b8a2-d70ab1a704cb::urn:schemas-upnp-org:device:InternetGatewayDevice:1

[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > retrieving command details
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > sending GET request to http://192.168.1.1:60000/59767d25/gatedesc1.xml
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > got reply:
<?xml version="1.0"?>
<root xmlns="urn:schemas-upnp-org:device-1-0">
 <specVersion>
   <major>1</major>
   <minor>0</minor>
 </specVersion>

[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > local address: 192.168.1.100:2992
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > removing possible conflicting mappings first...
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > sending DeletePortMapping to service WANPPPConnection:1
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > sending POST command to http://192.168.1.1:60000/59767d25/59767d25/upnp/control/WANIPConn1
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:DeletePortMapping xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1">
<NewExternalPort>55363</NewExternalPort>
<NewProtocol>TCP</NewProtocol>
<NewRemoteHost></NewRemoteHost>
</u:DeletePortMapping>
</s:Body>
</s:Envelope>
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > got HTTP response code 500
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > got reply:
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<s:Fault>
<faultcode>s:Client</faultcode>
<faultstring>UPnPError</faultstring>
<detail>
<UPnPError xmlns="urn:schemas-upnp-org:control-1-0">
<errorCode>-1</errorCode>
<errorDescription>Invalid Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > sending AddPortMapping to service WANPPPConnection:1
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > sending POST command to http://192.168.1.1:60000/59767d25/59767d25/upnp/control/WANIPConn1
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:AddPortMapping xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1">
<NewEnabled>1</NewEnabled>
<NewExternalPort>55363</NewExternalPort>
<NewInternalClient>192.168.1.100</NewInternalClient>
<NewInternalPort>55363</NewInternalPort>
<NewLeaseDuration>898</NewLeaseDuration>
<NewPortMappingDescription>Tixati</NewPortMappingDescription>
<NewProtocol>TCP</NewProtocol>
<NewRemoteHost></NewRemoteHost>
</u:AddPortMapping>
</s:Body>
</s:Envelope>
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > got HTTP response code 500
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > got reply:
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<s:Fault>
<faultcode>s:Client</faultcode>
<faultstring>UPnPError</faultstring>
<detail>
<UPnPError xmlns="urn:schemas-upnp-org:control-1-0">
<errorCode>-1</errorCode>
<errorDescription>Invalid Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>
[09:01:33]  UPNP mapping TCP port 55363 on gateway 192.168.1.1 > error searching: Timed out searching for commands (50178)
[09:01:33]  mapping TCP port 55363 on gateway 192.168.1.1 will be retried every 5 minutes
[09:01:33]  NAT-PMP mapping UDP port 55363 on gateway 192.168.1.1 > Sending to 192.168.1.1:5351
 12  00010000.D843D843.00000382
[09:01:37]  NAT-PMP mapping UDP port 55363 on gateway 192.168.1.1 > socket error waiting for reply: Timed out (50434)
[09:01:37]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > searching for device:InternetGatewayDevice:1
[09:01:37]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > sending to 192.168.1.1:1900
M-SEARCH * HTTP/1.1
HOST: 192.168.1.1:1900
MAN: "ssdp:discover"
MX: 3
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1

[09:01:44]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > error searching: Timed out searching for commands (50178)
[09:01:44]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > retrying search with alternate parameters
[09:01:44]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > searching for service:WANIPConnection:1
[09:01:44]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > sending to 192.168.1.1:1900
M-SEARCH * HTTP/1.1
HOST: 192.168.1.1:1900
MAN: "ssdp:discover"
MX: 3
ST: urn:schemas-upnp-org:service:WANIPConnection:1

[09:01:51]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > error searching: Timed out searching for commands (50178)
[09:01:51]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > retrying search with alternate parameters again
[09:01:51]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > searching for service:WANPPPConnection:1
[09:01:51]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > sending to 192.168.1.1:1900
M-SEARCH * HTTP/1.1
HOST: 192.168.1.1:1900
MAN: "ssdp:discover"
MX: 3
ST: urn:schemas-upnp-org:service:WANPPPConnection:1

[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > error searching: Timed out searching for commands (50178)
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > retrying search with more alternate parameters
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > searching for device:InternetGatewayDevice:1
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > sending to 192.168.1.1:1900
M-SEARCH * HTTP/1.1
HOST: 239.255.255.250:1900
MAN: "ssdp:discover"
MX: 3
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1

[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > got reply from: 192.168.1.1:58293
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=1800
DATE: Fri, 22 Apr 2016 07:00:48 GMT
EXT:
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: 457bd4b2-1dd2-11b2-8ca6-8db88d941aa9
SERVER: Unspecified, UPnP/1.0, SoftAtHome
X-User-Agent: redsonic
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1
USN: uuid:59767d25-7c1e-3528-b8a2-d70ab1a704cb::urn:schemas-upnp-org:device:InternetGatewayDevice:1

[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > retrieving command details
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > sending GET request to http://192.168.1.1:60000/59767d25/gatedesc1.xml
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > got reply:
<?xml version="1.0"?>
<root xmlns="urn:schemas-upnp-org:device-1-0">
 <specVersion>
   <major>1</major>
   <minor>0</minor>
 </specVersion>

</root>
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > local address: 192.168.1.100:3015
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > removing possible conflicting mappings first...
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > sending DeletePortMapping to service WANPPPConnection:1
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > sending POST command to http://192.168.1.1:60000/59767d25/59767d25/upnp/control/WANIPConn1
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:DeletePortMapping xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1">
<NewExternalPort>55363</NewExternalPort>
<NewProtocol>UDP</NewProtocol>
<NewRemoteHost></NewRemoteHost>
</u:DeletePortMapping>
</s:Body>
</s:Envelope>
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > got HTTP response code 500
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > got reply:
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<s:Fault>
<faultcode>s:Client</faultcode>
<faultstring>UPnPError</faultstring>
<detail>
<UPnPError xmlns="urn:schemas-upnp-org:control-1-0">
<errorCode>-1</errorCode>
<errorDescription>Invalid Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > sending AddPortMapping to service WANPPPConnection:1
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > sending POST command to http://192.168.1.1:60000/59767d25/59767d25/upnp/control/WANIPConn1
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:AddPortMapping xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1">
<NewEnabled>1</NewEnabled>
<NewExternalPort>55363</NewExternalPort>
<NewInternalClient>192.168.1.100</NewInternalClient>
<NewInternalPort>55363</NewInternalPort>
<NewLeaseDuration>898</NewLeaseDuration>
<NewPortMappingDescription>Tixati</NewPortMappingDescription>
<NewProtocol>UDP</NewProtocol>
<NewRemoteHost></NewRemoteHost>
</u:AddPortMapping>
</s:Body>
</s:Envelope>
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > got HTTP response code 500
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > got reply:
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<s:Fault>
<faultcode>s:Client</faultcode>
<faultstring>UPnPError</faultstring>
<detail>
<UPnPError xmlns="urn:schemas-upnp-org:control-1-0">
<errorCode>-1</errorCode>
<errorDescription>Invalid Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>
[09:01:58]  UPNP mapping UDP port 55363 on gateway 192.168.1.1 > error searching: Timed out searching for commands (50178)
[09:01:58]  mapping UDP port 55363 on gateway 192.168.1.1 will be retried every 5 minutes

by Guest on 2016/09/07 01:40:16 AM    
yeah, me too, but DHT is working and the Home screen shows

Incoming Connections
Port 17130
Status Listening / OK
Received 4,499 (increasing)
Negotiating 1 (varies, small integer)
Placed 3,326 (increasing)


in the Home log I see

[9:34:34 AM]  listening on tcp:0.0.0.0:17130
[9:34:34 AM]  listening on udp:0.0.0.0:17130
[9:34:34 AM]  listening on tcp:[::]:17130
[9:34:34 AM]  listening on udp:[::]:17130
[9:34:34 AM]  DHT > started
...
[9:34:49 AM]  NAT-PMP mapping TCP port 17130 on gateway 192.168.1.254 > socket error waiting for reply: Timed out (50434)
[9:35:52 AM]  UPNP mapping TCP port 17130 on gateway 192.168.1.254 > error searching: Timed out searching for commands (50178)
[9:35:52 AM]  mapping TCP port 17130 on gateway 192.168.1.254 will be retried every 5 minutes
[9:35:57 AM]  NAT-PMP mapping UDP port 17130 on gateway 192.168.1.254 > socket error waiting for reply: Timed out (50434)
[9:37:01 AM]  UPNP mapping UDP port 17130 on gateway 192.168.1.254 > error searching: Timed out searching for commands (50178)
[9:37:01 AM]  mapping UDP port 17130 on gateway 192.168.1.254 will be retried every 5 minutes


also, canyouseeme.org says my Tixati ports are blocked, but if I forward the ports in my router, canyouseeme.org sees the ports as open.

i'm definitely not a network expert, just trying to understand why so many "errors" in Help -> Diagnostics -> UPNP/NAT-PMP log and if it's fixable or ignorable.
by swetnap on 2016/09/11 03:04:11 AM    
Having read something similiar on reddits r/torrent, someone said that (this case applies to µTorrent) as long as your router is not compatible with UPnP/NAT but you still manually opened the ports on the router, you can disable that function. In that particular case, µTorrent was blocking itself from downloading torrents just because the feature "Forward port with UPnP/NAT" was enabled, but the router did not support UPnP/NAT, thus the user had the port forwarded manually. Disabling that feature, µTorrent got to download the torrents without any problems.


In short (and applied on Tixati): If your router does *not* support UPnP/NAT, you can disable that feature in Tixati, as long as you have the port used by Tixati forwarded in the router's settings.

For me, it's like...
[12:33:16]  listening on tcp:0.0.0.0:15846
[12:33:16]  listening on udp:0.0.0.0:15846
[12:33:16]  listening on tcp:[::]:15846
[12:33:16]  listening on udp:[::]:15846


...without any other things happening around it.

canyouseeme.org can see my port indeed:
Success: I can see your service on 88.72.*.* on port (15846)
Your ISP is not blocking port 15846

Sorry for any mistakes, I'm a little drunk. :-)




This web site is powered by Super Simple Server