search

trailofbits / algo

Set up a personal VPN in the cloud
https://blog.trailofbits.com/2016/12/12/meet-algo-the-vpn-that-works/
GNU Affero General Public License v3.0
29.02k stars 2.33k forks source link

iOS device failed handshake #14247

Open villageFlower opened 3 years ago

villageFlower commented 3 years ago

Describe the bug

I was able to connect using other devices includes android and windows but on one iOS device. It gave me a log that Handshake did not complete after 5 seconds, retrying (try 2). I dug into my server and it was up and running with no firewalls configured. The wired thing is that there were data transfers for both in and out, but no hand handshake was discovered on the server.

The client-side log:

2021-07-18 18:36:32.209548: [APP] App version: 1.0.13 (24) 2021-07-18 18:37:47.462318: [APP] startActivation: Entering (tunnel: ht) 2021-07-18 18:37:47.464182: [APP] startActivation: Starting tunnel 2021-07-18 18:37:47.464694: [APP] startActivation: Success 2021-07-18 18:37:47.484071: [APP] Tunnel 'ht' connection status changed to 'connecting' 2021-07-18 18:37:47.690844: [NET] App version: 1.0.13 (24) 2021-07-18 18:37:47.690966: [NET] Starting tunnel from the app 2021-07-18 18:37:47.787479: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:37:47.788220: [NET] Attaching to interface 2021-07-18 18:37:47.788671: [NET] Routine: decryption worker 3 - started 2021-07-18 18:37:47.788715: [NET] UAPI: Updating private key 2021-07-18 18:37:47.788723: [NET] Routine: handshake worker 1 - started 2021-07-18 18:37:47.788817: [NET] Routine: decryption worker 2 - started 2021-07-18 18:37:47.788845: [NET] Routine: handshake worker 2 - started 2021-07-18 18:37:47.788874: [NET] Routine: event worker - started 2021-07-18 18:37:47.788891: [NET] Routine: encryption worker 2 - started 2021-07-18 18:37:47.788959: [NET] Routine: encryption worker 1 - started 2021-07-18 18:37:47.788963: [NET] Routine: decryption worker 5 - started 2021-07-18 18:37:47.788975: [NET] Routine: decryption worker 1 - started 2021-07-18 18:37:47.788985: [NET] Routine: encryption worker 3 - started 2021-07-18 18:37:47.788991: [NET] Routine: decryption worker 6 - started 2021-07-18 18:37:47.789041: [NET] UAPI: Removing all peers 2021-07-18 18:37:47.789094: [NET] Routine: decryption worker 4 - started 2021-07-18 18:37:47.789111: [NET] Routine: handshake worker 6 - started 2021-07-18 18:37:47.789116: [NET] Routine: handshake worker 5 - started 2021-07-18 18:37:47.789205: [NET] Routine: encryption worker 6 - started 2021-07-18 18:37:47.789231: [NET] Routine: handshake worker 3 - started 2021-07-18 18:37:47.789307: [NET] Routine: handshake worker 4 - started 2021-07-18 18:37:47.789333: [NET] Routine: encryption worker 4 - started 2021-07-18 18:37:47.789338: [NET] Routine: encryption worker 5 - started 2021-07-18 18:37:47.789346: [NET] peer(CT6/…lWC0) - UAPI: Created 2021-07-18 18:37:47.789368: [NET] Routine: TUN reader - started 2021-07-18 18:37:47.789565: [NET] peer(CT6/…lWC0) - UAPI: Updating preshared key 2021-07-18 18:37:47.789799: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:37:47.789938: [NET] peer(CT6/…lWC0) - UAPI: Updating persistent keepalive interval 2021-07-18 18:37:47.789964: [NET] peer(CT6/…lWC0) - UAPI: Removing all allowedips 2021-07-18 18:37:47.790054: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:37:47.790125: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:37:47.790423: [NET] UDP bind has been updated 2021-07-18 18:37:47.790456: [NET] peer(CT6/…lWC0) - Starting 2021-07-18 18:37:47.790699: [NET] Routine: receive incoming v4 - started 2021-07-18 18:37:47.790710: [NET] Interface state was Down, requested Up, now Up 2021-07-18 18:37:47.790769: [NET] Routine: receive incoming v6 - started 2021-07-18 18:37:47.790897: [NET] Device started 2021-07-18 18:37:47.791000: [NET] peer(CT6/…lWC0) - Routine: sequential sender - started 2021-07-18 18:37:47.791004: [NET] Tunnel interface is utun2 2021-07-18 18:37:47.791000: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - started 2021-07-18 18:37:47.792811: [APP] Tunnel 'ht' connection status changed to 'connected' 2021-07-18 18:37:47.793009: [NET] Network change detected with unsatisfied route and interface order [en0, pdp_ip0] 2021-07-18 18:37:47.793044: [NET] Connectivity offline, pausing backend. 2021-07-18 18:37:47.793137: [NET] Device closing 2021-07-18 18:37:47.793322: [NET] Routine: TUN reader - stopped 2021-07-18 18:37:47.793399: [NET] Routine: event worker - stopped 2021-07-18 18:37:47.793518: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:37:47.793683: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:37:47.793839: [NET] peer(CT6/…lWC0) - Stopping 2021-07-18 18:37:47.793943: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - stopped 2021-07-18 18:37:47.793984: [NET] peer(CT6/…lWC0) - Routine: sequential sender - stopped 2021-07-18 18:37:47.794053: [NET] Device closed 2021-07-18 18:37:47.794136: [NET] Routine: decryption worker 1 - stopped 2021-07-18 18:37:47.794150: [NET] Routine: decryption worker 5 - stopped 2021-07-18 18:37:47.794130: [NET] Routine: decryption worker 3 - stopped 2021-07-18 18:37:47.794172: [NET] Routine: decryption worker 6 - stopped 2021-07-18 18:37:47.794179: [NET] Routine: decryption worker 4 - stopped 2021-07-18 18:37:47.794210: [NET] Routine: decryption worker 2 - stopped 2021-07-18 18:37:47.794363: [NET] Routine: handshake worker 1 - stopped 2021-07-18 18:37:47.794502: [NET] Routine: handshake worker 3 - stopped 2021-07-18 18:37:47.794605: [NET] Routine: handshake worker 5 - stopped 2021-07-18 18:37:47.794806: [NET] Routine: handshake worker 6 - stopped 2021-07-18 18:37:47.794799: [NET] Routine: handshake worker 2 - stopped 2021-07-18 18:37:47.794858: [NET] Routine: handshake worker 4 - stopped 2021-07-18 18:37:47.795177: [NET] Routine: encryption worker 2 - stopped 2021-07-18 18:37:47.795238: [NET] Routine: encryption worker 6 - stopped 2021-07-18 18:37:47.795264: [NET] Routine: encryption worker 5 - stopped 2021-07-18 18:37:47.795285: [NET] Routine: encryption worker 4 - stopped 2021-07-18 18:37:47.795301: [NET] Routine: encryption worker 3 - stopped 2021-07-18 18:37:47.795358: [NET] Routine: encryption worker 1 - stopped 2021-07-18 18:37:48.246216: [NET] Network change detected with unsatisfied route and interface order [en0, utun2, pdp_ip0] 2021-07-18 18:37:49.704251: [NET] Network change detected with satisfied route and interface order [en0, utun2, pdp_ip0] 2021-07-18 18:37:49.704557: [NET] Connectivity online, resuming backend. 2021-07-18 18:37:49.822643: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:37:49.823251: [NET] Attaching to interface 2021-07-18 18:37:49.823444: [NET] Routine: encryption worker 1 - started 2021-07-18 18:37:49.823446: [NET] Routine: decryption worker 1 - started 2021-07-18 18:37:49.823477: [NET] Routine: decryption worker 4 - started 2021-07-18 18:37:49.823483: [NET] UAPI: Updating private key 2021-07-18 18:37:49.823487: [NET] Routine: handshake worker 5 - started 2021-07-18 18:37:49.823517: [NET] Routine: encryption worker 5 - started 2021-07-18 18:37:49.823569: [NET] Routine: handshake worker 2 - started 2021-07-18 18:37:49.823577: [NET] Routine: encryption worker 3 - started 2021-07-18 18:37:49.823592: [NET] Routine: decryption worker 6 - started 2021-07-18 18:37:49.823606: [NET] Routine: decryption worker 5 - started 2021-07-18 18:37:49.823613: [NET] Routine: decryption worker 3 - started 2021-07-18 18:37:49.823616: [NET] Routine: handshake worker 4 - started 2021-07-18 18:37:49.823636: [NET] Routine: encryption worker 6 - started 2021-07-18 18:37:49.823645: [NET] Routine: handshake worker 1 - started 2021-07-18 18:37:49.823663: [NET] Routine: encryption worker 2 - started 2021-07-18 18:37:49.823705: [NET] Routine: handshake worker 3 - started 2021-07-18 18:37:49.823712: [NET] Routine: decryption worker 2 - started 2021-07-18 18:37:49.823715: [NET] Routine: handshake worker 6 - started 2021-07-18 18:37:49.823725: [NET] Routine: TUN reader - started 2021-07-18 18:37:49.823762: [NET] Routine: encryption worker 4 - started 2021-07-18 18:37:49.823798: [NET] Routine: event worker - started 2021-07-18 18:37:49.823948: [NET] UAPI: Removing all peers 2021-07-18 18:37:49.824340: [NET] peer(CT6/…lWC0) - UAPI: Created 2021-07-18 18:37:49.824384: [NET] peer(CT6/…lWC0) - UAPI: Updating preshared key 2021-07-18 18:37:49.824445: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:37:49.824540: [NET] peer(CT6/…lWC0) - UAPI: Updating persistent keepalive interval 2021-07-18 18:37:49.824590: [NET] peer(CT6/…lWC0) - UAPI: Removing all allowedips 2021-07-18 18:37:49.824664: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:37:49.824748: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:37:49.825053: [NET] UDP bind has been updated 2021-07-18 18:37:49.825083: [NET] Routine: receive incoming v4 - started 2021-07-18 18:37:49.825127: [NET] peer(CT6/…lWC0) - Starting 2021-07-18 18:37:49.825152: [NET] Routine: receive incoming v6 - started 2021-07-18 18:37:49.825306: [NET] Interface state was Down, requested Up, now Up 2021-07-18 18:37:49.825386: [NET] Device started 2021-07-18 18:37:49.825385: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - started 2021-07-18 18:37:49.825414: [NET] peer(CT6/…lWC0) - Routine: sequential sender - started 2021-07-18 18:37:50.247614: [NET] Network change detected with satisfied route and interface order [en0, utun2, pdp_ip0] 2021-07-18 18:37:50.248318: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:37:50.248633: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:37:50.248979: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:37:50.249068: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:37:50.249762: [NET] UDP bind has been updated 2021-07-18 18:37:50.249774: [NET] Routine: receive incoming v4 - started 2021-07-18 18:37:50.249864: [NET] Routine: receive incoming v6 - started 2021-07-18 18:37:50.819242: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:37:52.464715: [APP] Status update notification timeout for tunnel 'ht'. Tunnel status is now 'connected'. 2021-07-18 18:37:55.894364: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:01.058294: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:38:01.058566: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:06.097235: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:11.276422: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:38:11.276592: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:16.533800: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:38:16.534053: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:21.861148: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:38:21.861505: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:26.948636: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:38:26.949013: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:32.260995: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:38:32.261323: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:37.409582: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:38:37.409909: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:42.420539: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:47.432966: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:52.458157: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:38:57.478271: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:39:02.593277: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:39:02.593592: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:39:07.634584: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:39:12.732599: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:39:12.732741: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:39:17.745066: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:39:19.765240: [APP] startDeactivation: Tunnel: ht 2021-07-18 18:39:19.791276: [APP] Tunnel 'ht' connection status changed to 'disconnecting' 2021-07-18 18:39:20.137411: [NET] Stopping tunnel 2021-07-18 18:39:20.138604: [NET] Device closing 2021-07-18 18:39:20.138762: [NET] Routine: TUN reader - stopped 2021-07-18 18:39:20.139206: [NET] Routine: event worker - stopped 2021-07-18 18:39:20.139673: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:39:20.139669: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:39:20.139777: [NET] peer(CT6/…lWC0) - Stopping 2021-07-18 18:39:20.140038: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - stopped 2021-07-18 18:39:20.140042: [NET] peer(CT6/…lWC0) - Routine: sequential sender - stopped 2021-07-18 18:39:20.140418: [NET] Device closed 2021-07-18 18:39:20.140487: [NET] Routine: decryption worker 1 - stopped 2021-07-18 18:39:20.140534: [NET] Routine: decryption worker 6 - stopped 2021-07-18 18:39:20.140578: [NET] Routine: decryption worker 5 - stopped 2021-07-18 18:39:20.140595: [NET] Routine: decryption worker 4 - stopped 2021-07-18 18:39:20.140618: [NET] Routine: handshake worker 5 - stopped 2021-07-18 18:39:20.140644: [NET] Routine: decryption worker 2 - stopped 2021-07-18 18:39:20.140686: [NET] Routine: decryption worker 3 - stopped 2021-07-18 18:39:20.140715: [NET] Routine: handshake worker 1 - stopped 2021-07-18 18:39:20.140716: [NET] Routine: handshake worker 4 - stopped 2021-07-18 18:39:20.140770: [NET] Routine: handshake worker 6 - stopped 2021-07-18 18:39:20.140856: [NET] Routine: handshake worker 2 - stopped 2021-07-18 18:39:20.140852: [NET] Routine: handshake worker 3 - stopped 2021-07-18 18:39:20.141354: [NET] Routine: encryption worker 2 - stopped 2021-07-18 18:39:20.141359: [NET] Routine: encryption worker 1 - stopped 2021-07-18 18:39:20.141388: [NET] Routine: encryption worker 5 - stopped 2021-07-18 18:39:20.141384: [NET] Routine: encryption worker 4 - stopped 2021-07-18 18:39:20.141392: [NET] Routine: encryption worker 6 - stopped 2021-07-18 18:39:20.141402: [NET] Routine: encryption worker 3 - stopped 2021-07-18 18:39:20.190337: [APP] Tunnel 'ht' connection status changed to 'disconnected' 2021-07-18 18:39:42.246811: [APP] startActivation: Entering (tunnel: ht) 2021-07-18 18:39:42.248340: [APP] startActivation: Starting tunnel 2021-07-18 18:39:42.248863: [APP] startActivation: Success 2021-07-18 18:39:42.256997: [APP] Tunnel 'ht' connection status changed to 'connecting' 2021-07-18 18:39:42.332808: [NET] App version: 1.0.13 (24) 2021-07-18 18:39:42.332913: [NET] Starting tunnel from the app 2021-07-18 18:39:42.416594: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:39:42.417656: [NET] Attaching to interface 2021-07-18 18:39:42.418205: [NET] UAPI: Updating private key 2021-07-18 18:39:42.418274: [NET] Routine: handshake worker 1 - started 2021-07-18 18:39:42.418314: [NET] Routine: handshake worker 3 - started 2021-07-18 18:39:42.418327: [NET] Routine: event worker - started 2021-07-18 18:39:42.418372: [NET] Routine: decryption worker 1 - started 2021-07-18 18:39:42.418386: [NET] Routine: encryption worker 1 - started 2021-07-18 18:39:42.418397: [NET] Routine: handshake worker 2 - started 2021-07-18 18:39:42.418435: [NET] Routine: encryption worker 3 - started 2021-07-18 18:39:42.418452: [NET] Routine: encryption worker 2 - started 2021-07-18 18:39:42.418469: [NET] Routine: decryption worker 3 - started 2021-07-18 18:39:42.418478: [NET] Routine: decryption worker 2 - started 2021-07-18 18:39:42.418522: [NET] Routine: decryption worker 5 - started 2021-07-18 18:39:42.418521: [NET] Routine: decryption worker 6 - started 2021-07-18 18:39:42.418544: [NET] Routine: handshake worker 6 - started 2021-07-18 18:39:42.418602: [NET] Routine: TUN reader - started 2021-07-18 18:39:42.418630: [NET] Routine: handshake worker 5 - started 2021-07-18 18:39:42.418635: [NET] Routine: encryption worker 4 - started 2021-07-18 18:39:42.418643: [NET] Routine: encryption worker 6 - started 2021-07-18 18:39:42.418657: [NET] Routine: handshake worker 4 - started 2021-07-18 18:39:42.418723: [NET] UAPI: Removing all peers 2021-07-18 18:39:42.418729: [NET] Routine: decryption worker 4 - started 2021-07-18 18:39:42.418731: [NET] Routine: encryption worker 5 - started 2021-07-18 18:39:42.419233: [NET] peer(CT6/…lWC0) - UAPI: Created 2021-07-18 18:39:42.419288: [NET] peer(CT6/…lWC0) - UAPI: Updating preshared key 2021-07-18 18:39:42.419383: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:39:42.419634: [NET] peer(CT6/…lWC0) - UAPI: Updating persistent keepalive interval 2021-07-18 18:39:42.419693: [NET] peer(CT6/…lWC0) - UAPI: Removing all allowedips 2021-07-18 18:39:42.419833: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:39:42.419897: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:39:42.420199: [NET] UDP bind has been updated 2021-07-18 18:39:42.420301: [NET] peer(CT6/…lWC0) - Starting 2021-07-18 18:39:42.420351: [NET] Interface state was Down, requested Up, now Up 2021-07-18 18:39:42.420391: [NET] Device started 2021-07-18 18:39:42.420396: [NET] Routine: receive incoming v4 - started 2021-07-18 18:39:42.420409: [NET] Routine: receive incoming v6 - started 2021-07-18 18:39:42.420472: [NET] Tunnel interface is utun2 2021-07-18 18:39:42.420641: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - started 2021-07-18 18:39:42.420643: [NET] peer(CT6/…lWC0) - Routine: sequential sender - started 2021-07-18 18:39:42.421480: [NET] Network change detected with satisfied route and interface order [en0, pdp_ip0] 2021-07-18 18:39:42.421592: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:39:42.421619: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:39:42.421654: [APP] Tunnel 'ht' connection status changed to 'connected' 2021-07-18 18:39:42.421792: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:39:42.421809: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:39:42.422185: [NET] UDP bind has been updated 2021-07-18 18:39:42.422203: [NET] Routine: receive incoming v4 - started 2021-07-18 18:39:42.422230: [NET] Routine: receive incoming v6 - started 2021-07-18 18:39:42.563067: [NET] Network change detected with satisfied route and interface order [en0, utun2, pdp_ip0] 2021-07-18 18:39:42.563327: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:39:42.563445: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:39:42.563577: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:39:42.563609: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:39:42.563840: [NET] UDP bind has been updated 2021-07-18 18:39:42.563855: [NET] Routine: receive incoming v4 - started 2021-07-18 18:39:42.563875: [NET] Routine: receive incoming v6 - started 2021-07-18 18:39:42.565560: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:39:47.651376: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:39:51.603253: [NET] Network change detected with unsatisfied route and interface order [en0, utun2, pdp_ip0] 2021-07-18 18:39:51.603468: [NET] Connectivity offline, pausing backend. 2021-07-18 18:39:51.603976: [NET] Device closing 2021-07-18 18:39:51.604092: [NET] Routine: TUN reader - stopped 2021-07-18 18:39:51.604187: [NET] Routine: event worker - stopped 2021-07-18 18:39:51.604276: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:39:51.604363: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:39:51.604434: [NET] peer(CT6/…lWC0) - Stopping 2021-07-18 18:39:51.604505: [NET] peer(CT6/…lWC0) - Routine: sequential sender - stopped 2021-07-18 18:39:51.604538: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - stopped 2021-07-18 18:39:51.604741: [NET] Device closed 2021-07-18 18:39:51.604957: [NET] Routine: decryption worker 1 - stopped 2021-07-18 18:39:51.604999: [NET] Routine: decryption worker 4 - stopped 2021-07-18 18:39:51.605027: [NET] Routine: decryption worker 6 - stopped 2021-07-18 18:39:51.605054: [NET] Routine: decryption worker 2 - stopped 2021-07-18 18:39:51.605075: [NET] Routine: decryption worker 5 - stopped 2021-07-18 18:39:51.605080: [NET] Routine: decryption worker 3 - stopped 2021-07-18 18:39:51.605128: [NET] Routine: handshake worker 3 - stopped 2021-07-18 18:39:51.605142: [NET] Routine: handshake worker 6 - stopped 2021-07-18 18:39:51.605167: [NET] Routine: handshake worker 5 - stopped 2021-07-18 18:39:51.605194: [NET] Routine: handshake worker 4 - stopped 2021-07-18 18:39:51.605211: [NET] Routine: handshake worker 2 - stopped 2021-07-18 18:39:51.605271: [NET] Routine: handshake worker 1 - stopped 2021-07-18 18:39:51.605424: [NET] Routine: encryption worker 1 - stopped 2021-07-18 18:39:51.605429: [NET] Routine: encryption worker 5 - stopped 2021-07-18 18:39:51.605448: [NET] Routine: encryption worker 6 - stopped 2021-07-18 18:39:51.605472: [NET] Routine: encryption worker 3 - stopped 2021-07-18 18:39:51.605472: [NET] Routine: encryption worker 2 - stopped 2021-07-18 18:39:51.605495: [NET] Routine: encryption worker 4 - stopped 2021-07-18 18:39:52.611449: [NET] Network change detected with satisfied route and interface order [pdp_ip0, utun2] 2021-07-18 18:39:52.611639: [NET] Connectivity online, resuming backend. 2021-07-18 18:39:52.736552: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:39:52.737175: [NET] Attaching to interface 2021-07-18 18:39:52.737424: [NET] Routine: handshake worker 1 - started 2021-07-18 18:39:52.737425: [NET] Routine: encryption worker 3 - started 2021-07-18 18:39:52.737451: [NET] UAPI: Updating private key 2021-07-18 18:39:52.737455: [NET] Routine: encryption worker 2 - started 2021-07-18 18:39:52.737461: [NET] Routine: encryption worker 1 - started 2021-07-18 18:39:52.737474: [NET] Routine: decryption worker 2 - started 2021-07-18 18:39:52.737476: [NET] Routine: decryption worker 1 - started 2021-07-18 18:39:52.737506: [NET] Routine: handshake worker 2 - started 2021-07-18 18:39:52.737558: [NET] Routine: encryption worker 5 - started 2021-07-18 18:39:52.737574: [NET] Routine: encryption worker 6 - started 2021-07-18 18:39:52.737575: [NET] Routine: handshake worker 6 - started 2021-07-18 18:39:52.737620: [NET] Routine: TUN reader - started 2021-07-18 18:39:52.737632: [NET] Routine: decryption worker 3 - started 2021-07-18 18:39:52.737642: [NET] Routine: decryption worker 6 - started 2021-07-18 18:39:52.737644: [NET] Routine: handshake worker 5 - started 2021-07-18 18:39:52.737650: [NET] Routine: decryption worker 5 - started 2021-07-18 18:39:52.737688: [NET] Routine: handshake worker 3 - started 2021-07-18 18:39:52.737696: [NET] Routine: decryption worker 4 - started 2021-07-18 18:39:52.737710: [NET] Routine: encryption worker 4 - started 2021-07-18 18:39:52.737739: [NET] Routine: handshake worker 4 - started 2021-07-18 18:39:52.737766: [NET] Routine: event worker - started 2021-07-18 18:39:52.737900: [NET] UAPI: Removing all peers 2021-07-18 18:39:52.738268: [NET] peer(CT6/…lWC0) - UAPI: Created 2021-07-18 18:39:52.738312: [NET] peer(CT6/…lWC0) - UAPI: Updating preshared key 2021-07-18 18:39:52.738373: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:39:52.738455: [NET] peer(CT6/…lWC0) - UAPI: Updating persistent keepalive interval 2021-07-18 18:39:52.738504: [NET] peer(CT6/…lWC0) - UAPI: Removing all allowedips 2021-07-18 18:39:52.738571: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:39:52.738634: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:39:52.738906: [NET] UDP bind has been updated 2021-07-18 18:39:52.738916: [NET] Routine: receive incoming v4 - started 2021-07-18 18:39:52.738940: [NET] Routine: receive incoming v6 - started 2021-07-18 18:39:52.738946: [NET] peer(CT6/…lWC0) - Starting 2021-07-18 18:39:52.739012: [NET] Interface state was Down, requested Up, now Up 2021-07-18 18:39:52.739021: [NET] peer(CT6/…lWC0) - Routine: sequential sender - started 2021-07-18 18:39:52.739026: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - started 2021-07-18 18:39:52.739088: [NET] Device started 2021-07-18 18:39:52.808087: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:39:53.587317: [NET] Network change detected with satisfied route and interface order [pdp_ip0, utun2] 2021-07-18 18:39:53.588133: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:39:53.588450: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:39:53.588792: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:39:53.588878: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:39:53.589376: [NET] UDP bind has been updated 2021-07-18 18:39:53.589422: [NET] Routine: receive incoming v4 - started 2021-07-18 18:39:53.589464: [NET] Routine: receive incoming v6 - started 2021-07-18 18:39:57.828765: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:40:03.007145: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:40:03.007366: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:40:08.073413: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:40:11.460896: [NET] Network change detected with satisfied route and interface order [pdp_ip0, utun2] 2021-07-18 18:40:11.461215: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:40:11.461374: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:40:11.461632: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:40:11.461661: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:40:11.461864: [NET] UDP bind has been updated 2021-07-18 18:40:11.461876: [NET] Routine: receive incoming v4 - started 2021-07-18 18:40:11.461901: [NET] Routine: receive incoming v6 - started 2021-07-18 18:40:13.404233: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:40:13.404436: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:40:14.658763: [APP] Status update notification timeout for tunnel 'ht'. Tunnel status is now 'connected'. 2021-07-18 18:40:18.485816: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:40:18.485925: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:40:23.741274: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:40:25.384837: [APP] startDeactivation: Tunnel: ht 2021-07-18 18:40:25.387690: [APP] Tunnel 'ht' connection status changed to 'disconnecting' 2021-07-18 18:40:25.661306: [NET] Network change detected with satisfied route and interface order [pdp_ip0] 2021-07-18 18:40:25.661794: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:40:25.661909: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:40:25.665229: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:40:25.665437: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:40:25.665675: [NET] UDP bind has been updated 2021-07-18 18:40:25.665721: [NET] Routine: receive incoming v6 - started 2021-07-18 18:40:25.667564: [NET] Routine: receive incoming v4 - started 2021-07-18 18:40:25.782530: [NET] Stopping tunnel 2021-07-18 18:40:25.782733: [NET] Device closing 2021-07-18 18:40:25.782848: [NET] Routine: TUN reader - stopped 2021-07-18 18:40:25.782897: [NET] Routine: event worker - stopped 2021-07-18 18:40:25.782924: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:40:25.782959: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:40:25.783045: [NET] peer(CT6/…lWC0) - Stopping 2021-07-18 18:40:25.783109: [NET] peer(CT6/…lWC0) - Routine: sequential sender - stopped 2021-07-18 18:40:25.783121: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - stopped 2021-07-18 18:40:25.783293: [NET] Device closed 2021-07-18 18:40:25.783312: [NET] Routine: handshake worker 1 - stopped 2021-07-18 18:40:25.783312: [NET] Routine: decryption worker 2 - stopped 2021-07-18 18:40:25.783335: [NET] Routine: decryption worker 6 - stopped 2021-07-18 18:40:25.783342: [NET] Routine: handshake worker 4 - stopped 2021-07-18 18:40:25.783353: [NET] Routine: decryption worker 4 - stopped 2021-07-18 18:40:25.783388: [NET] Routine: decryption worker 5 - stopped 2021-07-18 18:40:25.783392: [NET] Routine: decryption worker 1 - stopped 2021-07-18 18:40:25.783411: [NET] Routine: handshake worker 6 - stopped 2021-07-18 18:40:25.783437: [NET] Routine: handshake worker 2 - stopped 2021-07-18 18:40:25.783448: [NET] Routine: handshake worker 5 - stopped 2021-07-18 18:40:25.783454: [NET] Routine: handshake worker 3 - stopped 2021-07-18 18:40:25.783497: [NET] Routine: decryption worker 3 - stopped 2021-07-18 18:40:25.783672: [NET] Routine: encryption worker 6 - stopped 2021-07-18 18:40:25.783670: [NET] Routine: encryption worker 3 - stopped 2021-07-18 18:40:25.783678: [NET] Routine: encryption worker 4 - stopped 2021-07-18 18:40:25.783691: [NET] Routine: encryption worker 2 - stopped 2021-07-18 18:40:25.783697: [NET] Routine: encryption worker 5 - stopped 2021-07-18 18:40:25.783709: [NET] Routine: encryption worker 1 - stopped 2021-07-18 18:40:25.791936: [APP] Tunnel 'ht' connection status changed to 'disconnected' 2021-07-18 18:41:56.608727: [APP] startActivation: Entering (tunnel: ht) 2021-07-18 18:41:56.611065: [APP] startActivation: Starting tunnel 2021-07-18 18:41:56.611431: [APP] startActivation: Success 2021-07-18 18:41:56.623005: [APP] Tunnel 'ht' connection status changed to 'connecting' 2021-07-18 18:41:56.712880: [NET] App version: 1.0.13 (24) 2021-07-18 18:41:56.713028: [NET] Starting tunnel from the app 2021-07-18 18:41:56.800888: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:41:56.801824: [NET] Attaching to interface 2021-07-18 18:41:56.802771: [NET] UAPI: Updating private key 2021-07-18 18:41:56.802990: [NET] Routine: handshake worker 3 - started 2021-07-18 18:41:56.803023: [NET] Routine: decryption worker 5 - started 2021-07-18 18:41:56.803058: [NET] Routine: encryption worker 4 - started 2021-07-18 18:41:56.803116: [NET] Routine: decryption worker 6 - started 2021-07-18 18:41:56.803150: [NET] Routine: encryption worker 1 - started 2021-07-18 18:41:56.803179: [NET] Routine: event worker - started 2021-07-18 18:41:56.803261: [NET] Routine: decryption worker 4 - started 2021-07-18 18:41:56.803284: [NET] Routine: handshake worker 5 - started 2021-07-18 18:41:56.803306: [NET] Routine: encryption worker 6 - started 2021-07-18 18:41:56.803342: [NET] Routine: handshake worker 6 - started 2021-07-18 18:41:56.803359: [NET] UAPI: Removing all peers 2021-07-18 18:41:56.803379: [NET] Routine: TUN reader - started 2021-07-18 18:41:56.803488: [NET] Routine: handshake worker 4 - started 2021-07-18 18:41:56.803485: [NET] Routine: encryption worker 5 - started 2021-07-18 18:41:56.803497: [NET] Routine: decryption worker 1 - started 2021-07-18 18:41:56.803499: [NET] Routine: decryption worker 2 - started 2021-07-18 18:41:56.803518: [NET] Routine: encryption worker 3 - started 2021-07-18 18:41:56.803522: [NET] Routine: handshake worker 1 - started 2021-07-18 18:41:56.803631: [NET] Routine: decryption worker 3 - started 2021-07-18 18:41:56.803668: [NET] Routine: encryption worker 2 - started 2021-07-18 18:41:56.803763: [NET] Routine: handshake worker 2 - started 2021-07-18 18:41:56.803858: [NET] peer(CT6/…lWC0) - UAPI: Created 2021-07-18 18:41:56.804049: [NET] peer(CT6/…lWC0) - UAPI: Updating preshared key 2021-07-18 18:41:56.804106: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:41:56.804571: [NET] peer(CT6/…lWC0) - UAPI: Updating persistent keepalive interval 2021-07-18 18:41:56.804616: [NET] peer(CT6/…lWC0) - UAPI: Removing all allowedips 2021-07-18 18:41:56.804697: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:41:56.804771: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:41:56.805168: [NET] UDP bind has been updated 2021-07-18 18:41:56.805317: [NET] peer(CT6/…lWC0) - Starting 2021-07-18 18:41:56.805424: [NET] Interface state was Down, requested Up, now Up 2021-07-18 18:41:56.805432: [NET] Routine: receive incoming v6 - started 2021-07-18 18:41:56.805436: [NET] Routine: receive incoming v4 - started 2021-07-18 18:41:56.805500: [NET] Device started 2021-07-18 18:41:56.805691: [NET] Tunnel interface is utun2 2021-07-18 18:41:56.806037: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - started 2021-07-18 18:41:56.806031: [NET] peer(CT6/…lWC0) - Routine: sequential sender - started 2021-07-18 18:41:56.806814: [NET] Network change detected with satisfied route and interface order [en0, pdp_ip0] 2021-07-18 18:41:56.807020: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:41:56.807087: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:41:56.807331: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:41:56.807347: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:41:56.807404: [APP] Tunnel 'ht' connection status changed to 'connected' 2021-07-18 18:41:56.807684: [NET] UDP bind has been updated 2021-07-18 18:41:56.807698: [NET] Routine: receive incoming v4 - started 2021-07-18 18:41:56.807733: [NET] Routine: receive incoming v6 - started 2021-07-18 18:41:57.150832: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:41:57.336635: [NET] Network change detected with satisfied route and interface order [en0, utun2, pdp_ip0] 2021-07-18 18:41:57.337648: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:41:57.338198: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:41:57.338632: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:41:57.338721: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:41:57.339310: [NET] UDP bind has been updated 2021-07-18 18:41:57.339330: [NET] Routine: receive incoming v4 - started 2021-07-18 18:41:57.339389: [NET] Routine: receive incoming v6 - started 2021-07-18 18:42:01.612455: [APP] Status update notification timeout for tunnel 'ht'. Tunnel status is now 'connected'. 2021-07-18 18:42:02.427113: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:42:02.427340: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:07.596054: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:42:07.596205: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:12.718427: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:17.723279: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:22.771049: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:28.101466: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:42:28.101758: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:33.184687: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:42:33.184958: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:38.215080: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:43.361281: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:42:43.361617: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:48.508245: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:42:48.508581: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:53.805318: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:42:53.805459: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:42:58.995142: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:03.999464: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:05.026455: [APP] startDeactivation: Tunnel: ht 2021-07-18 18:43:05.037164: [APP] Tunnel 'ht' connection status changed to 'disconnecting' 2021-07-18 18:43:05.437462: [NET] Network change detected with satisfied route and interface order [en0, pdp_ip0] 2021-07-18 18:43:05.443597: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:43:05.444013: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:43:05.448382: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:43:05.448596: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:43:05.448956: [NET] UDP bind has been updated 2021-07-18 18:43:05.449118: [NET] Routine: receive incoming v6 - started 2021-07-18 18:43:05.449321: [NET] Routine: receive incoming v4 - started 2021-07-18 18:43:05.489942: [NET] Stopping tunnel 2021-07-18 18:43:05.490388: [NET] Device closing 2021-07-18 18:43:05.490622: [NET] Routine: TUN reader - stopped 2021-07-18 18:43:05.490952: [NET] Routine: event worker - stopped 2021-07-18 18:43:05.490982: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:43:05.491138: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:43:05.491398: [NET] peer(CT6/…lWC0) - Stopping 2021-07-18 18:43:05.491583: [NET] peer(CT6/…lWC0) - Routine: sequential sender - stopped 2021-07-18 18:43:05.491667: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - stopped 2021-07-18 18:43:05.492167: [NET] Device closed 2021-07-18 18:43:05.492287: [NET] Routine: decryption worker 5 - stopped 2021-07-18 18:43:05.492348: [NET] Routine: decryption worker 1 - stopped 2021-07-18 18:43:05.492404: [NET] Routine: decryption worker 2 - stopped 2021-07-18 18:43:05.492462: [NET] Routine: decryption worker 3 - stopped 2021-07-18 18:43:05.492517: [NET] Routine: handshake worker 3 - stopped 2021-07-18 18:43:05.492566: [NET] Routine: decryption worker 4 - stopped 2021-07-18 18:43:05.492634: [NET] Routine: handshake worker 4 - stopped 2021-07-18 18:43:05.492765: [NET] Routine: handshake worker 5 - stopped 2021-07-18 18:43:05.492861: [NET] Routine: handshake worker 2 - stopped 2021-07-18 18:43:05.492919: [NET] Routine: handshake worker 6 - stopped 2021-07-18 18:43:05.492993: [NET] Routine: decryption worker 6 - stopped 2021-07-18 18:43:05.493041: [NET] Routine: handshake worker 1 - stopped 2021-07-18 18:43:05.493109: [NET] Routine: encryption worker 4 - stopped 2021-07-18 18:43:05.493133: [NET] Routine: encryption worker 2 - stopped 2021-07-18 18:43:05.493196: [NET] Routine: encryption worker 3 - stopped 2021-07-18 18:43:05.493196: [NET] Routine: encryption worker 1 - stopped 2021-07-18 18:43:05.493241: [NET] Routine: encryption worker 6 - stopped 2021-07-18 18:43:05.493330: [NET] Routine: encryption worker 5 - stopped 2021-07-18 18:43:05.526158: [APP] Tunnel 'ht' connection status changed to 'disconnected' 2021-07-18 18:43:12.992277: [APP] startActivation: Entering (tunnel: ht) 2021-07-18 18:43:12.993745: [APP] startActivation: Starting tunnel 2021-07-18 18:43:12.994111: [APP] startActivation: Success 2021-07-18 18:43:13.002428: [APP] Tunnel 'ht' connection status changed to 'connecting' 2021-07-18 18:43:13.064989: [NET] App version: 1.0.13 (24) 2021-07-18 18:43:13.065089: [NET] Starting tunnel from the app 2021-07-18 18:43:13.153106: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:43:13.153618: [NET] Attaching to interface 2021-07-18 18:43:13.153919: [NET] UAPI: Updating private key 2021-07-18 18:43:13.154047: [NET] Routine: handshake worker 1 - started 2021-07-18 18:43:13.154051: [NET] Routine: encryption worker 3 - started 2021-07-18 18:43:13.154111: [NET] Routine: encryption worker 1 - started 2021-07-18 18:43:13.154112: [NET] Routine: decryption worker 1 - started 2021-07-18 18:43:13.154113: [NET] UAPI: Removing all peers 2021-07-18 18:43:13.154165: [NET] Routine: encryption worker 2 - started 2021-07-18 18:43:13.154208: [NET] Routine: handshake worker 2 - started 2021-07-18 18:43:13.154288: [NET] Routine: encryption worker 5 - started 2021-07-18 18:43:13.154425: [NET] Routine: decryption worker 2 - started 2021-07-18 18:43:13.154432: [NET] Routine: event worker - started 2021-07-18 18:43:13.154433: [NET] Routine: encryption worker 6 - started 2021-07-18 18:43:13.154519: [NET] Routine: encryption worker 4 - started 2021-07-18 18:43:13.154516: [NET] Routine: decryption worker 4 - started 2021-07-18 18:43:13.154522: [NET] Routine: decryption worker 3 - started 2021-07-18 18:43:13.154536: [NET] Routine: handshake worker 4 - started 2021-07-18 18:43:13.154666: [NET] Routine: decryption worker 5 - started 2021-07-18 18:43:13.154678: [NET] Routine: handshake worker 6 - started 2021-07-18 18:43:13.154689: [NET] peer(CT6/…lWC0) - UAPI: Created 2021-07-18 18:43:13.154759: [NET] Routine: handshake worker 5 - started 2021-07-18 18:43:13.154792: [NET] Routine: TUN reader - started 2021-07-18 18:43:13.154825: [NET] Routine: handshake worker 3 - started 2021-07-18 18:43:13.154905: [NET] Routine: decryption worker 6 - started 2021-07-18 18:43:13.155200: [NET] peer(CT6/…lWC0) - UAPI: Updating preshared key 2021-07-18 18:43:13.155228: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:43:13.155447: [NET] peer(CT6/…lWC0) - UAPI: Updating persistent keepalive interval 2021-07-18 18:43:13.155480: [NET] peer(CT6/…lWC0) - UAPI: Removing all allowedips 2021-07-18 18:43:13.155583: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:43:13.155663: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:43:13.155964: [NET] UDP bind has been updated 2021-07-18 18:43:13.155991: [NET] peer(CT6/…lWC0) - Starting 2021-07-18 18:43:13.156068: [NET] Routine: receive incoming v4 - started 2021-07-18 18:43:13.156068: [NET] Routine: receive incoming v6 - started 2021-07-18 18:43:13.156105: [NET] Interface state was Down, requested Up, now Up 2021-07-18 18:43:13.156158: [NET] Device started 2021-07-18 18:43:13.156204: [NET] peer(CT6/…lWC0) - Routine: sequential sender - started 2021-07-18 18:43:13.156215: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - started 2021-07-18 18:43:13.156293: [NET] Tunnel interface is utun2 2021-07-18 18:43:13.156843: [NET] Network change detected with satisfied route and interface order [pdp_ip0] 2021-07-18 18:43:13.156988: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:43:13.157034: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:43:13.157318: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:43:13.157319: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:43:13.157763: [NET] UDP bind has been updated 2021-07-18 18:43:13.157775: [NET] Routine: receive incoming v4 - started 2021-07-18 18:43:13.157778: [NET] Routine: receive incoming v6 - started 2021-07-18 18:43:13.157935: [APP] Tunnel 'ht' connection status changed to 'connected' 2021-07-18 18:43:13.317987: [NET] Network change detected with satisfied route and interface order [pdp_ip0, utun2] 2021-07-18 18:43:13.318777: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:43:13.319105: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:43:13.319428: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:43:13.319507: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:43:13.320046: [NET] UDP bind has been updated 2021-07-18 18:43:13.320076: [NET] Routine: receive incoming v4 - started 2021-07-18 18:43:13.320200: [NET] Routine: receive incoming v6 - started 2021-07-18 18:43:13.330423: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:18.566293: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:23.603241: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:28.608967: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:31.917584: [NET] Network change detected with satisfied route and interface order [pdp_ip0, utun2] 2021-07-18 18:43:31.918406: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:43:31.918723: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:43:31.919118: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:43:31.919190: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:43:31.919676: [NET] UDP bind has been updated 2021-07-18 18:43:31.919715: [NET] Routine: receive incoming v4 - started 2021-07-18 18:43:31.919788: [NET] Routine: receive incoming v6 - started 2021-07-18 18:43:33.793746: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:43:33.794039: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:39.053076: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:43:39.053352: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:44.349059: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:49.435770: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:43:49.436049: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:54.743889: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:43:54.744199: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:43:59.889017: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:43:59.889294: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:44:05.037099: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:44:05.037407: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:44:10.331347: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:44:10.331590: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:44:14.024259: [APP] Status update notification timeout for tunnel 'ht'. Tunnel status is now 'connected'. 2021-07-18 18:44:15.599803: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 3) 2021-07-18 18:44:15.600015: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:44:16.135369: [APP] startDeactivation: Tunnel: ht 2021-07-18 18:44:16.138967: [APP] Tunnel 'ht' connection status changed to 'disconnecting' 2021-07-18 18:44:16.531473: [NET] Stopping tunnel 2021-07-18 18:44:16.531934: [NET] Device closing 2021-07-18 18:44:16.532184: [NET] Routine: TUN reader - stopped 2021-07-18 18:44:16.532419: [NET] Routine: event worker - stopped 2021-07-18 18:44:16.532732: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:44:16.532885: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:44:16.533026: [NET] peer(CT6/…lWC0) - Stopping 2021-07-18 18:44:16.533227: [NET] peer(CT6/…lWC0) - Routine: sequential sender - stopped 2021-07-18 18:44:16.533229: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - stopped 2021-07-18 18:44:16.533527: [NET] Device closed 2021-07-18 18:44:16.533578: [NET] Routine: decryption worker 3 - stopped 2021-07-18 18:44:16.533568: [NET] Routine: decryption worker 1 - stopped 2021-07-18 18:44:16.533628: [NET] Routine: handshake worker 1 - stopped 2021-07-18 18:44:16.533670: [NET] Routine: decryption worker 5 - stopped 2021-07-18 18:44:16.533719: [NET] Routine: decryption worker 6 - stopped 2021-07-18 18:44:16.533723: [NET] Routine: decryption worker 4 - stopped 2021-07-18 18:44:16.533771: [NET] Routine: decryption worker 2 - stopped 2021-07-18 18:44:16.533776: [NET] Routine: handshake worker 3 - stopped 2021-07-18 18:44:16.533800: [NET] Routine: handshake worker 6 - stopped 2021-07-18 18:44:16.533869: [NET] Routine: handshake worker 4 - stopped 2021-07-18 18:44:16.533860: [NET] Routine: handshake worker 2 - stopped 2021-07-18 18:44:16.533887: [NET] Routine: handshake worker 5 - stopped 2021-07-18 18:44:16.534131: [NET] Routine: encryption worker 3 - stopped 2021-07-18 18:44:16.534147: [NET] Routine: encryption worker 5 - stopped 2021-07-18 18:44:16.534182: [NET] Routine: encryption worker 2 - stopped 2021-07-18 18:44:16.534193: [NET] Routine: encryption worker 4 - stopped 2021-07-18 18:44:16.534221: [NET] Routine: encryption worker 1 - stopped 2021-07-18 18:44:16.534255: [NET] Routine: encryption worker 6 - stopped 2021-07-18 18:44:16.542344: [APP] Tunnel 'ht' connection status changed to 'disconnected' 2021-07-18 18:46:15.526786: [APP] startActivation: Entering (tunnel: ht) 2021-07-18 18:46:15.529254: [APP] startActivation: Starting tunnel 2021-07-18 18:46:15.529568: [APP] startActivation: Success 2021-07-18 18:46:15.537916: [APP] Tunnel 'ht' connection status changed to 'connecting' 2021-07-18 18:46:15.607130: [NET] App version: 1.0.13 (24) 2021-07-18 18:46:15.607301: [NET] Starting tunnel from the app 2021-07-18 18:46:15.708388: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:46:15.709465: [NET] Attaching to interface 2021-07-18 18:46:15.709970: [NET] Routine: encryption worker 1 - started 2021-07-18 18:46:15.709963: [NET] Routine: handshake worker 1 - started 2021-07-18 18:46:15.710004: [NET] UAPI: Updating private key 2021-07-18 18:46:15.710059: [NET] Routine: handshake worker 3 - started 2021-07-18 18:46:15.710098: [NET] Routine: encryption worker 3 - started 2021-07-18 18:46:15.710109: [NET] Routine: handshake worker 2 - started 2021-07-18 18:46:15.710110: [NET] Routine: decryption worker 1 - started 2021-07-18 18:46:15.710112: [NET] Routine: decryption worker 5 - started 2021-07-18 18:46:15.710184: [NET] Routine: decryption worker 3 - started 2021-07-18 18:46:15.710211: [NET] Routine: decryption worker 6 - started 2021-07-18 18:46:15.710237: [NET] Routine: event worker - started 2021-07-18 18:46:15.710271: [NET] Routine: decryption worker 2 - started 2021-07-18 18:46:15.710272: [NET] Routine: encryption worker 2 - started 2021-07-18 18:46:15.710327: [NET] Routine: encryption worker 4 - started 2021-07-18 18:46:15.710350: [NET] Routine: decryption worker 4 - started 2021-07-18 18:46:15.710365: [NET] Routine: handshake worker 5 - started 2021-07-18 18:46:15.710442: [NET] Routine: handshake worker 6 - started 2021-07-18 18:46:15.710453: [NET] Routine: encryption worker 6 - started 2021-07-18 18:46:15.710505: [NET] Routine: TUN reader - started 2021-07-18 18:46:15.710597: [NET] Routine: handshake worker 4 - started 2021-07-18 18:46:15.710601: [NET] Routine: encryption worker 5 - started 2021-07-18 18:46:15.710944: [NET] UAPI: Removing all peers 2021-07-18 18:46:15.711589: [NET] peer(CT6/…lWC0) - UAPI: Created 2021-07-18 18:46:15.711662: [NET] peer(CT6/…lWC0) - UAPI: Updating preshared key 2021-07-18 18:46:15.711808: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:46:15.711974: [NET] peer(CT6/…lWC0) - UAPI: Updating persistent keepalive interval 2021-07-18 18:46:15.712023: [NET] peer(CT6/…lWC0) - UAPI: Removing all allowedips 2021-07-18 18:46:15.712168: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:46:15.712247: [NET] peer(CT6/…lWC0) - UAPI: Adding allowedip 2021-07-18 18:46:15.712683: [NET] UDP bind has been updated 2021-07-18 18:46:15.712786: [NET] Routine: receive incoming v6 - started 2021-07-18 18:46:15.712786: [NET] Routine: receive incoming v4 - started 2021-07-18 18:46:15.712799: [NET] peer(CT6/…lWC0) - Starting 2021-07-18 18:46:15.712926: [NET] Interface state was Down, requested Up, now Up 2021-07-18 18:46:15.712996: [NET] Device started 2021-07-18 18:46:15.713123: [NET] Tunnel interface is utun2 2021-07-18 18:46:15.713459: [NET] peer(CT6/…lWC0) - Routine: sequential sender - started 2021-07-18 18:46:15.713469: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - started 2021-07-18 18:46:15.713942: [NET] Network change detected with satisfied route and interface order [en0, pdp_ip0] 2021-07-18 18:46:15.714121: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:46:15.714166: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:46:15.714374: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:46:15.714466: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:46:15.714776: [APP] Tunnel 'ht' connection status changed to 'connected' 2021-07-18 18:46:15.715197: [NET] UDP bind has been updated 2021-07-18 18:46:15.715221: [NET] Routine: receive incoming v4 - started 2021-07-18 18:46:15.715229: [NET] Routine: receive incoming v6 - started 2021-07-18 18:46:16.031491: [NET] Network change detected with satisfied route and interface order [en0, utun2, pdp_ip0] 2021-07-18 18:46:16.032372: [NET] DNS64: mapped 18.166.154.203 to itself. 2021-07-18 18:46:16.032786: [NET] peer(CT6/…lWC0) - UAPI: Updating endpoint 2021-07-18 18:46:16.033192: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:46:16.033261: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:46:16.034088: [NET] UDP bind has been updated 2021-07-18 18:46:16.034188: [NET] Routine: receive incoming v4 - started 2021-07-18 18:46:16.034224: [NET] Routine: receive incoming v6 - started 2021-07-18 18:46:16.037798: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:46:21.318857: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:46:21.319052: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:46:26.483635: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:46:26.483726: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:46:31.514728: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:46:36.699049: [NET] peer(CT6/…lWC0) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:46:36.699288: [NET] peer(CT6/…lWC0) - Sending handshake initiation 2021-07-18 18:46:37.568232: [APP] Status update notification timeout for tunnel 'ht'. Tunnel status is now 'connected'. 2021-07-18 18:46:38.163881: [APP] startDeactivation: Tunnel: ht 2021-07-18 18:46:38.169627: [APP] Tunnel 'ht' connection status changed to 'disconnecting' 2021-07-18 18:46:38.542329: [NET] Stopping tunnel 2021-07-18 18:46:38.542726: [NET] Device closing 2021-07-18 18:46:38.542902: [NET] Routine: TUN reader - stopped 2021-07-18 18:46:38.543128: [NET] Routine: event worker - stopped 2021-07-18 18:46:38.543291: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:46:38.543397: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:46:38.543502: [NET] peer(CT6/…lWC0) - Stopping 2021-07-18 18:46:38.543783: [NET] peer(CT6/…lWC0) - Routine: sequential sender - stopped 2021-07-18 18:46:38.543793: [NET] peer(CT6/…lWC0) - Routine: sequential receiver - stopped 2021-07-18 18:46:38.544220: [NET] Device closed 2021-07-18 18:46:38.544283: [NET] Routine: handshake worker 1 - stopped 2021-07-18 18:46:38.544284: [NET] Routine: decryption worker 5 - stopped 2021-07-18 18:46:38.544348: [NET] Routine: handshake worker 2 - stopped 2021-07-18 18:46:38.544360: [NET] Routine: handshake worker 3 - stopped 2021-07-18 18:46:38.544365: [NET] Routine: decryption worker 4 - stopped 2021-07-18 18:46:38.544375: [NET] Routine: handshake worker 5 - stopped 2021-07-18 18:46:38.544432: [NET] Routine: decryption worker 2 - stopped 2021-07-18 18:46:38.544456: [NET] Routine: decryption worker 3 - stopped 2021-07-18 18:46:38.544468: [NET] Routine: decryption worker 1 - stopped 2021-07-18 18:46:38.544477: [NET] Routine: decryption worker 6 - stopped 2021-07-18 18:46:38.544507: [NET] Routine: handshake worker 6 - stopped 2021-07-18 18:46:38.544512: [NET] Routine: handshake worker 4 - stopped 2021-07-18 18:46:38.544945: [NET] Routine: encryption worker 1 - stopped 2021-07-18 18:46:38.544959: [NET] Routine: encryption worker 2 - stopped 2021-07-18 18:46:38.544987: [NET] Routine: encryption worker 6 - stopped 2021-07-18 18:46:38.544995: [NET] Routine: encryption worker 3 - stopped 2021-07-18 18:46:38.545008: [NET] Routine: encryption worker 5 - stopped 2021-07-18 18:46:38.545015: [NET] Routine: encryption worker 4 - stopped 2021-07-18 18:46:38.563937: [APP] Tunnel 'ht' connection status changed to 'disconnected' 2021-07-18 18:56:26.740048: [APP] App version: 1.0.13 (24) 2021-07-18 18:56:38.935160: [APP] startActivation: Entering (tunnel: htvpn) 2021-07-18 18:56:38.935876: [APP] startActivation: Starting tunnel 2021-07-18 18:56:38.936395: [APP] startActivation: Success 2021-07-18 18:56:38.951430: [APP] Tunnel 'htvpn' connection status changed to 'connecting' 2021-07-18 18:56:39.047633: [NET] App version: 1.0.13 (24) 2021-07-18 18:56:39.047760: [NET] Starting tunnel from the app 2021-07-18 18:56:39.137995: [NET] DNS64: mapped 158.247.225.243 to itself. 2021-07-18 18:56:39.138867: [NET] Attaching to interface 2021-07-18 18:56:39.139403: [NET] Routine: handshake worker 1 - started 2021-07-18 18:56:39.139581: [NET] UAPI: Updating private key 2021-07-18 18:56:39.139657: [NET] Routine: encryption worker 1 - started 2021-07-18 18:56:39.139702: [NET] Routine: decryption worker 4 - started 2021-07-18 18:56:39.139707: [NET] Routine: encryption worker 3 - started 2021-07-18 18:56:39.139946: [NET] Routine: encryption worker 4 - started 2021-07-18 18:56:39.139977: [NET] Routine: decryption worker 1 - started 2021-07-18 18:56:39.139986: [NET] Routine: encryption worker 2 - started 2021-07-18 18:56:39.139989: [NET] Routine: handshake worker 2 - started 2021-07-18 18:56:39.139991: [NET] Routine: decryption worker 2 - started 2021-07-18 18:56:39.140017: [NET] UAPI: Removing all peers 2021-07-18 18:56:39.140080: [NET] Routine: decryption worker 3 - started 2021-07-18 18:56:39.140081: [NET] Routine: handshake worker 5 - started 2021-07-18 18:56:39.140095: [NET] Routine: handshake worker 3 - started 2021-07-18 18:56:39.140107: [NET] Routine: decryption worker 6 - started 2021-07-18 18:56:39.140175: [NET] Routine: encryption worker 6 - started 2021-07-18 18:56:39.140209: [NET] Routine: handshake worker 6 - started 2021-07-18 18:56:39.140253: [NET] Routine: encryption worker 5 - started 2021-07-18 18:56:39.140259: [NET] Routine: decryption worker 5 - started 2021-07-18 18:56:39.140266: [NET] Routine: event worker - started 2021-07-18 18:56:39.140516: [NET] Routine: handshake worker 4 - started 2021-07-18 18:56:39.140576: [NET] peer(5x4E…4uyk) - UAPI: Created 2021-07-18 18:56:39.140608: [NET] Routine: TUN reader - started 2021-07-18 18:56:39.140714: [NET] peer(5x4E…4uyk) - UAPI: Updating preshared key 2021-07-18 18:56:39.140832: [NET] peer(5x4E…4uyk) - UAPI: Updating endpoint 2021-07-18 18:56:39.141000: [NET] peer(5x4E…4uyk) - UAPI: Updating persistent keepalive interval 2021-07-18 18:56:39.141027: [NET] peer(5x4E…4uyk) - UAPI: Removing all allowedips 2021-07-18 18:56:39.141131: [NET] peer(5x4E…4uyk) - UAPI: Adding allowedip 2021-07-18 18:56:39.141210: [NET] peer(5x4E…4uyk) - UAPI: Adding allowedip 2021-07-18 18:56:39.141569: [NET] UDP bind has been updated 2021-07-18 18:56:39.141737: [NET] peer(5x4E…4uyk) - Starting 2021-07-18 18:56:39.141812: [NET] Interface state was Down, requested Up, now Up 2021-07-18 18:56:39.141843: [NET] Routine: receive incoming v4 - started 2021-07-18 18:56:39.141864: [NET] Device started 2021-07-18 18:56:39.141921: [NET] Routine: receive incoming v6 - started 2021-07-18 18:56:39.141994: [NET] Tunnel interface is utun2 2021-07-18 18:56:39.142151: [NET] peer(5x4E…4uyk) - Routine: sequential receiver - started 2021-07-18 18:56:39.142153: [NET] peer(5x4E…4uyk) - Routine: sequential sender - started 2021-07-18 18:56:39.144544: [NET] Network change detected with satisfied route and interface order [en0, pdp_ip0] 2021-07-18 18:56:39.144732: [NET] DNS64: mapped 158.247.225.243 to itself. 2021-07-18 18:56:39.144780: [NET] peer(5x4E…4uyk) - UAPI: Updating endpoint 2021-07-18 18:56:39.144818: [APP] Tunnel 'htvpn' connection status changed to 'connected' 2021-07-18 18:56:39.145282: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:56:39.145282: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:56:39.145711: [NET] UDP bind has been updated 2021-07-18 18:56:39.145742: [NET] Routine: receive incoming v4 - started 2021-07-18 18:56:39.145771: [NET] Routine: receive incoming v6 - started 2021-07-18 18:56:39.477510: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 18:56:39.637927: [NET] Network change detected with satisfied route and interface order [en0, utun2, pdp_ip0] 2021-07-18 18:56:39.638696: [NET] DNS64: mapped 158.247.225.243 to itself. 2021-07-18 18:56:39.638998: [NET] peer(5x4E…4uyk) - UAPI: Updating endpoint 2021-07-18 18:56:39.639533: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:56:39.639677: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:56:39.640061: [NET] UDP bind has been updated 2021-07-18 18:56:39.640114: [NET] Routine: receive incoming v4 - started 2021-07-18 18:56:39.640365: [NET] Routine: receive incoming v6 - started 2021-07-18 18:56:44.753866: [NET] peer(5x4E…4uyk) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:56:44.754103: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 18:56:49.831515: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 18:56:54.842860: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 18:56:59.862534: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 18:57:05.120295: [NET] peer(5x4E…4uyk) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:57:05.120430: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 18:57:06.608287: [APP] Status update notification timeout for tunnel 'htvpn'. Tunnel status is now 'connected'. 2021-07-18 18:57:10.450023: [NET] peer(5x4E…4uyk) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 18:57:10.450334: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 18:57:14.243008: [APP] startDeactivation: Tunnel: htvpn 2021-07-18 18:57:14.252788: [APP] Tunnel 'htvpn' connection status changed to 'disconnecting' 2021-07-18 18:57:14.644400: [NET] Stopping tunnel 2021-07-18 18:57:14.644780: [NET] Device closing 2021-07-18 18:57:14.645316: [NET] Routine: TUN reader - stopped 2021-07-18 18:57:14.645445: [NET] Routine: event worker - stopped 2021-07-18 18:57:14.645521: [NET] Routine: receive incoming v4 - stopped 2021-07-18 18:57:14.645647: [NET] Routine: receive incoming v6 - stopped 2021-07-18 18:57:14.646017: [NET] peer(5x4E…4uyk) - Stopping 2021-07-18 18:57:14.646190: [NET] peer(5x4E…4uyk) - Routine: sequential sender - stopped 2021-07-18 18:57:14.646217: [NET] peer(5x4E…4uyk) - Routine: sequential receiver - stopped 2021-07-18 18:57:14.646513: [NET] Device closed 2021-07-18 18:57:14.646601: [NET] Routine: decryption worker 4 - stopped 2021-07-18 18:57:14.646619: [NET] Routine: decryption worker 3 - stopped 2021-07-18 18:57:14.646678: [NET] Routine: decryption worker 2 - stopped 2021-07-18 18:57:14.646741: [NET] Routine: handshake worker 3 - stopped 2021-07-18 18:57:14.646742: [NET] Routine: handshake worker 4 - stopped 2021-07-18 18:57:14.646751: [NET] Routine: decryption worker 1 - stopped 2021-07-18 18:57:14.646765: [NET] Routine: decryption worker 5 - stopped 2021-07-18 18:57:14.646770: [NET] Routine: handshake worker 1 - stopped 2021-07-18 18:57:14.646843: [NET] Routine: handshake worker 6 - stopped 2021-07-18 18:57:14.646855: [NET] Routine: handshake worker 2 - stopped 2021-07-18 18:57:14.646867: [NET] Routine: handshake worker 5 - stopped 2021-07-18 18:57:14.646924: [NET] Routine: decryption worker 6 - stopped 2021-07-18 18:57:14.647126: [NET] Routine: encryption worker 3 - stopped 2021-07-18 18:57:14.647157: [NET] Routine: encryption worker 2 - stopped 2021-07-18 18:57:14.647180: [NET] Routine: encryption worker 1 - stopped 2021-07-18 18:57:14.647191: [NET] Routine: encryption worker 4 - stopped 2021-07-18 18:57:14.647235: [NET] Routine: encryption worker 6 - stopped 2021-07-18 18:57:14.647254: [NET] Routine: encryption worker 5 - stopped 2021-07-18 18:57:14.678000: [APP] Tunnel 'htvpn' connection status changed to 'disconnected' 2021-07-18 19:03:18.048127: [APP] App version: 1.0.13 (24) 2021-07-18 19:03:19.965941: [APP] startActivation: Entering (tunnel: htvpn) 2021-07-18 19:03:19.966424: [APP] startActivation: Starting tunnel 2021-07-18 19:03:19.966741: [APP] startActivation: Success 2021-07-18 19:03:19.975941: [APP] Tunnel 'htvpn' connection status changed to 'connecting' 2021-07-18 19:03:20.049776: [NET] App version: 1.0.13 (24) 2021-07-18 19:03:20.049909: [NET] Starting tunnel from the app 2021-07-18 19:03:20.139677: [NET] DNS64: mapped 158.247.225.243 to itself. 2021-07-18 19:03:20.141096: [NET] Attaching to interface 2021-07-18 19:03:20.141666: [NET] Routine: encryption worker 3 - started 2021-07-18 19:03:20.141792: [NET] UAPI: Updating private key 2021-07-18 19:03:20.141819: [NET] Routine: encryption worker 1 - started 2021-07-18 19:03:20.141873: [NET] Routine: decryption worker 2 - started 2021-07-18 19:03:20.141921: [NET] Routine: encryption worker 2 - started 2021-07-18 19:03:20.141923: [NET] Routine: encryption worker 5 - started 2021-07-18 19:03:20.141966: [NET] Routine: handshake worker 2 - started 2021-07-18 19:03:20.141960: [NET] Routine: decryption worker 4 - started 2021-07-18 19:03:20.141992: [NET] Routine: decryption worker 1 - started 2021-07-18 19:03:20.142005: [NET] Routine: encryption worker 4 - started 2021-07-18 19:03:20.142082: [NET] Routine: handshake worker 4 - started 2021-07-18 19:03:20.142099: [NET] Routine: encryption worker 6 - started 2021-07-18 19:03:20.142102: [NET] Routine: decryption worker 3 - started 2021-07-18 19:03:20.142128: [NET] UAPI: Removing all peers 2021-07-18 19:03:20.142253: [NET] Routine: handshake worker 1 - started 2021-07-18 19:03:20.142367: [NET] Routine: handshake worker 6 - started 2021-07-18 19:03:20.142377: [NET] Routine: handshake worker 3 - started 2021-07-18 19:03:20.142396: [NET] peer(5x4E…4uyk) - UAPI: Created 2021-07-18 19:03:20.142440: [NET] Routine: decryption worker 5 - started 2021-07-18 19:03:20.142454: [NET] peer(5x4E…4uyk) - UAPI: Updating preshared key 2021-07-18 19:03:20.142473: [NET] Routine: handshake worker 5 - started 2021-07-18 19:03:20.142499: [NET] Routine: TUN reader - started 2021-07-18 19:03:20.142684: [NET] peer(5x4E…4uyk) - UAPI: Updating endpoint 2021-07-18 19:03:20.142607: [NET] Routine: decryption worker 6 - started 2021-07-18 19:03:20.143037: [NET] peer(5x4E…4uyk) - UAPI: Updating persistent keepalive interval 2021-07-18 19:03:20.143088: [NET] peer(5x4E…4uyk) - UAPI: Removing all allowedips 2021-07-18 19:03:20.143203: [NET] peer(5x4E…4uyk) - UAPI: Adding allowedip 2021-07-18 19:03:20.143320: [NET] peer(5x4E…4uyk) - UAPI: Adding allowedip 2021-07-18 19:03:20.143315: [NET] Routine: event worker - started 2021-07-18 19:03:20.143577: [NET] UDP bind has been updated 2021-07-18 19:03:20.143764: [NET] Routine: receive incoming v4 - started 2021-07-18 19:03:20.143777: [NET] Routine: receive incoming v6 - started 2021-07-18 19:03:20.143904: [NET] peer(5x4E…4uyk) - Starting 2021-07-18 19:03:20.144079: [NET] Interface state was Down, requested Up, now Up 2021-07-18 19:03:20.144117: [NET] Device started 2021-07-18 19:03:20.144168: [NET] peer(5x4E…4uyk) - Routine: sequential receiver - started 2021-07-18 19:03:20.144268: [NET] Tunnel interface is utun2 2021-07-18 19:03:20.144692: [NET] peer(5x4E…4uyk) - Routine: sequential sender - started 2021-07-18 19:03:20.145898: [NET] Network change detected with satisfied route and interface order [pdp_ip0] 2021-07-18 19:03:20.145996: [APP] Tunnel 'htvpn' connection status changed to 'connected' 2021-07-18 19:03:20.146209: [NET] DNS64: mapped 158.247.225.243 to itself. 2021-07-18 19:03:20.146264: [NET] peer(5x4E…4uyk) - UAPI: Updating endpoint 2021-07-18 19:03:20.146563: [NET] Routine: receive incoming v4 - stopped 2021-07-18 19:03:20.146563: [NET] Routine: receive incoming v6 - stopped 2021-07-18 19:03:20.146957: [NET] UDP bind has been updated 2021-07-18 19:03:20.146992: [NET] Routine: receive incoming v4 - started 2021-07-18 19:03:20.147015: [NET] Routine: receive incoming v6 - started 2021-07-18 19:03:20.543458: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 19:03:20.644413: [NET] Network change detected with satisfied route and interface order [pdp_ip0, utun2] 2021-07-18 19:03:20.646490: [NET] DNS64: mapped 158.247.225.243 to itself. 2021-07-18 19:03:20.646832: [NET] peer(5x4E…4uyk) - UAPI: Updating endpoint 2021-07-18 19:03:20.647190: [NET] Routine: receive incoming v4 - stopped 2021-07-18 19:03:20.647276: [NET] Routine: receive incoming v6 - stopped 2021-07-18 19:03:20.647797: [NET] UDP bind has been updated 2021-07-18 19:03:20.647818: [NET] Routine: receive incoming v4 - started 2021-07-18 19:03:20.647868: [NET] Routine: receive incoming v6 - started 2021-07-18 19:03:25.791648: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 19:03:27.828005: [NET] Network change detected with satisfied route and interface order [pdp_ip0, utun2] 2021-07-18 19:03:27.828479: [NET] DNS64: mapped 158.247.225.243 to itself. 2021-07-18 19:03:27.828683: [NET] peer(5x4E…4uyk) - UAPI: Updating endpoint 2021-07-18 19:03:27.828890: [NET] Routine: receive incoming v4 - stopped 2021-07-18 19:03:27.828942: [NET] Routine: receive incoming v6 - stopped 2021-07-18 19:03:27.829476: [NET] UDP bind has been updated 2021-07-18 19:03:27.829574: [NET] Routine: receive incoming v6 - started 2021-07-18 19:03:27.830374: [NET] Routine: receive incoming v4 - started 2021-07-18 19:03:30.794746: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 19:03:35.867329: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 19:03:38.639409: [APP] Status update notification timeout for tunnel 'htvpn'. Tunnel status is now 'connected'. 2021-07-18 19:03:39.240785: [APP] startDeactivation: Tunnel: htvpn 2021-07-18 19:03:39.247133: [APP] Tunnel 'htvpn' connection status changed to 'disconnecting' 2021-07-18 19:03:39.630676: [NET] Stopping tunnel 2021-07-18 19:03:39.631178: [NET] Device closing 2021-07-18 19:03:39.631454: [NET] Routine: TUN reader - stopped 2021-07-18 19:03:39.631906: [NET] Routine: event worker - stopped 2021-07-18 19:03:39.632179: [NET] Routine: receive incoming v4 - stopped 2021-07-18 19:03:39.632537: [NET] Routine: receive incoming v6 - stopped 2021-07-18 19:03:39.632623: [NET] peer(5x4E…4uyk) - Stopping 2021-07-18 19:03:39.632716: [NET] peer(5x4E…4uyk) - Routine: sequential sender - stopped 2021-07-18 19:03:39.632895: [NET] peer(5x4E…4uyk) - Routine: sequential receiver - stopped 2021-07-18 19:03:39.633198: [NET] Device closed 2021-07-18 19:03:39.633481: [NET] Routine: decryption worker 2 - stopped 2021-07-18 19:03:39.633481: [NET] Routine: handshake worker 2 - stopped 2021-07-18 19:03:39.633513: [NET] Routine: decryption worker 5 - stopped 2021-07-18 19:03:39.633530: [NET] Routine: handshake worker 3 - stopped 2021-07-18 19:03:39.633545: [NET] Routine: handshake worker 6 - stopped 2021-07-18 19:03:39.633608: [NET] Routine: decryption worker 6 - stopped 2021-07-18 19:03:39.633605: [NET] Routine: handshake worker 5 - stopped 2021-07-18 19:03:39.633670: [NET] Routine: handshake worker 1 - stopped 2021-07-18 19:03:39.633680: [NET] Routine: decryption worker 3 - stopped 2021-07-18 19:03:39.633741: [NET] Routine: decryption worker 4 - stopped 2021-07-18 19:03:39.633755: [NET] Routine: handshake worker 4 - stopped 2021-07-18 19:03:39.633775: [NET] Routine: decryption worker 1 - stopped 2021-07-18 19:03:39.633931: [NET] Routine: encryption worker 3 - stopped 2021-07-18 19:03:39.633973: [NET] Routine: encryption worker 1 - stopped 2021-07-18 19:03:39.633992: [NET] Routine: encryption worker 2 - stopped 2021-07-18 19:03:39.634004: [NET] Routine: encryption worker 6 - stopped 2021-07-18 19:03:39.634012: [NET] Routine: encryption worker 4 - stopped 2021-07-18 19:03:39.634016: [NET] Routine: encryption worker 5 - stopped 2021-07-18 19:03:39.645490: [APP] Tunnel 'htvpn' connection status changed to 'disconnected' 2021-07-18 20:49:12.543320: [APP] App version: 1.0.13 (24) 2021-07-18 20:49:22.444412: [APP] startActivation: Entering (tunnel: htvpn) 2021-07-18 20:49:22.444844: [APP] startActivation: Starting tunnel 2021-07-18 20:49:22.445166: [APP] startActivation: Success 2021-07-18 20:49:22.462303: [APP] Tunnel 'htvpn' connection status changed to 'connecting' 2021-07-18 20:49:22.565473: [NET] App version: 1.0.13 (24) 2021-07-18 20:49:22.565615: [NET] Starting tunnel from the app 2021-07-18 20:49:22.661409: [NET] DNS64: mapped 158.247.225.243 to itself. 2021-07-18 20:49:22.662210: [NET] Attaching to interface 2021-07-18 20:49:22.662685: [NET] Routine: decryption worker 3 - started 2021-07-18 20:49:22.662698: [NET] Routine: decryption worker 1 - started 2021-07-18 20:49:22.662758: [NET] Routine: decryption worker 2 - started 2021-07-18 20:49:22.662776: [NET] UAPI: Updating private key 2021-07-18 20:49:22.662873: [NET] Routine: encryption worker 2 - started 2021-07-18 20:49:22.662870: [NET] Routine: handshake worker 2 - started 2021-07-18 20:49:22.662885: [NET] Routine: encryption worker 3 - started 2021-07-18 20:49:22.662902: [NET] Routine: encryption worker 1 - started 2021-07-18 20:49:22.662937: [NET] Routine: event worker - started 2021-07-18 20:49:22.662961: [NET] Routine: decryption worker 5 - started 2021-07-18 20:49:22.662968: [NET] Routine: decryption worker 4 - started 2021-07-18 20:49:22.662970: [NET] Routine: handshake worker 1 - started 2021-07-18 20:49:22.663128: [NET] Routine: handshake worker 4 - started 2021-07-18 20:49:22.663138: [NET] Routine: encryption worker 4 - started 2021-07-18 20:49:22.663157: [NET] Routine: handshake worker 3 - started 2021-07-18 20:49:22.663162: [NET] Routine: decryption worker 6 - started 2021-07-18 20:49:22.663174: [NET] Routine: encryption worker 5 - started 2021-07-18 20:49:22.663179: [NET] Routine: handshake worker 6 - started 2021-07-18 20:49:22.663181: [NET] Routine: TUN reader - started 2021-07-18 20:49:22.663241: [NET] Routine: handshake worker 5 - started 2021-07-18 20:49:22.663257: [NET] Routine: encryption worker 6 - started 2021-07-18 20:49:22.663276: [NET] UAPI: Removing all peers 2021-07-18 20:49:22.663707: [NET] peer(5x4E…4uyk) - UAPI: Created 2021-07-18 20:49:22.663752: [NET] peer(5x4E…4uyk) - UAPI: Updating preshared key 2021-07-18 20:49:22.663863: [NET] peer(5x4E…4uyk) - UAPI: Updating endpoint 2021-07-18 20:49:22.664044: [NET] peer(5x4E…4uyk) - UAPI: Updating persistent keepalive interval 2021-07-18 20:49:22.664076: [NET] peer(5x4E…4uyk) - UAPI: Removing all allowedips 2021-07-18 20:49:22.664189: [NET] peer(5x4E…4uyk) - UAPI: Adding allowedip 2021-07-18 20:49:22.664289: [NET] peer(5x4E…4uyk) - UAPI: Adding allowedip 2021-07-18 20:49:22.664619: [NET] UDP bind has been updated 2021-07-18 20:49:22.664643: [NET] peer(5x4E…4uyk) - Starting 2021-07-18 20:49:22.664774: [NET] Interface state was Down, requested Up, now Up 2021-07-18 20:49:22.664828: [NET] Device started 2021-07-18 20:49:22.664868: [NET] Routine: receive incoming v6 - started 2021-07-18 20:49:22.664928: [NET] Routine: receive incoming v4 - started 2021-07-18 20:49:22.664926: [NET] Tunnel interface is utun2 2021-07-18 20:49:22.665422: [NET] Network change detected with satisfied route and interface order [en0, pdp_ip0] 2021-07-18 20:49:22.665462: [NET] peer(5x4E…4uyk) - Routine: sequential sender - started 2021-07-18 20:49:22.665550: [NET] DNS64: mapped 158.247.225.243 to itself. 2021-07-18 20:49:22.665664: [NET] peer(5x4E…4uyk) - Routine: sequential receiver - started 2021-07-18 20:49:22.665748: [NET] peer(5x4E…4uyk) - UAPI: Updating endpoint 2021-07-18 20:49:22.665956: [NET] Routine: receive incoming v4 - stopped 2021-07-18 20:49:22.665957: [NET] Routine: receive incoming v6 - stopped 2021-07-18 20:49:22.666322: [NET] UDP bind has been updated 2021-07-18 20:49:22.666375: [NET] Routine: receive incoming v4 - started 2021-07-18 20:49:22.666390: [NET] Routine: receive incoming v6 - started 2021-07-18 20:49:22.666653: [APP] Tunnel 'htvpn' connection status changed to 'connected' 2021-07-18 20:49:23.026342: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 20:49:23.563155: [NET] Network change detected with satisfied route and interface order [en0, utun2, pdp_ip0] 2021-07-18 20:49:23.563944: [NET] DNS64: mapped 158.247.225.243 to itself. 2021-07-18 20:49:23.564272: [NET] peer(5x4E…4uyk) - UAPI: Updating endpoint 2021-07-18 20:49:23.564692: [NET] Routine: receive incoming v4 - stopped 2021-07-18 20:49:23.564751: [NET] Routine: receive incoming v6 - stopped 2021-07-18 20:49:23.566143: [NET] UDP bind has been updated 2021-07-18 20:49:23.566381: [NET] Routine: receive incoming v6 - started 2021-07-18 20:49:23.566600: [NET] Routine: receive incoming v4 - started 2021-07-18 20:49:28.100400: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 20:49:32.494463: [APP] Status update notification timeout for tunnel 'htvpn'. Tunnel status is now 'connected'. 2021-07-18 20:49:33.166791: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 20:49:38.258222: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 20:49:43.271442: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 20:49:48.369502: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 20:49:53.697079: [NET] peer(5x4E…4uyk) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 20:49:53.697224: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 20:49:58.778273: [NET] peer(5x4E…4uyk) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 20:49:58.778625: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 20:50:03.936596: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 20:50:09.082791: [NET] peer(5x4E…4uyk) - Handshake did not complete after 5 seconds, retrying (try 2) 2021-07-18 20:50:09.083028: [NET] peer(5x4E…4uyk) - Sending handshake initiation 2021-07-18 20:50:09.959016: [APP] startDeactivation: Tunnel: htvpn 2021-07-18 20:50:09.961990: [APP] Tunnel 'htvpn' connection status changed to 'disconnecting' 2021-07-18 20:50:10.360087: [NET] Stopping tunnel 2021-07-18 20:50:10.360434: [NET] Device closing 2021-07-18 20:50:10.360647: [NET] Routine: TUN reader - stopped 2021-07-18 20:50:10.360764: [NET] Routine: event worker - stopped 2021-07-18 20:50:10.360835: [NET] Routine: receive incoming v4 - stopped 2021-07-18 20:50:10.360958: [NET] Routine: receive incoming v6 - stopped 2021-07-18 20:50:10.361106: [NET] peer(5x4E…4uyk) - Stopping 2021-07-18 20:50:10.361229: [NET] peer(5x4E…4uyk) - Routine: sequential receiver - stopped 2021-07-18 20:50:10.361246: [NET] peer(5x4E…4uyk) - Routine: sequential sender - stopped 2021-07-18 20:50:10.361824: [NET] Device closed 2021-07-18 20:50:10.361863: [NET] Routine: decryption worker 1 - stopped 2021-07-18 20:50:10.361870: [NET] Routine: decryption worker 5 - stopped 2021-07-18 20:50:10.361872: [NET] Routine: handshake worker 2 - stopped 2021-07-18 20:50:10.361920: [NET] Routine: decryption worker 6 - stopped 2021-07-18 20:50:10.361978: [NET] Routine: handshake worker 3 - stopped 2021-07-18 20:50:10.361971: [NET] Routine: handshake worker 6 - stopped 2021-07-18 20:50:10.361974: [NET] Routine: decryption worker 4 - stopped 2021-07-18 20:50:10.362009: [NET] Routine: decryption worker 2 - stopped 2021-07-18 20:50:10.362020: [NET] Routine: decryption worker 3 - stopped 2021-07-18 20:50:10.362082: [NET] Routine: handshake worker 4 - stopped 2021-07-18 20:50:10.362077: [NET] Routine: handshake worker 5 - stopped 2021-07-18 20:50:10.362109: [NET] Routine: handshake worker 1 - stopped 2021-07-18 20:50:10.362276: [NET] Routine: encryption worker 2 - stopped 2021-07-18 20:50:10.362287: [NET] Routine: encryption worker 4 - stopped 2021-07-18 20:50:10.362314: [NET] Routine: encryption worker 1 - stopped 2021-07-18 20:50:10.362329: [NET] Routine: encryption worker 3 - stopped 2021-07-18 20:50:10.362338: [NET] Routine: encryption worker 6 - stopped 2021-07-18 20:50:10.362349: [NET] Routine: encryption worker 5 - stopped 2021-07-18 20:50:10.384954: [APP] Tunnel 'htvpn' connection status changed to 'disconnected' 2021-07-18 21:17:21.118713: [APP] App version: 1.0.13 (24)

