[JWK46] 2017/11/19 10:45:37.741978 main.go:649: INFO: syncthing v0.14.41-rc.2 "Dysprosium Dragonfly" (go1.9.2 linux-amd64) teamcity@build.syncthing.net 2017-11-17 14:46:45 UTC [JWK46] 2017/11/19 10:45:37.742344 main.go:650: INFO: My ID: JWK46AV [JWK46] 2017/11/19 10:45:38.779747 sha256.go:92: INFO: Single thread SHA256 performance is 59 MB/s using minio/sha256-simd (49 MB/s using crypto/sha256). [JWK46] 2017/11/19 10:45:39.913791 main.go:707: INFO: Hashing performance with weak hash is 28.93 MB/s [JWK46] 2017/11/19 10:45:40.938630 main.go:709: INFO: Hashing performance without weak hash is 56.98 MB/s [JWK46] 2017/11/19 10:45:40.938739 main.go:712: INFO: Weak hash disabled, as it has an unacceptable performance impact. [JWK46] 2017/11/19 10:45:41.826760 model.go:167: INFO: Starting deadlock detector with 20m0s timeout [JWK46] 2017/11/19 10:45:41.834567 model.go:182: INFO: Ready to synchronize ... (readwrite) [...] [JWK46] 2017/11/19 10:46:40.901591 model.go:182: INFO: Ready to synchronize ... (readwrite) [JWK46] 2017/11/19 10:46:40.902056 limiter.go:84: INFO: Send rate is unlimited, receive rate is unlimited [JWK46] 2017/11/19 10:46:40.902168 limiter.go:89: INFO: Rate limits do not apply to LAN connections [JWK46] 2017/11/19 10:46:40.902928 service.go:466: DEBUG: Starting listener dynamic+https://relays.syncthing.net/endpoint [JWK46] 2017/11/19 10:46:40.903081 service.go:466: DEBUG: Starting listener kcp://0.0.0.0:22010 [JWK46] 2017/11/19 10:46:40.903227 service.go:466: DEBUG: Starting listener tcp://0.0.0.0:22010 [JWK46] 2017/11/19 10:46:40.903467 main.go:817: INFO: Using discovery server https://discovery-v4-2.syncthing.net/v2/?id=DVU36WY-H3LVZHW-E6LLFRE-YAFN5EL-HILWRYP-OC2M47J-Z4PE62Y-ADIBDQC [JWK46] 2017/11/19 10:46:40.903715 main.go:817: INFO: Using discovery server https://discovery-v4-3.syncthing.net/v2/?id=VK6HNJ3-VVMM66S-HRVWSCR-IXEHL2H-U4AQ4MW-UCPQBWX-J2L2UBK-NVZRDQZ [JWK46] 2017/11/19 10:46:40.903896 main.go:817: INFO: Using discovery server https://discovery-v4-4.syncthing.net/v2/?id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW [JWK46] 2017/11/19 10:46:40.904084 main.go:817: INFO: Using discovery server https://discovery-v6-2.syncthing.net/v2/?id=DVU36WY-H3LVZHW-E6LLFRE-YAFN5EL-HILWRYP-OC2M47J-Z4PE62Y-ADIBDQC [JWK46] 2017/11/19 10:46:40.904252 main.go:817: INFO: Using discovery server https://discovery-v6-3.syncthing.net/v2/?id=VK6HNJ3-VVMM66S-HRVWSCR-IXEHL2H-U4AQ4MW-UCPQBWX-J2L2UBK-NVZRDQZ [JWK46] 2017/11/19 10:46:40.904454 main.go:817: INFO: Using discovery server https://discovery-v6-4.syncthing.net/v2/?id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-U56GTUK-NA2MIAW [JWK46] 2017/11/19 10:46:40.917483 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:40.919122 tcp_listen.go:69: INFO: TCP listener ([::]:22010) starting [JWK46] 2017/11/19 10:46:40.931972 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:46:40.932144 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:46:40.933792 relay_listen.go:65: INFO: Relay listener (dynamic+https://relays.syncthing.net/endpoint) starting [JWK46] 2017/11/19 10:46:40.934878 kcp_listen.go:91: INFO: KCP listener ([::]:22010) starting [JWK46] 2017/11/19 10:46:41.017467 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:41.159270 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:41.255844 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:41.373738 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:41.417608 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:41.431952 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:41.441470 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:41.456630 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:41.552026 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:41.576466 main.go:862: INFO: Device S44AE6E is "caelum.uberspace.de" at [dynamic] [JWK46] 2017/11/19 10:46:41.577308 main.go:862: INFO: Device UNIFKNA is "alex-uni" at [dynamic] [JWK46] 2017/11/19 10:46:41.578068 main.go:862: INFO: Device WHDJVNU is "raspberrypi" at [dynamic] [JWK46] 2017/11/19 10:46:41.578885 main.go:862: INFO: Device X2KPZT7 is "Moto G" at [dynamic] [JWK46] 2017/11/19 10:46:41.579724 main.go:862: INFO: Device AGR6NY2 is "raspberrypi2" at [tcp://192.168.1.10:22000 dynamic] [JWK46] 2017/11/19 10:46:41.580490 main.go:862: INFO: Device ESBYS6E is "lapop-bombur" at [dynamic] [JWK46] 2017/11/19 10:46:41.581261 main.go:862: INFO: Device JWK46AV is "balin" at [dynamic] [JWK46] 2017/11/19 10:46:41.581968 main.go:862: INFO: Device TLRPHPS is "desktop-dior" at [dynamic] [JWK46] 2017/11/19 10:46:41.582717 main.go:869: INFO: Anonymous usage reporting is always enabled for candidate releases. [JWK46] 2017/11/19 10:46:41.620736 gui.go:361: INFO: GUI and API listening on 127.0.0.1:8384 [JWK46] 2017/11/19 10:46:41.621679 gui.go:362: INFO: Access the GUI via the following URL: http://127.0.0.1:8384/ [JWK46] 2017/11/19 10:46:41.788318 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:41.946960 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:42.022674 main.go:894: INFO: Automatic upgrade is always enabled for candidate releases. [JWK46] 2017/11/19 10:46:42.207563 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:42.917641 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:43.131023 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:43.328580 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:43.946884 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:44.018223 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:44.556313 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:44.810651 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:45.193535 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:47.574589 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:48.239258 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 0s, next dial is at 2017-11-19 10:47:40.932082041 +0100 CET m=+123.257374895 and current time is 2017-11-19 10:46:40.932082041 +0100 CET m=+63.257374895 [JWK46] 2017/11/19 10:46:48.262468 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:46:48.265665 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:46:50.750939 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:55.704701 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:56.726036 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:46:58.375322 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:46:58.375836 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:46:58.376426 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:46:58.376919 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:46:58.863674 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:47:00.043104 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:47:00.153443 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:47:01.442531 kcp_listen.go:249: INFO: kcp://0.0.0.0:22010 detected NAT type: Port restricted NAT [JWK46] 2017/11/19 10:47:01.443845 kcp_listen.go:269: INFO: kcp://0.0.0.0:22010 resolved external address kcp://85.216.59.171:22010 (via stun.counterpath.com:3478) [JWK46] 2017/11/19 10:47:02.410486 static.go:93: INFO: Joined relay relay://144.76.71.91:22067 [JWK46] 2017/11/19 10:47:06.725802 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:47:08.973397 folder.go:125: INFO: Completed initial scan of readwrite folder ... [JWK46] 2017/11/19 10:47:10.043856 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:47:10.045132 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:47:10.046402 service.go:343: DEBUG: Reconnect loop for S44AE6E [JWK46] 2017/11/19 10:47:11.380275 structs.go:189: DEBUG: dialing S44AE6E tcp://95.143.172.212:61232 prio 10 [JWK46] 2017/11/19 10:47:11.398520 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:47:11.658710 structs.go:191: DEBUG: dialing S44AE6E tcp://95.143.172.212:61232 outcome 192.168.1.11:41306-95.143.172.212:61232/tcp-client [JWK46] 2017/11/19 10:47:11.659091 service.go:771: DEBUG: connected to S44AE6E 10 using 192.168.1.11:41306-95.143.172.212:61232/tcp-client 10 [JWK46] 2017/11/19 10:47:11.659544 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:47:11.660575 service.go:774: DEBUG: discarding 0 connections while connecting to S44AE6E 10 [JWK46] 2017/11/19 10:47:11.680118 service.go:284: INFO: Established secure connection to S44AE6E at 192.168.1.11:41306-95.143.172.212:61232 (tcp-client) (TLS_ECDHE_RSA_WITH_CHACHA20_POLY1305) [JWK46] 2017/11/19 10:47:11.680891 model.go:1480: INFO: Device S44AE6E client is "syncthing v0.14.41-rc.2" named "caelum.uberspace.de" [JWK46] 2017/11/19 10:47:12.146556 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:47:12.180183 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:47:13.446726 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56734-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:47:13.447158 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56734-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:47:13.447666 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:47:13.546823 service.go:284: INFO: Established secure connection to WHDJVNU at 192.168.1.11:56734-79.231.96.156:22000 (tcp-client) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305) [JWK46] 2017/11/19 10:47:13.547196 model.go:1480: INFO: Device WHDJVNU client is "syncthing v0.14.40" named "raspberrypi" [JWK46] 2017/11/19 10:47:13.547481 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:47:15.553286 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:47:20.110846 service.go:422: DEBUG: initial rampup; sleep 1s and update to 2s [JWK46] 2017/11/19 10:47:21.018609 kcp_listen.go:122: DEBUG: connect from 79.231.96.156:22020 [JWK46] 2017/11/19 10:47:21.130858 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:47:21.166469 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:47:21.210753 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:47:21.211862 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:47:21.212908 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:47:21.514790 service.go:284: INFO: Established secure connection to TLRPHPS at [::]:22010-79.231.96.156:22020 (kcp-server) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305) [JWK46] 2017/11/19 10:47:21.515099 model.go:1480: INFO: Device TLRPHPS client is "syncthing v0.14.41-rc.2" named "desktop-dior" [JWK46] 2017/11/19 10:47:31.246698 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:47:31.270627 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:47:31.271485 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:47:31.272149 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:47:31.272827 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:47:31.273791 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:47:41.345198 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:47:41.346015 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:47:41.347152 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:47:41.348019 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:47:41.362487 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:47:41.434501 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:47:48.342620 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56762-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:47:48.343457 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56762-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:47:48.343786 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:47:48.344468 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:47:53.962968 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:47:53.963943 service.go:422: DEBUG: initial rampup; sleep 2s and update to 4s [JWK46] 2017/11/19 10:47:53.998588 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:47:56.014609 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:47:56.070456 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:47:56.346769 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:47:56.347759 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:47:56.348710 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:48:06.347941 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:48:06.349057 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:48:06.349976 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:48:06.350465 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:48:06.350656 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:48:06.359448 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:48:16.360070 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:48:16.360305 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:48:16.360805 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:48:16.369466 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:48:16.369760 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:48:16.406891 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:48:17.548557 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56772-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:48:17.549019 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56772-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:48:17.549590 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:48:17.551049 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:48:17.559127 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:48:17.559365 service.go:422: DEBUG: initial rampup; sleep 4s and update to 8s [JWK46] 2017/11/19 10:48:17.575810 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:48:21.559983 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:48:21.560841 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:48:21.562057 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:48:21.563409 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:48:21.564705 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:48:31.563555 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:48:31.564926 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:48:31.566184 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:48:31.566652 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:48:31.567253 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:48:31.568666 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:48:41.569327 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:48:41.569707 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:48:41.570807 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:48:41.571502 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:48:41.572281 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:48:41.612508 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:48:42.548746 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56784-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:48:42.549022 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56784-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:48:42.549199 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:48:42.549567 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:48:42.549890 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:48:42.559700 service.go:422: DEBUG: initial rampup; sleep 8s and update to 16s [JWK46] 2017/11/19 10:48:42.579758 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:48:50.560419 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:48:50.560605 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:48:50.561557 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:48:50.562216 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:48:50.563099 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:49:00.562465 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:49:00.563254 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:49:00.564474 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:49:00.564696 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:49:00.564834 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:49:00.566037 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:49:10.566759 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:49:10.566921 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:49:10.568058 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:49:10.568890 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:49:10.569754 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:49:10.604866 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:49:12.229550 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56794-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:49:12.229980 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56794-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:49:12.230498 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:49:12.231314 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:49:12.231909 service.go:422: DEBUG: initial rampup; sleep 16s and update to 32s [JWK46] 2017/11/19 10:49:12.232453 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:49:12.266725 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:49:28.232824 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:49:28.233022 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:49:28.242557 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:49:28.243375 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:49:28.244133 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:49:38.243265 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:49:38.244245 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:49:38.244889 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:49:38.245075 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:49:38.245218 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:49:38.254689 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:49:48.255348 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:49:48.255519 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:49:48.256663 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:49:48.265336 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:49:48.266362 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:49:48.304856 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:49:49.317446 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56806-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:49:49.317875 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56806-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:49:49.318066 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:49:49.318977 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:49:49.328742 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:49:49.336347 service.go:422: DEBUG: initial rampup; sleep 32s and update to 1m4s [JWK46] 2017/11/19 10:49:49.345228 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:50:21.337006 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:50:21.337170 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:50:21.337521 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 1m0s, next dial is at 2017-11-19 10:50:28.23293814 +0100 CET m=+290.558230924 and current time is 2017-11-19 10:50:21.337103565 +0100 CET m=+283.662396489 [JWK46] 2017/11/19 10:50:21.337682 service.go:365: DEBUG: Not dialing relay://163.172.179.61:8443/?id=FU55S7D-DO2JQEP-DYE6SLV-UKU4FS2-BMDTHUE-L5633N2-CIFX32Y-QQUVQAC&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=harry.lu - Paris2, FR as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:50:21.337103565 +0100 CET m=+283.662396489 [JWK46] 2017/11/19 10:50:21.337815 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 1m0s, next dial is at 2017-11-19 10:50:28.23293814 +0100 CET m=+290.558230924 and current time is 2017-11-19 10:50:21.337103565 +0100 CET m=+283.662396489 [JWK46] 2017/11/19 10:50:21.337966 service.go:365: DEBUG: Not dialing tcp://85.216.59.171:22000 as sleep is 1m0s, next dial is at 2017-11-19 10:50:28.23293814 +0100 CET m=+290.558230924 and current time is 2017-11-19 10:50:21.337103565 +0100 CET m=+283.662396489 [JWK46] 2017/11/19 10:50:21.338102 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:50:21.338477 service.go:365: DEBUG: Not dialing relay://144.76.71.91:22067/?id=EWO4HFG-YCA3UYC-ATA4RR7-XB3UK4C-D24VKXD-5MUL42X-U3C32BV-PH6IHAT&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch - hetzner.de as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:50:21.337103565 +0100 CET m=+283.662396489 [JWK46] 2017/11/19 10:50:21.338635 service.go:365: DEBUG: Not dialing tcp://79.231.96.156:22002 as sleep is 1m0s, next dial is at 2017-11-19 10:50:28.23293814 +0100 CET m=+290.558230924 and current time is 2017-11-19 10:50:21.337103565 +0100 CET m=+283.662396489 [JWK46] 2017/11/19 10:50:21.339056 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:50:21.339359 service.go:365: DEBUG: Not dialing kcp://79.231.96.156:22020 as sleep is 1m0s, next dial is at 2017-11-19 10:50:28.23293814 +0100 CET m=+290.558230924 and current time is 2017-11-19 10:50:21.337103565 +0100 CET m=+283.662396489 [JWK46] 2017/11/19 10:50:21.339500 service.go:365: DEBUG: Not dialing tcp://79.231.96.156:22000 as sleep is 1m0s, next dial is at 2017-11-19 10:50:28.23293814 +0100 CET m=+290.558230924 and current time is 2017-11-19 10:50:21.337103565 +0100 CET m=+283.662396489 [JWK46] 2017/11/19 10:50:21.339633 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:50:21.340013 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:50:21.340269 service.go:426: DEBUG: sleep until next dial 6.895834435s [JWK46] 2017/11/19 10:50:28.236700 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:50:28.237023 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:50:28.246593 service.go:365: DEBUG: Not dialing relay://163.172.179.61:8443/?id=FU55S7D-DO2JQEP-DYE6SLV-UKU4FS2-BMDTHUE-L5633N2-CIFX32Y-QQUVQAC&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=harry.lu - Paris2, FR as sleep is 6.895834435s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:50:28.236821516 +0100 CET m=+290.562114300 [JWK46] 2017/11/19 10:50:28.246754 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 6.895834435s, next dial is at 2017-11-19 10:51:28.236821516 +0100 CET m=+350.562114300 and current time is 2017-11-19 10:50:28.236821516 +0100 CET m=+290.562114300 [JWK46] 2017/11/19 10:50:28.247465 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:50:28.248120 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:50:38.248106 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:50:38.248932 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:50:38.249157 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:50:38.249314 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:50:38.258394 service.go:365: DEBUG: Not dialing relay://144.76.71.91:22067/?id=EWO4HFG-YCA3UYC-ATA4RR7-XB3UK4C-D24VKXD-5MUL42X-U3C32BV-PH6IHAT&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch - hetzner.de as sleep is 6.895834435s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:50:28.236821516 +0100 CET m=+290.562114300 [JWK46] 2017/11/19 10:50:38.258727 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:50:48.259378 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:50:48.260281 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:50:48.261303 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:50:48.269992 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:50:48.271173 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:50:48.334751 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:50:49.339771 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56816-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:50:49.340209 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56816-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:50:49.340445 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:50:49.342134 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:50:49.350401 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:50:49.358943 service.go:426: DEBUG: sleep until next dial 1m0s [JWK46] 2017/11/19 10:50:49.371149 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:51:49.359666 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:51:49.360294 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:51:51.467874 service.go:365: DEBUG: Not dialing relay://163.172.179.61:8443/?id=FU55S7D-DO2JQEP-DYE6SLV-UKU4FS2-BMDTHUE-L5633N2-CIFX32Y-QQUVQAC&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=harry.lu - Paris2, FR as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:51:49.359790965 +0100 CET m=+371.685083819 [JWK46] 2017/11/19 10:51:51.468402 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 1m0s, next dial is at 2017-11-19 10:52:49.359790965 +0100 CET m=+431.685083819 and current time is 2017-11-19 10:51:49.359790965 +0100 CET m=+371.685083819 [JWK46] 2017/11/19 10:51:51.469057 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:51:51.469906 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:52:01.470134 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:52:01.471001 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:52:01.471526 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:52:01.471932 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:52:02.621020 service.go:365: DEBUG: Not dialing relay://144.76.71.91:22067/?id=EWO4HFG-YCA3UYC-ATA4RR7-XB3UK4C-D24VKXD-5MUL42X-U3C32BV-PH6IHAT&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch - hetzner.de as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:51:49.359790965 +0100 CET m=+371.685083819 [JWK46] 2017/11/19 10:52:02.621306 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:52:12.621827 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:52:12.622021 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:52:12.623300 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:52:13.487885 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:52:13.488957 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:52:13.526691 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:52:14.463269 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56838-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:52:14.463554 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56838-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:52:14.463720 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:52:14.465055 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:52:14.472774 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:52:14.479955 service.go:426: DEBUG: sleep until next dial 1m0s [JWK46] 2017/11/19 10:52:14.492982 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:53:14.480597 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:53:14.480808 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:53:14.491870 service.go:365: DEBUG: Not dialing relay://163.172.179.61:8443/?id=FU55S7D-DO2JQEP-DYE6SLV-UKU4FS2-BMDTHUE-L5633N2-CIFX32Y-QQUVQAC&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=harry.lu - Paris2, FR as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:53:14.480721018 +0100 CET m=+456.806013872 [JWK46] 2017/11/19 10:53:14.492070 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 1m0s, next dial is at 2017-11-19 10:54:14.480721018 +0100 CET m=+516.806013872 and current time is 2017-11-19 10:53:14.480721018 +0100 CET m=+456.806013872 [JWK46] 2017/11/19 10:53:14.492954 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:53:14.493904 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:53:24.493693 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:53:24.494959 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:53:24.495831 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:53:24.496601 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:53:24.531000 service.go:365: DEBUG: Not dialing relay://144.76.71.91:22067/?id=EWO4HFG-YCA3UYC-ATA4RR7-XB3UK4C-D24VKXD-5MUL42X-U3C32BV-PH6IHAT&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch - hetzner.de as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:53:14.480721018 +0100 CET m=+456.806013872 [JWK46] 2017/11/19 10:53:24.531419 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:53:34.532129 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:53:34.532851 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:53:34.533829 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:53:34.542148 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:53:34.542814 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:53:34.579731 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:53:35.591287 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56850-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:53:35.592034 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56850-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:53:35.592292 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:53:35.594507 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:53:35.603017 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:53:35.621231 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:53:35.832203 service.go:426: DEBUG: sleep until next dial 1m0s [JWK46] 2017/11/19 10:54:35.832874 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:54:35.833080 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:54:35.842017 service.go:365: DEBUG: Not dialing relay://163.172.179.61:8443/?id=FU55S7D-DO2JQEP-DYE6SLV-UKU4FS2-BMDTHUE-L5633N2-CIFX32Y-QQUVQAC&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=harry.lu - Paris2, FR as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:54:35.832998468 +0100 CET m=+538.158291323 [JWK46] 2017/11/19 10:54:35.842222 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 1m0s, next dial is at 2017-11-19 10:55:35.832998468 +0100 CET m=+598.158291323 and current time is 2017-11-19 10:54:35.832998468 +0100 CET m=+538.158291323 [JWK46] 2017/11/19 10:54:35.842671 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:54:35.842978 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:54:45.843332 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:54:45.844019 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:54:45.844368 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:54:45.844625 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:54:45.853057 service.go:365: DEBUG: Not dialing relay://144.76.71.91:22067/?id=EWO4HFG-YCA3UYC-ATA4RR7-XB3UK4C-D24VKXD-5MUL42X-U3C32BV-PH6IHAT&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch - hetzner.de as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:54:35.832998468 +0100 CET m=+538.158291323 [JWK46] 2017/11/19 10:54:45.853543 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:54:55.854575 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:54:55.854814 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:54:55.855283 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:54:55.863161 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:54:55.863427 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:54:55.894471 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:54:56.859991 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56864-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:54:56.860236 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56864-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:54:56.860368 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:54:56.861295 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:54:56.868999 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:54:56.891456 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:54:58.653295 service.go:426: DEBUG: sleep until next dial 1m0s [JWK46] 2017/11/19 10:55:58.653972 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:55:58.654181 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:55:58.663356 service.go:365: DEBUG: Not dialing relay://163.172.179.61:8443/?id=FU55S7D-DO2JQEP-DYE6SLV-UKU4FS2-BMDTHUE-L5633N2-CIFX32Y-QQUVQAC&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=harry.lu - Paris2, FR as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:55:58.654095302 +0100 CET m=+620.979388226 [JWK46] 2017/11/19 10:55:58.663563 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 1m0s, next dial is at 2017-11-19 10:56:58.654095302 +0100 CET m=+680.979388226 and current time is 2017-11-19 10:55:58.654095302 +0100 CET m=+620.979388226 [JWK46] 2017/11/19 10:55:58.663947 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:55:58.664315 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:56:08.664589 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:56:08.665193 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:56:08.665421 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:56:08.665577 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:56:08.674561 service.go:365: DEBUG: Not dialing relay://144.76.71.91:22067/?id=EWO4HFG-YCA3UYC-ATA4RR7-XB3UK4C-D24VKXD-5MUL42X-U3C32BV-PH6IHAT&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch - hetzner.de as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:55:58.654095302 +0100 CET m=+620.979388226 [JWK46] 2017/11/19 10:56:08.674895 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:56:18.675564 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:56:18.675730 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:56:18.676056 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:56:18.683937 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:56:18.684401 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:56:18.718803 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:56:19.888232 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56882-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:56:19.888528 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56882-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:56:19.888796 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:56:19.889361 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:56:19.897731 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:56:19.906147 service.go:426: DEBUG: sleep until next dial 1m0s [JWK46] 2017/11/19 10:56:19.916893 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:57:10.456260 relay_listen.go:88: DEBUG: Listen (BEP/relay): setting traffic class: operation not supported [JWK46] 2017/11/19 10:57:10.873345 service.go:284: INFO: Established secure connection to ESBYS6E at 192.168.1.11:53092-144.76.71.91:22067 (relay-server) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305) [JWK46] 2017/11/19 10:57:10.873832 model.go:1480: INFO: Device ESBYS6E client is "syncthing v0.14.41-rc.2" named "lapop-bombur" [JWK46] 2017/11/19 10:57:19.910634 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:57:19.910888 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:57:22.048786 service.go:365: DEBUG: Not dialing relay://163.172.179.61:8443/?id=FU55S7D-DO2JQEP-DYE6SLV-UKU4FS2-BMDTHUE-L5633N2-CIFX32Y-QQUVQAC&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=harry.lu - Paris2, FR as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:57:19.910810399 +0100 CET m=+702.236103184 [JWK46] 2017/11/19 10:57:22.049029 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 1m0s, next dial is at 2017-11-19 10:58:19.910810399 +0100 CET m=+762.236103184 and current time is 2017-11-19 10:57:19.910810399 +0100 CET m=+702.236103184 [JWK46] 2017/11/19 10:57:22.049324 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:57:22.049848 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:57:32.050037 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:57:32.050680 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:57:32.051039 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:57:32.051496 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:57:33.264312 service.go:365: DEBUG: Not dialing relay://144.76.71.91:22067/?id=EWO4HFG-YCA3UYC-ATA4RR7-XB3UK4C-D24VKXD-5MUL42X-U3C32BV-PH6IHAT&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch - hetzner.de as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:57:19.910810399 +0100 CET m=+702.236103184 [JWK46] 2017/11/19 10:57:33.264579 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:57:38.133203 relay_listen.go:88: DEBUG: Listen (BEP/relay): setting traffic class: operation not supported [JWK46] 2017/11/19 10:57:38.713699 service.go:284: INFO: Established secure connection to AGR6NY2 at 192.168.1.11:53112-144.76.71.91:22067 (relay-server) (TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305) [JWK46] 2017/11/19 10:57:38.714049 model.go:1480: INFO: Device AGR6NY2 client is "syncthing v0.14.41-rc.2" named "raspberrypi2" [JWK46] 2017/11/19 10:57:43.334623 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:57:43.334882 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:57:44.339389 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:57:45.707473 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:57:45.707826 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:57:45.749175 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:57:47.232406 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56912-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:57:47.232675 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56912-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:57:47.232851 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:57:47.233764 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:57:48.889599 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:57:48.891108 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:57:48.897880 service.go:426: DEBUG: sleep until next dial 1m0s [JWK46] 2017/11/19 10:58:48.898709 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:58:48.899101 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:58:48.908584 service.go:365: DEBUG: Not dialing relay://163.172.179.61:8443/?id=FU55S7D-DO2JQEP-DYE6SLV-UKU4FS2-BMDTHUE-L5633N2-CIFX32Y-QQUVQAC&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=harry.lu - Paris2, FR as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:58:48.898830572 +0100 CET m=+791.224123426 [JWK46] 2017/11/19 10:58:48.908790 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 1m0s, next dial is at 2017-11-19 10:59:48.898830572 +0100 CET m=+851.224123426 and current time is 2017-11-19 10:58:48.898830572 +0100 CET m=+791.224123426 [JWK46] 2017/11/19 10:58:48.909149 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:58:48.909762 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:58:58.909895 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:58:58.910532 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:58:58.910701 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:58:58.910960 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:58:58.918985 service.go:365: DEBUG: Not dialing relay://144.76.71.91:22067/?id=EWO4HFG-YCA3UYC-ATA4RR7-XB3UK4C-D24VKXD-5MUL42X-U3C32BV-PH6IHAT&pingInterval=1m0s&networkTimeout=2m0s&sessionLimitBps=0&globalLimitBps=0&statusAddr=:22070&providedBy=tuxli.ch - hetzner.de as sleep is 1m0s, next dial is at 2017-11-19 10:59:28.23293814 +0100 CET m=+830.558230924 and current time is 2017-11-19 10:58:48.898830572 +0100 CET m=+791.224123426 [JWK46] 2017/11/19 10:58:58.919483 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 10:59:08.920317 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 10:59:08.920646 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 10:59:08.921153 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 10:59:08.930345 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 10:59:08.930679 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 10:59:08.972761 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 10:59:09.938934 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56924-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 10:59:09.939311 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56924-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 10:59:09.939607 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 10:59:09.940925 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 10:59:09.948252 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 10:59:09.956002 service.go:426: DEBUG: sleep until next dial 39.334107498s [JWK46] 2017/11/19 10:59:09.963311 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 10:59:49.290927 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 10:59:49.291318 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 10:59:49.301234 service.go:398: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200) [JWK46] 2017/11/19 10:59:49.301450 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 39.334107498s, next dial is at 2017-11-19 11:00:49.291049518 +0100 CET m=+911.616342442 and current time is 2017-11-19 10:59:49.291049518 +0100 CET m=+851.616342442 [JWK46] 2017/11/19 10:59:49.301825 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:59:49.302331 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 10:59:59.302513 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:59:59.302976 structs.go:191: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 85.216.59.171:22000: i/o timeout [JWK46] 2017/11/19 10:59:59.303186 service.go:781: DEBUG: failed to connect to AGR6NY2 10 [JWK46] 2017/11/19 10:59:59.303420 service.go:343: DEBUG: Reconnect loop for ESBYS6E [JWK46] 2017/11/19 10:59:59.311493 service.go:398: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200) [JWK46] 2017/11/19 10:59:59.311974 structs.go:189: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 prio 10 [JWK46] 2017/11/19 11:00:09.312739 structs.go:191: DEBUG: dialing ESBYS6E tcp://79.231.96.156:22002 outcome %!v(PANIC=runtime error: invalid memory address or nil pointer dereference) dial tcp 79.231.96.156:22002: i/o timeout [JWK46] 2017/11/19 11:00:09.312979 service.go:781: DEBUG: failed to connect to ESBYS6E 10 [JWK46] 2017/11/19 11:00:09.313540 service.go:343: DEBUG: Reconnect loop for TLRPHPS [JWK46] 2017/11/19 11:00:09.321754 service.go:398: DEBUG: Not dialing using KCP Dialer as priority is less than current connection (50 >= 50) [JWK46] 2017/11/19 11:00:09.322184 structs.go:189: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 prio 10 [JWK46] 2017/11/19 11:00:09.352604 tcp_dial.go:46: DEBUG: Dial (BEP/tcp): setting traffic class: operation not supported [JWK46] 2017/11/19 11:00:10.400368 structs.go:191: DEBUG: dialing TLRPHPS tcp://79.231.96.156:22000 outcome 192.168.1.11:56936-79.231.96.156:22000/tcp-client [JWK46] 2017/11/19 11:00:10.400676 service.go:771: DEBUG: connected to TLRPHPS 10 using 192.168.1.11:56936-79.231.96.156:22000/tcp-client 10 [JWK46] 2017/11/19 11:00:10.400957 service.go:343: DEBUG: Reconnect loop for UNIFKNA [JWK46] 2017/11/19 11:00:10.403309 service.go:774: DEBUG: discarding 0 connections while connecting to TLRPHPS 10 [JWK46] 2017/11/19 11:00:10.409684 service.go:343: DEBUG: Reconnect loop for X2KPZT7 [JWK46] 2017/11/19 11:00:10.426658 service.go:247: INFO: Connected to already connected device (WHDJVNU) [JWK46] 2017/11/19 11:00:10.722410 service.go:426: DEBUG: sleep until next dial 1m0s [JWK46] 2017/11/19 11:01:10.723179 service.go:317: DEBUG: Reconnect loop [JWK46] 2017/11/19 11:01:10.723567 service.go:343: DEBUG: Reconnect loop for AGR6NY2 [JWK46] 2017/11/19 11:01:10.732756 service.go:398: DEBUG: Not dialing using Relay Dialer as priority is less than current connection (200 >= 200) [JWK46] 2017/11/19 11:01:10.732981 service.go:365: DEBUG: Not dialing tcp://192.168.1.10:22000 as sleep is 1m0s, next dial is at 2017-11-19 11:02:10.723302094 +0100 CET m=+993.048594948 and current time is 2017-11-19 11:01:10.723302094 +0100 CET m=+933.048594948 [JWK46] 2017/11/19 11:01:10.733350 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10 [JWK46] 2017/11/19 11:01:10.733716 structs.go:189: DEBUG: dialing AGR6NY2 tcp://85.216.59.171:22000 prio 10