Repeater in Network causes Error-Message

merlinuwe · October 19, 2014, 4:18pm

Hi,

thank you for the new release 0.10.2 which updated on FreeBSD, Windows 8.1, Windows 7 (32 and 64 bit) and over the Android App without manual intervention!

I have an issue since the start of working with Pulse/Syncthing, which is not blocking the work, but annoying.

In my home network there is a Fritzbox 7490 router and a Fritzbox 7113 router configured as a repeater. When I start Pulse/Syncthing (and only if the repeater is connected) I get the following message;

17:45:54: Error renewing UPnP port mapping for external port 22000 on device 'FRITZ!Box 7490' (192.168.178.1): AddPortMapping: 500 Internal Server Error

It would be glad, if I could configure the Fritzbox 7490 as the only router, who is responsible for UUnP. Or do you have a better suggestion for me?

Greetings,

merlinuwe

calmh · October 19, 2014, 4:28pm

So, the new behavior here is that we try to work with all upnp devices found, rather than just the first / a random one. It sounds like the repeater here shouldn’t be offering any functionality over upnp here - can you disable upnp on it?

merlinuwe · October 19, 2014, 5:22pm

Yes, you’re right. This device has no UPnP function implemented, so there is nothing to disable. An excluding list or so would be helpful in such cases.

calmh · October 19, 2014, 5:49pm

Hmm. It seems to be responding to a query for ip gateway devices though. Can you post an issue, with the output of running syncthing with STTRACE=upnp?

merlinuwe · October 20, 2014, 8:49am

I used this command on the Shell:

/mnt/NAS1GB/syncthing/syncthing -home="/mnt/NAS1GB/syncthing/.config/syncthing" STTRACE=upnp > FreeBSD.log

I’ve got this result on FreeBSD:

[monitor] 10:36:56 INFO: Starting syncthing
[7YKEI] 10:36:57 INFO: syncthing v0.10.2 (go1.3.3 freebsd-amd64 default) jenkins@build.syncthing.net 2014-10-18 18:50:40 UTC
[7YKEI] 10:36:57 INFO: My ID: XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX
[7YKEI] 10:36:57 INFO: Starting web GUI on http://192.168.178.23:8080/
[7YKEI] 10:36:58 INFO: Starting UPnP discovery...
[7YKEI] 10:37:04 INFO: UPnP discovery complete (found 2 devices).
[7YKEI] 10:37:04 WARNING: Failed to create UPnP port mapping
[7YKEI] 10:37:04 INFO: Starting local discovery announcements
[7YKEI] 10:37:04 INFO: Local discovery over IPv6 unavailable
[7YKEI] 10:37:04 INFO: Starting global discovery announcements
[7YKEI] 10:37:04 OK: Ready to synchronize camera (read-write)
[7YKEI] 10:37:04 OK: Ready to synchronize default (read-write)
[7YKEI] 10:37:04 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "freenas.local" at [dynamic]
[7YKEI] 10:37:04 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "ACER" at [dynamic]
[7YKEI] 10:37:04 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "SERVICE-PC" at [dynamic]
[7YKEI] 10:37:04 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "TAB3" at [dynamic]
[7YKEI] 10:37:04 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "UWE-PC" at [dynamic]
[7YKEI] 10:37:04 INFO: Starting usage reporting
[7YKEI] 10:37:04 INFO: Completed initial scan (rw) of folder camera
[7YKEI] 10:37:04 INFO: Completed initial scan (rw) of folder default
[7YKEI] 10:37:21 INFO: Established secure connection to XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX at 192.168.178.23:36506-91.62.240.225:22000
[7YKEI] 10:37:21 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX client is "syncthing v0.10.2"
[7YKEI] 10:37:21 INFO: DeviceXXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX name is "SERVICE-PC"
[7YKEI] 10:42:47 INFO: Restarting
[7YKEI] 10:42:47 OK: Exiting
[monitor] 10:42:47 INFO: Syncthing exited: exit status 3
[monitor] 10:42:48 INFO: Starting syncthing
[7YKEI] 10:42:49 INFO: syncthing v0.10.2 (go1.3.3 freebsd-amd64 default) jenkins@build.syncthing.net 2014-10-18 18:50:40 UTC
[7YKEI] 10:42:49 INFO: My ID: XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX
[7YKEI] 10:42:49 INFO: Starting web GUI on http://192.168.178.23:8080/
[7YKEI] 10:42:50 INFO: Starting UPnP discovery...
[7YKEI] 10:42:56 INFO: UPnP discovery complete (found 2 devices).
[7YKEI] 10:42:56 WARNING: Failed to create UPnP port mapping
[7YKEI] 10:42:56 INFO: Starting local discovery announcements
[7YKEI] 10:42:56 INFO: Local discovery over IPv6 unavailable
[7YKEI] 10:42:56 INFO: Starting global discovery announcements
[7YKEI] 10:42:56 OK: Ready to synchronize camera (read-write)
[7YKEI] 10:42:56 OK: Ready to synchronize default (read-write)
[7YKEI] 10:42:56 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "freenas.local" at [dynamic]
[7YKEI] 10:42:56 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "ACER" at [dynamic]
[7YKEI] 10:42:56 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "SERVICE-PC" at [dynamic]
[7YKEI] 10:42:56 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "TAB3" at [dynamic]
[7YKEI] 10:42:56 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "UWE-PC" at [dynamic]
[7YKEI] 10:42:56 INFO: Starting usage reporting
[7YKEI] 10:42:56 INFO: Completed initial scan (rw) of folder camera
[7YKEI] 10:42:56 INFO: Completed initial scan (rw) of folder default
[7YKEI] 10:43:18 INFO: Established secure connection to XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX at 192.168.178.23:16526-91.62.240.225:22000
[7YKEI] 10:43:18 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX client is "syncthing v0.10.2"
[7YKEI] 10:43:18 INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX name is "SERVICE-PC"

