Private Network to Public Network Device Sync Not working - Disconnected - Need Suggestions

venkat_Bang · August 1, 2018, 12:57pm

Dear Syncthing experts, we need your valuable inputs in one of the issue we are facing. Below is description, any help highly appreciated.

Problem Description: We are not able to connect two devices which are in different network (Source device in private corporate network and Destination Device in public internet).

In Source corporate network we have enabled the firewall rules for all the Relay servers with required ports.

Destination Public Relay Servers IP : 162.255.117.212 195.219.226.6 163.172.85.202 94.16.122.162 78.47.248.86 Port : 443 , 22000/TCP, 21027/UDP, 22067/TCP.

we have also configured Relay servers in both source and destination

Relay Server: relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY

Below are masked device IDs for source and destination - This will help in understnading the logs

Source (Internal): QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3

Destination(External): yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC

Devices showing Disconnected in both sides and doesn’t sync any folders.

Alternative, we have tested below scenarios and it is working well.

Sync between Source(Private Network) and Destination(Private Network) - Working Sync between Source(Public Network) and Destination(Public Network) - Working.

Only in Hybrid networks we have this issue.

we see consistently same logs being displayed for

Below are the Logs for Source and destination for your interpretations and recommendation.

========================
Source Device Logs
========================
2018-08-01 17:23:05 syncthing v0.14.48 "Dysprosium Dragonfly" (go1.10.2 windows-amd64) teamcity@build.syncthing.net 2018-05-14 06:53:06 UTC
2018-08-01 17:23:05 My ID: QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:23:06 Single thread SHA256 performance is 159 MB/s using minio/sha256-simd (99 MB/s using crypto/sha256).
2018-08-01 17:23:06 Hashing performance is 82.83 MB/s
2018-08-01 17:23:06 Overall send rate is unlimited, receive rate is unlimited
2018-08-01 17:23:06 Rate limits do not apply to LAN connections
2018-08-01 17:23:06 Using discovery server https://discovery-v4.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
2018-08-01 17:23:06 Using discovery server https://discovery-v6.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
2018-08-01 17:23:06 Using discovery server https://discovery.syncthing.net/v2/?noannounce&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
2018-08-01 17:23:06 Relay listener (relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY) starting
2018-08-01 17:23:06 ...
2018-08-01 17:23:06 GUI and API listening on 127.0.0.1:8384
2018-08-01 17:23:06 Access the GUI via the following URL: http://127.0.0.1:8384/
2018-08-01 17:23:06 Device QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 is "computerinternal" at [dynamic]
2018-08-01 17:23:07 Joined relay relay://94.16.122.162:443
2018-08-01 17:23:28 Couldn't fetch release information: Get https://upgrades.syncthing.net/meta.json: dial tcp 82.196.13.137:443: connectex: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.
2018-08-01 17:23:28 Automatic upgrade: no version to select
2018-08-01 17:23:37 Couldn't fetch release information: Get https://upgrades.syncthing.net/meta.json: dial tcp 82.196.13.137:443: connectex: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.
2018-08-01 17:24:43 Adding folder "D:\\SyncMan" (gvrhg-s54sr)
2018-08-01 17:24:43 No stored folder metadata for "gvrhg-s54sr": recalculating
2018-08-01 17:24:43 Ready to synchronize "D:\\SyncMan" (gvrhg-s54sr) (readwrite)
2018-08-01 17:24:43 Completed initial scan of readwrite folder "D:\\SyncMan" (gvrhg-s54sr)
2018-08-01 17:27:04 Restarted folder "D:\\SyncMan" (gvrhg-s54sr) (readwrite)
2018-08-01 17:27:04 Completed initial scan of readwrite folder "D:\\SyncMan" (gvrhg-s54sr)
2018-08-01 17:27:15 Enabled debug data for "connections"
2018-08-01 17:27:16 Enabled debug data for "discover"
2018-08-01 17:27:19 Enabled debug data for "nat"
2018-08-01 17:27:20 Enabled debug data for "protocol"
2018-08-01 17:27:23 Enabled debug data for "sync"
2018-08-01 17:27:24 Enabled debug data for "upnp"
2018-08-01 17:27:24 Reconnect loop
2018-08-01 17:27:24 negative cache entry for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at global@https://discovery-v4.syncthing.net/v2/ valid until 2018-08-01 17:25:09.9102675 +0530 IST m=+125.275890501 or 2018-08-01 18:24:09.9102675 +0530 IST m=+3665.275890501
2018-08-01 17:27:24 negative cache entry for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at global@https://discovery-v6.syncthing.net/v2/ valid until 2018-08-01 17:25:09.9102675 +0530 IST m=+125.275890501 or 2018-08-01 18:24:09.9102675 +0530 IST m=+3665.275890501
2018-08-01 17:27:29 globalClient.Lookup https://discovery.syncthing.net/v2/?device=yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC Get https://discovery.syncthing.net/v2/?device=yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)
2018-08-01 17:27:29 lookup for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at IPv4 local
2018-08-01 17:27:29 addresses: []
2018-08-01 17:27:29 lookup for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at IPv6 local
2018-08-01 17:27:29 addresses: []
2018-08-01 17:27:29 lookup results for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:27:29 addresses:  []
2018-08-01 17:27:29 Reconnect loop for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC []
2018-08-01 17:27:29 sleep until next dial 1m0s
2018-08-01 17:27:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:27:36 discover: Received local announcement from 192.168.x.x:62600 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:27:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:27:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:27:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:27:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:06 discover: Received local announcement from 192.168.x.x:62600 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:07 Announcement: {"addresses":["relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY"]}
2018-08-01 17:28:07 announce POST: Post https://discovery-v6.syncthing.net/v2/: dial tcp: lookup discovery-v6.syncthing.net: getaddrinfow: The requested name is valid, but no data of the requested type was found.
2018-08-01 17:28:11 Announcement: {"addresses":["relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY"]}
2018-08-01 17:28:16 announce POST: Post https://discovery-v4.syncthing.net/v2/: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)
2018-08-01 17:28:29 Reconnect loop
2018-08-01 17:28:29 negative cache entry for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at global@https://discovery-v4.syncthing.net/v2/ valid until 2018-08-01 17:25:09.9102675 +0530 IST m=+125.275890501 or 2018-08-01 18:24:09.9102675 +0530 IST m=+3665.275890501
2018-08-01 17:28:29 negative cache entry for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at global@https://discovery-v6.syncthing.net/v2/ valid until 2018-08-01 17:25:09.9102675 +0530 IST m=+125.275890501 or 2018-08-01 18:24:09.9102675 +0530 IST m=+3665.275890501
2018-08-01 17:28:34 globalClient.Lookup https://discovery.syncthing.net/v2/?device=yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC Get https://discovery.syncthing.net/v2/?device=yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)
2018-08-01 17:28:34 lookup for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at IPv4 local
2018-08-01 17:28:34 addresses: []
2018-08-01 17:28:34 lookup for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at IPv6 local
2018-08-01 17:28:34 addresses: []
2018-08-01 17:28:34 lookup results for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:28:34 addresses:  []
2018-08-01 17:28:34 Reconnect loop for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC []
2018-08-01 17:28:34 sleep until next dial 1m0s
2018-08-01 17:28:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:36 discover: Received local announcement from 192.168.x.x:62600 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:28:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:06 discover: Received local announcement from 192.168.x.x:62600 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:34 Reconnect loop
2018-08-01 17:29:34 negative cache entry for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at global@https://discovery-v4.syncthing.net/v2/ valid until 2018-08-01 17:25:09.9102675 +0530 IST m=+125.275890501 or 2018-08-01 18:24:09.9102675 +0530 IST m=+3665.275890501
2018-08-01 17:29:34 negative cache entry for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at global@https://discovery-v6.syncthing.net/v2/ valid until 2018-08-01 17:25:09.9102675 +0530 IST m=+125.275890501 or 2018-08-01 18:24:09.9102675 +0530 IST m=+3665.275890501
2018-08-01 17:29:36 discover: Received local announcement from 192.168.x.x:62600 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:29:39 globalClient.Lookup https://discovery.syncthing.net/v2/?device=yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC Get https://discovery.syncthing.net/v2/?device=yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)
2018-08-01 17:29:39 lookup for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at IPv4 local
2018-08-01 17:29:39 addresses: []
2018-08-01 17:29:39 lookup for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at IPv6 local
2018-08-01 17:29:39 addresses: []
2018-08-01 17:29:39 lookup results for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:29:39 addresses:  []
2018-08-01 17:29:39 Reconnect loop for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC []
2018-08-01 17:29:39 sleep until next dial 1m0s
2018-08-01 17:30:06 discover: Received local announcement from 192.168.x.x:62600 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:06 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:36 discover: Received local announcement from 192.168.x.x:62600 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:36 discover: Received local announcement from [fe80::e0:0:0:0%Local Area Connection* 11]:62601 for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:30:39 Reconnect loop
2018-08-01 17:30:39 negative cache entry for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at global@https://discovery-v4.syncthing.net/v2/ valid until 2018-08-01 17:25:09.9102675 +0530 IST m=+125.275890501 or 2018-08-01 18:24:09.9102675 +0530 IST m=+3665.275890501
2018-08-01 17:30:39 negative cache entry for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at global@https://discovery-v6.syncthing.net/v2/ valid until 2018-08-01 17:25:09.9102675 +0530 IST m=+125.275890501 or 2018-08-01 18:24:09.9102675 +0530 IST m=+3665.275890501
2018-08-01 17:30:45 globalClient.Lookup https://discovery.syncthing.net/v2/?device=yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC Get https://discovery.syncthing.net/v2/?device=yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)
2018-08-01 17:30:45 lookup for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at IPv4 local
2018-08-01 17:30:45 addresses: []
2018-08-01 17:30:45 lookup for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC at IPv6 local
2018-08-01 17:30:45 addresses: []
2018-08-01 17:30:45 lookup results for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:30:45 addresses:  []
2018-08-01 17:30:45 Reconnect loop for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC []
2018-08-01 17:30:45 sleep until next dial 1m0s

