Hang during startup
When I start conjure + Tor, nothing is happening for several minutes.
Looks like conjure encounter i/o timeout, but for some reason do not propagate this error to Tor.
Timeout by itself is not much of a problem, incorrect processing of timeout is.
Here is what I see in Tor logs:
Jan 27 11:38:53.000 [notice] Bootstrapped 0% (starting): Starting
Jan 27 11:38:53.000 [notice] Starting with guard context "bridges"
Jan 27 11:38:53.000 [notice] Delaying directory fetches: No running bridges
Jan 27 11:38:54.000 [notice] Bootstrapped 1% (conn_pt): Connecting to pluggable transport
Jan 27 11:38:54.000 [notice] Bootstrapped 2% (conn_done_pt): Connected to pluggable transport
Jan 27 11:38:54.000 [notice] Bootstrapped 10% (conn_done): Connected to a relay
Jan 27 11:43:54.000 [warn] Problem bootstrapping. Stuck at 10% (conn_done): Connected to a relay. (DONE; DONE; count 1; recommendation warn; host 0000000000000000000000000000000000000000 at 143.110.214.222:80)
Jan 27 11:43:54.000 [warn] 1 connections have failed:
Jan 27 11:43:54.000 [warn] 1 connections died in state handshaking (TLS) with SSL state SSLv3/TLS write client hello in HANDSHAKE
Jan 27 11:50:39.000 [warn] Problem bootstrapping. Stuck at 10% (conn_done): Connected to a relay. (DONE; DONE; count 2; recommendation warn; host 0000000000000000000000000000000000000000 at 143.110.214.222:80)
Jan 27 11:50:39.000 [warn] 2 connections have failed:
Jan 27 11:50:39.000 [warn] 2 connections died in state handshaking (TLS) with SSL state SSLv3/TLS write client hello in HANDSHAKE
Jan 27 11:51:11.000 [notice] Application request when we haven't used client functionality lately. Optimistically trying known bridges again.
Jan 27 11:51:11.000 [notice] Application request when we haven't used client functionality lately. Optimistically trying known bridges again.
Jan 27 11:51:11.000 [notice] Application request when we haven't used client functionality lately. Optimistically trying known bridges again.Here is what I see in conjure logs:
[11:38:53] Redirecting log to file
2023/01/27 08:38:53 Started SOCKS listener at 127.0.0.1:49518
2023/01/27 08:38:54 SOCKS accepted: {143.110.214.222:80 url=https://registration.refraction.network.global.prod.fastly.net/api;front=cdn.sstatic.net map[front:[cdn.sstatic.net] url:[https://registration.refraction.network.global.prod.fastly.net/api]]}
2023/01/27 08:38:54 Attempting to connect to bridge at 143.110.214.222:80
2023/01/27 08:38:54 Using the registration API at https://registration.refraction.network.global.prod.fastly.net/api
[11:38:54] [0-993fa7] Shared Secret - 993fa79ab18355764b72929c0ab200426a6820e4e3b3296d9c20c7a67668d459
[11:38:54] [0-993fa7] covert 143.110.214.222:80
[11:38:54] [0-993fa7] Representative - a78b0aca057f22e950441300511844dfd611b9d30ee42ee9e3fb96c230eae5d5
[11:38:54] [0-993fa7] registering via APIRegistrarBidirectional
2023/01/27 08:38:54 Buffering 517 bytes to send later
2023/01/27 08:38:54 Performing a Conjure registration with domain fronting...
2023/01/27 08:38:54 Conjure station URL: https://registration.refraction.network.global.prod.fastly.net/api
2023/01/27 08:38:54 Domain front: cdn.sstatic.net
[11:38:55] [0-993fa7] bidirectional API registration succeeded
[11:38:55] [0-993fa7] sleeping for 1s
[11:38:56] [0-993fa7] Attempting to Connect ...
[11:38:56] [0-993fa7] Connected to phantom 2001:48a8:687f:1::a32 using transport 1
2023/01/27 08:38:56 Successfully connected to phantom proxy!
2023/01/27 08:38:56 Connected to bridge at 143.110.214.222:80
2023/01/27 08:38:56 Flushed 517 bytes from buffer
[11:38:57] [0-993fa7] failed to dial phantom 35.6.92.87: dial tcp 35.6.92.87:443: i/o timeout
2023/01/27 08:45:39 SOCKS accepted: {143.110.214.222:80 url=https://registration.refraction.network.global.prod.fastly.net/api;front=cdn.sstatic.net map[front:[cdn.sstatic.net] url:[https://registration.refraction.network.global.prod.fastly.net/api]]}
2023/01/27 08:45:39 Attempting to connect to bridge at 143.110.214.222:80
2023/01/27 08:45:39 Using the registration API at https://registration.refraction.network.global.prod.fastly.net/api
2023/01/27 08:45:39 Buffering 517 bytes to send later
[11:45:39] [1-d395fc] Shared Secret - d395fc83724535c06a8696810887780d39dc92c55906a672a8009d4f2959e25c
[11:45:39] [1-d395fc] covert 143.110.214.222:80
[11:45:39] [1-d395fc] Representative - 3fc023e0ac6e4a8561591b150a5744bd62d379ac9291899e248e1e60bbf10775
[11:45:39] [1-d395fc] registering via APIRegistrarBidirectional
2023/01/27 08:45:39 Performing a Conjure registration with domain fronting...
2023/01/27 08:45:39 Conjure station URL: https://registration.refraction.network.global.prod.fastly.net/api
2023/01/27 08:45:39 Domain front: cdn.sstatic.net
[11:45:40] [1-d395fc] bidirectional API registration succeeded
[11:45:40] [1-d395fc] sleeping for 1s
[11:45:41] [1-d395fc] Attempting to Connect ...
[11:45:41] [1-d395fc] Connected to phantom 35.8.49.148 using transport 1
2023/01/27 08:45:41 Successfully connected to phantom proxy!
2023/01/27 08:45:41 Connected to bridge at 143.110.214.222:80
2023/01/27 08:45:41 Flushed 517 bytes from buffer
[11:45:42] [1-d395fc] failed to dial phantom 2001:48a8:7fff:2:a1ae:301b:83bf:dd9d: dial tcp [2001:48a8:7fff:2:a1ae:301b:83bf:dd9d]:443: i/o timeout
2023/01/27 08:51:20 SOCKS accepted: {143.110.214.222:80 url=https://registration.refraction.network.global.prod.fastly.net/api;front=cdn.sstatic.net map[front:[cdn.sstatic.net] url:[https://registration.refraction.network.global.prod.fastly.net/api]]}
2023/01/27 08:51:20 Attempting to connect to bridge at 143.110.214.222:80
2023/01/27 08:51:20 Using the registration API at https://registration.refraction.network.global.prod.fastly.net/api
2023/01/27 08:51:20 Buffering 517 bytes to send later
[11:51:20] [2-e1613b] Shared Secret - e1613b624c8f3e54d59282f8d4b9e443f21bb99fbb00abdc35536be48fa0515d
[11:51:20] [2-e1613b] covert 143.110.214.222:80
[11:51:20] [2-e1613b] Representative - 22d161cbd51034061570b0e680a5170a4816666ef76cdcd6e12ca134fce80689
[11:51:20] [2-e1613b] registering via APIRegistrarBidirectional
2023/01/27 08:51:20 Performing a Conjure registration with domain fronting...
2023/01/27 08:51:20 Conjure station URL: https://registration.refraction.network.global.prod.fastly.net/api
2023/01/27 08:51:20 Domain front: cdn.sstatic.net
[11:51:20] [2-e1613b] bidirectional API registration succeeded
[11:51:20] [2-e1613b] sleeping for 1s
[11:51:21] [2-e1613b] Attempting to Connect ...
[11:51:22] [2-e1613b] Connected to phantom 141.219.104.210 using transport 1
2023/01/27 08:51:22 Successfully connected to phantom proxy!
2023/01/27 08:51:22 Connected to bridge at 143.110.214.222:80
2023/01/27 08:51:22 Flushed 517 bytes from buffer
[11:51:23] [2-e1613b] failed to dial phantom 2001:48a8:7fff:2:431b:5a3b:e14e:2515: dial tcp [2001:48a8:7fff:2:431b:5a3b:e14e:2515]:443: i/o timeout Version: 49f2601b