UPnP: tries to open other ports even after successful AddPortMapping

Support
21

Try one of these builds:

https://build.syncthing.net/viewLog.html?buildId=14711&buildTypeId=Syncthing_BuildWindows&tab=artifacts

https://build.syncthing.net/viewLog.html?buildId=14710&buildTypeId=Syncthing_BuildLinuxCross&tab=artifacts

and see if that makes things better.

22

Great thanks! I will give it a try later today as soon as I am back home and revert - Arch Linux - amd64

23

It works great, I see you changed the pseudo-random seed because it starts with 65173 instead of 65422 before. It finds the 2 IGDs IP and PPP and then it starts trying to map PPP.

I am wondering why it does not seem to do anything with IP. I am interested to see the code as I was trying with some difficulty to implement the same.:blush:

When 65173 is already taken it tries with a new port and works as expected as well.

[6JY3H] 2017/12/30 15:37:01.414915 upnp.go:190: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on enp2s0 finished.
[6JY3H] 2017/12/30 15:37:01.415055 upnp.go:190: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on enp2s0 finished.
[6JY3H] 2017/12/30 15:37:11.444312 upnp.go:417: DEBUG: SOAP Request URL: http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 15:37:11.444349 upnp.go:418: DEBUG: SOAP Action: 
[6JY3H] 2017/12/30 15:37:11.444374 upnp.go:419: DEBUG: SOAP Request:

<?xml version="1.0" ?>
	<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">
	<NewRemoteHost></NewRemoteHost>
	<NewExternalPort>65173</NewExternalPort>
	<NewProtocol>TCP</NewProtocol>
	<NewInternalPort>22000</NewInternalPort>
	<NewInternalClient>192.168.1.100</NewInternalClient>
	<NewEnabled>1</NewEnabled>
	<NewPortMappingDescription>syncthing-65173</NewPortMappingDescription>
	<NewLeaseDuration>3600</NewLeaseDuration>
	</u:AddPortMapping></s:Body>
	</s:Envelope>

[6JY3H] 2017/12/30 15:37:11.618478 upnp.go:428: DEBUG: SOAP Response: 200 OK

<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:AddPortMappingResponse xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1"/></s:Body></s:Envelope>


[6JY3H] 2017/12/30 15:37:11.618538 upnp.go:417: DEBUG: SOAP Request URL: http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 15:37:11.618552 upnp.go:418: DEBUG: SOAP Action: 
[6JY3H] 2017/12/30 15:37:11.618562 upnp.go:419: DEBUG: SOAP Request:

<?xml version="1.0" ?>
	<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
	<s:Body><u:GetExternalIPAddress xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1" /></s:Body>
	</s:Envelope>

[6JY3H] 2017/12/30 15:37:11.756407 upnp.go:428: DEBUG: SOAP Response: 200 OK

<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetExternalIPAddressResponse xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1"><NewExternalIPAddress>99.999.99.999</NewExternalIPAddress></u:GetExternalIPAddressResponse></s:Body></s:Envelope>


[6JY3H] 2017/12/30 15:37:11.756586 structs.go:32: INFO: New NAT port mapping: external TCP address 99.999.99.999:65173 to local address 0.0.0.0:22000.
[6JY3H] 2017/12/30 15:37:11.756668 service.go:61: INFO: Detected 2 NAT devices
24

This doesn’t look like the whole log, can you post the whole log?

25

yes sure, do you need STTRACE all ?

26

No, there should be a few lines before about what devices under what service IDs were discovered. Before it says finished discovery etc.

27

Also, there is only 1 request for 1 port mapping, which doesn’t look right, given you said it reports 2 services.

28

yes it does not deal with IP, PPP only

