IPtProxy/Onion Browser: Snowflake client just stops with errors when bootstrapping Tor on iOS (and therefore the whole bootstrap)
We're experiencing a lot of instability with Snowflake on Android and iOS.
Esp. on iOS, I always seem to get only the "unrestricted" types. What exactly are the preconditions, to get to the "restricted" snowflakes? Is there something we can do?
Latest IPtProxy currently uses Snowflake at this commit: https://gitweb.torproject.org/pluggable-transports/snowflake.git/commit/?id=af6e2c30e1a6aacc6e7adf9a31df0a387891cc37
While trying to understand, why Snowflake connections are often delayed a long time or not happening at all, I experienced random stops of the code. This is esp. on starting up Onion Browser on iOS.
E.g. see the 2 different logs below.
(It's Onion Browser, Tor and Snowflake combined.)
That's all. That's not just a part. After the last log line, everything stopped. (If the user wouldn't change bridge settings, that is...) The change is about 50/50 that it just stops.
Do you have an idea, why that happens?
Interestingly enough, this seems to happen on a real device way more often than on a simulator on an Intel MacBook Pro running Big Sur. (Note simulator vs. emulator!) So that might point to a timing issue?
This is our config:
https://github.com/OnionBrowser/OnionBrowser/blob/2.X/OnionBrowser/OnionManager.swift#L245-L248
Is there something we can do to mitigate this in IPtProxy? Some catch-exception-and-restart or something?
2021-05-11 17:04:17.463104+0200 OnionBrowser[4397:1863717] [HSTSCache] locked and loaded with 67296 preloaded hosts
[OnionManager] dataDir=file:///var/mobile/Containers/Data/Application/164FD3FE-402A-4A05-A626-1F4F48B6573C/Library/Caches/tor/
[OnionManager] bridgesId=snowflake
[OnionManager] #stopObfs4proxy
2021/05/11 15:04:18
--- Starting Snowflake Client ---
2021/05/11 15:04:18 Using ICE servers:
2021/05/11 15:04:18 url: stun:stun.voipgate.com:3478
2021/05/11 15:04:18 url: stun:stun.uls.co.za:3478
2021/05/11 15:04:18 url: stun:stun.altar.com.pl:3478
2021/05/11 15:04:18 url: stun:stun.voys.nl:3478
2021/05/11 15:04:18 url: stun:stun.dus.net:3478
2021/05/11 15:04:18 url: stun:stun.epygi.com:3478
2021/05/11 15:04:18 url: stun:stun.sonetel.com:3478
2021/05/11 15:04:18 Rendezvous using Broker at: https://snowflake-broker.torproject.net.global.prod.fastly.net/
2021/05/11 15:04:18 Domain fronting using: cdn.sstatic.net
VERSION 1
CMETHOD-ERROR meek_lite no such method
CMETHOD-ERROR obfs2 no such method
CMETHOD-ERROR obfs3 no such method
CMETHOD-ERROR obfs4 no such method
CMETHOD-ERROR scramblesuit no such method
[OnionManager] ipv6_status: 0
[OnionManager] arguments=["--allow-missing-torrc", "--ignore-missing-torrc", "--ClientOnly", "1", "--AvoidDiskWrites", "1", "--SocksPort", "127.0.0.1:39050", "--ControlPort", "127.0.0.1:39060", "--Log", "notice stdout", "--ClientUseIPv6", "1", "--ClientTransportPlugin", "obfs4 socks5 127.0.0.1:47351", "--ClientTransportPlugin", "meek_lite socks5 127.0.0.1:47352", "--ClientTransportPlugin", "snowflake socks5 127.0.0.1:52610", "--GeoIPFile", "/private/var/containers/Bundle/Application/B0BCF18E-0C46-41A6-A093-4B4879EB264C/OnionBrowser.app/geoip", "--GeoIPv6File", "/private/var/containers/Bundle/Application/B0BCF18E-0C46-41A6-A093-4B4879EB264C/OnionBrowser.app/geoip6", "--ClientOnionAuthDir", "/var/mobile/Containers/Data/Application/164FD3FE-402A-4A05-A626-1F4F48B6573C/Library/Caches/tor/auth", "--Bridge", "snowflake 192.0.2.3:1", "--UseBridges", "1", "--ClientPreferIPv6ORPort", "auto", "--ClientUseIPv4", "1"]
[OnionManager] Starting Tor
2021/05/11 15:04:18 Started SOCKS listener at 127.0.0.1:52610.
CMETHOD snowflake socks5 127.0.0.1:52610
CMETHODS DONE
2021-05-11 17:04:18.232173+0200 OnionBrowser[4397:1863717] [Assert] Trying to present the presentation controller while transitioning already. (<_UIRootPresentationController: 0x111d17d30>)
May 11 17:04:18.251 [notice] Tor 0.4.5.7 (git-83f895c015de5520) running on Darwin with Libevent 2.1.12-stable, OpenSSL 1.1.1k, Zlib 1.2.11, Liblzma 5.2.5, Libzstd N/A and Unknown N/A as libc.
May 11 17:04:18.251 [notice] Tor can't help you if you use it wrong! Learn how to be safe at https://www.torproject.org/download/download#warning
May 11 17:04:18.251 [notice] Configuration file "/private/var/mobile/Containers/Data/Application/164FD3FE-402A-4A05-A626-1F4F48B6573C/.torrc" not present, using reasonable defaults.
May 11 17:04:18.256 [notice] Opening Socks listener on 127.0.0.1:39050
May 11 17:04:18.256 [notice] Opened Socks listener connection (ready) on 127.0.0.1:39050
May 11 17:04:18.256 [notice] Opening Control listener on 127.0.0.1:39060
May 11 17:04:18.256 [notice] Opened Control listener connection (ready) on 127.0.0.1:39060
May 11 17:04:18.000 [notice] Parsing GEOIP IPv4 file /private/var/containers/Bundle/Application/B0BCF18E-0C46-41A6-A093-4B4879EB264C/OnionBrowser.app/geoip.
2021/05/11 15:04:18 NAT Type: unrestricted
May 11 17:04:18.000 [notice] Parsing GEOIP IPv6 file /private/var/containers/Bundle/Application/B0BCF18E-0C46-41A6-A093-4B4879EB264C/OnionBrowser.app/geoip6.
May 11 17:04:18.000 [notice] Bootstrapped 0% (starting): Starting
May 11 17:04:18.000 [notice] Starting with guard context "bridges"
May 11 17:04:18.000 [notice] Delaying directory fetches: No running bridges
May 11 17:04:19.000 [notice] New control connection opened from 127.0.0.1.
[OnionManager] cookie= XEkOaClqVqXuS7OS0W9SkYS8Qpf9TxnSdRNrqRZngEA=
May 11 17:04:19.000 [notice] Bootstrapped 1% (conn_pt): Connecting to pluggable transport
May 11 17:04:19.000 [notice] Bootstrapped 2% (conn_done_pt): Connected to pluggable transport
2021/05/11 15:04:19 SOCKS accepted: {192.0.2.3:1 map[]}
2021/05/11 15:04:19 ---- Handler: begin collecting snowflakes ---
2021/05/11 15:04:19 ---- Handler: starting a new session ---
May 11 17:04:19.000 [notice] Bootstrapped 10% (conn_done): Connected to a relay
2021/05/11 15:04:19 WebRTC: Collecting a new Snowflake. Currently at [0/3]
2021/05/11 15:04:19 snowflake-b5939212541b4c43 connecting...
2021/05/11 15:04:19 redialing on same connection
[OnionManager] progress=1
[OnionManager] progress=2
2021/05/11 15:04:19 ---- Handler: begin stream 3 ---
[OnionManager] progress=10
2021/05/11 15:04:20 WebRTC: DataChannel created.
2021/05/11 15:04:20 WebRTC: Created offer
2021/05/11 15:04:20 WebRTC: Set local description
2021/05/11 15:04:20 WebRTC: PeerConnection created.
2021/05/11 15:04:20 Negotiating via BrokerChannel...
Target URL: snowflake-broker.torproject.net.global.prod.fastly.net Front URL: cdn.sstatic.net
2021/05/11 15:04:23 BrokerChannel Response:
200 OK
2021/05/11 15:04:23 Received answer: {"type":"answer","sdp":"v=0\r\no=mozilla...THIS_IS_SDPARTA-87.0 5216436289655477355 0 IN IP4 0.0.0.0\r\ns=-\r\nt=0 0\r\na=sendrecv\r\na=fingerprint:sha-256 E9:DE:10:5F:A8:C9:7F:D1:49:1F:26:49:DF:56:7C:7F:97:C5:DE:DD:EF:D7:E6:6D:DD:E3:66:3F:D2:6F:31:49\r\na=group:BUNDLE 0\r\na=ice-options:trickle\r\na=msid-semantic:WMS *\r\nm=application 46050 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 85.114.140.58\r\na=candidate:0 1 UDP 2122187007 65ea5e9f-adaf-4680-989d-c6b2f66a0d14.local 46050 typ host\r\na=candidate:2 1 UDP 2122252543 5d3245fa-bea1-4e18-ab51-954ee57e291f.local 59991 typ host\r\na=candidate:4 1 TCP 2105458943 65ea5e9f-adaf-4680-989d-c6b2f66a0d14.local 9 typ host tcptype active\r\na=candidate:5 1 TCP 2105524479 5d3245fa-bea1-4e18-ab51-954ee57e291f.local 9 typ host tcptype active\r\na=candidate:1 1 UDP 1685987327 85.114.140.58 46050 typ srflx raddr 0.0.0.0 rport 0\r\na=candidate:3 1 UDP 1686052607 2a01:4f8:191:22f6:77bf:d860:9b03:d768 59991 typ srflx raddr 0.0.0.0 rport 0\r\na=sendrecv\r\na=end-of-candidates\r\na=ice-pwd:b2a69faea3356249fb9abf124a9dfec3\r\na=ice-ufrag:e5f0ed04\r\na=mid:0\r\na=setup:active\r\na=sctp-port:5000\r\na=max-message-size:1073741823\r\n"}
2021/05/11 15:04:23 Received Answer.
2021/05/11 15:04:25 WebRTC: DataChannel.OnOpen
2021/05/11 15:04:25 ---- Handler: snowflake assigned ----
May 11 17:04:26.000 [notice] Bootstrapped 14% (handshake): Handshaking with a relay
[OnionManager] progress=14
May 11 17:04:26.000 [notice] Learned fingerprint 2B280B23E1107BB62ABFC40DDCC8824814F80A72 for bridge 192.0.2.3:1 (with transport 'snowflake').
May 11 17:04:26.000 [notice] Bootstrapped 15% (handshake_done): Handshake with a relay done
May 11 17:04:26.000 [notice] Bootstrapped 20% (onehop_create): Establishing an encrypted directory connection
[OnionManager] progress=15
[OnionManager] progress=20
May 11 17:04:27.000 [notice] Bootstrapped 25% (requesting_status): Asking for networkstatus consensus
[OnionManager] progress=25
May 11 17:04:27.000 [notice] new bridge descriptor 'flakey' (fresh): $2B280B23E1107BB62ABFC40DDCC8824814F80A72~flakey [1zOHpg+FxqQfi/6jDLtCpHHqBTH8gjYmCKXkus1D5Ko] at 192.0.2.3
2021/05/11 15:04:29 Traffic Bytes (in|out): 9355 | 7290 -- (19 OnMessages, 22 Sends)
2021/05/11 15:04:29 WebRTC: Collecting a new Snowflake. Currently at [1/3]
2021/05/11 15:04:29 snowflake-69370291c40e5fbb connecting...
2021/05/11 15:04:29 WebRTC: DataChannel created.
2021/05/11 15:04:29 WebRTC: Created offer
2021/05/11 15:04:29 WebRTC: Set local description
2021/05/11 15:04:29 WebRTC: PeerConnection created.
2021/05/11 15:04:29 Negotiating via BrokerChannel...
Target URL: snowflake-broker.torproject.net.global.prod.fastly.net Front URL: cdn.sstatic.net
[OnionManager] Triggering Tor connection retry.
May 11 17:04:33.000 [notice] Closing no-longer-configured Socks listener on 127.0.0.1:39050
May 11 17:04:33.000 [notice] DisableNetwork is set. Tor will not make or accept non-control network connections. Shutting down all existing connections.
May 11 17:04:33.000 [notice] Opening Socks listener on 127.0.0.1:39050
May 11 17:04:33.000 [notice] Opened Socks listener connection (ready) on 127.0.0.1:39050
2021/05/11 15:04:33 copy loop ended
2021/05/11 15:04:33 ---- Handler: closed stream 3 ---
2021/05/11 15:04:34 BrokerChannel Response:
200 OK
2021/05/11 15:04:34 Received answer: {"type":"answer","sdp":"v=0\r\no=- 7009743295814005219 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0\r\na=msid-semantic: WMS\r\nm=application 42411 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 85.107.124.19\r\na=candidate:111704799 1 udp 2122260223 192.168.1.34 54705 typ host generation 0 network-id 1 network-cost 10\r\na=candidate:1210811951 1 tcp 1518280447 192.168.1.34 9 typ host tcptype active generation 0 network-id 1 network-cost 10\r\na=candidate:4038591243 1 udp 1686052607 85.107.124.19 42411 typ srflx raddr 192.168.1.34 rport 54705 generation 0 network-id 1 network-cost 10\r\na=ice-ufrag:t5qZ\r\na=ice-pwd:AiKgb8nIocPjprb7/w469Q28\r\na=ice-options:trickle\r\na=fingerprint:sha-256 0E:C5:3A:6E:E7:87:9A:9A:08:13:25:C0:24:2E:92:E7:A6:87:5E:A2:53:9A:AA:3F:96:23:8F:E2:05:26:FE:5D\r\na=setup:active\r\na=mid:0\r\na=sctp-port:5000\r\n"}
2021/05/11 15:04:34 Received Answer.
2021/05/11 15:04:34 Traffic Bytes (in|out): 7168 | 1323 -- (10 OnMessages, 9 Sends)
2021/05/11 15:04:36 WebRTC: DataChannel.OnOpen
2021/05/11 15:04:36 ConnectLoop: stopped.
2021/05/11 15:04:36 WebRTC: closing DataChannel
2021/05/11 15:04:36 WebRTC: closing PeerConnection
2021/05/11 15:04:36 WebRTC: DataChannel.OnClose
2021/05/11 15:04:36 WebRTC: Closing
2021/05/11 15:04:36 WebRTC: closing DataChannel
2021/05/11 15:04:36 redialing on same connection
2021/05/11 15:04:36 WebRTC: closing PeerConnection
2021/05/11 15:04:36 WebRTC: DataChannel.OnClose
2021/05/11 15:04:36 copying WebRTC to SOCKS resulted in error: read dummy dummy->dummy: handler: Received invalid Snowflake
2021/05/11 15:04:36 WebRTC: Closing
2021/05/11 15:04:36 WebRTC: melted all 2 snowflakes.
2021/05/11 15:04:36 ---- Handler: end collecting snowflakes ---
2021/05/11 15:04:36 ---- Handler: discarding finished session ---
2021/05/11 15:04:36 Handler ended
2021-05-11 17:23:07.954229+0200 OnionBrowser[4416:1871406] [HSTSCache] locked and loaded with 67296 preloaded hosts
[OnionManager] dataDir=file:///var/mobile/Containers/Data/Application/173396C5-9677-4A89-9DBE-A0275AF0102A/Library/Caches/tor/
[OnionManager] bridgesId=snowflake
[OnionManager] #stopObfs4proxy
2021/05/11 15:23:08
--- Starting Snowflake Client ---
2021/05/11 15:23:08 Using ICE servers:
2021/05/11 15:23:08 url: stun:stun.altar.com.pl:3478
2021/05/11 15:23:08 url: stun:stun.antisip.com:3478
2021/05/11 15:23:08 url: stun:stun.voys.nl:3478
2021/05/11 15:23:08 url: stun:stun.voip.blackberry.com:3478
2021/05/11 15:23:08 url: stun:stun.uls.co.za:3478
2021/05/11 15:23:08 url: stun:stun.bluesip.net:3478
2021/05/11 15:23:08 url: stun:stun.voipgate.com:3478
2021/05/11 15:23:08 Rendezvous using Broker at: https://snowflake-broker.torproject.net.global.prod.fastly.net/
2021/05/11 15:23:08 Domain fronting using: cdn.sstatic.net
[OnionManager] ipv6_status: 0
VERSION 1
CMETHOD-ERROR meek_lite no such method
CMETHOD-ERROR obfs2 no such method
CMETHOD-ERROR obfs3 no such method
CMETHOD-ERROR obfs4 no such method
CMETHOD-ERROR scramblesuit no such method
[OnionManager] arguments=["--allow-missing-torrc", "--ignore-missing-torrc", "--ClientOnly", "1", "--AvoidDiskWrites", "1", "--SocksPort", "127.0.0.1:39050", "--ControlPort", "127.0.0.1:39060", "--Log", "notice stdout", "--ClientUseIPv6", "1", "--ClientTransportPlugin", "obfs4 socks5 127.0.0.1:47351", "--ClientTransportPlugin", "meek_lite socks5 127.0.0.1:47352", "--ClientTransportPlugin", "snowflake socks5 127.0.0.1:52610", "--GeoIPFile", "/private/var/containers/Bundle/Application/D62B6AF8-18A6-4C05-95D3-0A1C0AD669DE/OnionBrowser.app/geoip", "--GeoIPv6File", "/private/var/containers/Bundle/Application/D62B6AF8-18A6-4C05-95D3-0A1C0AD669DE/OnionBrowser.app/geoip6", "--ClientOnionAuthDir", "/var/mobile/Containers/Data/Application/173396C5-9677-4A89-9DBE-A0275AF0102A/Library/Caches/tor/auth", "--Bridge", "snowflake 192.0.2.3:1", "--UseBridges", "1", "--ClientPreferIPv6ORPort", "auto", "--ClientUseIPv4", "1"]
2021/05/11 15:23:08 Started SOCKS listener at 127.0.0.1:52610.
CMETHOD snowflake socks5 127.0.0.1:52610
[OnionManager] Starting Tor
CMETHODS DONE
2021-05-11 17:23:08.707322+0200 OnionBrowser[4416:1871406] [Assert] Trying to present the presentation controller while transitioning already. (<_UIRootPresentationController: 0x10850ce10>)
May 11 17:23:08.722 [notice] Tor 0.4.5.7 (git-83f895c015de5520) running on Darwin with Libevent 2.1.12-stable, OpenSSL 1.1.1k, Zlib 1.2.11, Liblzma 5.2.5, Libzstd N/A and Unknown N/A as libc.
May 11 17:23:08.722 [notice] Tor can't help you if you use it wrong! Learn how to be safe at https://www.torproject.org/download/download#warning
May 11 17:23:08.723 [notice] Configuration file "/private/var/mobile/Containers/Data/Application/173396C5-9677-4A89-9DBE-A0275AF0102A/.torrc" not present, using reasonable defaults.
May 11 17:23:08.726 [notice] Opening Socks listener on 127.0.0.1:39050
May 11 17:23:08.726 [notice] Opened Socks listener connection (ready) on 127.0.0.1:39050
May 11 17:23:08.726 [notice] Opening Control listener on 127.0.0.1:39060
May 11 17:23:08.726 [notice] Opened Control listener connection (ready) on 127.0.0.1:39060
May 11 17:23:08.000 [notice] Parsing GEOIP IPv4 file /private/var/containers/Bundle/Application/D62B6AF8-18A6-4C05-95D3-0A1C0AD669DE/OnionBrowser.app/geoip.
2021/05/11 15:23:08 NAT Type: unrestricted
May 11 17:23:08.000 [notice] Parsing GEOIP IPv6 file /private/var/containers/Bundle/Application/D62B6AF8-18A6-4C05-95D3-0A1C0AD669DE/OnionBrowser.app/geoip6.
May 11 17:23:08.000 [notice] Bootstrapped 0% (starting): Starting
May 11 17:23:09.000 [notice] Starting with guard context "bridges"
May 11 17:23:09.000 [notice] Delaying directory fetches: No running bridges
May 11 17:23:09.000 [notice] New control connection opened from 127.0.0.1.
[OnionManager] cookie= tjBUpSa7mqXyVlHo25Xszzu170VQ5Tm2HBfzepFXwqk=
May 11 17:23:10.000 [notice] Bootstrapped 1% (conn_pt): Connecting to pluggable transport
May 11 17:23:10.000 [notice] Bootstrapped 2% (conn_done_pt): Connected to pluggable transport
2021/05/11 15:23:10 SOCKS accepted: {192.0.2.3:1 map[]}
2021/05/11 15:23:10 ---- Handler: begin collecting snowflakes ---
2021/05/11 15:23:10 ---- Handler: starting a new session ---
2021/05/11 15:23:10 WebRTC: Collecting a new Snowflake. Currently at [0/3]
2021/05/11 15:23:10 snowflake-cdad34980557846d connecting...
May 11 17:23:10.000 [notice] Bootstrapped 10% (conn_done): Connected to a relay
2021/05/11 15:23:10 redialing on same connection
2021/05/11 15:23:10 ---- Handler: begin stream 3 ---
2021/05/11 15:23:10 WebRTC: DataChannel created.
2021/05/11 15:23:10 WebRTC: Created offer
2021/05/11 15:23:10 WebRTC: Set local description
2021/05/11 15:23:10 WebRTC: PeerConnection created.
2021/05/11 15:23:10 Negotiating via BrokerChannel...
Target URL: snowflake-broker.torproject.net.global.prod.fastly.net Front URL: cdn.sstatic.net
2021/05/11 15:23:12 BrokerChannel Response:
200 OK
2021/05/11 15:23:12 Received answer: {"type":"answer","sdp":"v=0\r\no=mozilla...THIS_IS_SDPARTA-89.0 3176598337624981171 0 IN IP4 0.0.0.0\r\ns=-\r\nt=0 0\r\na=sendrecv\r\na=fingerprint:sha-256 6B:41:FC:99:15:80:AE:56:9A:B1:31:6B:9A:D1:1D:A4:91:13:71:1D:11:BD:78:15:C8:D9:31:33:03:AE:71:98\r\na=group:BUNDLE 0\r\na=ice-options:trickle\r\na=msid-semantic:WMS *\r\nm=application 61455 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 67.171.207.78\r\na=candidate:0 1 UDP 2122187007 65d476bf-60a6-4ec2-b9bb-99ca00f2c4ac.local 61455 typ host\r\na=candidate:2 1 UDP 2122252543 67213273-8df2-472b-82c5-dff6924d2b2f.local 61456 typ host\r\na=candidate:4 1 TCP 2105458943 65d476bf-60a6-4ec2-b9bb-99ca00f2c4ac.local 9 typ host tcptype active\r\na=candidate:5 1 TCP 2105524479 67213273-8df2-472b-82c5-dff6924d2b2f.local 9 typ host tcptype active\r\na=candidate:1 1 UDP 1685987327 67.171.207.78 61455 typ srflx raddr 0.0.0.0 rport 0\r\na=sendrecv\r\na=end-of-candidates\r\na=ice-pwd:ae2e1b7f176aaec61adcbce660136628\r\na=ice-ufrag:123f9481\r\na=mid:0\r\na=setup:active\r\na=sctp-port:5000\r\na=max-message-size:1073741823\r\n"}
2021/05/11 15:23:12 Received Answer.
2021/05/11 15:23:14 WebRTC: DataChannel.OnOpen
2021/05/11 15:23:14 ---- Handler: snowflake assigned ----
2021/05/11 15:23:15 Traffic Bytes (in|out): 0 | 2796 -- (0 OnMessages, 10 Sends)
May 11 17:23:17.000 [notice] Bootstrapped 14% (handshake): Handshaking with a relay
[OnionManager] progress=14
May 11 17:23:17.000 [notice] Learned fingerprint 2B280B23E1107BB62ABFC40DDCC8824814F80A72 for bridge 192.0.2.3:1 (with transport 'snowflake').
May 11 17:23:17.000 [notice] Bootstrapped 15% (handshake_done): Handshake with a relay done
May 11 17:23:17.000 [notice] Bootstrapped 20% (onehop_create): Establishing an encrypted directory connection
[OnionManager] progress=15
[OnionManager] progress=20
May 11 17:23:17.000 [notice] Bootstrapped 25% (requesting_status): Asking for networkstatus consensus
[OnionManager] progress=25
May 11 17:23:18.000 [notice] new bridge descriptor 'flakey' (fresh): $2B280B23E1107BB62ABFC40DDCC8824814F80A72~flakey [1zOHpg+FxqQfi/6jDLtCpHHqBTH8gjYmCKXkus1D5Ko] at 192.0.2.3
2021/05/11 15:23:20 Traffic Bytes (in|out): 15952 | 5878 -- (24 OnMessages, 18 Sends)
2021/05/11 15:23:20 WebRTC: Collecting a new Snowflake. Currently at [1/3]
2021/05/11 15:23:20 snowflake-d4f8fb7718eed98e connecting...
2021/05/11 15:23:20 WebRTC: DataChannel created.
2021/05/11 15:23:20 WebRTC: Created offer
2021/05/11 15:23:20 WebRTC: Set local description
2021/05/11 15:23:20 WebRTC: PeerConnection created.
2021/05/11 15:23:20 Negotiating via BrokerChannel...
Target URL: snowflake-broker.torproject.net.global.prod.fastly.net Front URL: cdn.sstatic.net
[OnionManager] Triggering Tor connection retry.
May 11 17:23:23.000 [notice] Closing no-longer-configured Socks listener on 127.0.0.1:39050
May 11 17:23:23.000 [notice] DisableNetwork is set. Tor will not make or accept non-control network connections. Shutting down all existing connections.
May 11 17:23:23.000 [notice] Opening Socks listener on 127.0.0.1:39050
May 11 17:23:23.000 [notice] Opened Socks listener connection (ready) on 127.0.0.1:39050
2021/05/11 15:23:23 copy loop ended
2021/05/11 15:23:23 ---- Handler: closed stream 3 ---
2021/05/11 15:23:25 Traffic Bytes (in|out): 25 | 33 -- (1 OnMessages, 1 Sends)
2021/05/11 15:23:30 Traffic Bytes (in|out): 33 | 25 -- (1 OnMessages, 1 Sends)
2021/05/11 15:23:30 BrokerChannel Response:
504 Gateway Timeout
2021/05/11 15:23:30 WebRTC: closing DataChannel
2021/05/11 15:23:30 WebRTC: closing PeerConnection
2021/05/11 15:23:30 WebRTC: Closing
2021/05/11 15:23:30 WebRTC: Unexpected error, no answer. Retrying...
2021/05/11 15:23:30 WebRTC: Snowflakes have melted Retrying...
2021/05/11 15:23:30 ConnectLoop: stopped.
2021/05/11 15:23:30 WebRTC: closing DataChannel
2021/05/11 15:23:30 WebRTC: closing PeerConnection
2021/05/11 15:23:30 WebRTC: DataChannel.OnClose
2021/05/11 15:23:30 WebRTC: Closing
2021/05/11 15:23:30 WebRTC: melted all 1 snowflakes.
2021/05/11 15:23:30 ---- Handler: end collecting snowflakes ---
2021/05/11 15:23:30 ---- Handler: discarding finished session ---
2021/05/11 15:23:30 Handler ended
2021/05/11 15:23:30 copying WebRTC to SOCKS resulted in error: io: read/write on closed pipe
2021/05/11 15:23:35 Traffic Bytes (in|out): 0 | 33 -- (0 OnMessages, 1 Sends)