Syncthing syncing on local network but not on other guest networks


(Peter van Nes) #1

I have a setup with Syncthing installed on ;

  • Synology DiskStation
  • 2 MacOS machines
  • 1 Windows Tablet running Windows 10

When all on the same (local) network synchronization works fine. If one of the two MacOS machines are connection to another (guest) network they still are able to synchronize. When Windows tablet is connected to the guest network the remote devices are disconnected and synchronization does not take place.

Although most company and guest networks will not allow inbound connections i enabled tcp:22000 for incoming connections.

When connected to the guest network and STTRACE set to discover and connections is see that it is able to join a relay Joined relay relay://71.120.2.222:22067.

Only noticable error is [F4E57] 2017/05/06 17:19:59.398953 global.go:142: DEBUG: globalClient.Lookup https://discovery-v4-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v4-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers) [

When entering the address in a browser (on a Mac) i also do get timeouts, so assume that this is not an issue related to the synchronization i have with the Windows Table.

Any help how to further resolve this issue ?

Cheets, Peter


Syncthing sync on local network but not on WAN
(Peter van Nes) #2

Here the full syncthing debug log when running on the Windows Tablet. It seems that it is able to access some discovery servers and able to join a relay at relay://178.19.96.186:22067. I can’t distill from the logfiles what goes wrong.