[6JY3H] 2017/12/30 16:15:10.703473 upnp.go:144: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on enp2s0
[6JY3H] 2017/12/30 16:15:10.703559 upnp.go:159: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on enp2s0
[6JY3H] 2017/12/30 16:15:10.703582 upnp.go:169: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on enp2s0
[6JY3H] 2017/12/30 16:15:10.703608 upnp.go:144: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on enp2s0
[6JY3H] 2017/12/30 16:15:10.703666 upnp.go:159: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on enp2s0
[6JY3H] 2017/12/30 16:15:10.703685 upnp.go:169: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on enp2s0
[6JY3H] 2017/12/30 16:15:10.714798 upnp.go:194: DEBUG: Handling UPnP response:

HTTP/1.1 200 OK
CACHE-CONTROL: max-age=1800
DATE: Tue, 08 Sep 1992 16:04:48 GMT
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1
USN: uuid:UPnP_BTHomeHub5.0B-1_A8D3F7BE5F5B::urn:schemas-upnp-org:device:InternetGatewayDevice:1
EXT:
SERVER: Linux/2.6.30 UPnP/1.1 MiniUPnPd/1.7
LOCATION: http://192.168.1.254:46698/rootDesc.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: 1
BOOTID.UPNP.ORG: 1
CONFIGID.UPNP.ORG: 1337


[6JY3H] 2017/12/30 16:15:10.733398 upnp.go:341: DEBUG: http://192.168.1.254:46698/rootDesc.xml - no services of type urn:schemas-upnp-org:service:WANIPConnection:1  found on connection.
[6JY3H] 2017/12/30 16:15:10.733444 upnp.go:350: DEBUG: http://192.168.1.254:46698/rootDesc.xml - found urn:schemas-upnp-org:service:WANIPConnection:1 with URL http://192.168.1.254:46698/ctl/IPConn
[6JY3H] 2017/12/30 16:15:10.733451 upnp.go:341: DEBUG: http://192.168.1.254:46698/rootDesc.xml - no services of type urn:schemas-upnp-org:service:WANPPPConnection:1  found on connection.
[6JY3H] 2017/12/30 16:15:10.733458 upnp.go:350: DEBUG: http://192.168.1.254:46698/rootDesc.xml - found urn:schemas-upnp-org:service:WANPPPConnection:1 with URL http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 16:15:10.733474 upnp.go:121: DEBUG: UPnP discovery result UPnP_BTHomeHub5.0B-1_A8D3F7BE5F5B/WANDevice/urn:upnp-org:serviceId:WANPPPConn1/urn:schemas-upnp-org:service:WANPPPConnection:1/http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 16:15:10.733483 upnp.go:114: DEBUG: Skipping duplicate result UPnP_BTHomeHub5.0B-1_A8D3F7BE5F5B/WANDevice/urn:upnp-org:serviceId:WANPPPConn1/urn:schemas-upnp-org:service:WANPPPConnection:1/http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 16:15:10.733801 main.go:889: INFO: Automatic upgrade is always enabled for candidate releases.
[6JY3H] 2017/12/30 16:15:10.921436 main.go:1228: INFO: Automatic upgrade: couldn't find a release to download
[6JY3H] 2017/12/30 16:15:20.703724 upnp.go:190: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on enp2s0 finished.
[6JY3H] 2017/12/30 16:15:20.703774 upnp.go:190: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on enp2s0 finished.
[6JY3H] 2017/12/30 16:15:30.774717 upnp.go:417: DEBUG: SOAP Request URL: http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 16:15:30.774738 upnp.go:418: DEBUG: SOAP Action: 
[6JY3H] 2017/12/30 16:15:30.774747 upnp.go:419: DEBUG: SOAP Request:

<?xml version="1.0" ?>
    <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">
    <NewRemoteHost></NewRemoteHost>
    <NewExternalPort>65173</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>22000</NewInternalPort>
    <NewInternalClient>192.168.1.100</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing-65173</NewPortMappingDescription>
    <NewLeaseDuration>3600</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[6JY3H] 2017/12/30 16:15:30.967944 upnp.go:428: DEBUG: SOAP Response: 200 OK

<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:AddPortMappingResponse xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1"/></s:Body></s:Envelope>


