Folder not syncing

calmh · September 17, 2015, 4:16pm

Yes.

jpkelly · September 17, 2015, 4:18pm

Since I used the package manager Homebrew to install syncthing I am not really sure how syncthing is being started. I can just kill the process ID but is there a better way?

calmh · September 17, 2015, 4:21pm

I’m not really sure what homebrew does. Try launchctl unload ~/Library/LaunchAgents/*syncthing*. If that doesn’t complain, it probably worked.

jpkelly · September 17, 2015, 4:42pm

Also I am seeing the following in the log:

INFO: syncthing v0.11.25 "Aluminium Ant" (go1.5.1 darwin-amd64 default) brew@yosemitevm.local 2015-09-13 09:46:17 UTC
INFO: Database block cache capacity 65536 KiB
FATAL: Cannot open database: resource temporarily unavailable - Is another copy of Syncthing already running?

jpkelly · September 17, 2015, 4:44pm

Yeah there seems to be a bigger problem: STTRACE=upnp syncthing gives:

[RMVXH] 2015/09/17 09:42:49.096312 main.go:772: INFO: Database block cache capacity 65536 KiB
[RMVXH] 2015/09/17 09:42:49.096391 main.go:605: FATAL: Cannot open database: resource temporarily unavailable - Is another copy of Syncthing already running?
[monitor] 2015/09/17 09:42:49.097583 monitor.go:158: INFO: Syncthing exited: exit status 1
[monitor] 2015/09/17 09:42:50.098294 monitor.go:75: WARNING: 4 restarts in 4.076156539s; not retrying further

AudriusButkevicius · September 17, 2015, 4:47pm

Well, if you read the logs, it complains that another copy is running.

jpkelly · September 17, 2015, 4:50pm

I think the other copy is stopped now.

jpkelly · September 17, 2015, 4:51pm

[monitor] 2015/09/17 09:49:15.192100 monitor.go:94: INFO: Starting syncthing
[RMVXH] 2015/09/17 09:49:15.206816 main.go:475: INFO: syncthing v0.11.25 "Aluminium Ant" (go1.5.1 darwin-amd64 default) brew@yosemitevm.local 2015-09-13 09:46:17 UTC
[RMVXH] 2015/09/17 09:49:15.206937 main.go:476: INFO: My ID: RMVXHID-O2GRZGN-VZVOLIW-WIXFUPV-CNOQ3GT-VPA2AZO-GY66AM6-7RXX2AN
[RMVXH] 2015/09/17 09:49:15.215695 main.go:772: INFO: Database block cache capacity 65536 KiB
[RMVXH] 2015/09/17 09:49:17.627482 model.go:194: OK: Ready to synchronize default (read-write)
[RMVXH] 2015/09/17 09:50:02.170114 model.go:194: OK: Ready to synchronize projects (read-write)
[RMVXH] 2015/09/17 09:50:02.170171 main.go:827: INFO: Starting web GUI on http://127.0.0.1:8384/
[RMVXH] 2015/09/17 09:50:02.173104 main.go:904: INFO: Starting local discovery announcements
[RMVXH] 2015/09/17 09:50:02.174056 main.go:909: INFO: Starting global discovery announcements
[RMVXH] 2015/09/17 09:50:02.174509 main.go:689: INFO: Device FJOOVCS-6MSG6E7-DN6ZVBQ-YDXYHWM-VGS4KGP-IVB2FWO-OT7CZWD-SY67CQS is "tokyo.local" at [dynamic]
[RMVXH] 2015/09/17 09:50:02.174571 main.go:689: INFO: Device RMVXHID-O2GRZGN-VZVOLIW-WIXFUPV-CNOQ3GT-VPA2AZO-GY66AM6-7RXX2AN is "wilbur.local" at [dynamic]
[RMVXH] 2015/09/17 09:50:02.175058 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en2
[RMVXH] 2015/09/17 09:50:02.175359 upnp.go:177: DEBUG: listen udp4 0.0.0.0:61224->239.255.255.250: setsockopt: no such network interface
[RMVXH] 2015/09/17 09:50:02.175383 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on lo0
[RMVXH] 2015/09/17 09:50:02.175463 upnp.go:190: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on lo0
[RMVXH] 2015/09/17 09:50:02.175498 upnp.go:200: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on lo0
[RMVXH] 2015/09/17 09:50:02.175552 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on lo0
[RMVXH] 2015/09/17 09:50:02.175620 upnp.go:190: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on lo0
[RMVXH] 2015/09/17 09:50:02.175647 upnp.go:200: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on lo0
[RMVXH] 2015/09/17 09:50:02.175663 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en2
[RMVXH] 2015/09/17 09:50:02.175701 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on bridge0
[RMVXH] 2015/09/17 09:50:02.175730 upnp.go:177: DEBUG: listen udp4 0.0.0.0:51464->239.255.255.250: setsockopt: no such network interface
[RMVXH] 2015/09/17 09:50:02.175748 upnp.go:177: DEBUG: listen udp4 0.0.0.0:51217->239.255.255.250: setsockopt: no such network interface
[RMVXH] 2015/09/17 09:50:02.175759 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en3
[RMVXH] 2015/09/17 09:50:02.175769 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en0
[RMVXH] 2015/09/17 09:50:02.175816 upnp.go:177: DEBUG: listen udp4 0.0.0.0:56475->239.255.255.250: setsockopt: no such network interface
[RMVXH] 2015/09/17 09:50:02.175835 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en0
[RMVXH] 2015/09/17 09:50:02.175863 upnp.go:190: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en3
[RMVXH] 2015/09/17 09:50:02.175877 upnp.go:177: DEBUG: listen udp4 0.0.0.0:54752->239.255.255.250: setsockopt: no such network interface
[RMVXH] 2015/09/17 09:50:02.175900 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en1
[RMVXH] 2015/09/17 09:50:02.175939 upnp.go:177: DEBUG: listen udp4 0.0.0.0:52280->239.255.255.250: setsockopt: no such network interface
[RMVXH] 2015/09/17 09:50:02.175949 upnp.go:200: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en3
[RMVXH] 2015/09/17 09:50:02.175958 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en1
[RMVXH] 2015/09/17 09:50:02.176001 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en3
[RMVXH] 2015/09/17 09:50:02.176017 upnp.go:177: DEBUG: listen udp4 0.0.0.0:52758->239.255.255.250: setsockopt: no such network interface
[RMVXH] 2015/09/17 09:50:02.176026 rwfolder.go:337: INFO: Completed initial scan (rw) of folder default
[RMVXH] 2015/09/17 09:50:02.176092 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on utun0
[RMVXH] 2015/09/17 09:50:02.176110 upnp.go:190: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en3
[RMVXH] 2015/09/17 09:50:02.176146 upnp.go:177: DEBUG: listen udp4 0.0.0.0:50530->239.255.255.250: setsockopt: no such network interface
[RMVXH] 2015/09/17 09:50:02.176152 upnp.go:200: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en3
[RMVXH] 2015/09/17 09:50:02.176163 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on bridge0
[RMVXH] 2015/09/17 09:50:02.176212 upnp.go:177: DEBUG: listen udp4 0.0.0.0:62143->239.255.255.250: setsockopt: no such network interface
[RMVXH] 2015/09/17 09:50:02.176231 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on utun0
[RMVXH] 2015/09/17 09:50:02.176280 upnp.go:177: DEBUG: listen udp4 0.0.0.0:64653->239.255.255.250: setsockopt: no such network interface
[RMVXH] 2015/09/17 09:50:02.189439 gui.go:221: INFO: API listening on 127.0.0.1:8384
[RMVXH] 2015/09/17 09:50:12.175616 upnp.go:221: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on lo0 finished.
[RMVXH] 2015/09/17 09:50:12.175655 upnp.go:221: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on lo0 finished.
[RMVXH] 2015/09/17 09:50:12.175912 upnp.go:221: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en3 finished.
[RMVXH] 2015/09/17 09:50:12.176136 upnp.go:221: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en3 finished.
[RMVXH] 2015/09/17 09:50:12.176168 upnpsvc.go:46: INFO: No UPnP device detected

jpkelly · September 17, 2015, 4:54pm

It could be the network I am on does not allow upnp. Is upnp required for devices to connect outside of my LAN?

AudriusButkevicius · September 17, 2015, 4:54pm

There is no UPnP in this network. Was the previous error you posted (about port 0) in a different location?

jpkelly · September 17, 2015, 4:56pm

Yes it was on my home LAN. I am currently on another network.

jpkelly · September 17, 2015, 4:59pm

Is UPnP required to connect a to device from outside my LAN?

AudriusButkevicius · September 17, 2015, 4:59pm

Well this LAN does not have UPnP, so you’d have to setup manual port forwarding (or UPnP or portforward on the other peer), or get UPnP enabled.

AudriusButkevicius · September 17, 2015, 5:00pm

UPnP exposes a service on to the internet, via the router. For syncthing to connect, either end of the connection has to be exposed to the internet.

jpkelly · September 17, 2015, 5:03pm

The network I am currently on is out of my control but I can set up port forwarding on my home network. It seems that is the way to go. Correct? What port do I need to forward to my home device?

calmh · September 17, 2015, 5:07pm

See the firewall page on the docs site, basically it’s port 22000. But rerun the upnp debug thing when you’re on the network where it’s relevant, please.

jpkelly · September 17, 2015, 5:08pm

Will do. Thank you for your help!

jpkelly · September 18, 2015, 6:44am

after running STTRACE=upnp syncthing

[monitor] 2015/09/17 23:32:04.069217 monitor.go:94: INFO: Starting syncthing
[FJOOV] 2015/09/17 23:32:04.078026 main.go:475: INFO: syncthing v0.11.25 "Aluminium Ant" (go1.5.1 darwin-amd64 default) brew@yosemitevm.local 2015-09-13 09:46:17 UTC
[FJOOV] 2015/09/17 23:32:04.078149 main.go:476: INFO: My ID: FJOOVCS-6MSG6E7-DN6ZVBQ-YDXYHWM-VGS4KGP-IVB2FWO-OT7CZWD-SY67CQS
[FJOOV] 2015/09/17 23:32:04.081558 main.go:772: INFO: Database block cache capacity 65536 KiB
[FJOOV] 2015/09/17 23:32:06.681393 model.go:194: OK: Ready to synchronize default (read-write)
[FJOOV] 2015/09/17 23:33:00.747674 model.go:194: OK: Ready to synchronize projects (read-write)
[FJOOV] 2015/09/17 23:33:00.747742 main.go:827: INFO: Starting web GUI on http://127.0.0.1:8384/
[FJOOV] 2015/09/17 23:33:00.751501 main.go:904: INFO: Starting local discovery announcements
[FJOOV] 2015/09/17 23:33:00.752900 main.go:909: INFO: Starting global discovery announcements
[FJOOV] 2015/09/17 23:33:00.756332 main.go:689: INFO: Device FJOOVCS-6MSG6E7-DN6ZVBQ-YDXYHWM-VGS4KGP-IVB2FWO-OT7CZWD-SY67CQS is "tokyo.local" at [dynamic]
[FJOOV] 2015/09/17 23:33:00.756734 main.go:689: INFO: Device RMVXHID-O2GRZGN-VZVOLIW-WIXFUPV-CNOQ3GT-VPA2AZO-GY66AM6-7RXX2AN is "wilbur.local" at [dynamic]
[FJOOV] 2015/09/17 23:33:00.756813 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en6
[FJOOV] 2015/09/17 23:33:00.756840 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en2
[FJOOV] 2015/09/17 23:33:00.756864 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en2
[FJOOV] 2015/09/17 23:33:00.756911 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on lo0
[FJOOV] 2015/09/17 23:33:00.757048 upnp.go:177: DEBUG: listen udp4 0.0.0.0:61007->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757068 upnp.go:177: DEBUG: listen udp4 0.0.0.0:58099->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757078 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on lo0
[FJOOV] 2015/09/17 23:33:00.757092 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en10
[FJOOV] 2015/09/17 23:33:00.757117 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on fw0
[FJOOV] 2015/09/17 23:33:00.757168 upnp.go:177: DEBUG: listen udp4 0.0.0.0:64380->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757184 upnp.go:177: DEBUG: listen udp4 0.0.0.0:56346->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757197 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en1
[FJOOV] 2015/09/17 23:33:00.757203 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en7
[FJOOV] 2015/09/17 23:33:00.757222 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on bridge0
[FJOOV] 2015/09/17 23:33:00.757242 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on fw0
[FJOOV] 2015/09/17 23:33:00.757278 upnp.go:177: DEBUG: listen udp4 0.0.0.0:62351->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757290 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en5
[FJOOV] 2015/09/17 23:33:00.757325 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on bridge0
[FJOOV] 2015/09/17 23:33:00.757340 upnp.go:190: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en1
[FJOOV] 2015/09/17 23:33:00.757350 upnp.go:190: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on lo0
[FJOOV] 2015/09/17 23:33:00.757363 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en7
[FJOOV] 2015/09/17 23:33:00.757374 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en0
[FJOOV] 2015/09/17 23:33:00.757388 upnp.go:177: DEBUG: listen udp4 0.0.0.0:53852->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757398 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en3
[FJOOV] 2015/09/17 23:33:00.757421 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en4
[FJOOV] 2015/09/17 23:33:00.757439 upnp.go:177: DEBUG: listen udp4 0.0.0.0:61189->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757453 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on utun0
[FJOOV] 2015/09/17 23:33:00.757477 upnp.go:177: DEBUG: listen udp4 0.0.0.0:53054->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757490 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en10
[FJOOV] 2015/09/17 23:33:00.757530 upnp.go:177: DEBUG: listen udp4 0.0.0.0:61377->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757542 upnp.go:177: DEBUG: listen udp4 0.0.0.0:62709->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757548 upnp.go:177: DEBUG: listen udp4 0.0.0.0:61578->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757553 upnp.go:190: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on lo0
[FJOOV] 2015/09/17 23:33:00.757583 upnp.go:200: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on lo0
[FJOOV] 2015/09/17 23:33:00.757627 upnp.go:177: DEBUG: listen udp4 0.0.0.0:62436->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757679 upnp.go:200: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on lo0
[FJOOV] 2015/09/17 23:33:00.757719 upnp.go:177: DEBUG: listen udp4 0.0.0.0:57036->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757730 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en1
[FJOOV] 2015/09/17 23:33:00.757783 rwfolder.go:337: INFO: Completed initial scan (rw) of folder default
[FJOOV] 2015/09/17 23:33:00.757803 upnp.go:190: DEBUG: Sending search request for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en1
[FJOOV] 2015/09/17 23:33:00.757835 upnp.go:200: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en1
[FJOOV] 2015/09/17 23:33:00.757890 upnp.go:200: DEBUG: Listening for UPnP response for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en1
[FJOOV] 2015/09/17 23:33:00.757922 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en3
[FJOOV] 2015/09/17 23:33:00.757975 upnp.go:177: DEBUG: listen udp4 0.0.0.0:55521->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.757984 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en0
[FJOOV] 2015/09/17 23:33:00.758030 upnp.go:177: DEBUG: listen udp4 0.0.0.0:60393->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.758039 upnp.go:177: DEBUG: listen udp4 0.0.0.0:54872->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.758044 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en8
[FJOOV] 2015/09/17 23:33:00.758092 upnp.go:177: DEBUG: listen udp4 0.0.0.0:63872->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.758099 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en5
[FJOOV] 2015/09/17 23:33:00.758154 upnp.go:177: DEBUG: listen udp4 0.0.0.0:56739->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.758168 upnp.go:177: DEBUG: listen udp4 0.0.0.0:54870->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.758176 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en4
[FJOOV] 2015/09/17 23:33:00.758259 upnp.go:177: DEBUG: listen udp4 0.0.0.0:56432->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.758268 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en6
[FJOOV] 2015/09/17 23:33:00.758312 upnp.go:177: DEBUG: listen udp4 0.0.0.0:64174->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.758361 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on utun0
[FJOOV] 2015/09/17 23:33:00.758424 upnp.go:177: DEBUG: listen udp4 0.0.0.0:59726->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.758435 upnp.go:177: DEBUG: listen udp4 0.0.0.0:53589->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.758440 upnp.go:171: DEBUG: Starting discovery of device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en8
[FJOOV] 2015/09/17 23:33:00.758512 upnp.go:177: DEBUG: listen udp4 0.0.0.0:51408->239.255.255.250: setsockopt: no such network interface
[FJOOV] 2015/09/17 23:33:00.759002 upnp.go:227: DEBUG: Handling UPnP response:

HTTP/1.1 200 OK
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1
LOCATION: http://10.0.0.1:5000/Public_UPNP_gatedesc.xml
SERVER: Linux/2.6.12, UPnP/1.0, NETGEAR-UPNP/1.0
EXT:
CACHE-CONTROL: max-age=3600
USN: uuid:28a004f8-c8bc-9f4a-3d35-2d630e094b1a::urn:schemas-upnp-org:device:InternetGatewayDevice:1


[FJOOV] 2015/09/17 23:33:00.766343 gui.go:221: INFO: API listening on 127.0.0.1:8384
[FJOOV] 2015/09/17 23:33:01.063400 upnp.go:398: DEBUG: [http://10.0.0.1:5000/Public_UPNP_gatedesc.xml] Found urn:schemas-upnp-org:service:WANIPConnection:1 with URL http://10.0.0.1:5000/Public_UPNP_C3
[FJOOV] 2015/09/17 23:33:01.063419 upnp.go:398: DEBUG: [http://10.0.0.1:5000/Public_UPNP_gatedesc.xml] Found urn:schemas-upnp-org:service:WANPPPConnection:1 with URL http://10.0.0.1:5000/Public_UPNP_C5
[FJOOV] 2015/09/17 23:33:01.063930 upnp.go:144: DEBUG: UPnP discovery result 28a004f8-c8bc-9f4a-3d35-2d630e094b1a with services:
[FJOOV] 2015/09/17 23:33:01.063939 upnp.go:146: DEBUG: * [urn:upnp-org:serviceId:WANIPConn1] http://10.0.0.1:5000/Public_UPNP_C3
[FJOOV] 2015/09/17 23:33:01.063943 upnp.go:146: DEBUG: * [urn:upnp-org:serviceId:WANPPPConn1] http://10.0.0.1:5000/Public_UPNP_C5
[FJOOV] 2015/09/17 23:33:01.961721 connections.go:176: INFO: Established secure connection to RMVXHID-O2GRZGN-VZVOLIW-WIXFUPV-CNOQ3GT-VPA2AZO-GY66AM6-7RXX2AN at 10.0.0.121:50993-10.0.0.111:22000
[FJOOV] 2015/09/17 23:33:10.761575 upnp.go:221: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on lo0 finished.
[FJOOV] 2015/09/17 23:33:10.761630 upnp.go:221: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on lo0 finished.
[FJOOV] 2015/09/17 23:33:10.761673 upnp.go:221: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:1 on en1 finished.
[FJOOV] 2015/09/17 23:33:10.761695 upnp.go:221: DEBUG: Discovery for device type urn:schemas-upnp-org:device:InternetGatewayDevice:2 on en1 finished.
[FJOOV] 2015/09/17 23:33:10.761783 upnp.go:460: DEBUG: SOAP Request URL: http://10.0.0.1:5000/Public_UPNP_C3
[FJOOV] 2015/09/17 23:33:10.761796 upnp.go:461: DEBUG: SOAP Action: 
[FJOOV] 2015/09/17 23:33:10.761803 upnp.go:462: 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>34880</NewExternalPort>
	<NewProtocol>TCP</NewProtocol>
	<NewInternalPort>22000</NewInternalPort>
	<NewInternalClient>10.0.0.121</NewInternalClient>
	<NewEnabled>1</NewEnabled>
	<NewPortMappingDescription>syncthing-34880</NewPortMappingDescription>
	<NewLeaseDuration>3600</NewLeaseDuration>
	</u:AddPortMapping></s:Body>
	</s:Envelope>

[FJOOV] 2015/09/17 23:33:10.764336 upnp.go:475: DEBUG: SOAP Response: 500

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


[FJOOV] 2015/09/17 23:33:10.764435 upnp.go:460: DEBUG: SOAP Request URL: http://10.0.0.1:5000/Public_UPNP_C3
[FJOOV] 2015/09/17 23:33:10.764444 upnp.go:461: DEBUG: SOAP Action: 
[FJOOV] 2015/09/17 23:33:10.764449 upnp.go:462: 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>34880</NewExternalPort>
	<NewProtocol>TCP</NewProtocol>
	<NewInternalPort>22000</NewInternalPort>
	<NewInternalClient>10.0.0.121</NewInternalClient>
	<NewEnabled>1</NewEnabled>
	<NewPortMappingDescription>syncthing-34880</NewPortMappingDescription>
	<NewLeaseDuration>0</NewLeaseDuration>
	</u:AddPortMapping></s:Body>
	</s:Envelope>

[FJOOV] 2015/09/17 23:33:10.767396 upnp.go:475: DEBUG: SOAP Response: 500

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


[FJOOV] 2015/09/17 23:33:10.767422 upnp.go:460: DEBUG: SOAP Request URL: http://10.0.0.1:5000/Public_UPNP_C3
[FJOOV] 2015/09/17 23:33:10.767428 upnp.go:461: DEBUG: SOAP Action: 
[FJOOV] 2015/09/17 23:33:10.767432 upnp.go:462: 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>15382</NewExternalPort>
	<NewProtocol>TCP</NewProtocol>
	<NewInternalPort>22000</NewInternalPort>
	<NewInternalClient>10.0.0.121</NewInternalClient>
	<NewEnabled>1</NewEnabled>
	<NewPortMappingDescription>syncthing-15382</NewPortMappingDescription>
	<NewLeaseDuration>3600</NewLeaseDuration>
	</u:AddPortMapping></s:Body>
	</s:Envelope>

[FJOOV] 2015/09/17 23:33:10.770294 upnp.go:475: DEBUG: SOAP Response: 500

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


[FJOOV] 2015/09/17 23:33:10.770369 upnp.go:460: DEBUG: SOAP Request URL: http://10.0.0.1:5000/Public_UPNP_C3
[FJOOV] 2015/09/17 23:33:10.770376 upnp.go:461: DEBUG: SOAP Action: 
[FJOOV] 2015/09/17 23:33:10.770380 upnp.go:462: 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>15382</NewExternalPort>
	<NewProtocol>TCP</NewProtocol>
	<NewInternalPort>22000</NewInternalPort>
	<NewInternalClient>10.0.0.121</NewInternalClient>
	<NewEnabled>1</NewEnabled>
	<NewPortMappingDescription>syncthing-15382</NewPortMappingDescription>
	<NewLeaseDuration>0</NewLeaseDuration>
	</u:AddPortMapping></s:Body>
	</s:Envelope>

[FJOOV] 2015/09/17 23:33:10.773235 upnp.go:475: DEBUG: SOAP Response: 500

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


[FJOOV] 2015/09/17 23:33:10.773259 upnp.go:460: DEBUG: SOAP Request URL: http://10.0.0.1:5000/Public_UPNP_C3
[FJOOV] 2015/09/17 23:33:10.773264 upnp.go:461: DEBUG: SOAP Action: 
[FJOOV] 2015/09/17 23:33:10.773268 upnp.go:462: 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>4368</NewExternalPort>
	<NewProtocol>TCP</NewProtocol>
	<NewInternalPort>22000</NewInternalPort>
	<NewInternalClient>10.0.0.121</NewInternalClient>
	<NewEnabled>1</NewEnabled>
	<NewPortMappingDescription>syncthing-4368</NewPortMappingDescription>
	<NewLeaseDuration>3600</NewLeaseDuration>
	</u:AddPortMapping></s:Body>
	</s:Envelope>

[FJOOV] 2015/09/17 23:33:10.776397 upnp.go:475: DEBUG: SOAP Response: 500

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

calmh · September 18, 2015, 6:45am

Ah. The router doesn’t support what we’re trying to set up. We should probably handle that some way.

calmh · September 18, 2015, 6:59am

As a workaround, if I remember correctly, try setting the upnp lease time to zero in the advanced config options.