===============================
Destination Device Logs
==============================

2018-08-01 17:42:52 syncthing v0.14.49 "Dysprosium Dragonfly" (go1.10.3 windows-amd64) teamcity@build.syncthing.net 2018-07-10 15:40:06 UTC
2018-08-01 17:42:52 My ID: yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:42:53 Single thread SHA256 performance is 297 MB/s using crypto/sha256 (293 MB/s using minio/sha256-simd).
2018-08-01 17:42:54 Hashing performance is 246.58 MB/s
2018-08-01 17:42:54 Ready to synchronize "DefaultInternal" (pxtwe-ocap4) (sendreceive)
2018-08-01 17:42:54 Overall send rate is unlimited, receive rate is unlimited
2018-08-01 17:42:54 Rate limits do not apply to LAN connections
2018-08-01 17:42:54 Using discovery server https://discovery-v4.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
2018-08-01 17:42:54 Using discovery server https://discovery-v6.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
2018-08-01 17:42:54 Using discovery server https://discovery.syncthing.net/v2/?noannounce&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
2018-08-01 17:42:54 ...
2018-08-01 17:42:54 Relay listener (relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY) starting
2018-08-01 17:42:54 Device yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC is "LaptopExternal" at [dynamic]
2018-08-01 17:42:54 Device QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 is "InternalVM" at [dynamic]
2018-08-01 17:42:54 Completed initial scan of sendreceive folder "DefaultInternal" (pxtwe-ocap4)
2018-08-01 17:42:54 GUI and API listening on 127.0.0.1:8384
2018-08-01 17:42:54 Access the GUI via the following URL: http://127.0.0.1:8384/
2018-08-01 17:43:04 Could not connect to relay relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY: read tcp 192.168.43.36:54837->94.16.122.162:443: i/o timeout
2018-08-01 17:43:04 Relay listener (relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY) shutting down
2018-08-01 17:43:04 Relay listener (relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY) starting
2018-08-01 17:43:04 c.S.listenerSupervisor: Failed service 'relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY' (1.000000 failures of 2.000000), restarting: true, error: "{relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY relay://94.16.122.162:443/?id=GZ6RB5P-WEQSZ6G-22BT5VP-QGPYDKF-BPFMI6J-4BSOIUF-ICTPSB3-6FMOCQY} returned unexpectedly", stacktrace: [unknown stack trace]
2018-08-01 17:43:06 Joined relay relay://94.16.122.162:443
2018-08-01 17:43:09 Enabled debug data for "connections"
2018-08-01 17:43:11 Reconnect loop
2018-08-01 17:43:11 Reconnect loop for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 []
2018-08-01 17:43:11 initial rampup; sleep 16s and update to 32s
2018-08-01 17:43:11 Enabled debug data for "discover"
2018-08-01 17:43:13 Enabled debug data for "nat"
2018-08-01 17:43:14 Enabled debug data for "protocol"
2018-08-01 17:43:16 Enabled debug data for "sync"
2018-08-01 17:43:18 Enabled debug data for "upnp"
2018-08-01 17:43:24 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:24 discover: Received local announcement from 192.168.43.36:58904 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:24 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:24 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:24 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:24 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:27 Reconnect loop
2018-08-01 17:43:27 negative cache entry for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at global@https://discovery-v4.syncthing.net/v2/ valid until 2018-08-01 17:43:54.298492 +0530 IST m=+61.597419701 or 2018-08-01 18:42:54.298492 +0530 IST m=+3601.597419701
2018-08-01 17:43:27 negative cache entry for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at global@https://discovery-v6.syncthing.net/v2/ valid until 2018-08-01 17:43:54.298492 +0530 IST m=+61.597419701 or 2018-08-01 18:42:54.298492 +0530 IST m=+3601.597419701
2018-08-01 17:43:27 negative cache entry for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at global@https://discovery.syncthing.net/v2/ valid until 2018-08-01 17:43:56.4599984 +0530 IST m=+63.758994601 or 2018-08-01 17:52:28.4599984 +0530 IST m=+575.758994601
2018-08-01 17:43:27 lookup for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at IPv4 local
2018-08-01 17:43:27 addresses: []
2018-08-01 17:43:27 lookup for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at IPv6 local
2018-08-01 17:43:27 addresses: []
2018-08-01 17:43:27 lookup results for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:43:27 addresses:  []
2018-08-01 17:43:27 Reconnect loop for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 []
2018-08-01 17:43:27 initial rampup; sleep 32s and update to 1m4s
2018-08-01 17:43:54 discover: Received local announcement from 192.168.43.36:58904 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:54 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:54 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:54 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:54 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:54 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:43:59 Reconnect loop
2018-08-01 17:43:59 negative cache entry for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at global@https://discovery-v4.syncthing.net/v2/ valid until 2018-08-01 17:43:54.298492 +0530 IST m=+61.597419701 or 2018-08-01 18:42:54.298492 +0530 IST m=+3601.597419701
2018-08-01 17:43:59 negative cache entry for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at global@https://discovery-v6.syncthing.net/v2/ valid until 2018-08-01 17:43:54.298492 +0530 IST m=+61.597419701 or 2018-08-01 18:42:54.298492 +0530 IST m=+3601.597419701
2018-08-01 17:43:59 negative cache entry for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at global@https://discovery.syncthing.net/v2/ valid until 2018-08-01 17:43:56.4599984 +0530 IST m=+63.758994601 or 2018-08-01 17:52:28.4599984 +0530 IST m=+575.758994601
2018-08-01 17:43:59 lookup for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at IPv4 local
2018-08-01 17:43:59 addresses: []
2018-08-01 17:43:59 lookup for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at IPv6 local
2018-08-01 17:43:59 addresses: []
2018-08-01 17:43:59 lookup results for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:43:59 addresses:  []
2018-08-01 17:43:59 Reconnect loop for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 []
2018-08-01 17:43:59 sleep until next dial 1m0s
2018-08-01 17:44:24 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:24 discover: Received local announcement from 192.168.43.36:58904 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:24 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:24 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:24 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:24 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:54 discover: Received local announcement from 192.168.43.36:58904 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:54 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:54 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:54 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:54 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:54 discover: Received local announcement from [fe80::5445:e9dc:c113:f043%Ethernet]:58905 for yyyyyK-INyyyyy-yyyyyRL-yyyyyF-5yyyyy-yyyyy4V-yyyyyT5-TR2JSAC
2018-08-01 17:44:59 Reconnect loop
2018-08-01 17:44:59 negative cache entry for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at global@https://discovery-v4.syncthing.net/v2/ valid until 2018-08-01 17:43:54.298492 +0530 IST m=+61.597419701 or 2018-08-01 18:42:54.298492 +0530 IST m=+3601.597419701
2018-08-01 17:44:59 negative cache entry for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at global@https://discovery-v6.syncthing.net/v2/ valid until 2018-08-01 17:43:54.298492 +0530 IST m=+61.597419701 or 2018-08-01 18:42:54.298492 +0530 IST m=+3601.597419701
2018-08-01 17:44:59 negative cache entry for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at global@https://discovery.syncthing.net/v2/ valid until 2018-08-01 17:43:56.4599984 +0530 IST m=+63.758994601 or 2018-08-01 17:52:28.4599984 +0530 IST m=+575.758994601
2018-08-01 17:44:59 lookup for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at IPv4 local
2018-08-01 17:44:59 addresses: []
2018-08-01 17:44:59 lookup for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 at IPv6 local
2018-08-01 17:44:59 addresses: []
2018-08-01 17:44:59 lookup results for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3
2018-08-01 17:44:59 addresses:  []
2018-08-01 17:44:59 Reconnect loop for QWZLDZR-6Yxxxx-xxxxCF-KxxxxX-xxxxBNY-Kxxxx-xxxxZC-xxxxA3 []
2018-08-01 17:44:59 sleep until next dial 1m0s