[6JY3H] 2017/12/30 16:15:30.967976 upnp.go:417: DEBUG: SOAP Request URL: http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 16:15:30.967982 upnp.go:418: DEBUG: SOAP Action: 
[6JY3H] 2017/12/30 16:15:30.967987 upnp.go:419: DEBUG: SOAP Request:

<?xml version="1.0" ?>
    <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
    <s:Body><u:GetExternalIPAddress xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1" /></s:Body>
    </s:Envelope>

[6JY3H] 2017/12/30 16:15:31.101111 upnp.go:428: DEBUG: SOAP Response: 200 OK

<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetExternalIPAddressResponse xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1"><NewExternalIPAddress>xxx</NewExternalIPAddress></u:GetExternalIPAddressResponse></s:Body></s:Envelope>


[6JY3H] 2017/12/30 16:15:31.101213 structs.go:32: INFO: New NAT port mapping: external TCP address xxx:65173 to local address 0.0.0.0:22000.
[6JY3H] 2017/12/30 16:15:31.101251 service.go:61: INFO: Detected 2 NAT devices
29

This doesn’t look right, it marks one as a duplicate of the other for some reason.

30

Try this one once the build finishes:

and post the same log as above again.

1 Like
31 
[monitor] 2017/12/30 17:27:49.023284 monitor.go:95: INFO: Starting syncthing
[6JY3H] 2017/12/30 17:27:50.419141 main.go:702: INFO: Hashing performance with weak hash is 269.70 MB/s
[6JY3H] 2017/12/30 17:27:50.889069 main.go:704: INFO: Hashing performance without weak hash is 307.39 MB/s
[6JY3H] 2017/12/30 17:27:50.889091 main.go:710: INFO: Weak hash enabled, as it has an acceptable performance impact.
[6JY3H] 2017/12/30 17:27:50.946044 model.go:167: INFO: Starting deadlock detector with 20m0s timeout
[6JY3H] 2017/12/30 17:27:50.948031 limiter.go:84: INFO: Send rate is unlimited, receive rate is unlimited
[6JY3H] 2017/12/30 17:27:50.948043 limiter.go:89: INFO: Rate limits do not apply to LAN connections
[6JY3H] 2017/12/30 17:27:50.952072 upnp.go:144: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on enp2s0
[6JY3H] 2017/12/30 17:27:50.952981 upnp.go:159: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on enp2s0
[6JY3H] 2017/12/30 17:27:50.953032 upnp.go:169: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on enp2s0
[6JY3H] 2017/12/30 17:27:50.953101 upnp.go:144: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on enp2s0
[6JY3H] 2017/12/30 17:27:50.953196 upnp.go:159: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on enp2s0
[6JY3H] 2017/12/30 17:27:50.953251 upnp.go:169: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on enp2s0
[6JY3H] 2017/12/30 17:27:50.961132 upnp.go:195: DEBUG: Handling UPnP response:

HTTP/1.1 200 OK
CACHE-CONTROL: max-age=1800
DATE: Tue, 08 Sep 1992 16:04:48 GMT
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1
USN: uuid:UPnP_BTHomeHub5.0B-1_A8D3F7BE5F5B::urn:schemas-upnp-org:device:InternetGatewayDevice:1
EXT:
SERVER: Linux/2.6.30 UPnP/1.1 MiniUPnPd/1.7
LOCATION: http://192.168.1.254:46698/rootDesc.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: 1
BOOTID.UPNP.ORG: 1
CONFIGID.UPNP.ORG: 1337