After a restart I get the following error-message in the UI:

"10:42:56: Failed to create UPnP port mapping"

Do you need more or other logs?

Greetings,

Uwe

Alex · October 20, 2014, 8:59am

that log does not look like the STTRACE=upnp is active, at least in linux you have to place this in front of your command because it’s a environment variable and not a command line parameter. Try this: STTRACE=upnp /mnt/NAS1GB/syncthing/syncthing -home="/mnt/NAS1GB/syncthing/.config/syncthing" > FreeBSD.log

merlinuwe · October 20, 2014, 9:14am

Hi Alex,

thanks for your advice. Here is a new logfile:

[monitor] 2014/10/20 11:10:33.309157 monitor.go:75: INFO: Starting syncthing
[7YKEI] 2014/10/20 11:10:34.050972 main.go:352: INFO: syncthing v0.10.2 (go1.3.3 freebsd-amd64 default) jenkins@build.syncthing.net 2014-10-18 18:50:40 UTC
[7YKEI] 2014/10/20 11:10:34.051257 main.go:353: INFO: My ID: XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX
[7YKEI] 2014/10/20 11:10:34.117006 main.go:580: INFO: Starting web GUI on http://192.168.178.23:8080/
[7YKEI] 2014/10/20 11:10:34.748779 upnp.go:80: INFO: Starting UPnP discovery...
[7YKEI] 2014/10/20 11:10:34.748852 upnp.go:129: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2...
[7YKEI] 2014/10/20 11:10:34.748963 upnp.go:149: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2...
[7YKEI] 2014/10/20 11:10:34.749038 upnp.go:161: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2...
[7YKEI] 2014/10/20 11:10:37.750184 upnp.go:191: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 finished.
[7YKEI] 2014/10/20 11:10:37.750271 upnp.go:129: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1...
[7YKEI] 2014/10/20 11:10:37.750367 upnp.go:149: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1...
[7YKEI] 2014/10/20 11:10:37.750409 upnp.go:161: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1...
[7YKEI] 2014/10/20 11:10:37.753589 upnp.go:201: DEBUG: Handling UPnP response:

HTTP/1.1 200 OK
LOCATION: http://192.168.178.2:49000/igddesc.xml
SERVER: FRITZ!Box Fon WLAN 7113 UPnP/1.0 AVM FRITZ!Box Fon WLAN 7113 (fs) 60.04.68
CACHE-CONTROL: max-age=1800
EXT:
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1
USN: uuid:75802409-bccb-40e7-8e6c-001F3F18ED3F::urn:schemas-upnp-org:device:InternetGatewayDevice:1


[7YKEI] 2014/10/20 11:10:37.756272 upnp.go:201: DEBUG: Handling UPnP response:

HTTP/1.1 200 OK
LOCATION: http://192.168.178.1:49000/igddesc.xml
SERVER: FRITZ!Box 7490 UPnP/1.0 AVM FRITZ!Box 7490 113.06.20
CACHE-CONTROL: max-age=1800
EXT:
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1
USN: uuid:75802409-bccb-40e7-8e6c-0896D78AC2F1::urn:schemas-upnp-org:device:InternetGatewayDevice:1


[7YKEI] 2014/10/20 11:10:37.769344 upnp.go:399: DEBUG: [http://192.168.178.2:49000/igddesc.xml] Found urn:schemas-upnp-org:service:WANIPConnection:1 with URL http://192.168.178.2:49000/upnp/control/WANIPConn1
[7YKEI] 2014/10/20 11:10:37.769448 upnp.go:388: DEBUG: [http://192.168.178.2:49000/igddesc.xml] No services of type urn:schemas-upnp-org:service:WANPPPConnection:1 found on connection.
[7YKEI] 2014/10/20 11:10:37.769977 upnp.go:299: DEBUG: Finished handling of UPnP response.
[7YKEI] 2014/10/20 11:10:37.772377 upnp.go:399: DEBUG: [http://192.168.178.1:49000/igddesc.xml] Found urn:schemas-upnp-org:service:WANIPConnection:1 with URL http://192.168.178.1:49000/igdupnp/control/WANIPConn1
[7YKEI] 2014/10/20 11:10:37.772399 upnp.go:388: DEBUG: [http://192.168.178.1:49000/igddesc.xml] No services of type urn:schemas-upnp-org:service:WANPPPConnection:1 found on connection.
[7YKEI] 2014/10/20 11:10:37.775544 upnp.go:299: DEBUG: Finished handling of UPnP response.
[7YKEI] 2014/10/20 11:10:40.752222 upnp.go:191: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 finished.
[7YKEI] 2014/10/20 11:10:40.752306 upnp.go:92: DEBUG: UPnP discovery result:
[7YKEI] 2014/10/20 11:10:40.752317 upnp.go:94: DEBUG: [75802409-bccb-40e7-8e6c-001F3F18ED3F]
[7YKEI] 2014/10/20 11:10:40.752325 upnp.go:97: DEBUG: * http://192.168.178.2:49000/upnp/control/WANIPConn1
[7YKEI] 2014/10/20 11:10:40.752333 upnp.go:94: DEBUG: [75802409-bccb-40e7-8e6c-0896D78AC2F1]
[7YKEI] 2014/10/20 11:10:40.752342 upnp.go:97: DEBUG: * http://192.168.178.1:49000/igdupnp/control/WANIPConn1
[7YKEI] 2014/10/20 11:10:40.752360 upnp.go:107: INFO: UPnP discovery complete (found 2 devices).
[7YKEI] 2014/10/20 11:10:40.752479 upnp.go:452: DEBUG: "urn:schemas-upnp-org:service:WANIPConnection:1#AddPortMapping"
[7YKEI] 2014/10/20 11:10:40.752494 upnp.go:453: 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>28029</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>54097</NewInternalPort>
    <NewInternalClient>192.168.178.23</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing</NewPortMappingDescription>
    <NewLeaseDuration>0</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[7YKEI] 2014/10/20 11:10:40.768045 upnp.go:463: DEBUG: SOAP Response:

<?xml version="1.0"?>
<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>403</errorCode>
<errorDescription>Not available Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>


[7YKEI] 2014/10/20 11:10:40.768158 upnp.go:452: DEBUG: "urn:schemas-upnp-org:service:WANIPConnection:1#AddPortMapping"
[7YKEI] 2014/10/20 11:10:40.768169 upnp.go:453: 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>64416</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>54097</NewInternalPort>
    <NewInternalClient>192.168.178.23</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing</NewPortMappingDescription>
    <NewLeaseDuration>0</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[7YKEI] 2014/10/20 11:10:40.785596 upnp.go:463: DEBUG: SOAP Response:

<?xml version="1.0"?>
<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>403</errorCode>
<errorDescription>Not available Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>


[7YKEI] 2014/10/20 11:10:40.785647 upnp.go:452: DEBUG: "urn:schemas-upnp-org:service:WANIPConnection:1#AddPortMapping"
[7YKEI] 2014/10/20 11:10:40.785673 upnp.go:453: 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>19183</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>54097</NewInternalPort>
    <NewInternalClient>192.168.178.23</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing</NewPortMappingDescription>
    <NewLeaseDuration>0</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[7YKEI] 2014/10/20 11:10:40.803236 upnp.go:463: DEBUG: SOAP Response:

<?xml version="1.0"?>
<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>403</errorCode>
<errorDescription>Not available Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>


[7YKEI] 2014/10/20 11:10:40.803276 upnp.go:452: DEBUG: "urn:schemas-upnp-org:service:WANIPConnection:1#AddPortMapping"
[7YKEI] 2014/10/20 11:10:40.803286 upnp.go:453: 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>61812</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>54097</NewInternalPort>
    <NewInternalClient>192.168.178.23</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing</NewPortMappingDescription>
    <NewLeaseDuration>0</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[7YKEI] 2014/10/20 11:10:40.820450 upnp.go:463: DEBUG: SOAP Response:

<?xml version="1.0"?>
<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>403</errorCode>
<errorDescription>Not available Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>


[7YKEI] 2014/10/20 11:10:40.820529 upnp.go:452: DEBUG: "urn:schemas-upnp-org:service:WANIPConnection:1#AddPortMapping"
[7YKEI] 2014/10/20 11:10:40.820547 upnp.go:453: 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>6899</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>54097</NewInternalPort>
    <NewInternalClient>192.168.178.23</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing</NewPortMappingDescription>
    <NewLeaseDuration>0</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[7YKEI] 2014/10/20 11:10:40.838205 upnp.go:463: DEBUG: SOAP Response:

<?xml version="1.0"?>
<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>403</errorCode>
<errorDescription>Not available Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>


[7YKEI] 2014/10/20 11:10:40.838246 upnp.go:452: DEBUG: "urn:schemas-upnp-org:service:WANIPConnection:1#AddPortMapping"
[7YKEI] 2014/10/20 11:10:40.838264 upnp.go:453: 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>54372</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>54097</NewInternalPort>
    <NewInternalClient>192.168.178.23</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing</NewPortMappingDescription>
    <NewLeaseDuration>0</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[7YKEI] 2014/10/20 11:10:40.856349 upnp.go:463: DEBUG: SOAP Response:

<?xml version="1.0"?>
<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>403</errorCode>
<errorDescription>Not available Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>


[7YKEI] 2014/10/20 11:10:40.856424 upnp.go:452: DEBUG: "urn:schemas-upnp-org:service:WANIPConnection:1#AddPortMapping"
[7YKEI] 2014/10/20 11:10:40.856479 upnp.go:453: 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>11374</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>54097</NewInternalPort>
    <NewInternalClient>192.168.178.23</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing</NewPortMappingDescription>
    <NewLeaseDuration>0</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[7YKEI] 2014/10/20 11:10:40.874594 upnp.go:463: DEBUG: SOAP Response:

<?xml version="1.0"?>
<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>403</errorCode>
<errorDescription>Not available Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>


[7YKEI] 2014/10/20 11:10:40.874689 upnp.go:452: DEBUG: "urn:schemas-upnp-org:service:WANIPConnection:1#AddPortMapping"
[7YKEI] 2014/10/20 11:10:40.874716 upnp.go:453: 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>40244</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>54097</NewInternalPort>
    <NewInternalClient>192.168.178.23</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing</NewPortMappingDescription>
    <NewLeaseDuration>0</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[7YKEI] 2014/10/20 11:10:40.891915 upnp.go:463: DEBUG: SOAP Response:

<?xml version="1.0"?>
<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>403</errorCode>
<errorDescription>Not available Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>


[7YKEI] 2014/10/20 11:10:40.891986 upnp.go:452: DEBUG: "urn:schemas-upnp-org:service:WANIPConnection:1#AddPortMapping"
[7YKEI] 2014/10/20 11:10:40.891997 upnp.go:453: 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>4530</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>54097</NewInternalPort>
    <NewInternalClient>192.168.178.23</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing</NewPortMappingDescription>
    <NewLeaseDuration>0</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[7YKEI] 2014/10/20 11:10:40.909347 upnp.go:463: DEBUG: SOAP Response:

<?xml version="1.0"?>
<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>403</errorCode>
<errorDescription>Not available Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>


[7YKEI] 2014/10/20 11:10:40.909397 upnp.go:452: DEBUG: "urn:schemas-upnp-org:service:WANIPConnection:1#AddPortMapping"
[7YKEI] 2014/10/20 11:10:40.909415 upnp.go:453: 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>41699</NewExternalPort>
    <NewProtocol>TCP</NewProtocol>
    <NewInternalPort>54097</NewInternalPort>
    <NewInternalClient>192.168.178.23</NewInternalClient>
    <NewEnabled>1</NewEnabled>
    <NewPortMappingDescription>syncthing</NewPortMappingDescription>
    <NewLeaseDuration>0</NewLeaseDuration>
    </u:AddPortMapping></s:Body>
    </s:Envelope>

[7YKEI] 2014/10/20 11:10:40.927255 upnp.go:463: DEBUG: SOAP Response:

<?xml version="1.0"?>
<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>403</errorCode>
<errorDescription>Not available Action</errorDescription>
</UPnPError>
</detail>
</s:Fault>
</s:Body>
</s:Envelope>


[7YKEI] 2014/10/20 11:10:40.927321 main.go:702: WARNING: Failed to create UPnP port mapping
[7YKEI] 2014/10/20 11:10:40.927381 main.go:1039: INFO: Starting local discovery announcements
[7YKEI] 2014/10/20 11:10:40.927567 discover.go:95: INFO: Local discovery over IPv6 unavailable
[7YKEI] 2014/10/20 11:10:40.927614 main.go:1044: INFO: Starting global discovery announcements
[7YKEI] 2014/10/20 11:10:40.927871 main.go:499: OK: Ready to synchronize camera (read-write)
[7YKEI] 2014/10/20 11:10:40.927919 main.go:499: OK: Ready to synchronize default (read-write)
[7YKEI] 2014/10/20 11:10:40.928200 main.go:515: INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "ACER" at [dynamic]
[7YKEI] 2014/10/20 11:10:40.928328 main.go:515: INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "SERVICE-PC" at [dynamic]
[7YKEI] 2014/10/20 11:10:40.928465 main.go:515: INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "TAB3" at [dynamic]
[7YKEI] 2014/10/20 11:10:40.928590 main.go:515: INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "UWE-PC" at [dynamic]
[7YKEI] 2014/10/20 11:10:40.928714 main.go:515: INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX is "freenas.local" at [dynamic]
[7YKEI] 2014/10/20 11:10:40.938291 usage_report.go:108: INFO: Starting usage reporting
[7YKEI] 2014/10/20 11:10:40.947904 puller.go:211: INFO: Completed initial scan (rw) of folder camera
[7YKEI] 2014/10/20 11:10:40.982364 puller.go:211: INFO: Completed initial scan (rw) of folder default
[7YKEI] 2014/10/20 11:11:02.574260 main.go:874: INFO: Established secure connection to XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX at 192.168.178.23:25889-91.62.240.225:22000
[7YKEI] 2014/10/20 11:11:02.607781 model.go:528: INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX client is "syncthing v0.10.2"
[7YKEI] 2014/10/20 11:11:02.608238 model.go:531: INFO: Device XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX-XXXXXXX name is "SERVICE-PC"

Greetings,

Uwe

calmh · October 20, 2014, 9:44am

So as far as I can see the repeater announces itself with the capabilities of a router, then suddenly fails when we try to use those capabilities. It looks to me like syncthing does the right thing. Can you not simply turn off UPnP on the repeater?

merlinuwe · October 21, 2014, 8:54am

The only entry I found in the router-configuration of the Fritzbox 7113 (configured as a repeater) is this:

[checkbox] Statusinformationen über UPnP übertragen (empfohlen)

Über Universal Plug & Play (UPnP) werden für angeschlossene Computer Statusinformationen der FRITZ!Box bereitgestellt. Wenn FRITZ!DSL Statusinformationen anzeigen soll, muss diese Funktion aktiviert sein. Die Funktion hat keinen Einfluss auf Sicherheitseinstellungen der FRITZ!Box.

This text is (for me) a little bit mistakable expressed. I deactivated the checkbox to test if the error-messages appear furthermore.

I made a few reboots of syncthing/Pulse, the error messages didn’t appear again!

Thank you very much for your helpful advices, which helped to solve the problem!

Greetings,

Uwe

system · May 12, 2017, 12:57pm