gistfile1.txt

[monitor] 2017/11/20 16:11:30.339780 monitor.go:95: INFO: Starting syncthing
[IDID_17] 2017/11/20 16:11:30.354718 main.go:649: INFO: syncthing v0.14.40 "Dysprosium Dragonfly" (go1.9.2 linux-amd64) deb@build.syncthing.net 2017-10-28 19:15:32 UTC [noupgrade]
[IDID_17] 2017/11/20 16:11:30.354866 main.go:650: INFO: My ID: IDID-IDID-IDID-IDID_17
[IDID_17] 2017/11/20 16:11:31.429280 sha256.go:92: INFO: Single thread SHA256 performance is 231 MB/s using minio/sha256-simd (123 MB/s using crypto/sha256).
[IDID_17] 2017/11/20 16:11:32.036468 main.go:709: INFO: Hashing performance with weak hash is 97.28 MB/s
[IDID_17] 2017/11/20 16:11:32.637599 main.go:711: INFO: Hashing performance without weak hash is 137.05 MB/s
[IDID_17] 2017/11/20 16:11:32.637729 main.go:714: INFO: Weak hash disabled, as it has an unacceptable performance impact.
[IDID_17] 2017/11/20 16:11:33.740580 model.go:182: INFO: Ready to synchronize "FOLDER_LABEL_31" (FOLDER_ID_31) (readwrite)
[IDID_17] 2017/11/20 16:11:33.740668 limiter.go:84: INFO: Send rate is unlimited, receive rate is unlimited
[IDID_17] 2017/11/20 16:11:33.740688 limiter.go:89: INFO: Rate limits do not apply to LAN connections
[IDID_17] 2017/11/20 16:11:33.740979 service.go:443: DEBUG: Starting listener dynamic+https://relays.syncthing.net/endpoint
[IDID_17] 2017/11/20 16:11:33.741059 service.go:443: DEBUG: Starting listener kcp://IPv4_0:PORT_2
[IDID_17] 2017/11/20 16:11:33.741109 service.go:443: DEBUG: Starting listener tcp://IPv4_0:PORT_1
[IDID_17] 2017/11/20 16:11:33.741124 service.go:520: DEBUG: Starting NAT service
[IDID_17] 2017/11/20 16:11:33.741187 main.go:826: INFO: Using discovery server https://discovery-v4-2.syncthing.net/v2/?id=DVU36WY-H3LVZHW-E6LLFRE-YAFN5EL-HILWRYP-OC2M47J-Z4PE62Y-ADIBDQC
[IDID_17] 2017/11/20 16:11:33.741234 main.go:826: INFO: Using discovery server https://discovery-v4-3.syncthing.net/v2/?id=VK6HNJ3-VVMM66S-HRVWSCR-IXEHL2H-U4AQ4MW-UCPQBWX-J2L2UBK-NVZRDQZ
[IDID_17] 2017/11/20 16:11:33.741259 main.go:826: INFO: Using discovery server https://discovery-v4-4.syncthing.net/v2/?id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
[IDID_17] 2017/11/20 16:11:33.741283 main.go:826: INFO: Using discovery server https://discovery-v6-2.syncthing.net/v2/?id=DVU36WY-H3LVZHW-E6LLFRE-YAFN5EL-HILWRYP-OC2M47J-Z4PE62Y-ADIBDQC
[IDID_17] 2017/11/20 16:11:33.741304 main.go:826: INFO: Using discovery server https://discovery-v6-3.syncthing.net/v2/?id=VK6HNJ3-VVMM66S-HRVWSCR-IXEHL2H-U4AQ4MW-UCPQBWX-J2L2UBK-NVZRDQZ
[IDID_17] 2017/11/20 16:11:33.741325 main.go:826: INFO: Using discovery server https://discovery-v6-4.syncthing.net/v2/?id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW
[IDID_17] 2017/11/20 16:11:33.741370 main.go:871: INFO: Device IDID-IDID-IDID-IDID_9 is "DEVICE_9" at [dynamic]
[IDID_17] 2017/11/20 16:11:33.741386 main.go:871: INFO: Device IDID-IDID-IDID-IDID_17 is "DEVICE_17" at [dynamic]
[IDID_17] 2017/11/20 16:11:33.741414 tcp_listen.go:69: INFO: TCP listener ([::]:PORT_1) starting
[IDID_17] 2017/11/20 16:11:33.741472 main.go:871: INFO: Device IDID-IDID-IDID-IDID_1 is "DEVICE_1" at [dynamic]
[IDID_17] 2017/11/20 16:11:33.741488 main.go:871: INFO: Device IDID-IDID-IDID-IDID_0 is "DEVICE_0" at [dynamic]
[IDID_17] 2017/11/20 16:11:33.741502 main.go:871: INFO: Device IDID-IDID-IDID-IDID_2 is "DEVICE_2" at [dynamic]
[IDID_17] 2017/11/20 16:11:33.741576 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:11:33.741597 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:11:33.741679 kcp_listen.go:89: INFO: KCP listener ([::]:PORT_2) starting
[IDID_17] 2017/11/20 16:11:33.761934 kcp_listen.go:120: DEBUG: connect from IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:11:34.843698 folder.go:128: INFO: Completed initial scan of readwrite folder "FOLDER_LABEL_31" (FOLDER_ID_31)
[IDID_17] 2017/11/20 16:11:39.891173 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 kcp://IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:11:39.891259 kcp_dial.go:42: DEBUG: dial kcp://IPv4_9:PORT_2 using existing conn on [::]:PORT_2
[IDID_17] 2017/11/20 16:11:43.543109 kcp_listen.go:247: INFO: kcp://IPv4_0:PORT_2 detected NAT type: Symetric NAT
[IDID_17] 2017/11/20 16:11:43.543154 kcp_listen.go:260: INFO: kcp://IPv4_0:PORT_2 resolved external address kcp://IPv4_1:PORT_2 (via stun.counterpath.net:3478)
[IDID_17] 2017/11/20 16:11:43.747987 service.go:61: INFO: Detected 0 NAT devices
[IDID_17] 2017/11/20 16:11:43.762452 kcp_listen.go:142: DEBUG: TLS handshake (BEP/kcp): i/o timeout
[IDID_17] 2017/11/20 16:11:44.278787 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:11:44.279168 service.go:284: INFO: Established secure connection to IDID-IDID-IDID-IDID_9 at [::]:PORT_2-IPv4_9:PORT_2 (kcp-client) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305)
[IDID_17] 2017/11/20 16:11:44.279238 model.go:1485: INFO: Device IDID-IDID-IDID-IDID_9 client is "syncthing v0.14.40" named "DEVICE_9"
[IDID_17] 2017/11/20 16:11:47.396072 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_0
[IDID_17] 2017/11/20 16:11:50.455675 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 0s, next dial is at 2017-11-20 16:11:57.741587278 +0000 UTC m=+27.400347794 and current time is 2017-11-20 16:11:33.741587278 +0000 UTC m=+3.400347794
[IDID_17] 2017/11/20 16:11:50.455717 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_0 kcp://IPv4_9:PORT_9
[IDID_17] 2017/11/20 16:11:50.455784 kcp_dial.go:42: DEBUG: dial kcp://IPv4_9:PORT_9 using existing conn on [::]:PORT_2
[IDID_17] 2017/11/20 16:11:50.647467 static.go:93: INFO: Joined relay relay://IPv4_7:PORT_3
[IDID_17] 2017/11/20 16:11:51.903755 kcp_listen.go:120: DEBUG: connect from IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:12:00.456441 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_0 kcp://IPv4_9:PORT_9 i/o timeout
[IDID_17] 2017/11/20 16:12:00.457526 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_0 relay://IPv4_10:PORT_3/?id=FS33S3S-IB6TKQE-DZBKBJ4-4YUFJDS-RV6T7HU-BM77ACQ-UTXS2D6-IDXKYAO&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=
[IDID_17] 2017/11/20 16:12:00.732041 relay_dial.go:48: DEBUG: failed to set traffic class: operation not supported
[IDID_17] 2017/11/20 16:12:01.206254 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:12:01.302475 service.go:284: INFO: Established secure connection to IDID-IDID-IDID-IDID_0 at IPv4_5:PORT_10-IPv4_10:PORT_3 (relay-client) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305)
[IDID_17] 2017/11/20 16:12:01.303143 model.go:1485: INFO: Device IDID-IDID-IDID-IDID_0 client is "syncthing v0.14.40" named "DEVICE_0"
[IDID_17] 2017/11/20 16:12:01.905133 kcp_listen.go:142: DEBUG: TLS handshake (BEP/kcp): i/o timeout
[IDID_17] 2017/11/20 16:12:03.858355 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 0s, next dial is at 2017-11-20 16:11:57.741587278 +0000 UTC m=+27.400347794 and current time is 2017-11-20 16:11:33.741587278 +0000 UTC m=+3.400347794
[IDID_17] 2017/11/20 16:12:03.858404 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_2 relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=
[IDID_17] 2017/11/20 16:12:04.027297 relay_dial.go:48: DEBUG: failed to set traffic class: operation not supported
[IDID_17] 2017/11/20 16:12:05.449459 service.go:417: DEBUG: initial rampup; sleep 1s and update to 2s
[IDID_17] 2017/11/20 16:12:05.555007 service.go:284: INFO: Established secure connection to IDID-IDID-IDID-IDID_2 at IPv4_5:PORT_4-IPv4_3:PORT_3 (relay-client) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305)
[IDID_17] 2017/11/20 16:12:05.555094 model.go:1485: INFO: Device IDID-IDID-IDID-IDID_2 client is "syncthing v0.14.40" named "DEVICE_2"
[IDID_17] 2017/11/20 16:12:06.449742 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:12:06.449942 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:12:06.450029 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:12:06.450042 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:12:06.450050 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:12:06.450057 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:12:06.450074 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:12:16.450390 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:12:16.450478 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:12:16.450501 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:12:16.450610 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_0
[IDID_17] 2017/11/20 16:12:16.450722 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_0 kcp://IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:12:16.450834 kcp_dial.go:42: DEBUG: dial kcp://IPv4_9:PORT_2 using existing conn on [::]:PORT_2
[IDID_17] 2017/11/20 16:12:17.365553 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:12:17.365653 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_2 kcp://IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:12:17.365724 kcp_dial.go:42: DEBUG: dial kcp://IPv4_9:PORT_2 using existing conn on [::]:PORT_2
[IDID_17] 2017/11/20 16:12:17.383610 service.go:247: INFO: Connected to already connected device (IDID-IDID-IDID-IDID_9)
[IDID_17] 2017/11/20 16:12:18.018265 service.go:421: DEBUG: sleep until next dial -8.708195881s
[IDID_17] 2017/11/20 16:12:18.018326 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:12:18.018450 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:12:18.018543 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is -8.708195881s, next dial is at 2017-11-20 16:12:30.449783161 +0000 UTC m=+60.108543675 and current time is 2017-11-20 16:12:18.018332753 +0000 UTC m=+47.677093267
[IDID_17] 2017/11/20 16:12:18.018568 service.go:364: DEBUG: Not dialing relay://IPv4_2:PORT_6/?id=VQKG3OG-7LVNC7S-4FP2JCE-Q42EEPY-UWHUS5K-PGWJSWD-2OGHYUI-SEMMXQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is -8.708195881s, next dial is at 2017-11-20 16:13:06.449783161 +0000 UTC m=+96.108543675 and current time is 2017-11-20 16:12:18.018332753 +0000 UTC m=+47.677093267
[IDID_17] 2017/11/20 16:12:18.018582 service.go:364: DEBUG: Not dialing relay://IPv4_4:PORT_3/?id=LS45IVJ-ZGE3OST-KOZ5FH6-YAGVMID-5ZBMBZU-MJ74EN6-H2OV77N-GDTFMQ5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is -8.708195881s, next dial is at 2017-11-20 16:13:06.449783161 +0000 UTC m=+96.108543675 and current time is 2017-11-20 16:12:18.018332753 +0000 UTC m=+47.677093267
[IDID_17] 2017/11/20 16:12:18.018594 service.go:364: DEBUG: Not dialing relay://IPv4_8:PORT_3/?id=5ULHJDG-7SQLTCG-XGMEZAO-4Y7IRFC-JAGHR3A-YXOCU4Q-XJKRAT5-4YVS5A5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=1048576&statusAddr=:22070&providedBy=ronny as sleep is -8.708195881s, next dial is at 2017-11-20 16:13:06.449783161 +0000 UTC m=+96.108543675 and current time is 2017-11-20 16:12:18.018332753 +0000 UTC m=+47.677093267
[IDID_17] 2017/11/20 16:12:18.018606 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is -8.708195881s, next dial is at 2017-11-20 16:13:06.449783161 +0000 UTC m=+96.108543675 and current time is 2017-11-20 16:12:18.018332753 +0000 UTC m=+47.677093267
[IDID_17] 2017/11/20 16:12:18.018621 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:12:18.018674 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_0
[IDID_17] 2017/11/20 16:12:18.018711 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is -8.708195881s, next dial is at 2017-11-20 16:12:30.449783161 +0000 UTC m=+60.108543675 and current time is 2017-11-20 16:12:18.018332753 +0000 UTC m=+47.677093267
[IDID_17] 2017/11/20 16:12:18.018737 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_0 kcp://IPv4_9:PORT_9
[IDID_17] 2017/11/20 16:12:18.018814 kcp_dial.go:42: DEBUG: dial kcp://IPv4_9:PORT_9 using existing conn on [::]:PORT_2
[IDID_17] 2017/11/20 16:12:18.027638 service.go:247: INFO: Connected to already connected device (IDID-IDID-IDID-IDID_9)
[IDID_17] 2017/11/20 16:12:28.019242 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_0 kcp://IPv4_9:PORT_9 i/o timeout
[IDID_17] 2017/11/20 16:12:28.019308 service.go:364: DEBUG: Not dialing relay://IPv4_10:PORT_3/?id=FS33S3S-IB6TKQE-DZBKBJ4-4YUFJDS-RV6T7HU-BM77ACQ-UTXS2D6-IDXKYAO&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is -8.708195881s, next dial is at 2017-11-20 16:21:33.741587278 +0000 UTC m=+603.400347794 and current time is 2017-11-20 16:12:18.018332753 +0000 UTC m=+47.677093267
[IDID_17] 2017/11/20 16:12:28.019340 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_0 tcp://IPv4_9:PORT_0
[IDID_17] 2017/11/20 16:12:28.091675 tcp_dial.go:47: DEBUG: failed to set traffic class: operation not supported
[IDID_17] 2017/11/20 16:12:28.289169 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:12:28.289333 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is -8.708195881s, next dial is at 2017-11-20 16:12:30.449783161 +0000 UTC m=+60.108543675 and current time is 2017-11-20 16:12:18.018332753 +0000 UTC m=+47.677093267
[IDID_17] 2017/11/20 16:12:28.289366 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is -8.708195881s, next dial is at 2017-11-20 16:21:33.741587278 +0000 UTC m=+603.400347794 and current time is 2017-11-20 16:12:18.018332753 +0000 UTC m=+47.677093267
[IDID_17] 2017/11/20 16:12:28.289389 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:12:28.289400 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is -8.708195881s, next dial is at 2017-11-20 16:13:06.449783161 +0000 UTC m=+96.108543675 and current time is 2017-11-20 16:12:18.018332753 +0000 UTC m=+47.677093267
[IDID_17] 2017/11/20 16:12:28.289424 service.go:417: DEBUG: initial rampup; sleep 2s and update to 4s
[IDID_17] 2017/11/20 16:12:28.359247 service.go:239: DEBUG: Switching connections IDID-IDID-IDID-IDID_0
[IDID_17] 2017/11/20 16:12:28.359313 service.go:284: INFO: Established secure connection to IDID-IDID-IDID-IDID_0 at IPv4_5:PORT_8-IPv4_9:PORT_0 (tcp-client) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305)
[IDID_17] 2017/11/20 16:12:28.359351 model.go:1451: INFO: Replacing old connection IPv4_5:PORT_10-IPv4_10:PORT_3/relay-client with IPv4_5:PORT_8-IPv4_9:PORT_0/tcp-client for IDID-IDID-IDID-IDID_0
[IDID_17] 2017/11/20 16:12:28.359840 model.go:1210: INFO: Connection to IDID-IDID-IDID-IDID_0 closed: reading length: read tcp IPv4_5:PORT_10->IPv4_10:PORT_3: use of closed network connection
[IDID_17] 2017/11/20 16:12:28.359978 model.go:1485: INFO: Device IDID-IDID-IDID-IDID_0 client is "syncthing v0.14.40" named "DEVICE_0"
[IDID_17] 2017/11/20 16:12:30.290147 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:12:30.290281 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:12:30.290352 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:12:30.290364 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:12:30.290371 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:12:30.290378 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:12:30.290393 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:12:40.290739 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:12:40.290812 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:12:40.290833 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:12:40.291017 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:12:40.291115 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_2 kcp://IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:12:40.291222 kcp_dial.go:42: DEBUG: dial kcp://IPv4_9:PORT_2 using existing conn on [::]:PORT_2
[IDID_17] 2017/11/20 16:12:43.303175 service.go:417: DEBUG: initial rampup; sleep 4s and update to 8s
[IDID_17] 2017/11/20 16:12:43.303502 service.go:247: INFO: Connected to already connected device (IDID-IDID-IDID-IDID_9)
[IDID_17] 2017/11/20 16:12:47.303421 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:12:47.303538 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:12:47.307661 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:12:47.307700 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:12:47.307709 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:12:47.307717 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:12:47.307735 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:12:57.308147 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:12:57.308219 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:12:57.308238 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:12:57.313056 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:12:57.313121 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_2 kcp://IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:12:57.313192 kcp_dial.go:42: DEBUG: dial kcp://IPv4_9:PORT_2 using existing conn on [::]:PORT_2
[IDID_17] 2017/11/20 16:13:07.313669 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_2 kcp://IPv4_9:PORT_2 i/o timeout
[IDID_17] 2017/11/20 16:13:07.313753 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:13:07.313765 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:13:07.313787 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_2 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:13:17.314028 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:13:17.314168 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_2 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:13:17.314197 service.go:417: DEBUG: initial rampup; sleep 8s and update to 16s
[IDID_17] 2017/11/20 16:13:25.314395 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:13:25.314794 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:13:25.314893 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:13:25.314913 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:13:25.314926 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:13:25.314934 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:13:25.314955 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:13:35.315272 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:13:35.315338 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:13:35.315359 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:13:35.315512 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:13:35.319278 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_2 kcp://IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:13:35.319482 kcp_dial.go:42: DEBUG: dial kcp://IPv4_9:PORT_2 using existing conn on [::]:PORT_2
[IDID_17] 2017/11/20 16:13:36.989245 service.go:417: DEBUG: initial rampup; sleep 16s and update to 32s
[IDID_17] 2017/11/20 16:13:37.037160 service.go:247: INFO: Connected to already connected device (IDID-IDID-IDID-IDID_9)
[IDID_17] 2017/11/20 16:13:52.989561 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:13:52.990476 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:13:52.994156 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:13:52.994209 service.go:364: DEBUG: Not dialing relay://IPv4_2:PORT_6/?id=VQKG3OG-7LVNC7S-4FP2JCE-Q42EEPY-UWHUS5K-PGWJSWD-2OGHYUI-SEMMXQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 21.989023945s, next dial is at 2017-11-20 16:14:25.314435008 +0000 UTC m=+174.973195520 and current time is 2017-11-20 16:13:52.990329171 +0000 UTC m=+142.649089691
[IDID_17] 2017/11/20 16:13:52.994223 service.go:364: DEBUG: Not dialing relay://IPv4_4:PORT_3/?id=LS45IVJ-ZGE3OST-KOZ5FH6-YAGVMID-5ZBMBZU-MJ74EN6-H2OV77N-GDTFMQ5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 21.989023945s, next dial is at 2017-11-20 16:14:25.314435008 +0000 UTC m=+174.973195520 and current time is 2017-11-20 16:13:52.990329171 +0000 UTC m=+142.649089691
[IDID_17] 2017/11/20 16:13:52.994235 service.go:364: DEBUG: Not dialing relay://IPv4_8:PORT_3/?id=5ULHJDG-7SQLTCG-XGMEZAO-4Y7IRFC-JAGHR3A-YXOCU4Q-XJKRAT5-4YVS5A5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=1048576&statusAddr=:22070&providedBy=ronny as sleep is 21.989023945s, next dial is at 2017-11-20 16:14:25.314435008 +0000 UTC m=+174.973195520 and current time is 2017-11-20 16:13:52.990329171 +0000 UTC m=+142.649089691
[IDID_17] 2017/11/20 16:13:52.994246 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 21.989023945s, next dial is at 2017-11-20 16:14:25.314435008 +0000 UTC m=+174.973195520 and current time is 2017-11-20 16:13:52.990329171 +0000 UTC m=+142.649089691
[IDID_17] 2017/11/20 16:13:52.994264 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:13:52.994346 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:13:52.994391 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 21.989023945s, next dial is at 2017-11-20 16:14:52.990329171 +0000 UTC m=+202.649089691 and current time is 2017-11-20 16:13:52.990329171 +0000 UTC m=+142.649089691
[IDID_17] 2017/11/20 16:13:52.994406 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:13:52.994415 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:13:52.994426 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 21.989023945s, next dial is at 2017-11-20 16:14:25.314435008 +0000 UTC m=+174.973195520 and current time is 2017-11-20 16:13:52.990329171 +0000 UTC m=+142.649089691
[IDID_17] 2017/11/20 16:13:52.994440 service.go:417: DEBUG: initial rampup; sleep 32s and update to 1m4s
[IDID_17] 2017/11/20 16:14:02.714797 kcp_listen.go:120: DEBUG: connect from IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:14:12.715369 kcp_listen.go:142: DEBUG: TLS handshake (BEP/kcp): i/o timeout
[IDID_17] 2017/11/20 16:14:24.994786 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:14:24.995231 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:14:24.995319 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 32.324105829s, next dial is at 2017-11-20 16:14:52.990329171 +0000 UTC m=+202.649089691 and current time is 2017-11-20 16:14:24.99512486 +0000 UTC m=+174.653885379
[IDID_17] 2017/11/20 16:14:24.995341 service.go:364: DEBUG: Not dialing relay://IPv4_2:PORT_6/?id=VQKG3OG-7LVNC7S-4FP2JCE-Q42EEPY-UWHUS5K-PGWJSWD-2OGHYUI-SEMMXQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 32.324105829s, next dial is at 2017-11-20 16:14:25.314435008 +0000 UTC m=+174.973195520 and current time is 2017-11-20 16:14:24.99512486 +0000 UTC m=+174.653885379
[IDID_17] 2017/11/20 16:14:24.995407 service.go:364: DEBUG: Not dialing relay://IPv4_4:PORT_3/?id=LS45IVJ-ZGE3OST-KOZ5FH6-YAGVMID-5ZBMBZU-MJ74EN6-H2OV77N-GDTFMQ5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 32.324105829s, next dial is at 2017-11-20 16:14:25.314435008 +0000 UTC m=+174.973195520 and current time is 2017-11-20 16:14:24.99512486 +0000 UTC m=+174.653885379
[IDID_17] 2017/11/20 16:14:24.995423 service.go:364: DEBUG: Not dialing relay://IPv4_8:PORT_3/?id=5ULHJDG-7SQLTCG-XGMEZAO-4Y7IRFC-JAGHR3A-YXOCU4Q-XJKRAT5-4YVS5A5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=1048576&statusAddr=:22070&providedBy=ronny as sleep is 32.324105829s, next dial is at 2017-11-20 16:14:25.314435008 +0000 UTC m=+174.973195520 and current time is 2017-11-20 16:14:24.99512486 +0000 UTC m=+174.653885379
[IDID_17] 2017/11/20 16:14:24.995434 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 32.324105829s, next dial is at 2017-11-20 16:14:25.314435008 +0000 UTC m=+174.973195520 and current time is 2017-11-20 16:14:24.99512486 +0000 UTC m=+174.653885379
[IDID_17] 2017/11/20 16:14:24.995446 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:14:24.999660 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:14:24.999748 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 32.324105829s, next dial is at 2017-11-20 16:14:52.990329171 +0000 UTC m=+202.649089691 and current time is 2017-11-20 16:14:24.99512486 +0000 UTC m=+174.653885379
[IDID_17] 2017/11/20 16:14:24.999768 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 32.324105829s, next dial is at 2017-11-20 16:14:52.990329171 +0000 UTC m=+202.649089691 and current time is 2017-11-20 16:14:24.99512486 +0000 UTC m=+174.653885379
[IDID_17] 2017/11/20 16:14:24.999784 service.go:364: DEBUG: Not dialing relay://IPv4_6:PORT_3/?id=YRB2IWT-7R23EWJ-3CMK5IQ-TY5U24F-ND4O25S-UZI4BE2-33TTB3U-BUHGVQD&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 32.324105829s, next dial is at 2017-11-20 16:14:52.990329171 +0000 UTC m=+202.649089691 and current time is 2017-11-20 16:14:24.99512486 +0000 UTC m=+174.653885379
[IDID_17] 2017/11/20 16:14:24.999798 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 32.324105829s, next dial is at 2017-11-20 16:14:25.314435008 +0000 UTC m=+174.973195520 and current time is 2017-11-20 16:14:24.99512486 +0000 UTC m=+174.653885379
[IDID_17] 2017/11/20 16:14:24.999813 service.go:421: DEBUG: sleep until next dial 319.310141ms
[IDID_17] 2017/11/20 16:14:25.319360 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:14:25.319483 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:14:25.319569 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 319.310141ms, next dial is at 2017-11-20 16:14:52.990329171 +0000 UTC m=+202.649089691 and current time is 2017-11-20 16:14:25.319396421 +0000 UTC m=+174.978156948
[IDID_17] 2017/11/20 16:14:25.319597 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:14:25.319607 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:14:25.319616 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:14:25.319634 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:14:35.320994 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:14:35.321201 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:14:35.321224 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:14:35.321555 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:14:35.325656 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 319.310141ms, next dial is at 2017-11-20 16:14:52.990329171 +0000 UTC m=+202.649089691 and current time is 2017-11-20 16:14:25.319396421 +0000 UTC m=+174.978156948
[IDID_17] 2017/11/20 16:14:35.325689 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 319.310141ms, next dial is at 2017-11-20 16:14:52.990329171 +0000 UTC m=+202.649089691 and current time is 2017-11-20 16:14:25.319396421 +0000 UTC m=+174.978156948
[IDID_17] 2017/11/20 16:14:35.325701 service.go:364: DEBUG: Not dialing relay://IPv4_6:PORT_3/?id=YRB2IWT-7R23EWJ-3CMK5IQ-TY5U24F-ND4O25S-UZI4BE2-33TTB3U-BUHGVQD&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 319.310141ms, next dial is at 2017-11-20 16:14:52.990329171 +0000 UTC m=+202.649089691 and current time is 2017-11-20 16:14:25.319396421 +0000 UTC m=+174.978156948
[IDID_17] 2017/11/20 16:14:35.325712 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 319.310141ms, next dial is at 2017-11-20 16:15:25.319396421 +0000 UTC m=+234.978156948 and current time is 2017-11-20 16:14:25.319396421 +0000 UTC m=+174.978156948
[IDID_17] 2017/11/20 16:14:35.325726 service.go:421: DEBUG: sleep until next dial 27.670932743s
[IDID_17] 2017/11/20 16:15:02.996927 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:15:02.997110 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:15:03.002594 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:15:03.002651 service.go:364: DEBUG: Not dialing relay://IPv4_2:PORT_6/?id=VQKG3OG-7LVNC7S-4FP2JCE-Q42EEPY-UWHUS5K-PGWJSWD-2OGHYUI-SEMMXQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 27.670932743s, next dial is at 2017-11-20 16:15:25.319396421 +0000 UTC m=+234.978156948 and current time is 2017-11-20 16:15:02.996975639 +0000 UTC m=+212.655736152
[IDID_17] 2017/11/20 16:15:03.002714 service.go:364: DEBUG: Not dialing relay://IPv4_4:PORT_3/?id=LS45IVJ-ZGE3OST-KOZ5FH6-YAGVMID-5ZBMBZU-MJ74EN6-H2OV77N-GDTFMQ5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 27.670932743s, next dial is at 2017-11-20 16:15:25.319396421 +0000 UTC m=+234.978156948 and current time is 2017-11-20 16:15:02.996975639 +0000 UTC m=+212.655736152
[IDID_17] 2017/11/20 16:15:03.002742 service.go:364: DEBUG: Not dialing relay://IPv4_8:PORT_3/?id=5ULHJDG-7SQLTCG-XGMEZAO-4Y7IRFC-JAGHR3A-YXOCU4Q-XJKRAT5-4YVS5A5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=1048576&statusAddr=:22070&providedBy=ronny as sleep is 27.670932743s, next dial is at 2017-11-20 16:15:25.319396421 +0000 UTC m=+234.978156948 and current time is 2017-11-20 16:15:02.996975639 +0000 UTC m=+212.655736152
[IDID_17] 2017/11/20 16:15:03.002752 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 27.670932743s, next dial is at 2017-11-20 16:15:25.319396421 +0000 UTC m=+234.978156948 and current time is 2017-11-20 16:15:02.996975639 +0000 UTC m=+212.655736152
[IDID_17] 2017/11/20 16:15:03.002829 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:15:03.002947 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:15:03.002999 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 27.670932743s, next dial is at 2017-11-20 16:16:02.996975639 +0000 UTC m=+272.655736152 and current time is 2017-11-20 16:15:02.996975639 +0000 UTC m=+212.655736152
[IDID_17] 2017/11/20 16:15:03.003010 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:15:03.003016 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:15:03.003024 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 27.670932743s, next dial is at 2017-11-20 16:15:25.319396421 +0000 UTC m=+234.978156948 and current time is 2017-11-20 16:15:02.996975639 +0000 UTC m=+212.655736152
[IDID_17] 2017/11/20 16:15:03.003034 service.go:421: DEBUG: sleep until next dial 22.322420796s
[IDID_17] 2017/11/20 16:15:25.325636 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:15:25.325828 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:15:25.325902 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 22.322420796s, next dial is at 2017-11-20 16:16:02.996975639 +0000 UTC m=+272.655736152 and current time is 2017-11-20 16:15:25.325726503 +0000 UTC m=+234.984487029
[IDID_17] 2017/11/20 16:15:25.325928 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:15:25.325938 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:15:25.325947 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:15:25.325964 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:15:35.326205 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:15:35.326277 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:15:35.326297 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:15:35.330110 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:15:35.332579 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 22.322420796s, next dial is at 2017-11-20 16:16:02.996975639 +0000 UTC m=+272.655736152 and current time is 2017-11-20 16:15:25.325726503 +0000 UTC m=+234.984487029
[IDID_17] 2017/11/20 16:15:35.332681 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 22.322420796s, next dial is at 2017-11-20 16:16:02.996975639 +0000 UTC m=+272.655736152 and current time is 2017-11-20 16:15:25.325726503 +0000 UTC m=+234.984487029
[IDID_17] 2017/11/20 16:15:35.332788 service.go:364: DEBUG: Not dialing relay://IPv4_6:PORT_3/?id=YRB2IWT-7R23EWJ-3CMK5IQ-TY5U24F-ND4O25S-UZI4BE2-33TTB3U-BUHGVQD&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 22.322420796s, next dial is at 2017-11-20 16:16:02.996975639 +0000 UTC m=+272.655736152 and current time is 2017-11-20 16:15:25.325726503 +0000 UTC m=+234.984487029
[IDID_17] 2017/11/20 16:15:35.332838 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 22.322420796s, next dial is at 2017-11-20 16:16:25.325726503 +0000 UTC m=+294.984487029 and current time is 2017-11-20 16:15:25.325726503 +0000 UTC m=+234.984487029
[IDID_17] 2017/11/20 16:15:35.332882 service.go:421: DEBUG: sleep until next dial 37.671249123s
[IDID_17] 2017/11/20 16:16:13.004625 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:16:13.008037 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:16:13.014924 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:16:13.014983 service.go:364: DEBUG: Not dialing relay://IPv4_2:PORT_6/?id=VQKG3OG-7LVNC7S-4FP2JCE-Q42EEPY-UWHUS5K-PGWJSWD-2OGHYUI-SEMMXQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 37.671249123s, next dial is at 2017-11-20 16:16:25.325726503 +0000 UTC m=+294.984487029 and current time is 2017-11-20 16:16:13.004888243 +0000 UTC m=+282.663648759
[IDID_17] 2017/11/20 16:16:13.014997 service.go:364: DEBUG: Not dialing relay://IPv4_4:PORT_3/?id=LS45IVJ-ZGE3OST-KOZ5FH6-YAGVMID-5ZBMBZU-MJ74EN6-H2OV77N-GDTFMQ5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 37.671249123s, next dial is at 2017-11-20 16:16:25.325726503 +0000 UTC m=+294.984487029 and current time is 2017-11-20 16:16:13.004888243 +0000 UTC m=+282.663648759
[IDID_17] 2017/11/20 16:16:13.015008 service.go:364: DEBUG: Not dialing relay://IPv4_8:PORT_3/?id=5ULHJDG-7SQLTCG-XGMEZAO-4Y7IRFC-JAGHR3A-YXOCU4Q-XJKRAT5-4YVS5A5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=1048576&statusAddr=:22070&providedBy=ronny as sleep is 37.671249123s, next dial is at 2017-11-20 16:16:25.325726503 +0000 UTC m=+294.984487029 and current time is 2017-11-20 16:16:13.004888243 +0000 UTC m=+282.663648759
[IDID_17] 2017/11/20 16:16:13.015062 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 37.671249123s, next dial is at 2017-11-20 16:16:25.325726503 +0000 UTC m=+294.984487029 and current time is 2017-11-20 16:16:13.004888243 +0000 UTC m=+282.663648759
[IDID_17] 2017/11/20 16:16:13.015085 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:16:13.015181 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:16:13.015223 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 37.671249123s, next dial is at 2017-11-20 16:17:13.004888243 +0000 UTC m=+342.663648759 and current time is 2017-11-20 16:16:13.004888243 +0000 UTC m=+282.663648759
[IDID_17] 2017/11/20 16:16:13.015240 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:16:13.015251 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:16:13.015265 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 37.671249123s, next dial is at 2017-11-20 16:16:25.325726503 +0000 UTC m=+294.984487029 and current time is 2017-11-20 16:16:13.004888243 +0000 UTC m=+282.663648759
[IDID_17] 2017/11/20 16:16:13.015280 service.go:421: DEBUG: sleep until next dial 12.32083827s
[IDID_17] 2017/11/20 16:16:25.336414 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:16:25.336583 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:16:25.336657 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 12.32083827s, next dial is at 2017-11-20 16:17:13.004888243 +0000 UTC m=+342.663648759 and current time is 2017-11-20 16:16:25.336456575 +0000 UTC m=+294.995217087
[IDID_17] 2017/11/20 16:16:25.336682 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:16:25.336694 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:16:25.336701 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:16:25.336777 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:16:35.337065 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:16:35.337130 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:16:35.337148 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:16:35.431957 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:16:35.435403 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 12.32083827s, next dial is at 2017-11-20 16:17:13.004888243 +0000 UTC m=+342.663648759 and current time is 2017-11-20 16:16:25.336456575 +0000 UTC m=+294.995217087
[IDID_17] 2017/11/20 16:16:35.435442 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 12.32083827s, next dial is at 2017-11-20 16:17:13.004888243 +0000 UTC m=+342.663648759 and current time is 2017-11-20 16:16:25.336456575 +0000 UTC m=+294.995217087
[IDID_17] 2017/11/20 16:16:35.435452 service.go:364: DEBUG: Not dialing relay://IPv4_6:PORT_3/?id=YRB2IWT-7R23EWJ-3CMK5IQ-TY5U24F-ND4O25S-UZI4BE2-33TTB3U-BUHGVQD&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 12.32083827s, next dial is at 2017-11-20 16:17:13.004888243 +0000 UTC m=+342.663648759 and current time is 2017-11-20 16:16:25.336456575 +0000 UTC m=+294.995217087
[IDID_17] 2017/11/20 16:16:35.435459 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 12.32083827s, next dial is at 2017-11-20 16:17:25.336456575 +0000 UTC m=+354.995217087 and current time is 2017-11-20 16:16:25.336456575 +0000 UTC m=+294.995217087
[IDID_17] 2017/11/20 16:16:35.435472 service.go:421: DEBUG: sleep until next dial 47.668431672s
[IDID_17] 2017/11/20 16:17:23.104141 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:17:23.104294 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:17:24.910040 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:17:24.910098 service.go:364: DEBUG: Not dialing relay://IPv4_2:PORT_6/?id=VQKG3OG-7LVNC7S-4FP2JCE-Q42EEPY-UWHUS5K-PGWJSWD-2OGHYUI-SEMMXQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 47.668431672s, next dial is at 2017-11-20 16:17:25.336456575 +0000 UTC m=+354.995217087 and current time is 2017-11-20 16:17:23.104193364 +0000 UTC m=+352.762953877
[IDID_17] 2017/11/20 16:17:24.910110 service.go:364: DEBUG: Not dialing relay://IPv4_4:PORT_3/?id=LS45IVJ-ZGE3OST-KOZ5FH6-YAGVMID-5ZBMBZU-MJ74EN6-H2OV77N-GDTFMQ5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 47.668431672s, next dial is at 2017-11-20 16:17:25.336456575 +0000 UTC m=+354.995217087 and current time is 2017-11-20 16:17:23.104193364 +0000 UTC m=+352.762953877
[IDID_17] 2017/11/20 16:17:24.910119 service.go:364: DEBUG: Not dialing relay://IPv4_8:PORT_3/?id=5ULHJDG-7SQLTCG-XGMEZAO-4Y7IRFC-JAGHR3A-YXOCU4Q-XJKRAT5-4YVS5A5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=1048576&statusAddr=:22070&providedBy=ronny as sleep is 47.668431672s, next dial is at 2017-11-20 16:17:25.336456575 +0000 UTC m=+354.995217087 and current time is 2017-11-20 16:17:23.104193364 +0000 UTC m=+352.762953877
[IDID_17] 2017/11/20 16:17:24.910130 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 47.668431672s, next dial is at 2017-11-20 16:17:25.336456575 +0000 UTC m=+354.995217087 and current time is 2017-11-20 16:17:23.104193364 +0000 UTC m=+352.762953877
[IDID_17] 2017/11/20 16:17:24.910145 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:17:24.910278 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:17:25.402082 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 47.668431672s, next dial is at 2017-11-20 16:18:23.104193364 +0000 UTC m=+412.762953877 and current time is 2017-11-20 16:17:23.104193364 +0000 UTC m=+352.762953877
[IDID_17] 2017/11/20 16:17:25.402146 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:17:25.402157 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 47.668431672s, next dial is at 2017-11-20 16:17:25.336456575 +0000 UTC m=+354.995217087 and current time is 2017-11-20 16:17:23.104193364 +0000 UTC m=+352.762953877
[IDID_17] 2017/11/20 16:17:25.402170 service.go:421: DEBUG: sleep until next dial 2.23226321s
[IDID_17] 2017/11/20 16:17:27.634670 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:17:27.634807 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:17:27.634904 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 2.23226321s, next dial is at 2017-11-20 16:18:23.104193364 +0000 UTC m=+412.762953877 and current time is 2017-11-20 16:17:27.634720658 +0000 UTC m=+357.293481171
[IDID_17] 2017/11/20 16:17:27.634932 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:17:27.634939 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:17:27.634946 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:17:27.634959 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:17:37.635231 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:17:37.635395 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:17:37.635445 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:17:37.639619 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:17:37.642495 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 2.23226321s, next dial is at 2017-11-20 16:18:23.104193364 +0000 UTC m=+412.762953877 and current time is 2017-11-20 16:17:27.634720658 +0000 UTC m=+357.293481171
[IDID_17] 2017/11/20 16:17:37.642531 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 2.23226321s, next dial is at 2017-11-20 16:18:23.104193364 +0000 UTC m=+412.762953877 and current time is 2017-11-20 16:17:27.634720658 +0000 UTC m=+357.293481171
[IDID_17] 2017/11/20 16:17:37.642541 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 2.23226321s, next dial is at 2017-11-20 16:18:27.634720658 +0000 UTC m=+417.293481171 and current time is 2017-11-20 16:17:27.634720658 +0000 UTC m=+357.293481171
[IDID_17] 2017/11/20 16:17:37.642552 service.go:421: DEBUG: sleep until next dial 55.469472706s
[IDID_17] 2017/11/20 16:18:33.112300 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:18:33.112553 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:18:33.116112 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:18:33.116143 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:18:33.116150 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:18:33.116156 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:18:33.116171 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:18:43.116424 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:18:43.116505 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:18:43.116525 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:18:43.119925 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:18:43.122229 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 55.469472706s, next dial is at 2017-11-20 16:19:33.112458363 +0000 UTC m=+482.771218875 and current time is 2017-11-20 16:18:33.112458363 +0000 UTC m=+422.771218875
[IDID_17] 2017/11/20 16:18:43.122257 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200)
[IDID_17] 2017/11/20 16:18:43.122267 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 55.469472706s, next dial is at 2017-11-20 16:19:33.112458363 +0000 UTC m=+482.771218875 and current time is 2017-11-20 16:18:33.112458363 +0000 UTC m=+422.771218875
[IDID_17] 2017/11/20 16:18:43.122277 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:19:35.555584 model.go:1210: INFO: Connection to IDID-IDID-IDID-IDID_2 closed: read timeout
[IDID_17] 2017/11/20 16:19:43.122504 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:19:43.122667 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:19:43.127108 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:19:43.127142 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:19:43.127150 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:19:43.127156 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:19:43.127172 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:19:53.127435 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:19:53.127505 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:19:53.127527 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:19:53.132165 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:19:53.134418 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 1m0s, next dial is at 2017-11-20 16:20:43.122546587 +0000 UTC m=+552.781307101 and current time is 2017-11-20 16:19:43.122546587 +0000 UTC m=+492.781307101
[IDID_17] 2017/11/20 16:19:53.134461 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_2 relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=
[IDID_17] 2017/11/20 16:19:53.310745 relay_dial.go:48: DEBUG: failed to set traffic class: operation not supported
[IDID_17] 2017/11/20 16:19:54.449059 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:20:14.449382 service.go:214: INFO: Failed to exchange Hello messages with IDID-IDID-IDID-IDID_2 (IPv4_3:PORT_3): read tcp IPv4_5:PORT_7->IPv4_3:PORT_3: i/o timeout
[IDID_17] 2017/11/20 16:20:54.449336 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:20:54.449519 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:20:54.453132 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:20:54.453168 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:20:54.453176 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:20:54.453181 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:20:54.453195 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:21:04.453471 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:21:04.453551 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:21:04.453571 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:21:04.457257 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:21:04.459486 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 1m0s, next dial is at 2017-11-20 16:21:54.44938846 +0000 UTC m=+624.108148973 and current time is 2017-11-20 16:20:54.44938846 +0000 UTC m=+564.108148973
[IDID_17] 2017/11/20 16:21:04.459517 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 1m0s, next dial is at 2017-11-20 16:29:43.122546587 +0000 UTC m=+1092.781307101 and current time is 2017-11-20 16:20:54.44938846 +0000 UTC m=+564.108148973
[IDID_17] 2017/11/20 16:21:04.459526 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 1m0s, next dial is at 2017-11-20 16:21:54.44938846 +0000 UTC m=+624.108148973 and current time is 2017-11-20 16:20:54.44938846 +0000 UTC m=+564.108148973
[IDID_17] 2017/11/20 16:21:04.459538 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:22:04.459779 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:22:04.459946 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:22:04.464576 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:22:04.464620 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:22:04.464627 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:22:04.464634 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:22:04.464651 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:22:14.464950 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:22:14.465024 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:22:14.465046 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:22:14.469275 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:22:14.472132 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 1m0s, next dial is at 2017-11-20 16:23:04.459824528 +0000 UTC m=+694.118585041 and current time is 2017-11-20 16:22:04.459824528 +0000 UTC m=+634.118585041
[IDID_17] 2017/11/20 16:22:14.472171 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 1m0s, next dial is at 2017-11-20 16:29:43.122546587 +0000 UTC m=+1092.781307101 and current time is 2017-11-20 16:22:04.459824528 +0000 UTC m=+634.118585041
[IDID_17] 2017/11/20 16:22:14.628872 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 1m0s, next dial is at 2017-11-20 16:23:04.459824528 +0000 UTC m=+694.118585041 and current time is 2017-11-20 16:22:04.459824528 +0000 UTC m=+634.118585041
[IDID_17] 2017/11/20 16:22:14.628904 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:23:14.629149 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:23:14.629347 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:23:16.661089 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:23:16.661145 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:23:16.661162 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:23:16.661169 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:23:16.661186 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:23:26.661431 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:23:26.661484 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:23:26.661500 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:23:26.830776 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:23:27.609819 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 1m0s, next dial is at 2017-11-20 16:24:14.629192381 +0000 UTC m=+764.287952895 and current time is 2017-11-20 16:23:14.629192381 +0000 UTC m=+704.287952895
[IDID_17] 2017/11/20 16:23:27.609866 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 1m0s, next dial is at 2017-11-20 16:29:43.122546587 +0000 UTC m=+1092.781307101 and current time is 2017-11-20 16:23:14.629192381 +0000 UTC m=+704.287952895
[IDID_17] 2017/11/20 16:23:27.609880 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 1m0s, next dial is at 2017-11-20 16:24:14.629192381 +0000 UTC m=+764.287952895 and current time is 2017-11-20 16:23:14.629192381 +0000 UTC m=+704.287952895
[IDID_17] 2017/11/20 16:23:27.609962 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:23:52.009271 kcp_listen.go:120: DEBUG: connect from IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:24:02.011309 kcp_listen.go:142: DEBUG: TLS handshake (BEP/kcp): i/o timeout
[IDID_17] 2017/11/20 16:24:27.610152 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:24:27.610358 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:24:27.615058 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:24:27.615097 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:24:27.615105 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:24:27.615112 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:24:27.615126 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:24:37.615464 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:24:37.615542 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:24:37.615560 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:24:37.619814 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:24:37.622286 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 1m0s, next dial is at 2017-11-20 16:25:27.610277909 +0000 UTC m=+837.269038431 and current time is 2017-11-20 16:24:27.610277909 +0000 UTC m=+777.269038431
[IDID_17] 2017/11/20 16:24:37.622381 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 1m0s, next dial is at 2017-11-20 16:29:43.122546587 +0000 UTC m=+1092.781307101 and current time is 2017-11-20 16:24:27.610277909 +0000 UTC m=+777.269038431
[IDID_17] 2017/11/20 16:24:37.622391 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 1m0s, next dial is at 2017-11-20 16:25:27.610277909 +0000 UTC m=+837.269038431 and current time is 2017-11-20 16:24:27.610277909 +0000 UTC m=+777.269038431
[IDID_17] 2017/11/20 16:24:37.622402 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:25:37.622666 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:25:37.623261 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:25:37.631297 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:25:37.631330 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:25:37.631337 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:25:37.631343 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:25:37.631359 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:25:47.631686 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:25:47.631749 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:25:47.631767 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:25:47.636398 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:25:47.639594 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 1m0s, next dial is at 2017-11-20 16:26:37.62272183 +0000 UTC m=+907.281482343 and current time is 2017-11-20 16:25:37.62272183 +0000 UTC m=+847.281482343
[IDID_17] 2017/11/20 16:25:47.639661 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 1m0s, next dial is at 2017-11-20 16:29:43.122546587 +0000 UTC m=+1092.781307101 and current time is 2017-11-20 16:25:37.62272183 +0000 UTC m=+847.281482343
[IDID_17] 2017/11/20 16:25:47.639672 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 1m0s, next dial is at 2017-11-20 16:26:37.62272183 +0000 UTC m=+907.281482343 and current time is 2017-11-20 16:25:37.62272183 +0000 UTC m=+847.281482343
[IDID_17] 2017/11/20 16:25:47.639683 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:26:47.639906 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:26:47.640101 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:26:47.645921 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:26:47.645966 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:26:47.645973 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:26:47.645980 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:26:47.645998 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:26:57.646334 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:26:57.646392 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:26:57.646410 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:26:57.650886 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:26:57.653488 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 1m0s, next dial is at 2017-11-20 16:27:47.639980587 +0000 UTC m=+977.298741100 and current time is 2017-11-20 16:26:47.639980587 +0000 UTC m=+917.298741100
[IDID_17] 2017/11/20 16:26:57.653532 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 1m0s, next dial is at 2017-11-20 16:29:43.122546587 +0000 UTC m=+1092.781307101 and current time is 2017-11-20 16:26:47.639980587 +0000 UTC m=+917.298741100
[IDID_17] 2017/11/20 16:26:57.653548 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 1m0s, next dial is at 2017-11-20 16:27:47.639980587 +0000 UTC m=+977.298741100 and current time is 2017-11-20 16:26:47.639980587 +0000 UTC m=+917.298741100
[IDID_17] 2017/11/20 16:26:57.653561 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:27:57.653786 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:27:57.653945 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:27:57.657155 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:27:57.657180 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:27:57.657188 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:27:57.657194 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:27:57.657206 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:28:07.657587 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:28:07.657744 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:28:07.657767 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:28:07.661981 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:28:07.664285 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is 1m0s, next dial is at 2017-11-20 16:28:57.653833797 +0000 UTC m=+1047.312594324 and current time is 2017-11-20 16:27:57.653833797 +0000 UTC m=+987.312594324
[IDID_17] 2017/11/20 16:28:07.664320 service.go:364: DEBUG: Not dialing relay://IPv4_3:PORT_3/?id=XF5CN3H-ODS3SR6-ZDPBMPN-2VS5Q3R-XXVY4EH-4MC67E7-MVKXMSZ-OLLEKQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 1m0s, next dial is at 2017-11-20 16:29:43.122546587 +0000 UTC m=+1092.781307101 and current time is 2017-11-20 16:27:57.653833797 +0000 UTC m=+987.312594324
[IDID_17] 2017/11/20 16:28:07.664334 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 1m0s, next dial is at 2017-11-20 16:28:57.653833797 +0000 UTC m=+1047.312594324 and current time is 2017-11-20 16:27:57.653833797 +0000 UTC m=+987.312594324
[IDID_17] 2017/11/20 16:28:07.664353 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:29:07.664660 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:29:07.664948 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:29:09.536173 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:29:09.536217 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:29:09.536228 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:29:09.536237 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:29:09.536256 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:29:19.536541 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:29:19.536606 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:29:19.536625 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:29:19.540024 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:29:20.302209 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:30:20.302491 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:30:20.303973 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:30:20.308842 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:30:20.308878 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:30:20.308885 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:30:20.308891 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:30:20.308909 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:30:30.309237 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:30:30.309299 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:30:30.309318 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:30:30.312849 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:30:30.315498 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:31:30.315693 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:31:30.315855 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:31:30.319419 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:31:30.319455 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:31:30.319466 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:31:30.319475 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:31:30.319492 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:31:40.319797 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:31:40.319859 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:31:40.319876 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:31:40.323236 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:31:40.459406 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:32:40.459640 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:32:40.459803 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:32:40.464086 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:32:40.464200 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:32:40.464208 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:32:40.464215 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:32:40.464229 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:32:50.464557 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:32:50.464629 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:32:50.464648 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:32:50.468310 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:32:52.070478 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:32:52.563084 relay_listen.go:85: DEBUG: failed to set traffic class: operation not supported
[IDID_17] 2017/11/20 16:32:53.444198 service.go:284: INFO: Established secure connection to IDID-IDID-IDID-IDID_2 at IPv4_5:PORT_5-IPv4_7:PORT_3 (relay-server) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305)
[IDID_17] 2017/11/20 16:32:53.444413 model.go:1485: INFO: Device IDID-IDID-IDID-IDID_2 client is "syncthing v0.14.40" named "DEVICE_2"
[IDID_17] 2017/11/20 16:33:38.464356 model.go:1210: INFO: Connection to IDID-IDID-IDID-IDID_2 closed: reading length: EOF
[IDID_17] 2017/11/20 16:33:52.070733 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:33:52.070964 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:33:52.074864 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:33:52.074902 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:33:52.074911 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:33:52.074917 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:33:52.074933 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:34:02.075229 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:34:02.075309 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:34:02.075329 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:34:02.079611 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:34:02.162147 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:35:02.163739 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:35:02.167015 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:35:04.393377 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:35:04.393430 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:35:04.393441 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:35:04.393453 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:35:04.393475 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:35:14.393797 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:35:14.393889 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:35:14.393912 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:35:14.397280 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:35:14.399315 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:36:14.399567 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:36:14.399717 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:36:14.404123 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:36:14.404152 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:36:14.404160 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:36:14.404166 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:36:14.404180 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:36:24.404401 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:36:24.404574 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:36:24.404599 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:36:24.408325 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:36:26.103885 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:37:26.104101 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:37:26.104237 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:37:26.107762 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:37:26.107790 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:37:26.107799 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:37:26.107808 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:37:26.107825 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:37:36.108174 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:37:36.108240 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:37:36.108287 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:37:36.112366 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:37:36.114854 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:38:36.115082 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:38:36.115242 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:38:36.120097 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:38:36.120141 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:38:36.120149 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:38:36.120155 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:38:36.120172 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:38:46.120410 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:38:46.120469 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:38:46.120487 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:38:46.123921 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:38:46.195907 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:39:46.196174 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:39:46.196343 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:39:46.200043 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:39:46.200079 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:39:46.200089 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:39:46.200098 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:39:46.200115 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:39:56.200347 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:39:56.200495 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:39:56.200536 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:39:56.205343 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:39:57.842886 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:40:57.846981 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:40:57.847122 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:40:59.614519 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:40:59.614559 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:40:59.614569 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:40:59.614578 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:40:59.614595 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:41:09.614874 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:41:09.614945 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:41:09.614966 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:41:09.633451 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:41:09.767608 service.go:421: DEBUG: sleep until next dial 1m0s
[IDID_17] 2017/11/20 16:41:39.893190 model.go:1210: INFO: Connection to IDID-IDID-IDID-IDID_9 closed: reading length: broken pipe
[IDID_17] 2017/11/20 16:42:09.767835 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:42:09.767908 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:42:09.831408 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 kcp://IPv4_9:PORT_2
[IDID_17] 2017/11/20 16:42:09.831745 kcp_dial.go:42: DEBUG: dial kcp://IPv4_9:PORT_2 using existing conn on [::]:PORT_2
[IDID_17] 2017/11/20 16:42:10.126623 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:42:10.127112 service.go:284: INFO: Established secure connection to IDID-IDID-IDID-IDID_9 at [::]:PORT_2-IPv4_9:PORT_2 (kcp-client) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305)
[IDID_17] 2017/11/20 16:42:10.127332 model.go:1485: INFO: Device IDID-IDID-IDID-IDID_9 client is "syncthing v0.14.40" named "DEVICE_9"
[IDID_17] 2017/11/20 16:42:10.130112 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:42:10.132056 service.go:421: DEBUG: sleep until next dial -11.920852506s
[IDID_17] 2017/11/20 16:42:10.132099 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:42:10.132157 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:42:10.132212 service.go:364: DEBUG: Not dialing kcp://IPv4_9:PORT_2 as sleep is -11.920852506s, next dial is at 2017-11-20 16:42:33.767877165 +0000 UTC m=+1863.426637694 and current time is 2017-11-20 16:42:10.132105629 +0000 UTC m=+1839.790866142
[IDID_17] 2017/11/20 16:42:10.132242 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:42:10.132252 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:42:10.132260 service.go:395: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 50)
[IDID_17] 2017/11/20 16:42:10.132274 service.go:400: DEBUG: dial IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1
[IDID_17] 2017/11/20 16:42:20.132552 tcp_dial.go:36: DEBUG: dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:42:20.132610 service.go:405: DEBUG: dial failed IDID-IDID-IDID-IDID_9 tcp://IPv4_9:PORT_1 dial tcp IPv4_9:PORT_1: i/o timeout
[IDID_17] 2017/11/20 16:42:20.132632 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:42:20.132899 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:42:20.132943 service.go:421: DEBUG: sleep until next dial 23.635771552s
[IDID_17] 2017/11/20 16:42:43.769014 service.go:317: DEBUG: Reconnect loop
[IDID_17] 2017/11/20 16:42:43.769153 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_9
[IDID_17] 2017/11/20 16:42:43.769226 service.go:395: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50)
[IDID_17] 2017/11/20 16:42:43.769266 service.go:364: DEBUG: Not dialing relay://IPv4_2:PORT_6/?id=VQKG3OG-7LVNC7S-4FP2JCE-Q42EEPY-UWHUS5K-PGWJSWD-2OGHYUI-SEMMXQB&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 23.635771552s, next dial is at 2017-11-20 16:43:10.132105629 +0000 UTC m=+1899.790866142 and current time is 2017-11-20 16:42:43.769053232 +0000 UTC m=+1873.427813748
[IDID_17] 2017/11/20 16:42:43.769279 service.go:364: DEBUG: Not dialing relay://IPv4_4:PORT_3/?id=LS45IVJ-ZGE3OST-KOZ5FH6-YAGVMID-5ZBMBZU-MJ74EN6-H2OV77N-GDTFMQ5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy= as sleep is 23.635771552s, next dial is at 2017-11-20 16:43:10.132105629 +0000 UTC m=+1899.790866142 and current time is 2017-11-20 16:42:43.769053232 +0000 UTC m=+1873.427813748
[IDID_17] 2017/11/20 16:42:43.769295 service.go:364: DEBUG: Not dialing relay://IPv4_8:PORT_3/?id=5ULHJDG-7SQLTCG-XGMEZAO-4Y7IRFC-JAGHR3A-YXOCU4Q-XJKRAT5-4YVS5A5&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=1048576&statusAddr=:22070&providedBy=ronny as sleep is 23.635771552s, next dial is at 2017-11-20 16:43:10.132105629 +0000 UTC m=+1899.790866142 and current time is 2017-11-20 16:42:43.769053232 +0000 UTC m=+1873.427813748
[IDID_17] 2017/11/20 16:42:43.769307 service.go:364: DEBUG: Not dialing tcp://IPv4_9:PORT_1 as sleep is 23.635771552s, next dial is at 2017-11-20 16:43:10.132105629 +0000 UTC m=+1899.790866142 and current time is 2017-11-20 16:42:43.769053232 +0000 UTC m=+1873.427813748
[IDID_17] 2017/11/20 16:42:43.769318 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_1
[IDID_17] 2017/11/20 16:42:43.769351 service.go:344: DEBUG: Reconnect loop for IDID-IDID-IDID-IDID_2
[IDID_17] 2017/11/20 16:42:43.831020 service.go:421: DEBUG: sleep until next dial 26.363052394s