[6JY3H] 2017/12/30 17:27:50.979518 upnp.go:342: DEBUG: http://192.168.1.254:46698/rootDesc.xml - no services of type urn:schemas-upnp-org:service:WANIPConnection:1  found on connection.
[6JY3H] 2017/12/30 17:27:50.979549 upnp.go:351: DEBUG: http://192.168.1.254:46698/rootDesc.xml - found urn:schemas-upnp-org:service:WANIPConnection:1 with URL http://192.168.1.254:46698/ctl/IPConn
[6JY3H] 2017/12/30 17:27:50.979577 upnp.go:342: DEBUG: http://192.168.1.254:46698/rootDesc.xml - no services of type urn:schemas-upnp-org:service:WANPPPConnection:1  found on connection.
[6JY3H] 2017/12/30 17:27:50.979586 upnp.go:351: DEBUG: http://192.168.1.254:46698/rootDesc.xml - found urn:schemas-upnp-org:service:WANPPPConnection:1 with URL http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 17:27:50.979599 upnp.go:121: DEBUG: UPnP discovery result UPnP_BTHomeHub5.0B-1_A8D3F7BE5F5B/WANDevice/urn:upnp-org:serviceId:WANIPConn1/urn:schemas-upnp-org:service:WANIPConnection:1/http://192.168.1.254:46698/ctl/IPConn
[6JY3H] 2017/12/30 17:27:50.979608 upnp.go:121: DEBUG: UPnP discovery result UPnP_BTHomeHub5.0B-1_A8D3F7BE5F5B/WANDevice/urn:upnp-org:serviceId:WANPPPConn1/urn:schemas-upnp-org:service:WANPPPConnection:1/http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 17:27:51.587907 folder.go:123: INFO: Completed initial scan of readwrite folder "PortableApps" (dxi5x-jmrde)
[6JY3H] 2017/12/30 17:28:00.952331 upnp.go:191: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on enp2s0 finished.
[6JY3H] 2017/12/30 17:28:00.953337 upnp.go:191: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on enp2s0 finished.
[6JY3H] 2017/12/30 17:28:10.980628 upnp.go:418: DEBUG: SOAP Request URL: http://192.168.1.254:46698/ctl/IPConn
[6JY3H] 2017/12/30 17:28:10.980667 upnp.go:419: DEBUG: SOAP Action: 
[6JY3H] 2017/12/30 17:28:10.980683 upnp.go:420: DEBUG: SOAP Request:

<?xml version="1.0" ?>
    <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:WANIPConnection:1">
    <NewRemoteHost></NewRemoteHost>
    <NewExternalPort>6373</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>22000</NewInternalPort>
    <NewInternalClient>192.168.1.100</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing-6373</NewPortMappingDescription>
    <NewLeaseDuration>3600</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[6JY3H] 2017/12/30 17:28:11.018722 upnp.go:429: DEBUG: SOAP Response: 500 Internal Server Error

<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>718</errorCode><errorDescription>ConflictInMappingEntry</errorDescription></UPnPError></detail></s:Fault></s:Body></s:Envelope>

[6JY3H] 2017/12/30 17:28:11.018886 upnp.go:418: DEBUG: SOAP Request URL: http://192.168.1.254:46698/ctl/IPConn
[6JY3H] 2017/12/30 17:28:11.018906 upnp.go:419: DEBUG: SOAP Action: 
[6JY3H] 2017/12/30 17:28:11.018916 upnp.go:420: DEBUG: SOAP Request:

<?xml version="1.0" ?>
    <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:WANIPConnection:1">
    <NewRemoteHost></NewRemoteHost>
    <NewExternalPort>61749</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>22000</NewInternalPort>
    <NewInternalClient>192.168.1.100</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing-61749</NewPortMappingDescription>
    <NewLeaseDuration>3600</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[6JY3H] 2017/12/30 17:28:11.202215 upnp.go:429: DEBUG: SOAP Response: 200 OK

<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:AddPortMappingResponse xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1"/></s:Body></s:Envelope>


[6JY3H] 2017/12/30 17:28:11.202264 upnp.go:418: DEBUG: SOAP Request URL: http://192.168.1.254:46698/ctl/IPConn
[6JY3H] 2017/12/30 17:28:11.202279 upnp.go:419: DEBUG: SOAP Action: 
[6JY3H] 2017/12/30 17:28:11.202288 upnp.go:420: DEBUG: SOAP Request:

