[2021-05-28 04:49:18] DEBG [geph4-client/src/main.rs:180] inserting extra 1 fronts [2021-05-28 04:49:18] DEBG [geph4-client/src/main.rs:173] getting extra fronts... [2021-05-28 04:49:18] WARN [lib/aioutils/src/dns.rs:7] using custom DNS implementation to resolve f001.backblazeb2.com:443 [2021-05-28 04:49:19] DEBG [geph4-client/src/main.rs:180] inserting extra 1 fronts [2021-05-28 04:49:19] WARN [lib/aioutils/src/dns.rs:7] using custom DNS implementation to resolve checkip.amazonaws.com:80 [2021-05-28 04:49:19] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 0.00; recv_loss = 0.00% [2021-05-28 04:49:19] INFO [geph4-client/src/main_connect.rs:95] we are in CHINA :O [2021-05-28 04:49:19] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:49:19] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54441... [2021-05-28 04:49:19] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:12593... [2021-05-28 04:49:19] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:37371... [2021-05-28 04:49:21] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:54441 is our fastest bridge, latency=1249 [2021-05-28 04:49:22] DEBG [geph4-client/src/tunman/mod.rs:257] sending auth info... [2021-05-28 04:49:22] DEBG [geph4-client/src/tunman/mod.rs:267] sent auth info! [2021-05-28 04:49:23] INFO [geph4-client/src/tunman/mod.rs:138] TUNNEL_MANAGER MAIN LOOP for exit_host=us-pdx-01.exits.geph.io, use_tcp=false [2021-05-28 04:49:23] INFO [geph4-client/src/main_connect.rs:95] we are in CHINA :O [2021-05-28 04:49:28] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 307.24; recv_loss = 0.00% [2021-05-28 04:49:28] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(3.9472257s) [2021-05-28 04:49:28] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:49:28] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54441... [2021-05-28 04:49:28] DEBG [geph4-client/src/tunman/mod.rs:246] ** watchdog completed in 319.408634ms ** [2021-05-28 04:49:28] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 310 ms [2021-05-28 04:49:29] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 319 ms [2021-05-28 04:49:29] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 319 ms [2021-05-28 04:49:29] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 319 ms [2021-05-28 04:49:29] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 317 ms [2021-05-28 04:49:29] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:54441 is our fastest bridge, latency=1277 [2021-05-28 04:49:30] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 316 ms [2021-05-28 04:49:30] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 321 ms [2021-05-28 04:49:30] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 319 ms [2021-05-28 04:49:30] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 322 ms [2021-05-28 04:49:30] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 316 ms [2021-05-28 04:49:30] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 321 ms [2021-05-28 04:49:31] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 307.31; recv_loss = 0.00% [2021-05-28 04:49:31] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 314 ms [2021-05-28 04:49:31] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 322 ms [2021-05-28 04:49:31] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 305 ms [2021-05-28 04:49:31] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 320 ms [2021-05-28 04:49:31] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 319 ms [2021-05-28 04:49:33] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 324 ms [2021-05-28 04:49:33] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 323 ms [2021-05-28 04:49:33] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 320 ms [2021-05-28 04:49:33] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 322 ms [2021-05-28 04:49:33] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 323 ms [2021-05-28 04:49:33] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 324 ms [2021-05-28 04:49:33] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 302 ms [2021-05-28 04:49:34] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 302.21; recv_loss = 0.00% [2021-05-28 04:49:37] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 309.73; recv_loss = 0.00% [2021-05-28 04:49:39] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 323 ms [2021-05-28 04:49:40] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 303.49; recv_loss = 0.00% [2021-05-28 04:49:40] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 305 ms [2021-05-28 04:49:40] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 307 ms [2021-05-28 04:49:40] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 321 ms [2021-05-28 04:49:40] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 322 ms [2021-05-28 04:49:40] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 324 ms [2021-05-28 04:49:43] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 305.73; recv_loss = 0.00% [2021-05-28 04:49:46] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 298 ms [2021-05-28 04:49:46] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 301.47; recv_loss = 0.00% [2021-05-28 04:49:49] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 301.47; recv_loss = 0.00% [2021-05-28 04:49:51] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 324 ms [2021-05-28 04:49:52] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 300.65; recv_loss = 0.00% [2021-05-28 04:49:55] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 302.15; recv_loss = 0.00% [2021-05-28 04:49:58] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 302.12; recv_loss = 0.00% [2021-05-28 04:49:59] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(10.0006ms) [2021-05-28 04:49:59] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:49:59] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54441... [2021-05-28 04:50:00] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:54441 is our fastest bridge, latency=1256 [2021-05-28 04:50:01] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 304.16; recv_loss = 0.00% [2021-05-28 04:50:04] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 314.48; recv_loss = 0.00% [2021-05-28 04:50:06] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 313 ms [2021-05-28 04:50:07] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 307.55; recv_loss = 0.00% [2021-05-28 04:50:08] DEBG [geph4-client/src/tunman/mod.rs:246] ** watchdog completed in 303.75042ms ** [2021-05-28 04:50:10] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 302.23; recv_loss = 0.00% [2021-05-28 04:50:13] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 308.77; recv_loss = 0.00% [2021-05-28 04:50:16] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 312.60; recv_loss = 0.00% [2021-05-28 04:50:19] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 307.49; recv_loss = 0.00% [2021-05-28 04:50:22] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 300.15; recv_loss = 0.00% [2021-05-28 04:50:25] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 300.96; recv_loss = 0.00% [2021-05-28 04:50:28] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 303.81; recv_loss = 0.00% [2021-05-28 04:50:30] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(14.0008ms) [2021-05-28 04:50:30] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:50:30] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54441... [2021-05-28 04:50:31] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 315.43; recv_loss = 0.00% [2021-05-28 04:50:31] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:54441 is our fastest bridge, latency=1264 [2021-05-28 04:50:34] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 307.17; recv_loss = 0.00% [2021-05-28 04:50:37] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 306.18; recv_loss = 0.00% [2021-05-28 04:50:39] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 303 ms [2021-05-28 04:50:40] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 297 ms [2021-05-28 04:50:40] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 303.72; recv_loss = 0.00% [2021-05-28 04:50:40] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 309 ms [2021-05-28 04:50:40] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 313 ms [2021-05-28 04:50:41] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 308 ms [2021-05-28 04:50:41] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 309 ms [2021-05-28 04:50:41] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 315 ms [2021-05-28 04:50:43] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 301.61; recv_loss = 0.00% [2021-05-28 04:50:46] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 313.85; recv_loss = 0.00% [2021-05-28 04:50:49] DEBG [geph4-client/src/tunman/mod.rs:246] ** watchdog completed in 312.984239ms ** [2021-05-28 04:50:49] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 307.06; recv_loss = 0.00% [2021-05-28 04:50:52] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 309.79; recv_loss = 0.00% [2021-05-28 04:50:55] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 306.76; recv_loss = 0.00% [2021-05-28 04:50:58] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 311.36; recv_loss = 0.00% [2021-05-28 04:51:01] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(10.0006ms) [2021-05-28 04:51:01] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:51:01] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54441... [2021-05-28 04:51:01] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 313.85; recv_loss = 0.00% [2021-05-28 04:51:03] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:54441 is our fastest bridge, latency=1290 [2021-05-28 04:51:04] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 305.36; recv_loss = 0.00% [2021-05-28 04:51:07] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 302.71; recv_loss = 0.00% [2021-05-28 04:51:11] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 305.42; recv_loss = 0.00% [2021-05-28 04:51:14] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 308.23; recv_loss = 0.00% [2021-05-28 04:51:17] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 306.61; recv_loss = 0.00% [2021-05-28 04:51:20] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 308.96; recv_loss = 0.00% [2021-05-28 04:51:23] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 310.79; recv_loss = 0.00% [2021-05-28 04:51:25] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 315 ms [2021-05-28 04:51:26] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 305.22; recv_loss = 0.00% [2021-05-28 04:51:27] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 303 ms [2021-05-28 04:51:27] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 328 ms [2021-05-28 04:51:29] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 307.22; recv_loss = 0.00% [2021-05-28 04:51:29] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 314 ms [2021-05-28 04:51:29] DEBG [geph4-client/src/tunman/mod.rs:246] ** watchdog completed in 312.90546ms ** [2021-05-28 04:51:32] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 308.03; recv_loss = 0.00% [2021-05-28 04:51:33] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(6.0004ms) [2021-05-28 04:51:33] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:51:33] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54441... [2021-05-28 04:51:34] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:54441 is our fastest bridge, latency=1285 [2021-05-28 04:51:35] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 317.81; recv_loss = 0.00% [2021-05-28 04:51:38] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 312.55; recv_loss = 0.00% [2021-05-28 04:51:41] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 312.22; recv_loss = 0.00% [2021-05-28 04:51:44] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 318.20; recv_loss = 0.00% [2021-05-28 04:51:45] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 319 ms [2021-05-28 04:51:45] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 317 ms [2021-05-28 04:51:47] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 299.49; recv_loss = 0.00% [2021-05-28 04:51:50] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 315.20; recv_loss = 0.00% [2021-05-28 04:51:53] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 302.17; recv_loss = 0.00% [2021-05-28 04:51:56] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 302.98; recv_loss = 0.00% [2021-05-28 04:51:58] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:51:59] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 314.77; recv_loss = 0.00% [2021-05-28 04:52:02] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 312.43; recv_loss = 0.00% [2021-05-28 04:52:04] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(4.0003ms) [2021-05-28 04:52:04] DEBG [geph4-client/src/cache.rs:138] refreshing from binder for cache.bridges.us-pdx-01.exits.geph.io [2021-05-28 04:52:04] WARN [lib/aioutils/src/dns.rs:7] using custom DNS implementation to resolve www.netlify.com:443 [2021-05-28 04:52:04] WARN [lib/aioutils/src/dns.rs:7] using custom DNS implementation to resolve ajax.aspnetcdn.com:443 [2021-05-28 04:52:04] WARN [lib/aioutils/src/dns.rs:7] using custom DNS implementation to resolve d1hoqe10mv32pv.cloudfront.net:443 [2021-05-28 04:52:04] WARN [lib/aioutils/src/dns.rs:7] using custom DNS implementation to resolve d3dsacqprgcsqh.cloudfront.net:443 [2021-05-28 04:52:04] WARN [lib/aioutils/src/dns.rs:7] using custom DNS implementation to resolve 216-24-183-77.nip.io:443 [2021-05-28 04:52:04] WARN [lib/aioutils/src/dns.rs:7] using custom DNS implementation to resolve www.cdn77.com:443 [2021-05-28 04:52:04] WARN [lib/binder_transport/src/lib.rs:116] request_multi failed: Some(Err(Other("远程主机强迫关闭了一个现有的连接。 (os error 10054)"))) [2021-05-28 04:52:05] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 299.49; recv_loss = 0.00% [2021-05-28 04:52:05] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:52:07] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54582... [2021-05-28 04:52:07] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:51848... [2021-05-28 04:52:07] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:37371... [2021-05-28 04:52:08] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 298.58; recv_loss = 0.00% [2021-05-28 04:52:08] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:51848 is our fastest bridge, latency=3073 [2021-05-28 04:52:08] DEBG [geph4-client/src/tunman/getsess.rs:162] starting hijack of other_id = 88559b9523... [2021-05-28 04:52:08] DEBG [geph4-client/src/tunman/getsess.rs:180] finished hijack of other_id = 88559b9523 with downstream data of 637! [2021-05-28 04:52:09] DEBG [geph4-client/src/tunman/mod.rs:246] ** watchdog completed in 282.177382ms ** [2021-05-28 04:52:11] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 232.57; recv_loss = 0.00% [2021-05-28 04:52:14] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 236.64; recv_loss = 0.98% [2021-05-28 04:52:17] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 221.41; recv_loss = 3.81% [2021-05-28 04:52:20] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 222.39; recv_loss = 3.81% [2021-05-28 04:52:21] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 235 ms [2021-05-28 04:52:22] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 275 ms [2021-05-28 04:52:22] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 242 ms [2021-05-28 04:52:22] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 244 ms [2021-05-28 04:52:23] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 236.79; recv_loss = 6.36% [2021-05-28 04:52:26] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 238.62; recv_loss = 6.36% [2021-05-28 04:52:29] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 238.66; recv_loss = 6.36% [2021-05-28 04:52:32] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:52:32] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 220.80; recv_loss = 8.18% [2021-05-28 04:52:34] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:52:34] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:52:34] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:52:34] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:52:34] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:52:35] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 225.82; recv_loss = 9.01% [2021-05-28 04:52:38] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:52:38] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 248.67; recv_loss = 11.40% [2021-05-28 04:52:38] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(17.0009ms) [2021-05-28 04:52:38] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:52:38] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:51848... [2021-05-28 04:52:40] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54582... [2021-05-28 04:52:40] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:37371... [2021-05-28 04:52:40] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:51848 is our fastest bridge, latency=2008 [2021-05-28 04:52:41] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 245.42; recv_loss = 13.68% [2021-05-28 04:52:44] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 237.88; recv_loss = 14.29% [2021-05-28 04:52:47] DEBG [geph4-client/src/tunman/mod.rs:188] opened connection in 240 ms [2021-05-28 04:52:47] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 224.11; recv_loss = 13.68% [2021-05-28 04:52:49] DEBG [geph4-client/src/tunman/mod.rs:246] ** watchdog completed in 235.844626ms ** [2021-05-28 04:52:50] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 238.81; recv_loss = 11.40% [2021-05-28 04:52:53] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 246.33; recv_loss = 15.83% [2021-05-28 04:52:56] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 237.21; recv_loss = 14.29% [2021-05-28 04:52:59] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 219.64; recv_loss = 14.29% [2021-05-28 04:53:02] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 227.30; recv_loss = 4.67% [2021-05-28 04:53:05] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 237.89; recv_loss = 4.67% [2021-05-28 04:53:08] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 238.83; recv_loss = 12.93% [2021-05-28 04:53:10] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(20.0011ms) [2021-05-28 04:53:10] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:53:10] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:51848... [2021-05-28 04:53:11] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 236.17; recv_loss = 12.61% [2021-05-28 04:53:11] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:51848 is our fastest bridge, latency=1083 [2021-05-28 04:53:13] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:53:14] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 220.85; recv_loss = 12.61% [2021-05-28 04:53:17] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 226.14; recv_loss = 12.61% [2021-05-28 04:53:20] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 224.65; recv_loss = 12.61% [2021-05-28 04:53:23] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 224.59; recv_loss = 12.93% [2021-05-28 04:53:26] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 234.66; recv_loss = 14.41% [2021-05-28 04:53:29] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 216.02; recv_loss = 15.13% [2021-05-28 04:53:30] DEBG [geph4-client/src/tunman/mod.rs:246] ** watchdog completed in 240.930883ms ** [2021-05-28 04:53:32] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 237.65; recv_loss = 16.53% [2021-05-28 04:53:35] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 219.15; recv_loss = 20.93% [2021-05-28 04:53:38] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 229.95; recv_loss = 16.53% [2021-05-28 04:53:41] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 235.10; recv_loss = 19.20% [2021-05-28 04:53:41] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(4.0002ms) [2021-05-28 04:53:41] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:53:41] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:51848... [2021-05-28 04:53:42] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:51848 is our fastest bridge, latency=1024 [2021-05-28 04:53:44] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 260.29; recv_loss = 19.05% [2021-05-28 04:53:47] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 237.48; recv_loss = 14.41% [2021-05-28 04:53:50] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 232.12; recv_loss = 13.45% [2021-05-28 04:53:53] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 231.02; recv_loss = 12.93% [2021-05-28 04:53:56] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 244.40; recv_loss = 12.93% [2021-05-28 04:53:59] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 238.32; recv_loss = 12.93% [2021-05-28 04:54:02] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 229.18; recv_loss = 12.93% [2021-05-28 04:54:05] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 229.02; recv_loss = 12.93% [2021-05-28 04:54:08] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:54:08] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 225.74; recv_loss = 5.50% [2021-05-28 04:54:10] DEBG [geph4-client/src/tunman/mod.rs:246] ** watchdog completed in 235.246015ms ** [2021-05-28 04:54:11] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 232.73; recv_loss = 8.04% [2021-05-28 04:54:12] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(5.0002ms) [2021-05-28 04:54:12] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:54:12] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:51848... [2021-05-28 04:54:14] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 235.80; recv_loss = 8.04% [2021-05-28 04:54:14] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54582... [2021-05-28 04:54:14] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:37371... [2021-05-28 04:54:15] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:54:16] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:54582 is our fastest bridge, latency=3281 [2021-05-28 04:54:16] DEBG [geph4-client/src/tunman/getsess.rs:162] starting hijack of other_id = 88559b9523... [2021-05-28 04:54:17] DEBG [geph4-client/src/tunman/getsess.rs:180] finished hijack of other_id = 88559b9523 with downstream data of 636! [2021-05-28 04:54:17] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 1000000.00; recv_loss = 0.00% [2021-05-28 04:54:20] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 316.73; recv_loss = 0.00% [2021-05-28 04:54:23] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 312.63; recv_loss = 0.00% [2021-05-28 04:54:26] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:54:26] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:54:26] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:54:26] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:54:26] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:54:26] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 314.04; recv_loss = 0.00% [2021-05-28 04:54:27] WARN [lib/sosistab/src/stats.rs:75] decimation! [2021-05-28 04:54:29] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 312.65; recv_loss = 0.00% [2021-05-28 04:54:32] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 315.87; recv_loss = 0.00% [2021-05-28 04:54:35] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 315.68; recv_loss = 0.00% [2021-05-28 04:54:38] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 314.57; recv_loss = 0.00% [2021-05-28 04:54:41] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 319.88; recv_loss = 0.00% [2021-05-28 04:54:44] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 315.83; recv_loss = 0.00% [2021-05-28 04:54:47] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(2.0001ms) [2021-05-28 04:54:47] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:54:47] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54582... [2021-05-28 04:54:47] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 318.87; recv_loss = 0.00% [2021-05-28 04:54:48] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:54582 is our fastest bridge, latency=1248 [2021-05-28 04:54:50] DEBG [geph4-client/src/tunman/mod.rs:246] ** watchdog completed in 316.443038ms ** [2021-05-28 04:54:50] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 315.42; recv_loss = 0.00% [2021-05-28 04:54:53] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 314.39; recv_loss = 0.00% [2021-05-28 04:54:56] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 311.96; recv_loss = 0.00% [2021-05-28 04:54:59] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 312.63; recv_loss = 0.00% [2021-05-28 04:55:02] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 309.30; recv_loss = 0.00% [2021-05-28 04:55:05] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 309.19; recv_loss = 0.00% [2021-05-28 04:55:09] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 320.30; recv_loss = 0.00% [2021-05-28 04:55:12] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 319.04; recv_loss = 0.00% [2021-05-28 04:55:15] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 316.75; recv_loss = 0.00% [2021-05-28 04:55:18] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 316.30; recv_loss = 0.00% [2021-05-28 04:55:18] DEBG [geph4-client/src/tunman/reroute.rs:35] rerouter called after interval of Ok(10.0005ms) [2021-05-28 04:55:18] DEBG [geph4-client/src/tunman/getsess.rs:23] got 3 bridges [2021-05-28 04:55:18] DEBG [geph4-client/src/tunman/getsess.rs:40] connecting through [redacted]:54582... [2021-05-28 04:55:20] INFO [geph4-client/src/tunman/getsess.rs:78] [redacted]:54582 is our fastest bridge, latency=1288 [2021-05-28 04:55:21] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 317.03; recv_loss = 0.00% [2021-05-28 04:55:24] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 317.02; recv_loss = 0.00% [2021-05-28 04:55:27] INFO [geph4-client/src/main_connect.rs:228] ** STATS **: smooth_ping = 313.90; recv_loss = 0.00%