the server side wg show:

interface: wg0 public key: CT6/x4kxIV+j91CNJIQP/jKaCoJYZiHdbsCBuFJlWC0= private key: (hidden) listening port: 51820

peer: JcqDL4UaAjpjHY5vxKW2Lxxs2jMTEIU+N1c/+wUG3D4= preshared key: (hidden) endpoint: 116.86.228.32:34840 allowed ips: 10.49.0.25/32 latest handshake: 15 minutes, 49 seconds ago transfer: 574.56 MiB received, 874.85 MiB sent

peer: GPJBQPEDMAVjGJ1Vcrmul+5aDldHlj2lfx7GHSBnyAE= preshared key: (hidden) endpoint: 183.228.25.31:12587 allowed ips: 10.49.0.6/32 latest handshake: 22 hours, 3 minutes, 41 seconds ago transfer: 582.23 KiB received, 1.89 MiB sent

peer: kwNAbY0ciVOHrTv+vYY6+0EdBD17SABga3zNqwFIfSk= preshared key: (hidden) endpoint: 111.127.85.40:19358 allowed ips: 10.49.0.9/32 latest handshake: 22 hours, 31 minutes, 16 seconds ago transfer: 180.09 KiB received, 1.40 MiB sent

peer: Exh9ckHM5YXcXMYpvLQIOUjLrxPkuQlah1k2hsXNtHM= preshared key: (hidden) endpoint: 116.86.228.32:50011 allowed ips: 10.49.0.5/32 latest handshake: 22 hours, 39 minutes, 37 seconds ago transfer: 409.56 KiB received, 705.21 KiB sent

alanning commented 2 years ago

For what it's worth I ran into this same "Handshake did not complete after 5 seconds" issue today on both macOS Monterey 12.4 and iOS 15.5. Server was on Digital Ocean using "algo" command; clients were the latest Wireguard apps from the Mac and iOS App Stores.

Using the built-in IPSEC VPN got it working on macOS. I have had mixed results with it on iOS; initially it worked but after a period of non-use (where the device display was turned off), the internet connection says its connected but no websites are successfully pulled up in Safari.

Troubleshooting steps tried:

  1. Destroyed DigitalOcean droplet and recreated using "algo" command. No change.
  2. The top result for this issue on ServerFault recommends re-creating keys, which I don't know how to do with algo, and adjusting client MTU, which did not have a noticeable effect for me: https://serverfault.com/questions/1040165/wireguard-not-completing-handshake
  3. Tried built-in IPSEC VPN as described here: https://github.com/trailofbits/algo/blob/master/docs/client-apple-ipsec.md. Worked!