Appreciate if you can help us what we are missing and next steps.

Thanks alot.

AudriusButkevicius · August 1, 2018, 5:20pm

Is discover enabled on both devices?

Given you are blocking outbound traffic, it seems that you don’t whitelist discovery. We make no guarantees about where discovery servers will run or on what IP address on so in general case you’d have to whitelist outgoing https traffic to a domain rather than ip or just whitelist it in general.

You should see discovery status in the web ui, together with addresses discovered for the remote device showing if discovery is working, which might help you understand whats happening. The log segments you pasted are not full logs and are not immensly useful.

venkat_Bang · August 10, 2018, 6:03am

Thanks Audrius for the reply. yeah our corporate IT won’t allow to whitelist. would you elaborate the domain concept of discovery so that we can request IT to open.

is there any specific IP address with Port which we can request to open outbound ?

In the current logs we see these servers …

[7IFVJ] 14:33:05 INFO: Using discovery server https://discovery-v4.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW [7IFVJ] 14:33:05 INFO: Using discovery server https://discovery-v6.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW [7IFVJ] 14:33:05 INFO: Using discovery server https://discovery.syncthing.net/v2/?noannounce&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW

any help appreciated ?

AudriusButkevicius · August 10, 2018, 6:36am

Your can resolve the domain names yourself, but as I said, its unlikely to work forever, because we make no guarantees where the discovery servers are located.

venkat_Bang · August 10, 2018, 11:59am

Sure, I have couple of questions.

Instead of URL , shall we give IP address with Port to whitelist.
when I resolve the https://discovery-v4.syncthing.net and https://discovery.syncthing.net. It is resulting these 2 IP address 198.211.120.59 139.59.84.212

if open this IP and 8443 port, would it work

calmh · August 10, 2018, 12:09pm

Those looks like the current discovery IPv4 addresses, yes. I’m not sure what port 8443 is for? Syncthing uses port 22000 by default for sync connections, relays use various different ports. You will need to permit both discovery and sync connections, somehow.

system · September 9, 2018, 12:09pm

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