<?xml version="1.0" ?>
    <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
    <s:Body><u:GetExternalIPAddress xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1" /></s:Body>
    </s:Envelope>

[6JY3H] 2017/12/30 17:28:11.336205 upnp.go:429: DEBUG: SOAP Response: 200 OK

<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetExternalIPAddressResponse xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1"><NewExternalIPAddress>xxx</NewExternalIPAddress></u:GetExternalIPAddressResponse></s:Body></s:Envelope>


[6JY3H] 2017/12/30 17:28:11.336350 structs.go:32: INFO: New NAT port mapping: external TCP address xxx:61749 to local address 0.0.0.0:22000.
[6JY3H] 2017/12/30 17:28:11.336423 upnp.go:418: DEBUG: SOAP Request URL: http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 17:28:11.336438 upnp.go:419: DEBUG: SOAP Action: 
[6JY3H] 2017/12/30 17:28:11.336460 upnp.go:420: DEBUG: SOAP Request:

<?xml version="1.0" ?>
    <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">
    <NewRemoteHost></NewRemoteHost>
    <NewExternalPort>65173</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>22000</NewInternalPort>
    <NewInternalClient>192.168.1.100</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing-65173</NewPortMappingDescription>
    <NewLeaseDuration>3600</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[6JY3H] 2017/12/30 17:28:11.376995 upnp.go:429: DEBUG: SOAP Response: 500 Internal Server Error

<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>718</errorCode><errorDescription>ConflictInMappingEntry</errorDescription></UPnPError></detail></s:Fault></s:Body></s:Envelope>

[6JY3H] 2017/12/30 17:28:11.377129 upnp.go:418: DEBUG: SOAP Request URL: http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 17:28:11.377147 upnp.go:419: DEBUG: SOAP Action: 
[6JY3H] 2017/12/30 17:28:11.377164 upnp.go:420: DEBUG: SOAP Request:

<?xml version="1.0" ?>
    <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">
    <NewRemoteHost></NewRemoteHost>
    <NewExternalPort>37989</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>22000</NewInternalPort>
    <NewInternalClient>192.168.1.100</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing-37989</NewPortMappingDescription>
    <NewLeaseDuration>3600</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[6JY3H] 2017/12/30 17:28:11.558318 upnp.go:429: DEBUG: SOAP Response: 200 OK

<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:AddPortMappingResponse xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1"/></s:Body></s:Envelope>


[6JY3H] 2017/12/30 17:28:11.558362 upnp.go:418: DEBUG: SOAP Request URL: http://192.168.1.254:46698/ctl/PPPConn
[6JY3H] 2017/12/30 17:28:11.558375 upnp.go:419: DEBUG: SOAP Action: 
[6JY3H] 2017/12/30 17:28:11.558384 upnp.go:420: DEBUG: SOAP Request:

<?xml version="1.0" ?>
    <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
    <s:Body><u:GetExternalIPAddress xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1" /></s:Body>
    </s:Envelope>

[6JY3H] 2017/12/30 17:28:11.691665 upnp.go:429: DEBUG: SOAP Response: 200 OK

<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetExternalIPAddressResponse xmlns:u="urn:schemas-upnp-org:service:WANPPPConnection:1"><NewExternalIPAddress>xxx</NewExternalIPAddress></u:GetExternalIPAddressResponse></s:Body></s:Envelope>


[6JY3H] 2017/12/30 17:28:11.691793 structs.go:32: INFO: New NAT port mapping: external TCP address xxx:37989 to local address 0.0.0.0:22000.
[6JY3H] 2017/12/30 17:28:11.691856 service.go:61: INFO: Detected 3 NAT services
32

This looks ok.

1 Like
33

Thanks! it looks ok to me as well. The solution is neat and elegant I am quite impressed, I was stuck with a few difficulties on my side.

34

This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.