[monitor] 2017/05/07 13:26:32.046735 monitor.go:58: INFO: Log output saved to file "C:\Users\TabletAdmin\AppData\Local\Syncthing\syncthing.log"
[monitor] 2017/05/07 13:26:32.084848 monitor.go:95: INFO: Starting syncthing
[F4E57] 2017/05/07 13:26:32.108939 main.go:665: INFO: syncthing v0.14.27 "Dysprosium Dragonfly" (go1.8.1 windows-amd64) jenkins@build.syncthing.net 2017-04-15 07:38:23 UTC
[F4E57] 2017/05/07 13:26:32.148005 main.go:666: INFO: My ID: F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:33.052416 sha256.go:92: INFO: Single thread SHA256 performance is 355 MB/s using minio/sha256-simd (299 MB/s using crypto/sha256).
[F4E57] 2017/05/07 13:26:33.520699 main.go:671: INFO: Hashing performance with weak hash is 288.63 MB/s
[F4E57] 2017/05/07 13:26:33.979888 main.go:673: INFO: Hashing performance without weak hash is 343.44 MB/s
[F4E57] 2017/05/07 13:26:33.980890 main.go:730: INFO: Weak hash enabled, as it has an acceptable performance impact.
[F4E57] 2017/05/07 13:26:34.027046 model.go:190: INFO: Ready to synchronize "Cloud Drive Renate" (gktyk-zxmi5) (readwrite)
[F4E57] 2017/05/07 13:26:34.027046 model.go:190: INFO: Ready to synchronize "Default Folder" (default) (readwrite)
[F4E57] 2017/05/07 13:26:34.027046 limiter.go:84: INFO: Send rate is unlimited, receive rate is unlimited
[F4E57] 2017/05/07 13:26:34.028016 limiter.go:89: INFO: Rate limits do not apply to LAN connections
[F4E57] 2017/05/07 13:26:34.028016 service.go:440: DEBUG: Starting listener dynamic+https://relays.syncthing.net/endpoint
[F4E57] 2017/05/07 13:26:34.028016 service.go:440: DEBUG: Starting listener tcp://0.0.0.0:22000
[F4E57] 2017/05/07 13:26:34.028016 service.go:517: DEBUG: Starting NAT service
[F4E57] 2017/05/07 13:26:34.028016 main.go:838: INFO: Using discovery server https://discovery-v4-2.syncthing.net/v2/?id=DVU36WY-H3LVZHW-E6LLFRE-YAFN5EL-HILWRYP-OC2M47J-Z4PE62Y-ADIBDQC
[F4E57] 2017/05/07 13:26:34.028016 service.go:314: DEBUG: Reconnect loop
[F4E57] 2017/05/07 13:26:34.028016 service.go:341: DEBUG: Reconnect loop for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:34.028016 main.go:838: INFO: Using discovery server https://discovery-v4-3.syncthing.net/v2/?id=VK6HNJ3-VVMM66S-HRVWSCR-IXEHL2H-U4AQ4MW-UCPQBWX-J2L2UBK-NVZRDQZ
[F4E57] 2017/05/07 13:26:34.028016 tcp_listen.go:69: INFO: TCP listener ([::]:22000) starting
[F4E57] 2017/05/07 13:26:34.028016 global.go:220: DEBUG: Announcement: {"addresses":["tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:34.030023 rwfolder.go:289: INFO: Completed initial scan (rw) of "Default Folder" (default)
[F4E57] 2017/05/07 13:26:34.032029 rwfolder.go:289: INFO: Completed initial scan (rw) of "Cloud Drive Renate" (gktyk-zxmi5)
[F4E57] 2017/05/07 13:26:34.239580 global.go:142: DEBUG: globalClient.Lookup https://discovery-v4-2.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v4-2.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: EOF
[F4E57] 2017/05/07 13:26:34.239580 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:34.239580 cache.go:139: DEBUG:   addresses:  []
[F4E57] 2017/05/07 13:26:34.239580 service.go:414: DEBUG: initial rampup; sleep 1s and update to 2s
[F4E57] 2017/05/07 13:26:34.239580 main.go:838: INFO: Using discovery server https://discovery-v4-4.syncthing.net/v2/?id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
[F4E57] 2017/05/07 13:26:34.239580 main.go:838: INFO: Using discovery server https://discovery-v6-2.syncthing.net/v2/?id=DVU36WY-H3LVZHW-E6LLFRE-YAFN5EL-HILWRYP-OC2M47J-Z4PE62Y-ADIBDQC
[F4E57] 2017/05/07 13:26:34.241587 main.go:838: INFO: Using discovery server https://discovery-v6-3.syncthing.net/v2/?id=VK6HNJ3-VVMM66S-HRVWSCR-IXEHL2H-U4AQ4MW-UCPQBWX-J2L2UBK-NVZRDQZ
[F4E57] 2017/05/07 13:26:34.241587 main.go:838: INFO: Using discovery server https://discovery-v6-4.syncthing.net/v2/?id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
[F4E57] 2017/05/07 13:26:34.250611 global.go:220: DEBUG: Announcement: {"addresses":["tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:34.250611 global.go:220: DEBUG: Announcement: {"addresses":["tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:34.278691 global.go:220: DEBUG: Announcement: {"addresses":["tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:34.284703 global.go:224: DEBUG: announce POST: Post https://discovery-v6-3.syncthing.net/v2/: dial tcp: lookup discovery-v6-3.syncthing.net: no such host
[F4E57] 2017/05/07 13:26:34.291719 global.go:220: DEBUG: Announcement: {"addresses":["tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:34.291719 global.go:220: DEBUG: Announcement: {"addresses":["tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:34.293733 main.go:883: INFO: Device HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG is "NewYork" at [dynamic]
[F4E57] 2017/05/07 13:26:34.293733 main.go:883: INFO: Device F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI is "HMCTAB-B7BB92A9" at [dynamic]
[F4E57] 2017/05/07 13:26:34.294728 global.go:224: DEBUG: announce POST: Post https://discovery-v6-2.syncthing.net/v2/: dial tcp: lookup discovery-v6-2.syncthing.net: no such host
[F4E57] 2017/05/07 13:26:34.295731 usage_report.go:267: INFO: Starting usage reporting
[F4E57] 2017/05/07 13:26:34.295731 global.go:224: DEBUG: announce POST: Post https://discovery-v6-4.syncthing.net/v2/: dial tcp: lookup discovery-v6-4.syncthing.net: no such host
[F4E57] 2017/05/07 13:26:34.299741 gui.go:340: INFO: GUI and API listening on 127.0.0.1:8384
[F4E57] 2017/05/07 13:26:34.299741 gui.go:341: INFO: Access the GUI via the following URL: http://127.0.0.1:8384/
[F4E57] 2017/05/07 13:26:34.325810 local.go:175: DEBUG: discover: Received local announcement from 192.168.182.210:56462 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.325810 local.go:175: DEBUG: discover: Received local announcement from 100.64.0.2:56462 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.326815 local.go:175: DEBUG: discover: Received local announcement from 192.168.179.20:56462 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.332830 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.333832 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.333832 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.333832 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.334834 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.335837 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.336840 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.340852 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.340852 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.340852 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:26:34.533365 global.go:224: DEBUG: announce POST: Post https://discovery-v4-2.syncthing.net/v2/: EOF
[F4E57] 2017/05/07 13:26:34.556472 static.go:93: INFO: Joined relay relay://178.19.96.186:22067
[F4E57] 2017/05/07 13:26:34.702818 global.go:224: DEBUG: announce POST: Post https://discovery-v4-3.syncthing.net/v2/: EOF
[F4E57] 2017/05/07 13:26:35.240248 service.go:314: DEBUG: Reconnect loop
[F4E57] 2017/05/07 13:26:35.240248 service.go:341: DEBUG: Reconnect loop for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:35.240248 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-2.syncthing.net/v2/ valid until 2017-05-07 13:27:34.2395808 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:35.671438 global.go:142: DEBUG: globalClient.Lookup https://discovery-v4-3.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v4-3.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: EOF
[F4E57] 2017/05/07 13:26:39.251209 global.go:224: DEBUG: announce POST: Post https://discovery-v4-4.syncthing.net/v2/: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)
[F4E57] 2017/05/07 13:26:40.672912 global.go:142: DEBUG: globalClient.Lookup https://discovery-v4-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v4-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)
[F4E57] 2017/05/07 13:26:40.674741 global.go:142: DEBUG: globalClient.Lookup https://discovery-v6-2.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v6-2.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: dial tcp: lookup discovery-v6-2.syncthing.net: no such host
[F4E57] 2017/05/07 13:26:40.675745 global.go:142: DEBUG: globalClient.Lookup https://discovery-v6-3.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v6-3.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: dial tcp: lookup discovery-v6-3.syncthing.net: no such host
[F4E57] 2017/05/07 13:26:40.677749 global.go:142: DEBUG: globalClient.Lookup https://discovery-v6-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v6-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: dial tcp: lookup discovery-v6-4.syncthing.net: no such host
[F4E57] 2017/05/07 13:26:40.678750 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv4 local
[F4E57] 2017/05/07 13:26:40.678750 cache.go:114: DEBUG:   addresses: []
[F4E57] 2017/05/07 13:26:40.678750 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv6 local
[F4E57] 2017/05/07 13:26:40.678750 cache.go:114: DEBUG:   addresses: []
[F4E57] 2017/05/07 13:26:40.678750 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:40.678750 cache.go:139: DEBUG:   addresses:  []
[F4E57] 2017/05/07 13:26:40.678750 service.go:414: DEBUG: initial rampup; sleep 2s and update to 4s
[F4E57] 2017/05/07 13:26:42.679325 service.go:314: DEBUG: Reconnect loop
[F4E57] 2017/05/07 13:26:42.679325 service.go:341: DEBUG: Reconnect loop for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:42.679325 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-2.syncthing.net/v2/ valid until 2017-05-07 13:27:34.2395808 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:42.679325 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-3.syncthing.net/v2/ valid until 2017-05-07 13:27:35.671438 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:42.679325 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-4.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6729129 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:42.679325 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-2.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6747414 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:42.680085 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-3.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6757453 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:42.680085 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-4.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6777494 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:42.680085 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv4 local
[F4E57] 2017/05/07 13:26:42.680085 cache.go:114: DEBUG:   addresses: []
[F4E57] 2017/05/07 13:26:42.680085 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv6 local
[F4E57] 2017/05/07 13:26:42.680085 cache.go:114: DEBUG:   addresses: []
[F4E57] 2017/05/07 13:26:42.680085 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:42.680085 cache.go:139: DEBUG:   addresses:  []
[F4E57] 2017/05/07 13:26:42.680085 service.go:414: DEBUG: initial rampup; sleep 4s and update to 8s
[F4E57] 2017/05/07 13:26:45.152906 service.go:61: INFO: Detected 0 NAT devices
[F4E57] 2017/05/07 13:26:46.030063 global.go:220: DEBUG: Announcement: {"addresses":["relay://178.19.96.186:22067/?id=WPFNARS-3RNUNSW-AE73DY7-CMID2BW-6KSTBGT-I4GYPN2-GZH7J6C-E3B2AQK\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=2000000\u0026globalLimitBps=20000000\u0026statusAddr=:22070\u0026providedBy=Mateusz","tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:46.030063 global.go:220: DEBUG: Announcement: {"addresses":["relay://178.19.96.186:22067/?id=WPFNARS-3RNUNSW-AE73DY7-CMID2BW-6KSTBGT-I4GYPN2-GZH7J6C-E3B2AQK\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=2000000\u0026globalLimitBps=20000000\u0026statusAddr=:22070\u0026providedBy=Mateusz","tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:46.030063 global.go:220: DEBUG: Announcement: {"addresses":["relay://178.19.96.186:22067/?id=WPFNARS-3RNUNSW-AE73DY7-CMID2BW-6KSTBGT-I4GYPN2-GZH7J6C-E3B2AQK\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=2000000\u0026globalLimitBps=20000000\u0026statusAddr=:22070\u0026providedBy=Mateusz","tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:46.030063 global.go:220: DEBUG: Announcement: {"addresses":["relay://178.19.96.186:22067/?id=WPFNARS-3RNUNSW-AE73DY7-CMID2BW-6KSTBGT-I4GYPN2-GZH7J6C-E3B2AQK\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=2000000\u0026globalLimitBps=20000000\u0026statusAddr=:22070\u0026providedBy=Mateusz","tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:46.031021 global.go:220: DEBUG: Announcement: {"addresses":["relay://178.19.96.186:22067/?id=WPFNARS-3RNUNSW-AE73DY7-CMID2BW-6KSTBGT-I4GYPN2-GZH7J6C-E3B2AQK\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=2000000\u0026globalLimitBps=20000000\u0026statusAddr=:22070\u0026providedBy=Mateusz","tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:46.033029 global.go:220: DEBUG: Announcement: {"addresses":["relay://178.19.96.186:22067/?id=WPFNARS-3RNUNSW-AE73DY7-CMID2BW-6KSTBGT-I4GYPN2-GZH7J6C-E3B2AQK\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=2000000\u0026globalLimitBps=20000000\u0026statusAddr=:22070\u0026providedBy=Mateusz","tcp://0.0.0.0:22000"]}
[F4E57] 2017/05/07 13:26:46.034030 global.go:224: DEBUG: announce POST: Post https://discovery-v6-3.syncthing.net/v2/: dial tcp: lookup discovery-v6-3.syncthing.net: no such host
[F4E57] 2017/05/07 13:26:46.040043 global.go:224: DEBUG: announce POST: Post https://discovery-v6-4.syncthing.net/v2/: dial tcp: lookup discovery-v6-4.syncthing.net: no such host
[F4E57] 2017/05/07 13:26:46.042050 global.go:224: DEBUG: announce POST: Post https://discovery-v6-2.syncthing.net/v2/: dial tcp: lookup discovery-v6-2.syncthing.net: no such host
[F4E57] 2017/05/07 13:26:46.257624 global.go:224: DEBUG: announce POST: Post https://discovery-v4-2.syncthing.net/v2/: EOF
[F4E57] 2017/05/07 13:26:46.490344 global.go:224: DEBUG: announce POST: Post https://discovery-v4-3.syncthing.net/v2/: EOF
[F4E57] 2017/05/07 13:26:46.680986 service.go:314: DEBUG: Reconnect loop
[F4E57] 2017/05/07 13:26:46.680986 service.go:341: DEBUG: Reconnect loop for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:46.680986 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-2.syncthing.net/v2/ valid until 2017-05-07 13:27:34.2395808 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:46.680986 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-3.syncthing.net/v2/ valid until 2017-05-07 13:27:35.671438 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:46.681758 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-4.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6729129 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:46.681758 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-2.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6747414 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:46.681758 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-3.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6757453 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:46.681758 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-4.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6777494 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:46.681758 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv4 local
[F4E57] 2017/05/07 13:26:46.681758 cache.go:114: DEBUG:   addresses: []
[F4E57] 2017/05/07 13:26:46.681758 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv6 local
[F4E57] 2017/05/07 13:26:46.681758 cache.go:114: DEBUG:   addresses: []
[F4E57] 2017/05/07 13:26:46.681758 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:46.681758 cache.go:139: DEBUG:   addresses:  []
[F4E57] 2017/05/07 13:26:46.681758 service.go:414: DEBUG: initial rampup; sleep 8s and update to 16s
[F4E57] 2017/05/07 13:26:51.031552 global.go:224: DEBUG: announce POST: Post https://discovery-v4-4.syncthing.net/v2/: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)
[F4E57] 2017/05/07 13:26:54.682326 service.go:314: DEBUG: Reconnect loop
[F4E57] 2017/05/07 13:26:54.682326 service.go:341: DEBUG: Reconnect loop for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:54.682326 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-2.syncthing.net/v2/ valid until 2017-05-07 13:27:34.2395808 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:54.682326 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-3.syncthing.net/v2/ valid until 2017-05-07 13:27:35.671438 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:54.682326 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-4.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6729129 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:54.683089 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-2.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6747414 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:54.683089 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-3.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6757453 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:54.683089 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-4.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6777494 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:26:54.683089 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv4 local
[F4E57] 2017/05/07 13:26:54.683089 cache.go:114: DEBUG:   addresses: []
[F4E57] 2017/05/07 13:26:54.683089 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv6 local
[F4E57] 2017/05/07 13:26:54.683089 cache.go:114: DEBUG:   addresses: []
[F4E57] 2017/05/07 13:26:54.683089 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:54.683089 cache.go:139: DEBUG:   addresses:  []
[F4E57] 2017/05/07 13:26:54.683089 service.go:414: DEBUG: initial rampup; sleep 16s and update to 32s
[F4E57] 2017/05/07 13:27:04.252600 local.go:175: DEBUG: discover: Received local announcement from 192.168.182.210:56462 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.252600 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.252600 local.go:175: DEBUG: discover: Received local announcement from 100.64.0.2:56462 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.252600 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.252600 local.go:175: DEBUG: discover: Received local announcement from 192.168.179.20:56462 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.252600 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.252600 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.252600 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.252600 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.253603 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.253603 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.253603 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:04.253603 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:10.684949 service.go:314: DEBUG: Reconnect loop
[F4E57] 2017/05/07 13:27:10.684949 service.go:341: DEBUG: Reconnect loop for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:27:10.684949 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-2.syncthing.net/v2/ valid until 2017-05-07 13:27:34.2395808 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:27:10.684949 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-3.syncthing.net/v2/ valid until 2017-05-07 13:27:35.671438 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:27:10.685755 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-4.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6729129 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:27:10.685755 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-2.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6747414 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:27:10.685755 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-3.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6757453 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:27:10.685755 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v6-4.syncthing.net/v2/ valid until 2017-05-07 13:27:40.6777494 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[F4E57] 2017/05/07 13:27:10.685755 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv4 local
[F4E57] 2017/05/07 13:27:10.685755 cache.go:114: DEBUG:   addresses: []
[F4E57] 2017/05/07 13:27:10.685755 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv6 local
[F4E57] 2017/05/07 13:27:10.685755 cache.go:114: DEBUG:   addresses: []
[F4E57] 2017/05/07 13:27:10.685755 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:27:10.686761 cache.go:139: DEBUG:   addresses:  []
[F4E57] 2017/05/07 13:27:10.686761 service.go:414: DEBUG: initial rampup; sleep 32s and update to 1m4s
[F4E57] 2017/05/07 13:27:34.247844 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:34.247844 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:34.248844 local.go:175: DEBUG: discover: Received local announcement from 192.168.182.210:56462 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:34.248844 local.go:175: DEBUG: discover: Received local announcement from 100.64.0.2:56462 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI
[F4E57] 2017/05/07 13:27:34.248844 local.go:175: DEBUG: discover: Received local announcement from [fe80::5c16:4ddd:fc47:d40f%Wi-Fi]:56463 for F4E572Y-GS37MJC-JB73AEE-7KKD642-S4RS4WT-JEW3AL2-KI4HA2T-JF3GEQI

(Jakob Borg) #3

It’s trying to look up HOVGCY5 but the discovery servers don’t know anything about it. Either that device is offline or it’s not using global discovery. (Or it can’t, due to some firewall or other failure.)


(Peter van Nes) #4

I saw that too, but that is odd, because that is the Synology DS. The MacOS machines can synchronize with this node when on an guest network. Global discovery is enabled on this node (verified it). The NewYork machine may use UPnP and is able to make any outbound connection.


(Peter van Nes) #5

Hi Jakob,

I do not get it. Now connected one of the MacOS machines to the same guest network and synchronization with NewYork is working fine. The lookup of the discovery now returns an entry for HOVGCY5.

[5ONXR] 2017/05/07 19:06:41.748009 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[5ONXR] 2017/05/07 19:06:41.748028 cache.go:139: DEBUG:   addresses:  [relay://62.210.137.230:22067/?id=NHB3KZ6-ENIYGWT-DRB3NNS-SFHFQTY-JQLPUTD-FK6YL2W-WJCRIEG-M7E2HAW&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch online.net FR tcp://83.162.195.246:22000 tcp://83.162.195.246:53099]
[5ONXR] 2017/05/07 19:06:41.748068 service.go:392: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[5ONXR] 2017/05/07 19:06:41.748142 service.go:397: DEBUG: dial HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG tcp://83.162.195.246:22000

More logging;

[5ONXR] 2017/05/07 19:05:05.309249 main.go:665: INFO: syncthing v0.14.27 "Dysprosium Dragonfly" (go1.8.1 darwin-amd64) brew@ElCapitan-2.local 2017-04-15 07:38:23 UTC [noupgrade]
[5ONXR] 2017/05/07 19:05:05.309671 main.go:666: INFO: My ID: 5ONXR2U-FWTEFWG-MVV5UNE-TZEXZNI-F2PCI6A-UP4V3BQ-RYTM6GB-EYKIQQ3
[5ONXR] 2017/05/07 19:05:06.269189 sha256.go:92: INFO: Single thread SHA256 performance is 174 MB/s using minio/sha256-simd (143 MB/s using crypto/sha256).
[5ONXR] 2017/05/07 19:05:06.957161 main.go:671: INFO: Hashing performance with weak hash is 148.67 MB/s
[5ONXR] 2017/05/07 19:05:07.614747 main.go:673: INFO: Hashing performance without weak hash is 170.72 MB/s
[5ONXR] 2017/05/07 19:05:07.617031 main.go:730: INFO: Weak hash enabled, as it has an acceptable performance impact.
[5ONXR] 2017/05/07 19:05:09.591240 model.go:190: INFO: Ready to synchronize "Cloud Drive Renate" (gktyk-zxmi5) (readwrite)
[5ONXR] 2017/05/07 19:05:21.056092 model.go:190: INFO: Ready to synchronize "Foto's Bibliotheek barcelona" (aey9j-sqcpe) (readonly)
[5ONXR] 2017/05/07 19:06:27.081255 model.go:190: INFO: Ready to synchronize "Homedir renatevanderhorst barcelona" (mccqf-4ccvf) (readonly)
[5ONXR] 2017/05/07 19:06:27.081519 limiter.go:84: INFO: Send rate is unlimited, receive rate is unlimited
[5ONXR] 2017/05/07 19:06:27.081929 limiter.go:89: INFO: Rate limits do not apply to LAN connections
[5ONXR] 2017/05/07 19:06:27.082073 service.go:440: DEBUG: Starting listener dynamic+https://relays.syncthing.net/endpoint
[5ONXR] 2017/05/07 19:06:27.082113 service.go:440: DEBUG: Starting listener tcp://0.0.0.0:22000
[5ONXR] 2017/05/07 19:06:27.082140 service.go:517: DEBUG: Starting NAT service
[5ONXR] 2017/05/07 19:06:27.082215 main.go:838: INFO: Using discovery server https://discovery-v4-2.syncthing.net/v2/?id=DVU36WY-H3LVZHW-E6LLFRE-YAFN5EL-HILWRYP-OC2M47J-Z4PE62Y-ADIBDQC
[5ONXR] 2017/05/07 19:06:27.082325 main.go:838: INFO: Using discovery server https://discovery-v4-3.syncthing.net/v2/?id=VK6HNJ3-VVMM66S-HRVWSCR-IXEHL2H-U4AQ4MW-UCPQBWX-J2L2UBK-NVZRDQZ
[5ONXR] 2017/05/07 19:06:27.082381 service.go:314: DEBUG: Reconnect loop
[5ONXR] 2017/05/07 19:06:27.082569 service.go:341: DEBUG: Reconnect loop for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX
[5ONXR] 2017/05/07 19:06:27.083601 main.go:838: INFO: Using discovery server https://discovery-v4-4.syncthing.net/v2/?id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
[5ONXR] 2017/05/07 19:06:27.083992 global.go:220: DEBUG: Announcement: {"addresses":["tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:27.084613 tcp_listen.go:69: INFO: TCP listener ([::]:22000) starting
[5ONXR] 2017/05/07 19:06:27.084681 global.go:220: DEBUG: Announcement: {"addresses":["tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:27.089448 rwfolder.go:289: INFO: Completed initial scan (rw) of "Cloud Drive Renate" (gktyk-zxmi5)
[5ONXR] 2017/05/07 19:06:27.533034 global.go:147: DEBUG: globalClient.Lookup https://discovery-v4-2.syncthing.net/v2/?device=DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX 404 Not Found
[5ONXR] 2017/05/07 19:06:27.543208 global.go:229: DEBUG: announce POST: 204 No Content
[5ONXR] 2017/05/07 19:06:27.543268 global.go:256: DEBUG: announce Reannounce-After: 1800 <nil>
[5ONXR] 2017/05/07 19:06:28.774460 global.go:229: DEBUG: announce POST: 204 No Content
[5ONXR] 2017/05/07 19:06:28.774522 global.go:256: DEBUG: announce Reannounce-After: 1800 <nil>
[5ONXR] 2017/05/07 19:06:29.105235 global.go:147: DEBUG: globalClient.Lookup https://discovery-v4-3.syncthing.net/v2/?device=DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX 404 Not Found
[5ONXR] 2017/05/07 19:06:29.105439 cache.go:138: DEBUG: lookup results for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX
[5ONXR] 2017/05/07 19:06:29.105459 cache.go:139: DEBUG:   addresses:  []
[5ONXR] 2017/05/07 19:06:29.105535 service.go:341: DEBUG: Reconnect loop for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[5ONXR] 2017/05/07 19:06:29.105587 main.go:838: INFO: Using discovery server https://discovery-v6-2.syncthing.net/v2/?id=DVU36WY-H3LVZHW-E6LLFRE-YAFN5EL-HILWRYP-OC2M47J-Z4PE62Y-ADIBDQC
[5ONXR] 2017/05/07 19:06:29.106262 global.go:220: DEBUG: Announcement: {"addresses":["tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:29.512346 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-2.syncthing.net/v2/
[5ONXR] 2017/05/07 19:06:29.512397 cache.go:114: DEBUG:   addresses: [relay://62.210.137.230:22067/?id=NHB3KZ6-ENIYGWT-DRB3NNS-SFHFQTY-JQLPUTD-FK6YL2W-WJCRIEG-M7E2HAW&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch online.net FR tcp://83.162.195.246:22000 tcp://83.162.195.246:53099]
[5ONXR] 2017/05/07 19:06:30.882388 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-3.syncthing.net/v2/
[5ONXR] 2017/05/07 19:06:30.882483 cache.go:114: DEBUG:   addresses: [relay://62.210.137.230:22067/?id=NHB3KZ6-ENIYGWT-DRB3NNS-SFHFQTY-JQLPUTD-FK6YL2W-WJCRIEG-M7E2HAW&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch online.net FR tcp://83.162.195.246:53099 tcp://83.162.195.246:22000]
[5ONXR] 2017/05/07 19:06:33.052660 global.go:229: DEBUG: announce POST: 204 No Content
[5ONXR] 2017/05/07 19:06:33.052716 global.go:256: DEBUG: announce Reannounce-After: 1800 <nil>
[5ONXR] 2017/05/07 19:06:34.546447 static.go:93: INFO: Joined relay relay://213.239.217.18:22067
[5ONXR] 2017/05/07 19:06:35.883011 global.go:142: DEBUG: globalClient.Lookup https://discovery-v4-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v4-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)
[5ONXR] 2017/05/07 19:06:35.883233 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[5ONXR] 2017/05/07 19:06:35.883265 cache.go:139: DEBUG:   addresses:  [relay://62.210.137.230:22067/?id=NHB3KZ6-ENIYGWT-DRB3NNS-SFHFQTY-JQLPUTD-FK6YL2W-WJCRIEG-M7E2HAW&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch online.net FR tcp://83.162.195.246:22000 tcp://83.162.195.246:53099]
[5ONXR] 2017/05/07 19:06:35.883368 service.go:397: DEBUG: dial HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG relay://62.210.137.230:22067/?id=NHB3KZ6-ENIYGWT-DRB3NNS-SFHFQTY-JQLPUTD-FK6YL2W-WJCRIEG-M7E2HAW&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch online.net FR
[5ONXR] 2017/05/07 19:06:35.883485 main.go:838: INFO: Using discovery server https://discovery-v6-3.syncthing.net/v2/?id=VK6HNJ3-VVMM66S-HRVWSCR-IXEHL2H-U4AQ4MW-UCPQBWX-J2L2UBK-NVZRDQZ
[5ONXR] 2017/05/07 19:06:35.883622 main.go:838: INFO: Using discovery server https://discovery-v6-4.syncthing.net/v2/?id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
[5ONXR] 2017/05/07 19:06:35.884207 global.go:220: DEBUG: Announcement: {"addresses":["relay://213.239.217.18:22067/?id=COGM5LT-SJS25BV-XNK2WOX-7CFO56V-NFISVTI-4H2LF2U-DPCFOFP-KLEXUAF\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=0\u0026globalLimitBps=0\u0026statusAddr=:22070\u0026providedBy=spaam","tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:35.889529 global.go:220: DEBUG: Announcement: {"addresses":["relay://213.239.217.18:22067/?id=COGM5LT-SJS25BV-XNK2WOX-7CFO56V-NFISVTI-4H2LF2U-DPCFOFP-KLEXUAF\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=0\u0026globalLimitBps=0\u0026statusAddr=:22070\u0026providedBy=spaam","tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:35.892318 global.go:220: DEBUG: Announcement: {"addresses":["relay://213.239.217.18:22067/?id=COGM5LT-SJS25BV-XNK2WOX-7CFO56V-NFISVTI-4H2LF2U-DPCFOFP-KLEXUAF\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=0\u0026globalLimitBps=0\u0026statusAddr=:22070\u0026providedBy=spaam","tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:35.898943 local.go:175: DEBUG: discover: Received local announcement from [fe80::1%lo0]:49615 for 5ONXR2U-FWTEFWG-MVV5UNE-TZEXZNI-F2PCI6A-UP4V3BQ-RYTM6GB-EYKIQQ3
[5ONXR] 2017/05/07 19:06:35.899378 global.go:224: DEBUG: announce POST: Post https://discovery-v6-4.syncthing.net/v2/: dial tcp [2a03:b0c0:0:1010::4ed:3001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:35.900298 global.go:224: DEBUG: announce POST: Post https://discovery-v6-3.syncthing.net/v2/: dial tcp [2400:6180:0:d0::d9:d001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:35.908141 global.go:224: DEBUG: announce POST: Post https://discovery-v6-2.syncthing.net/v2/: dial tcp [2604:a880:800:10::182:a001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:35.914354 main.go:883: INFO: Device DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX is "HMCTAB-B7BB92A9" at [dynamic]
[5ONXR] 2017/05/07 19:06:35.914452 main.go:883: INFO: Device HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG is "NewYork" at [dynamic]
[5ONXR] 2017/05/07 19:06:35.914528 main.go:883: INFO: Device 5ONXR2U-FWTEFWG-MVV5UNE-TZEXZNI-F2PCI6A-UP4V3BQ-RYTM6GB-EYKIQQ3 is "barcelona-10.fritz.box" at [dynamic]
[5ONXR] 2017/05/07 19:06:35.915425 usage_report.go:267: INFO: Starting usage reporting
[5ONXR] 2017/05/07 19:06:35.918086 gui.go:340: INFO: GUI and API listening on 127.0.0.1:8384
[5ONXR] 2017/05/07 19:06:35.918118 gui.go:341: INFO: Access the GUI via the following URL: http://127.0.0.1:8384/
[5ONXR] 2017/05/07 19:06:36.039039 relay_dial.go:48: DEBUG: failed to set traffic class: setsockopt: invalid argument
[5ONXR] 2017/05/07 19:06:36.240396 service.go:414: DEBUG: initial rampup; sleep 1s and update to 2s
[5ONXR] 2017/05/07 19:06:36.255413 service.go:281: INFO: Established secure connection to HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at 192.168.179.21:49395-62.210.137.230:22067 (relay-client) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305)
[5ONXR] 2017/05/07 19:06:36.255688 model.go:1399: INFO: Device HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG client is "syncthing v0.14.28-rc.3" named "newyork"
[5ONXR] 2017/05/07 19:06:37.243666 service.go:314: DEBUG: Reconnect loop
[5ONXR] 2017/05/07 19:06:37.243984 service.go:341: DEBUG: Reconnect loop for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX
[5ONXR] 2017/05/07 19:06:37.244536 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-2.syncthing.net/v2/ valid until 2017-05-07 19:07:27.53308567 +0200 CEST or 2017-05-07 19:18:32.533104923 +0200 CEST
[5ONXR] 2017/05/07 19:06:37.245051 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-3.syncthing.net/v2/ valid until 2017-05-07 19:07:29.105282655 +0200 CEST or 2017-05-07 19:18:34.105295871 +0200 CEST
[5ONXR] 2017/05/07 19:06:38.109763 service.go:61: INFO: Detected 0 NAT devices
[5ONXR] 2017/05/07 19:06:39.128457 global.go:220: DEBUG: Announcement: {"addresses":["relay://213.239.217.18:22067/?id=COGM5LT-SJS25BV-XNK2WOX-7CFO56V-NFISVTI-4H2LF2U-DPCFOFP-KLEXUAF\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=0\u0026globalLimitBps=0\u0026statusAddr=:22070\u0026providedBy=spaam","tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:39.130692 global.go:224: DEBUG: announce POST: Post https://discovery-v6-3.syncthing.net/v2/: dial tcp [2400:6180:0:d0::d9:d001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:39.130742 global.go:220: DEBUG: Announcement: {"addresses":["relay://213.239.217.18:22067/?id=COGM5LT-SJS25BV-XNK2WOX-7CFO56V-NFISVTI-4H2LF2U-DPCFOFP-KLEXUAF\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=0\u0026globalLimitBps=0\u0026statusAddr=:22070\u0026providedBy=spaam","tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:39.131869 global.go:224: DEBUG: announce POST: Post https://discovery-v6-4.syncthing.net/v2/: dial tcp [2a03:b0c0:0:1010::4ed:3001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:39.131953 global.go:220: DEBUG: Announcement: {"addresses":["relay://213.239.217.18:22067/?id=COGM5LT-SJS25BV-XNK2WOX-7CFO56V-NFISVTI-4H2LF2U-DPCFOFP-KLEXUAF\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=0\u0026globalLimitBps=0\u0026statusAddr=:22070\u0026providedBy=spaam","tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:39.132265 global.go:220: DEBUG: Announcement: {"addresses":["relay://213.239.217.18:22067/?id=COGM5LT-SJS25BV-XNK2WOX-7CFO56V-NFISVTI-4H2LF2U-DPCFOFP-KLEXUAF\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=0\u0026globalLimitBps=0\u0026statusAddr=:22070\u0026providedBy=spaam","tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:39.132553 global.go:220: DEBUG: Announcement: {"addresses":["relay://213.239.217.18:22067/?id=COGM5LT-SJS25BV-XNK2WOX-7CFO56V-NFISVTI-4H2LF2U-DPCFOFP-KLEXUAF\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=0\u0026globalLimitBps=0\u0026statusAddr=:22070\u0026providedBy=spaam","tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:39.133568 global.go:220: DEBUG: Announcement: {"addresses":["relay://213.239.217.18:22067/?id=COGM5LT-SJS25BV-XNK2WOX-7CFO56V-NFISVTI-4H2LF2U-DPCFOFP-KLEXUAF\u0026pingInterval=1m0s\u0026networkTimeout=2m0s\u0026sessionLimitBps=0\u0026globalLimitBps=0\u0026statusAddr=:22070\u0026providedBy=spaam","tcp://0.0.0.0:22000"]}
[5ONXR] 2017/05/07 19:06:39.134378 global.go:224: DEBUG: announce POST: Post https://discovery-v6-2.syncthing.net/v2/: dial tcp [2604:a880:800:10::182:a001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:39.144533 global.go:147: DEBUG: globalClient.Lookup https://discovery-v4-4.syncthing.net/v2/?device=DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX 404 Not Found
[5ONXR] 2017/05/07 19:06:39.146872 global.go:142: DEBUG: globalClient.Lookup https://discovery-v6-2.syncthing.net/v2/?device=DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX Get https://discovery-v6-2.syncthing.net/v2/?device=DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX: dial tcp [2604:a880:800:10::182:a001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:39.148037 global.go:142: DEBUG: globalClient.Lookup https://discovery-v6-3.syncthing.net/v2/?device=DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX Get https://discovery-v6-3.syncthing.net/v2/?device=DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX: dial tcp [2400:6180:0:d0::d9:d001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:39.149027 global.go:142: DEBUG: globalClient.Lookup https://discovery-v6-4.syncthing.net/v2/?device=DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX Get https://discovery-v6-4.syncthing.net/v2/?device=DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX: dial tcp [2a03:b0c0:0:1010::4ed:3001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:39.149158 cache.go:113: DEBUG: lookup for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at IPv4 local
[5ONXR] 2017/05/07 19:06:39.149178 cache.go:114: DEBUG:   addresses: []
[5ONXR] 2017/05/07 19:06:39.149248 cache.go:113: DEBUG: lookup for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at IPv6 local
[5ONXR] 2017/05/07 19:06:39.149260 cache.go:114: DEBUG:   addresses: []
[5ONXR] 2017/05/07 19:06:39.149330 cache.go:138: DEBUG: lookup results for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX
[5ONXR] 2017/05/07 19:06:39.149346 cache.go:139: DEBUG:   addresses:  []
[5ONXR] 2017/05/07 19:06:39.299803 global.go:229: DEBUG: announce POST: 204 No Content
[5ONXR] 2017/05/07 19:06:39.299856 global.go:256: DEBUG: announce Reannounce-After: 1800 <nil>
[5ONXR] 2017/05/07 19:06:39.522247 global.go:229: DEBUG: announce POST: 204 No Content
[5ONXR] 2017/05/07 19:06:39.522311 global.go:256: DEBUG: announce Reannounce-After: 1800 <nil>
[5ONXR] 2017/05/07 19:06:41.743128 service.go:341: DEBUG: Reconnect loop for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[5ONXR] 2017/05/07 19:06:41.743253 cache.go:92: DEBUG: cached discovery entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-2.syncthing.net/v2/
[5ONXR] 2017/05/07 19:06:41.743297 cache.go:93: DEBUG:   cache: {[relay://62.210.137.230:22067/?id=NHB3KZ6-ENIYGWT-DRB3NNS-SFHFQTY-JQLPUTD-FK6YL2W-WJCRIEG-M7E2HAW&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch online.net FR tcp://83.162.195.246:22000 tcp://83.162.195.246:53099] {63629773589 512409219 0x1cad420} true {0 0 <nil>} 0}
[5ONXR] 2017/05/07 19:06:41.743377 cache.go:92: DEBUG: cached discovery entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-3.syncthing.net/v2/
[5ONXR] 2017/05/07 19:06:41.743399 cache.go:93: DEBUG:   cache: {[relay://62.210.137.230:22067/?id=NHB3KZ6-ENIYGWT-DRB3NNS-SFHFQTY-JQLPUTD-FK6YL2W-WJCRIEG-M7E2HAW&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch online.net FR tcp://83.162.195.246:53099 tcp://83.162.195.246:22000] {63629773590 882500216 0x1cad420} true {0 0 <nil>} 0}
[5ONXR] 2017/05/07 19:06:41.743677 cache.go:104: DEBUG: negative cache entry for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at global@https://discovery-v4-4.syncthing.net/v2/ valid until 2017-05-07 19:07:35.883065518 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[5ONXR] 2017/05/07 19:06:41.746086 global.go:142: DEBUG: globalClient.Lookup https://discovery-v6-2.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v6-2.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: dial tcp [2604:a880:800:10::182:a001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:41.746987 global.go:142: DEBUG: globalClient.Lookup https://discovery-v6-3.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v6-3.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: dial tcp [2400:6180:0:d0::d9:d001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:41.747727 global.go:142: DEBUG: globalClient.Lookup https://discovery-v6-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v6-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: dial tcp [2a03:b0c0:0:1010::4ed:3001]:443: connect: no route to host
[5ONXR] 2017/05/07 19:06:41.747826 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv4 local
[5ONXR] 2017/05/07 19:06:41.747856 cache.go:114: DEBUG:   addresses: []
[5ONXR] 2017/05/07 19:06:41.747926 cache.go:113: DEBUG: lookup for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at IPv6 local
[5ONXR] 2017/05/07 19:06:41.747939 cache.go:114: DEBUG:   addresses: []
[5ONXR] 2017/05/07 19:06:41.748009 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[5ONXR] 2017/05/07 19:06:41.748028 cache.go:139: DEBUG:   addresses:  [relay://62.210.137.230:22067/?id=NHB3KZ6-ENIYGWT-DRB3NNS-SFHFQTY-JQLPUTD-FK6YL2W-WJCRIEG-M7E2HAW&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch online.net FR tcp://83.162.195.246:22000 tcp://83.162.195.246:53099]
[5ONXR] 2017/05/07 19:06:41.748068 service.go:392: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[5ONXR] 2017/05/07 19:06:41.748142 service.go:397: DEBUG: dial HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG tcp://83.162.195.246:22000
[5ONXR] 2017/05/07 19:06:44.052243 global.go:229: DEBUG: announce POST: 204 No Content
[5ONXR] 2017/05/07 19:06:44.052294 global.go:256: DEBUG: announce Reannounce-After: 1800 <nil>
[5ONXR] 2017/05/07 19:06:51.751397 tcp_dial.go:36: DEBUG: dial tcp 83.162.195.246:22000: i/o timeout
[5ONXR] 2017/05/07 19:06:51.751572 service.go:402: DEBUG: dial failed HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG tcp://83.162.195.246:22000 dial tcp 83.162.195.246:22000: i/o timeout
[5ONXR] 2017/05/07 19:06:51.751673 service.go:397: DEBUG: dial HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG tcp://83.162.195.246:53099
[5ONXR] 2017/05/07 19:06:51.756095 tcp_dial.go:47: DEBUG: failed to set traffic class: setsockopt: invalid argument
[5ONXR] 2017/05/07 19:06:52.106507 service.go:414: DEBUG: initial rampup; sleep 2s and update to 4s
[5ONXR] 2017/05/07 19:06:52.108474 service.go:236: DEBUG: Switching connections HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[5ONXR] 2017/05/07 19:06:52.108605 service.go:281: INFO: Established secure connection to HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG at 192.168.179.21:49426-83.162.195.246:53099 (tcp-client) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305)
[5ONXR] 2017/05/07 19:06:52.108859 model.go:1365: INFO: Replacing old connection 192.168.179.21:49395-62.210.137.230:22067/relay-client with 192.168.179.21:49426-83.162.195.246:53099/tcp-client for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[5ONXR] 2017/05/07 19:06:52.109185 model.go:1108: INFO: Connection to HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG closed: reading length: read tcp 192.168.179.21:49395->62.210.137.230:22067: use of closed network connection
[5ONXR] 2017/05/07 19:06:52.109426 model.go:1399: INFO: Device HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG client is "syncthing v0.14.28-rc.3" named "newyork"
[5ONXR] 2017/05/07 19:06:54.108979 service.go:314: DEBUG: Reconnect loop
[5ONXR] 2017/05/07 19:06:54.109327 service.go:341: DEBUG: Reconnect loop for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX
[5ONXR] 2017/05/07 19:06:54.109452 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-2.syncthing.net/v2/ valid until 2017-05-07 19:07:27.53308567 +0200 CEST or 2017-05-07 19:18:32.533104923 +0200 CEST
[5ONXR] 2017/05/07 19:06:54.109574 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-3.syncthing.net/v2/ valid until 2017-05-07 19:07:29.105282655 +0200 CEST or 2017-05-07 19:18:34.105295871 +0200 CEST
[5ONXR] 2017/05/07 19:06:54.109657 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-4.syncthing.net/v2/ valid until 2017-05-07 19:07:39.144574225 +0200 CEST or 2017-05-07 19:17:20.144581822 +0200 CEST
[5ONXR] 2017/05/07 19:06:54.109797 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v6-2.syncthing.net/v2/ valid until 2017-05-07 19:07:39.14689379 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[5ONXR] 2017/05/07 19:06:54.109911 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v6-3.syncthing.net/v2/ valid until 2017-05-07 19:07:39.14807211 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[5ONXR] 2017/05/07 19:06:54.110150 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v6-4.syncthing.net/v2/ valid until 2017-05-07 19:07:39.149046446 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[5ONXR] 2017/05/07 19:06:54.110241 cache.go:113: DEBUG: lookup for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at IPv4 local
[5ONXR] 2017/05/07 19:06:54.110257 cache.go:114: DEBUG:   addresses: []
[5ONXR] 2017/05/07 19:06:54.110437 cache.go:113: DEBUG: lookup for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at IPv6 local
[5ONXR] 2017/05/07 19:06:54.110499 cache.go:114: DEBUG:   addresses: []
[5ONXR] 2017/05/07 19:06:54.110579 cache.go:138: DEBUG: lookup results for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX
[5ONXR] 2017/05/07 19:06:54.110594 cache.go:139: DEBUG:   addresses:  []
[5ONXR] 2017/05/07 19:06:54.110731 service.go:414: DEBUG: initial rampup; sleep 4s and update to 8s
[5ONXR] 2017/05/07 19:06:58.111063 service.go:314: DEBUG: Reconnect loop
[5ONXR] 2017/05/07 19:06:58.111219 service.go:341: DEBUG: Reconnect loop for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX
[5ONXR] 2017/05/07 19:06:58.111340 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-2.syncthing.net/v2/ valid until 2017-05-07 19:07:27.53308567 +0200 CEST or 2017-05-07 19:18:32.533104923 +0200 CEST
[5ONXR] 2017/05/07 19:06:58.111417 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-3.syncthing.net/v2/ valid until 2017-05-07 19:07:29.105282655 +0200 CEST or 2017-05-07 19:18:34.105295871 +0200 CEST
[5ONXR] 2017/05/07 19:06:58.111499 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-4.syncthing.net/v2/ valid until 2017-05-07 19:07:39.144574225 +0200 CEST or 2017-05-07 19:17:20.144581822 +0200 CEST
[5ONXR] 2017/05/07 19:06:58.111576 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v6-2.syncthing.net/v2/ valid until 2017-05-07 19:07:39.14689379 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[5ONXR] 2017/05/07 19:06:58.111694 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v6-3.syncthing.net/v2/ valid until 2017-05-07 19:07:39.14807211 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[5ONXR] 2017/05/07 19:06:58.111770 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v6-4.syncthing.net/v2/ valid until 2017-05-07 19:07:39.149046446 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[5ONXR] 2017/05/07 19:06:58.111841 cache.go:113: DEBUG: lookup for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at IPv4 local
[5ONXR] 2017/05/07 19:06:58.111857 cache.go:114: DEBUG:   addresses: []
[5ONXR] 2017/05/07 19:06:58.111922 cache.go:113: DEBUG: lookup for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at IPv6 local
[5ONXR] 2017/05/07 19:06:58.111934 cache.go:114: DEBUG:   addresses: []
[5ONXR] 2017/05/07 19:06:58.112011 cache.go:138: DEBUG: lookup results for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX
[5ONXR] 2017/05/07 19:06:58.112026 cache.go:139: DEBUG:   addresses:  []
[5ONXR] 2017/05/07 19:06:58.112101 service.go:414: DEBUG: initial rampup; sleep 8s and update to 16s
[5ONXR] 2017/05/07 19:07:05.899136 local.go:175: DEBUG: discover: Received local announcement from [fe80::1%lo0]:49615 for 5ONXR2U-FWTEFWG-MVV5UNE-TZEXZNI-F2PCI6A-UP4V3BQ-RYTM6GB-EYKIQQ3
[5ONXR] 2017/05/07 19:07:06.120159 service.go:314: DEBUG: Reconnect loop
[5ONXR] 2017/05/07 19:07:06.120736 service.go:341: DEBUG: Reconnect loop for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX
[5ONXR] 2017/05/07 19:07:06.120999 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-2.syncthing.net/v2/ valid until 2017-05-07 19:07:27.53308567 +0200 CEST or 2017-05-07 19:18:32.533104923 +0200 CEST
[5ONXR] 2017/05/07 19:07:06.121107 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-3.syncthing.net/v2/ valid until 2017-05-07 19:07:29.105282655 +0200 CEST or 2017-05-07 19:18:34.105295871 +0200 CEST
[5ONXR] 2017/05/07 19:07:06.121268 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v4-4.syncthing.net/v2/ valid until 2017-05-07 19:07:39.144574225 +0200 CEST or 2017-05-07 19:17:20.144581822 +0200 CEST
[5ONXR] 2017/05/07 19:07:06.121454 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v6-2.syncthing.net/v2/ valid until 2017-05-07 19:07:39.14689379 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[5ONXR] 2017/05/07 19:07:06.121564 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v6-3.syncthing.net/v2/ valid until 2017-05-07 19:07:39.14807211 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[5ONXR] 2017/05/07 19:07:06.121788 cache.go:104: DEBUG: negative cache entry for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at global@https://discovery-v6-4.syncthing.net/v2/ valid until 2017-05-07 19:07:39.149046446 +0200 CEST or 0001-01-01 00:00:00 +0000 UTC
[5ONXR] 2017/05/07 19:07:06.121890 cache.go:113: DEBUG: lookup for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at IPv4 local
[5ONXR] 2017/05/07 19:07:06.122175 cache.go:114: DEBUG:   addresses: []
[5ONXR] 2017/05/07 19:07:06.122394 cache.go:113: DEBUG: lookup for DWMUF3Y-VBEVGPD-TFN7L5Q-ILLEYWF-H7PKQCX-WYMAW3H-ID5OKZ6-M65KGAX at IPv6 local

(Peter van Nes) #6

Now also compared the logging of the Windows Tablet with the MacOS machine

On MacOS the request to the discovery server can find HOVGCY5

[5ONXR] 2017/05/07 19:06:35.883011 global.go:142: DEBUG: globalClient.Lookup https://discovery-v4-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v4-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)
[5ONXR] 2017/05/07 19:06:35.883233 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[5ONXR] 2017/05/07 19:06:35.883265 cache.go:139: DEBUG:   addresses:  [relay://62.210.137.230:22067/?id=NHB3KZ6-ENIYGWT-DRB3NNS-SFHFQTY-JQLPUTD-FK6YL2W-WJCRIEG-M7E2HAW&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch online.net FR tcp://83.162.195.246:22000 tcp://83.162.195.246:53099]
[5ONXR] 2017/05/07 19:06:35.883368 service.go:397: DEBUG: dial HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG relay://62.210.137.230:22067/?id=NHB3KZ6-ENIYGWT-DRB3NNS-SFHFQTY-JQLPUTD-FK6YL2W-WJCRIEG-M7E2HAW&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch online.net FR

But on Windows not … :confused:

[F4E57] 2017/05/07 13:26:34.239580 global.go:142: DEBUG: globalClient.Lookup https://discovery-v4-2.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://discovery-v4-2.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: EOF
[F4E57] 2017/05/07 13:26:34.239580 cache.go:138: DEBUG: lookup results for HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG
[F4E57] 2017/05/07 13:26:34.239580 cache.go:139: DEBUG:   addresses:  []
[F4E57] 2017/05/07 13:26:34.239580 service.go:414: DEBUG: initial rampup; sleep 1s and update to 2s

(Jakob Borg) #7

It looks like it’s not getting a response from the discovery server. Either it’s timing out or something is interfering with it? The usual Windows response to such issues are to disable any antivirus… I don’t know if that’s a thing on tablets.


(Peter van Nes) #8

Hi Jakob,

Was just fiddling with the firewal settings. Syncthing.exe has now access as an application and is allowed to make outbound connections. That did not help. There has been installed a s***load of security applications on that tablet, see if i can find what is interfering.


(Peter van Nes) #9

Seems i am getting a bit closer. When i open the url https://discovery-v4-4.syncthing.net/v2 in edge i do get a http 404. If i replace the hostname with the resolved ip-adres in the request https://95.85.19.244/v2 the certificate needs to be confirmed. So it seems that when using the ip-address i am able to connect. If i accept the certificate requests, i do get a bad request in the end from the server.

But what does prevent in windows 10 this ? Checked the firewall rules, no issues there. Since i am able to connect using the ip-address there must probably something filtering certain URIs …


(Jakob Borg) #10

You can surf to https://discovery-v4-4.syncthing.net/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG to see the discovery results for that device. If things work it should return a blob of JSON (or potentially 404 if the device is not registered). The discovery server optionally requests a client certificate so your browser may pop up a request for one - you can cancel that and it should proceed.


(Peter van Nes) #11

I have opened the urls to the discovery servers in edge browser, if doing so https://discovery-v4-3.synchting.net/v2/ the browser reports that the site is unavailable. If i resolve ip-addresses from the hostname and replace the host-name with the ip-address i am ablet to connect and import the certificate in the truststore.

Just to try i resolved discovery-v4-3.synchting.net to 95.85.19.244 and added https://95.85.19.244/v2/ as the only discovery in the configuration. When starting syncthing the log reports

 [F4E57] 2017/05/09 22:12:15.151947 global.go:142: DEBUG: globalClient.Lookup https://95.85.19.244/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG Get https://95.85.19.244/v2/?device=HOVGCY5-3ZZMHT4-KXJZMEA-3OCEXYD-YIZNQOF-P3P4J3U-IV2DGSH-DL2WUQG: x509: certificate signed by unknown authority

(Peter van Nes) #12

It seems that the certificates for the discovery servers are self signed. I can imagine that this is not allowed somewhere in the windows policies. Why are the certificates not signed? Nowaday cost cannot be an issue, https://letsencrypt.org, delivers signed certificates for free.


(Audrius Butkevicius) #13

They are signed. You are not using a hostname, you are using an IP, and IPs do not have certificates.


(Jakob Borg) #14

While that is the reason for the failure, the discovery servers are actually authenticated by certificate pinning. When starting up, Syncthing prints something like this:

[SX5UR] 07:58:01 INFO: Using discovery server https://discovery-v4-2.syncthing.net/v2/?id=DVU36WY-H3LVZHW-E6LLFRE-YAFN5EL-HILWRYP-OC2M47J-Z4PE62Y-ADIBDQC
[SX5UR] 07:58:01 INFO: Using discovery server https://discovery-v4-3.syncthing.net/v2/?id=VK6HNJ3-VVMM66S-HRVWSCR-IXEHL2H-U4AQ4MW-UCPQBWX-J2L2UBK-NVZRDQZ
[SX5UR] 07:58:01 INFO: Using discovery server https://discovery-v4-4.syncthing.net/v2/?id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
[SX5UR] 07:58:01 INFO: Using discovery server https://discovery-v6-2.syncthing.net/v2/?id=DVU36WY-H3LVZHW-E6LLFRE-YAFN5EL-HILWRYP-OC2M47J-Z4PE62Y-ADIBDQC
[SX5UR] 07:58:01 INFO: Using discovery server https://discovery-v6-3.syncthing.net/v2/?id=VK6HNJ3-VVMM66S-HRVWSCR-IXEHL2H-U4AQ4MW-UCPQBWX-J2L2UBK-NVZRDQZ
[SX5UR] 07:58:01 INFO: Using discovery server https://discovery-v6-4.syncthing.net/v2/?id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW

That ?id=... parameter (scroll that box to the right) tells Syncthing exactly what certificate to expect. Copy that into your configuration and you can use it by IP as well.

We do this because

  • it predates letsencrypt and similar
  • it works on devices that don’t have DNS (with the entries replaced by IPs)
  • it works on devices that don’t have an up to date root CA store.

Usage reporting, relays, and upgrades do use CA-signed certificates and thus requires a bit more functional infrastructure to work. For discovery we try to make sure it works even in the most broken environments, although some sort of unknown brokenness is winning in your case. :slight_smile:


(Peter van Nes) #15

That is not correct. DNS Name (not Hostnames) nor IP adresses do have certificates. Private keys of certificates are installed on a host which can be reached using the DNS name or IP-address. If the host is accessed using an ip-address or DNS Name not matching the name in the certificate a warning is shown that the certificate does not match the hostname.

The certificates are signed indeed, every certicate is signed, these are self-signed which is not the same as signed by a Root CA.


(Jakob Borg) #16

Did you read my post above?


(Peter van Nes) #17

Hi Jakob,

Yes just did read it, already tried to open these URI’s but then IE / Edge will not open the URIs and give the error “This page can’t be displayed. Turn on TLS 1.0, TLS 1.1, and TLS 1.2 in Advanced settings”.

And as you might guess, these are all enabled.


#18

Hello everyone, I think I’m actually having the same issue. At home my Android device can connect my Work place Linux PC when my Home PC can’t. I also have this log message: "dial failed QQK52EX tcp://113.118.227.179:22000 dial tcp 113.118.227.179:22000: i/o timeout" I uninstalled my COMODO firewall, and it seems that windows firewall configuration is ok. I don’t understand What I have to do to solve it.


(Peter van Nes) #19

Hi,

I do not think this is the same issue. The i/o timeout is something different. The ip-address 113.118.227.179 is probably a non routable address or a firewall on either side blocks the port. Can you connect using telnet 113.118.227.179 22000 ? If you get a blank screen after executing the telnet command you are able to connect, if not the port on the specified ip-address is not reachable.

Cheers, Peter


(Peter van Nes) #20

Hi Jakob,

Thanks for the responses, but still no-go. After spending quite some hours debugging, testing, trying, screaming :wink: i give up. If you have more ideas, i am willing to try them out, but for now will leave it.

Cheers, Peter