2020-06-05 20:20:36.890 INFO OpenVPNTunnelProvider.startTunnel():216 - Starting tunnel... 2020-06-05 20:20:36.903 INFO OpenVPNTunnelProvider+Configuration.print():642 - Protocols: [UDP:1195, TCP:1194, TCP:443] 2020-06-05 20:20:36.903 INFO OpenVPNTunnelProvider+Configuration.print():643 - Cipher: AES-256-GCM 2020-06-05 20:20:36.903 INFO OpenVPNTunnelProvider+Configuration.print():644 - Digest: HMAC-SHA1 2020-06-05 20:20:36.903 INFO OpenVPNTunnelProvider+Configuration.print():645 - Compression framing: disabled 2020-06-05 20:20:36.904 INFO OpenVPNTunnelProvider+Configuration.print():649 - Compression algorithm: disabled 2020-06-05 20:20:36.904 INFO OpenVPNTunnelProvider+Configuration.print():652 - Client verification: enabled 2020-06-05 20:20:36.904 INFO OpenVPNTunnelProvider+Configuration.print():657 - TLS wrapping: crypt 2020-06-05 20:20:36.905 INFO OpenVPNTunnelProvider+Configuration.print():662 - TLS security level: 3 2020-06-05 20:20:36.905 INFO OpenVPNTunnelProvider+Configuration.print():669 - Keep-alive interval: never 2020-06-05 20:20:36.905 INFO OpenVPNTunnelProvider+Configuration.print():674 - Keep-alive timeout: never 2020-06-05 20:20:36.905 INFO OpenVPNTunnelProvider+Configuration.print():679 - Renegotiation: never 2020-06-05 20:20:36.905 INFO OpenVPNTunnelProvider+Configuration.print():682 - Server EKU verification: enabled 2020-06-05 20:20:36.905 INFO OpenVPNTunnelProvider+Configuration.print():689 - Host SAN verification: disabled 2020-06-05 20:20:36.905 INFO OpenVPNTunnelProvider+Configuration.print():697 - Gateway: not configured 2020-06-05 20:20:36.906 INFO OpenVPNTunnelProvider+Configuration.print():702 - DNS: not configured 2020-06-05 20:20:36.906 INFO OpenVPNTunnelProvider+Configuration.print():408 - MTU: 1250 2020-06-05 20:20:36.906 INFO OpenVPNTunnelProvider+Configuration.print():409 - Debug: true 2020-06-05 20:20:36.906 INFO OpenVPNTunnelProvider+Configuration.print():410 - Masks private data: false 2020-06-05 20:20:36.917 DEBUG OpenVPNTunnelProvider.logCurrentSSID():826 - Current SSID: none (disconnected from WiFi) 2020-06-05 20:20:36.917 INFO OpenVPNTunnelProvider.connectTunnel():317 - Creating link session 2020-06-05 20:20:36.917 DEBUG ConnectionStrategy.createSocket():164 - No endpoints available, will resort to DNS resolution 2020-06-05 20:20:36.917 DEBUG ConnectionStrategy.createSocket():171 - DNS resolve hostname: internet.nl.eduvpn.org 2020-06-05 20:20:37.108 DEBUG ConnectionStrategy.createSocket():175 - DNS resolved addresses: ["145.100.179.13"] 2020-06-05 20:20:37.111 DEBUG ConnectionStrategy.unrolledEndpoints():122 - Unrolled endpoints: [145.100.179.13:UDP:1195, 145.100.179.13:TCP:1194, 145.100.179.13:TCP:443] 2020-06-05 20:20:37.111 DEBUG ConnectionStrategy.createSocket():190 - Pick current endpoint: 145.100.179.13:UDP:1195 2020-06-05 20:20:37.127 INFO OpenVPNTunnelProvider.connectTunnel():336 - Will connect to 145.100.179.13:1195 2020-06-05 20:20:37.186 DEBUG OpenVPNTunnelProvider.connectTunnel():339 - Socket type is NEUDPSocket 2020-06-05 20:20:37.194 DEBUG NEUDPSocket.observeValueInTunnelQueue():147 - Socket state is ready (endpoint: 145.100.179.13:1195 -> 145.100.179.13:1195) 2020-06-05 20:20:37.195 DEBUG OpenVPNSession.setLink():261 - Starting VPN session 2020-06-05 20:20:37.196 DEBUG OpenVPNSession.hardReset():601 - Send hard reset 2020-06-05 20:20:37.197 DEBUG OpenVPNSession.hardReset():609 - Negotiation key index is 0 2020-06-05 20:20:37.198 DEBUG ControlChannel.enqueueOutboundPackets():159 - Control: Enqueued 1 packet [0] 2020-06-05 20:20:37.199 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 6d2c88f294cfebca, pid: 0, [0 bytes]} 2020-06-05 20:20:37.199 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 386d2c88f294cfebca000000015eda8cf476a10ddd15980e1671704fd40a87dbd7488c5d61e3730261345ece74ba92e8938d9319f539 2020-06-05 20:20:37.416 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 6d2c88f294cfebca, pid: 0, [0 bytes]} 2020-06-05 20:20:37.416 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 386d2c88f294cfebca000000025eda8cf470e5c0e10f4b23f3b9bdb3a6819cf48e68f537821541b9100f4c85589039d968a65a509b67 2020-06-05 20:20:37.441 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code ACK_V1 and key 0 2020-06-05 20:20:37.441 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {ACK_V1 | 0, sid: c60e71cd21c47d3a, acks: {[0], 6d2c88f294cfebca}} 2020-06-05 20:20:39.281 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code HARD_RESET_SERVER_V2 and key 0 2020-06-05 20:20:39.281 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {HARD_RESET_SERVER_V2 | 0, sid: c60e71cd21c47d3a, pid: 0} 2020-06-05 20:20:39.281 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 0 2020-06-05 20:20:39.283 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: 6d2c88f294cfebca, acks: {[0], c60e71cd21c47d3a}} 2020-06-05 20:20:39.284 DEBUG ControlChannel.remoteSessionId():50 - Control: Remote sessionId is c60e71cd21c47d3a 2020-06-05 20:20:39.284 DEBUG OpenVPNSession.handleControlPacket():785 - Start TLS handshake 2020-06-05 20:20:39.308 DEBUG OpenVPNSession.handleControlPacket():819 - TLS.connect: Pulled ciphertext (263 bytes) 2020-06-05 20:20:39.308 DEBUG ControlChannel.enqueueOutboundPackets():159 - Control: Enqueued 1 packet [1] 2020-06-05 20:20:39.309 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: 6d2c88f294cfebca, pid: 1, [263 bytes]} 2020-06-05 20:20:39.315 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (317 bytes): 206d2c88f294cfebca000000045eda8cf4c8335b3a4db65a725a161374e22c5d6118595cf2b8248dfeda424c13e7ccdbf3c1a35af407fdb88c74af4cc0081293b15561697e8d182c98a99b83d5d720c53272b33312c77508bb48b96bd83b85478e1f747c1d23ed038129221448c0221c7d0d60c44805590aa697938a683dd8bf6bd8b9a75bd83bb1efd7bd91882015698dcd01e1539fb3b0b94cc10312b812ca2803cedb85117d97b378e3d7eb1d2be087cd77dbd222201109b7c5c79892ec36a0d64cb01077a7b939ad910ebb3b44cb5c18a49040d2a66df0710485b8b2b4557770ee994e2459afefc991c5da32ba2eec09c231d645522b59fbe9214e45276e306beda612093c8143ea620dd095f64f40ee11b696655682f307b89608e2422a1d594499db79728655cfab15284f02bcd4414e34b7821934857bedad4a 2020-06-05 20:20:39.316 DEBUG ControlChannel.writeOutboundPackets():169 - Control: Skip writing packet with packetId 1 (sent on 2020-06-05 18:20:39 +0000, 0.006703019142150879 seconds ago) 2020-06-05 20:20:39.317 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 0 2020-06-05 20:20:39.335 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:20:39.336 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: c60e71cd21c47d3a, acks: {[1], 6d2c88f294cfebca}, pid: 1, [1062 bytes]} 2020-06-05 20:20:39.336 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 1 2020-06-05 20:20:39.336 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: 6d2c88f294cfebca, acks: {[1], c60e71cd21c47d3a}} 2020-06-05 20:20:39.336 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (1062 bytes) 2020-06-05 20:20:39.337 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:20:39.338 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: c60e71cd21c47d3a, pid: 2, [1062 bytes]} 2020-06-05 20:20:39.338 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 2 2020-06-05 20:20:39.338 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: 6d2c88f294cfebca, acks: {[2], c60e71cd21c47d3a}} 2020-06-05 20:20:39.340 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (1062 bytes) 2020-06-05 20:20:39.341 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:20:39.351 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: c60e71cd21c47d3a, pid: 3, [606 bytes]} 2020-06-05 20:20:39.351 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 3 2020-06-05 20:20:39.352 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: 6d2c88f294cfebca, acks: {[3], c60e71cd21c47d3a}} 2020-06-05 20:20:39.352 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (606 bytes) 2020-06-05 20:20:39.360 DEBUG OpenVPNSession.handleControlPacket():846 - TLS.connect: Send pulled ciphertext (2662 bytes) 2020-06-05 20:20:39.361 DEBUG ControlChannel.enqueueOutboundPackets():157 - Control: Enqueued 3 packets [2-4] 2020-06-05 20:20:39.361 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: 6d2c88f294cfebca, pid: 2, [1250 bytes]} 2020-06-05 20:20:39.361 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: 6d2c88f294cfebca, pid: 3, [1250 bytes]} 2020-06-05 20:20:39.362 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: 6d2c88f294cfebca, pid: 4, [162 bytes]} 2020-06-05 20:20:39.373 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (1304 bytes): 206d2c88f294cfebca000000085eda8cf45793160a4ff3fc7d21226314439285981fedb31e05463dd4e5a124787c2296badcab92226b90a50dc56e8deb7ecc8fa9e03c720d5e25f12ae4d062b5e15a9379ee303fdc3ab8a16a235d7c672eafe7f2537c7c2bcf4672206380f8e299aa7e33221384b2f61c052587e14db2dc4c1aadf2dbfe266d7e3dd4257a9a12bd9ca7ffd92f37872cdfd09a365d6a860c08d633c861d11759b0c97c8a4bb49c59cdaca59f436588a955e5f7bd736d8678fa7034d8572d9aeb9abfd8b999f742c1c80851d72527736a9979c97308841807820cfa161bdf5dd9d2ef0aa9d50c55fa81dc93c20c7e71477d8f06c5f5bafed61f729836f9eadf688cb4fd1d7d6a0ab17cb60825ffd465dcbdb68253b3867c04b719577b2cdeb9fa8b66681e6fcc25d08c43e604103770692adcda1d6e2ba915c82c3752ca4297552dece47ecb9c1befff1776e520203d0db94523047df1a67fbafcb6e7c4f9674b0a17239118a44b3ddc7fdddf4e33d6316031a3fd67d408815d16c73f656213b607747e0c3d525c101470112edb700103f1a8f7baf650d18016a1940bcc75d28aacd3266cf0e6d4adbbabdebe8347c6ac81914341e0814c582f260c9de3ff410d88a07092b25677823589c2dbfc1c5806db64fcd6e2e474d8f925cc1793e4d4582a56655b727d5ba4bf6737faded8d79e7feaac9a1fbd55e76905d4e05fd3a1100e38f71753e36fb6d3767c771fb00ed30657e5d06d25776d9f7effe06e3cc484fec39e7862cfb8713ba031181b7cabb3a2f839c1d1f8451eb804d18f58acce642a6cb7a31becfec39d2bd7c5f936e7f873edf60211cba5438634ba552f60b815bb9cc2e9f2a82b0407d8255ae3fc82e7d3dbf2dd6867316b690f00ff26d221c5d01aa6a7f6e39e15de2a97b26fb9caaeb5f6e444456ea53ffbe6e26e77a4745e19bfa0fbfd04413de2e9345ba02f71cca2497093e75bc48399f7d45392b840de56e9fea00b4312217c37c8dbea2108d31e54480d51a232f2bededf719e1ab38a865fddfe7e0859a242a3ea9216438ae00fbff7468a85e696b25185736c5d7b5c0e79149934ee9be1b9aa8d7a451ef281f17a69a2ad73bd5782a4b356956f618d0cbc99d7e77e36655c8dc60e1925a90fbcdf70b70b7f31203bbb1900b814a87c99bd00e22611da60b7b2a1fbe2aae609b388ccc81d56893c0992b4fbcbe970e2eea73af6c9f20a9adb62a5c7f1b7191fb32d8165c38140ddb25e68cb8487e35eec490f3d9c22f58511f9fffa1e6f06de34671dd4f36d0c63652dd94c1daf78721ef081d626b510cc2d4ba5f501bab0cac2349b47fd501b1fea0c60da7363e0e5523b6da9e0600b3a37c37a92ade832c403f6d7404d240c8a96bf9938afdb48669ebce5767996315909c4be31300a78bda66a4d2a47e92d4729aa1da7450612b7cdf74c06fa5c27be6fa84e2cf9dc87e14919cf66b8b6f23c34c8ec3460e85da7bf335768c059cf1b4c67f8c9400bc78cf363254f48d8856af7fe03a05ae03877ee25525387b8283dc5f92031218fe8e8a71d0ed003f872c5791e4737c96391f3fe3073252fcd89049fc8d582571b49f7da91ff143a35393069cc6bf1aa5d30a714c4aab02ecb6042bfc28095df960cdec327e540cf2a9808032610792a82c8000e6ee9dd8c582bae0ef60020c233765bdfe4c9580c089667cbc2a1b60b9c0c35dd05ca827415f4da6675ca3883af6f1562a38803a70ba8d9714ee071de0b31520d4086ab57a6de089538188085387db1cb20c7dcb8cd7fceb4cb2bc725102caeb8228b57ecc37b69f7429f43e3d799fbba23 2020-06-05 20:20:39.384 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (1304 bytes): 206d2c88f294cfebca000000095eda8cf4337d59619257e8310f4d9ce02c2a81bb55643694889e1857e9305db72cced15f60371cbd615b722ae59e2102f590e6590104dd290cf117c1092bcde98527500217eef58eb7d58b57d7ba2c3737786f75a0033e87df74c5ae2b2f88eee11c928bc2e370089e9592c45b653e9670845a4f16bffc6600e69e59505abe19a2a859b6eeb8819a57f5168254dc9b78d8ab6c98ed9a90f1b4eafbe65cdd76b918eabd303e23c875b9024057f3313ec3de758cb2e16a7b8bd320fcd03da2978c7104070803185f2ab76284a9dfea709e4833aad182116b2c781159b64f45919bba96183a6c3f3a7f36202ae07cd0f64c0d998435431d9b218a29806169342bb008e6ece17dee51ad875d21a0a333144ddb57f77f1af00e08a695d3b01357438b9cf53121ab5be1c3dec18739d663d07787db07ca35ce8cf1c23ad7f29ebb94f8f45e6823422ada2e0953fd508a6a8052ed1b978224eb09085d5748ffdf941cdee7b113baebce6ef14345e2f69faf498ed8345858d2f1062d5ef569614b9e84ee5b6d65f5cd254bc36dea09a5521869be5b1f6374866f410ef02a3d1f67f3c04d7e9c1df9c7bb4ff3442296591e305875ac1ce5315d7b200b4d8cde3b74a5a927bfafa141862f9f31d5f5a5b8bfbbf6ce1aa50ba45fc2856bb2ccce3b53584d175b0743d6d3f76bc7e5387ee18b524cef0dc079fcd23391446df9ebf9e0b74b4fb2053c2c593743ae02925fc70cb20fe838ebccaac5fb1d8e5df3c189aabb8be390bcdfff66e43e00d7923f164bf9eb8f5aaeb7d5651de12d7265b9712eb48648437aa5c8df4def5d5c9c17f68c9d35fcdb9eb892fded188d64d78f5b9d6c71d7674fe984348505f33aa3b1063b5e787b86b167aa66a1cbaf49ae071753f475d2209412de86019efc8abb2f8fb31185b37da0f35b2ac5355c99a4007713a4b88e61fec71e11c55dc0eb6173b2b0759bc9460d45b8cbe940fa0dc3e82d77d4ba5dbcb682e95200a30f6141d26e7f83f1d8026e6b22b39e3d9bad54e608e26cf034f7893035856ab09d70b9639a1bd891f30b67abf25e9ef5e8cd2f107967282aa1ffd7221d4d9059be165c472aefe80a01e422be7ef3371c8fdb5e8c00c9164629bff046d21aa4d2c00b6faeb51be9eecc53262f98418d2595b1df06cd3d8cced8b862ceae8faacf4f424d684afa7010619ff8ce5aff2049a26b7739c8d1b8e9ccbe77752528c9e79e7930af13d2b86da680357f0549eb63b62d1713f1697dd4f617b8c2292e442a2ccb3b2cc73a93fabc2f6b5800fb219152c9815243eb92f3bf5df5eac05f9255744244db8c73f4fe366fa68b8e3b8f79bf60cc0f73c19a64b03f7b44c6286f1ab63110c109157d6cbd407465dcd598a2cf0a705a373128bea1ac567f71a1765a3d573d5cd082b5bc78dbb73a4665ad3dc195f50237b4af93cb60b5e02ae9117d3150ebedc352ac85b1bacc48438fd0b555d864f342fe040afd72120ece6a603652d055322bcfcba7eacd7901f7271541a900212ce992c34a8092da0acb72680e04bc92f233a2809df8e0fad47a19ac1ec0190612fe414bcf24c9e461313064bd3feb907292c2eee78a99a4a0eb2983171ffa9cd677c76acc964cf7d90b4221d131886fb5443b3cf21196d25a04f42369dcd72303f3f86bc7728092f51185d6a45fb8ec7761317799ad9511f0a728474ae0c0d72d3a463e0e389ef2603cf75a98ebe78b798a73c278c58c11b77a30e769aba32c969886867acfe07b2a0ed4106be269ad57fb8a9eb250018656e4389831a26fc784af5e724a0c3d275d59b53b34589e89d6 2020-06-05 20:20:39.387 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (216 bytes): 206d2c88f294cfebca0000000a5eda8cf492b7013aad03d2e197e8936c2ad13c3ee499451a5938b15e11d43028b8d0ff18cb663d9d758d3eb6f077a4f1902a8d08d0382f69c583651c732f89a4e6adfb47c636e7882fd8f4756abdc48be968a6b7cbf3cdcc9b9c874978f557b0ea7ef13268713e67b14ed431b3edb8ec31df6ba322bff414e80bf288d357f2ba4056b77e08860f6f99414206d8ec3de17ad3a977aaa3002fc3263be88ad8f05a9b71b551032c8d463e123ff33313a4d39fd432c9f474d38ac1882c5038e7b3ac84fd25da17a130fb3255b7 2020-06-05 20:20:39.387 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 1 2020-06-05 20:20:39.387 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 2 2020-06-05 20:20:39.388 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 3 2020-06-05 20:20:39.408 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code ACK_V1 and key 0 2020-06-05 20:20:39.408 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {ACK_V1 | 0, sid: c60e71cd21c47d3a, acks: {[2], 6d2c88f294cfebca}} 2020-06-05 20:20:39.408 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code ACK_V1 and key 0 2020-06-05 20:20:39.409 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {ACK_V1 | 0, sid: c60e71cd21c47d3a, acks: {[3], 6d2c88f294cfebca}} 2020-06-05 20:20:39.409 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:20:39.409 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: c60e71cd21c47d3a, acks: {[4], 6d2c88f294cfebca}, pid: 4, [51 bytes]} 2020-06-05 20:20:39.409 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 4 2020-06-05 20:20:39.410 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: 6d2c88f294cfebca, acks: {[4], c60e71cd21c47d3a}} 2020-06-05 20:20:39.410 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (51 bytes) 2020-06-05 20:20:39.410 DEBUG OpenVPNSession.onTLSConnect():667 - TLS.connect: Handshake is complete 2020-06-05 20:20:39.410 DEBUG Authenticator.putAuth():146 - TLS.auth: Local options: V4,dev-type tun,keydir 1,cipher AES-256-GCM,auth SHA1,keysize 256,tls-crypt,key-method 2,tls-client 2020-06-05 20:20:39.412 DEBUG Authenticator.putAuth():164 - TLS.auth: Put plaintext (360 bytes) 2020-06-05 20:20:39.412 DEBUG OpenVPNSession.onTLSConnect():693 - TLS.auth: Pulled ciphertext (389 bytes) 2020-06-05 20:20:39.412 DEBUG ControlChannel.enqueueOutboundPackets():159 - Control: Enqueued 1 packet [5] 2020-06-05 20:20:39.412 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: 6d2c88f294cfebca, pid: 5, [389 bytes]} 2020-06-05 20:20:39.415 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (443 bytes): 206d2c88f294cfebca0000000c5eda8cf4b524f74165a2c415d62e5fb5b90d9246a10c8e8f5005f1fa24a1aec0ef9622e2f55c0b0c3e0b26f91ea935d50d30e2447f577430c42ff5e173cdaf0e0fd87e7e98bafcd034f23fd3affd1a6c55ffcf94b06249a9e072de034f1edab60429399b4242aee00e180bf7037d5ca11b48f7665c51a24271c96aa5deaeca2604a2a05d2511e0c8c8602b2368ea75f74b75adb0366a8685b5d87531c55cd8a18fadddaa37f563c43bbc1cbcb93325095b49b87ecd840cd6b7981eed3b2ee1a08cbb90fdfb3366887f63956ce92cffcbf5a8ef6c718935a092545f4ee10fa431dc75ee68c9e624b267c6f96dfaf2f60e71bd021b881ae5f355cf241ed16a874eedcc8225f1afec0125a7241bd182f234083494ebf32f14d2d07ad9966909dbd257b8c244b6dea4918e56b4501862fa5e8d658afc281ef344a2dc1589c6176f043c10b6f74d400ded75ca790dc267e4a6d9e7d2a1ac9ae5262ca19151cc4f6aeddd189d7784de07406fa7e660b1e9e8b636ea9c3fde9fbe35ae8daff7e2b7b8e67d1af6249725cd396291cd11e1dc7032bd122fd73f99cd4c208feaea2f38464880b6c20b5a974138caf4291f1bc2 2020-06-05 20:20:39.416 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 4 2020-06-05 20:20:39.437 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:20:39.437 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: c60e71cd21c47d3a, acks: {[5], 6d2c88f294cfebca}, pid: 5, [235 bytes]} 2020-06-05 20:20:39.437 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 5 2020-06-05 20:20:39.437 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: 6d2c88f294cfebca, acks: {[5], c60e71cd21c47d3a}} 2020-06-05 20:20:39.438 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (235 bytes) 2020-06-05 20:20:39.438 DEBUG OpenVPNSession.handleControlData():880 - Pulled plain control data (206 bytes) 2020-06-05 20:20:39.438 DEBUG Authenticator.parseAuthReply():209 - TLS.auth: Parsed server random 2020-06-05 20:20:39.438 DEBUG Authenticator.parseAuthReply():213 - TLS.auth: Parsed server options: "V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-256-GCM,auth [null-digest],keysize 256,key-method 2,tls-server" 2020-06-05 20:20:39.438 DEBUG OpenVPNSession.pushRequest():706 - TLS.ifconfig: Put plaintext (PUSH_REQUEST) 2020-06-05 20:20:39.439 DEBUG OpenVPNSession.pushRequest():722 - TLS.ifconfig: Send pulled ciphertext (42 bytes) 2020-06-05 20:20:39.439 DEBUG ControlChannel.enqueueOutboundPackets():159 - Control: Enqueued 1 packet [6] 2020-06-05 20:20:39.439 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: 6d2c88f294cfebca, pid: 6, [42 bytes]} 2020-06-05 20:20:39.440 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (96 bytes): 206d2c88f294cfebca0000000e5eda8cf42703e308813d73b3b8d78564dffa74a205b070e2594de4a24624cd6ef2e56c80701682c2d512e5fedc720cff38a02d3220cb49a98a84429f480d2eac1f6f8a5c220635ae6ba07ce4c9ee49fbb597d7 2020-06-05 20:20:39.440 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:20:39.440 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:20:39.440 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:20:39.440 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:20:39.440 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:20:39.441 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:20:39.441 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 5 2020-06-05 20:20:39.524 DEBUG ControlChannel.writeOutboundPackets():169 - Control: Skip writing packet with packetId 6 (sent on 2020-06-05 18:20:39 +0000, 0.08521509170532227 seconds ago) 2020-06-05 20:20:39.730 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: 6d2c88f294cfebca, pid: 6, [42 bytes]} 2020-06-05 20:20:39.733 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (96 bytes): 206d2c88f294cfebca0000000f5eda8cf47d2a6fef28a2f5cd302c8fa39913627d0e9cdc1c5a59624bc09f2656292192c1fb28baafb2471df9de2ca1119216c15a467017f47e59e5ade1b9752f22f096a08a860a75cafc79195c2ff8b04d8a2d 2020-06-05 20:20:39.813 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code ACK_V1 and key 0 2020-06-05 20:20:39.814 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {ACK_V1 | 0, sid: c60e71cd21c47d3a, acks: {[6], 6d2c88f294cfebca}} 2020-06-05 20:20:39.814 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:20:39.814 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: c60e71cd21c47d3a, pid: 6, [481 bytes]} 2020-06-05 20:20:39.815 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 6 2020-06-05 20:20:39.815 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: 6d2c88f294cfebca, acks: {[6], c60e71cd21c47d3a}} 2020-06-05 20:20:39.815 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (481 bytes) 2020-06-05 20:20:39.815 DEBUG OpenVPNSession.handleControlData():880 - Pulled plain control data (452 bytes) 2020-06-05 20:20:39.816 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (451 bytes) 2020-06-05 20:20:39.820 DEBUG OpenVPNSession.handleControlMessage():956 - Received PUSH_REPLY: "PUSH_REPLY,block-outside-dns,dhcp-option DNS 192.87.106.106,dhcp-option DNS 192.87.36.36,dhcp-option DNS 2001:610:1:800a:192:87:106:106,dhcp-option DNS 2001:610:3:200a:192:87:36:36,explicit-exit-notify 1,redirect-gateway def1 ipv6 block-local,tun-ipv6,route-gateway 145.90.228.65,topology subnet,ping 10,ping-restart 60,ifconfig-ipv6 2001:610:450:40::1:1032/112 2001:610:450:40::1:1,ifconfig 145.90.228.116 255.255.255.192,peer-id 9,cipher AES-256-GCM" 2020-06-05 20:20:39.820 DEBUG OpenVPNSession.setupEncryption():1086 - Set up encryption 2020-06-05 20:20:39.820 INFO OpenVPNSession.setupEncryption():1091 - Negotiated cipher: AES-256-GCM 2020-06-05 20:20:39.820 INFO OpenVPNSession.setupEncryption():1102 - Negotiated keep-alive interval: 10.0 seconds 2020-06-05 20:20:39.821 INFO OpenVPNSession.setupEncryption():1105 - Negotiated keep-alive timeout: 60.0 seconds 2020-06-05 20:20:39.822 INFO OpenVPNTunnelProvider.sessionDidStart():518 - Session did start 2020-06-05 20:20:39.822 INFO OpenVPNTunnelProvider.sessionDidStart():520 - Returned ifconfig parameters: 2020-06-05 20:20:39.822 INFO OpenVPNTunnelProvider.sessionDidStart():521 - Remote: 145.100.179.13 2020-06-05 20:20:39.822 INFO OpenVPNTunnelProvider.sessionDidStart():522 - IPv4: addr 145.90.228.116 netmask 255.255.255.192 gw 145.90.228.65 routes [] 2020-06-05 20:20:39.822 INFO OpenVPNTunnelProvider.sessionDidStart():523 - IPv6: addr 2001:610:450:40::1:1032/112 gw 2001:610:450:40::1:1 routes [] 2020-06-05 20:20:39.822 INFO OpenVPNTunnelProvider.sessionDidStart():525 - Gateway: ["IPv6", "IPv4", "blockLocal"] 2020-06-05 20:20:39.822 INFO OpenVPNTunnelProvider.sessionDidStart():530 - DNS: ["192.87.106.106", "192.87.36.36", "2001:610:1:800a:192:87:106:106", "2001:610:3:200a:192:87:36:36"] 2020-06-05 20:20:39.822 INFO OpenVPNTunnelProvider.sessionDidStart():537 - Search domains: not configured 2020-06-05 20:20:39.823 INFO OpenVPNTunnelProvider.bringNetworkUp():616 - Routing.IPv4: Setting default gateway to 145.90.228.65 2020-06-05 20:20:39.823 INFO OpenVPNTunnelProvider.bringNetworkUp():645 - Routing.IPv6: Setting default gateway to 2001:610:450:40::1:1 2020-06-05 20:20:39.823 INFO OpenVPNTunnelProvider.bringNetworkUp():677 - DNS: Using servers ["192.87.106.106", "192.87.36.36", "2001:610:1:800a:192:87:106:106", "2001:610:3:200a:192:87:36:36"] 2020-06-05 20:20:39.854 INFO OpenVPNTunnelProvider.bringNetworkUp():750 - Block local: Suppressing IPv4 route default/32 2020-06-05 20:20:39.854 INFO OpenVPNTunnelProvider.bringNetworkUp():750 - Block local: Suppressing IPv4 route default/32 2020-06-05 20:20:39.854 INFO OpenVPNTunnelProvider.bringNetworkUp():765 - Block local: Suppressing IPv6 route default/128 2020-06-05 20:20:39.854 INFO OpenVPNTunnelProvider.bringNetworkUp():765 - Block local: Suppressing IPv6 route default/128 2020-06-05 20:20:39.856 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code ACK_V1 and key 0 2020-06-05 20:20:39.856 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {ACK_V1 | 0, sid: c60e71cd21c47d3a, acks: {[6], 6d2c88f294cfebca}} 2020-06-05 20:20:39.857 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 6 2020-06-05 20:20:40.313 DEBUG OpenVPNTunnelProvider.reasserting():137 - Reasserting flag cleared 2020-06-05 20:20:40.314 INFO OpenVPNTunnelProvider.sessionDidStart():569 - Tunnel interface is now UP 2020-06-05 20:20:40.838 DEBUG NEUDPSocket.observeValueInTunnelQueue():179 - Socket has a better path 2020-06-05 20:20:40.839 DEBUG OpenVPNTunnelProvider.socketHasBetterPath():506 - Stopping tunnel due to a new better path 2020-06-05 20:20:40.839 DEBUG OpenVPNTunnelProvider.logCurrentSSID():826 - Current SSID: none (disconnected from WiFi) 2020-06-05 20:20:40.839 WARNING OpenVPNSession.receiveTunnel():543 - Discarding 1 TUN packets (should not handle) 2020-06-05 20:20:40.839 WARNING OpenVPNSession.receiveTunnel():543 - Discarding 3 TUN packets (should not handle) 2020-06-05 20:20:40.839 ERROR OpenVPNSession.doReconnect():1290 - Trigger reconnection (error: networkChanged) 2020-06-05 20:20:40.839 ERROR OpenVPNTunnelProvider.sessionDidStop():584 - Session did stop with error: networkChanged 2020-06-05 20:20:40.842 ERROR OpenVPNSession.loopLink():410 - Failed LINK read: Error Domain=NSPOSIXErrorDomain Code=89 "Operation canceled" 2020-06-05 20:20:40.844 DEBUG NEUDPSocket.observeValueInTunnelQueue():147 - Socket state is cancelled (endpoint: 145.100.179.13:1195 -> 145.100.179.13:1195) 2020-06-05 20:20:40.844 INFO OpenVPNSession.cleanup():334 - Cleaning up... 2020-06-05 20:20:40.848 ERROR OpenVPNTunnelProvider.finishTunnelDisconnection():355 - Tunnel did stop (error: networkChanged) 2020-06-05 20:20:40.849 DEBUG OpenVPNTunnelProvider.socket():484 - Disconnection is recoverable, tunnel will reconnect in 1000 milliseconds... 2020-06-05 20:20:40.849 WARNING OpenVPNSession.loopLink():406 - Ignoring read from outdated LINK 2020-06-05 20:20:40.849 WARNING OpenVPNSession.receiveTunnel():543 - Discarding 1 TUN packets (should not handle) 2020-06-05 20:20:41.849 DEBUG OpenVPNTunnelProvider.socket():493 - Tunnel is about to reconnect... 2020-06-05 20:20:41.849 DEBUG OpenVPNTunnelProvider.reasserting():137 - Reasserting flag set 2020-06-05 20:20:41.850 INFO OpenVPNTunnelProvider.connectTunnel():317 - Creating link session 2020-06-05 20:20:41.850 DEBUG OpenVPNTunnelProvider.connectTunnel():321 - Socket follows a path upgrade 2020-06-05 20:20:41.850 INFO OpenVPNTunnelProvider.connectTunnel():336 - Will connect to 145.100.179.13:1195 2020-06-05 20:20:41.852 DEBUG OpenVPNTunnelProvider.connectTunnel():339 - Socket type is NEUDPSocket 2020-06-05 20:20:41.853 DEBUG NEUDPSocket.observeValueInTunnelQueue():147 - Socket state is ready (endpoint: 145.100.179.13:1195 -> 145.100.179.13:1195) 2020-06-05 20:20:41.854 DEBUG OpenVPNSession.setLink():261 - Starting VPN session 2020-06-05 20:20:41.854 DEBUG OpenVPNSession.hardReset():601 - Send hard reset 2020-06-05 20:20:41.854 DEBUG OpenVPNSession.hardReset():609 - Negotiation key index is 0 2020-06-05 20:20:41.855 DEBUG ControlChannel.enqueueOutboundPackets():159 - Control: Enqueued 1 packet [0] 2020-06-05 20:20:41.855 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:41.855 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000015eda8cf45524b5bbd9caddfa904cfe4f5a5e5c8cc39da32ca67342770c07073aabc8ba12b9b031f07c 2020-06-05 20:20:42.058 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:42.059 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000025eda8cf4f7cf34d39a4d5dafe32f7964934e60b1bf7e6b2ab10f21dee381765734552ec983ddd3b99c 2020-06-05 20:20:42.262 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:42.264 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000035eda8cf4a7e69105899f649f0639b3958339a8bba5df34a2dcbea41c4ab7396f22ad08056a363ff84e 2020-06-05 20:20:42.472 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:42.472 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000045eda8cf44d29d2e6919f8b69aae426df9a64250d5e5d6de1453a593b0d8e26d54e2b8171518ede75c8 2020-06-05 20:20:42.674 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:42.675 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000055eda8cf497f06896577950ccaad6cc5aa4ef2e8cf618bfb5be8ba01b201e82f0c88a43f6cccc402846 2020-06-05 20:20:42.877 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:42.877 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000065eda8cf4175550bb82c1f69f98b33bdc9b100676678cecab5090adec48b51256be7a82e5eded180807 2020-06-05 20:20:43.094 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:43.094 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000075eda8cf4ee76abdb5dde4bc99755bdd4071ce31e603a69efbdb2fdf018883d46eb954fac3484f8869f 2020-06-05 20:20:43.304 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:43.305 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000085eda8cf4ac7e604936001fb5510b5f9eef8ddc1316c214df8b33565b02958346bd53df3471b90c3f64 2020-06-05 20:20:43.507 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:43.507 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000095eda8cf4fa6e0552f43f7f9027a62cf9776e9ccdfbe3054c7af5c5c27b53b5ade03b4d00cbb9331b97 2020-06-05 20:20:43.709 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:43.710 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000000a5eda8cf42bbfd7475b10808eb67155ff21d7e6732deaa38a784a68b60f6ea25399ebf2c9ca1351e324 2020-06-05 20:20:43.912 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:43.913 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000000b5eda8cf4af050a385e8a47e55108bb5870b9fab07486de0b97c11cb6715f6e34338f01f41fb7fd2994 2020-06-05 20:20:44.126 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:44.127 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000000c5eda8cf40cd183d0ee8f92d768d25201e5a6747e2d329f0fa60404a0ae126816f4b36e9c22cb267390 2020-06-05 20:20:44.329 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:44.330 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000000d5eda8cf4cc0ae8f6cc4e8536b876f74dea35103efeba882417a85a659188f2e1b6023d1e549186ff34 2020-06-05 20:20:44.542 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:44.544 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000000e5eda8cf46a5285e08d10ab66917612c3dbae99b5f852649132da58800c8b959195813f57b568434f84 2020-06-05 20:20:44.745 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:44.746 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000000f5eda8cf444d7f1a884c1a0a90fcb6cf39a93a8e40b19cf3babe723f0c8295933c35b5fa299aa2743cb 2020-06-05 20:20:44.958 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:44.959 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000105eda8cf4effc96c709935ed65544bd8104e2d1ce0977473acce67fba359357ae3178227b842a51eb45 2020-06-05 20:20:45.161 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:45.161 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000115eda8cf440e7019cb02a17d69ac1ad0230812624e364748bc347dbbf809692a72017a64a3500002732 2020-06-05 20:20:45.370 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:45.370 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000125eda8cf4ebade1e685aaf985f0245310c8bc01efe2dba7088953d9ff693fd7ca3410cff9e884d95491 2020-06-05 20:20:45.571 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:45.571 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000135eda8cf436938039d9f4827a01bfba966aa2ecc265c6e56da9f52c76799dd15886c9fb152e1616e11f 2020-06-05 20:20:45.775 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:45.775 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000145eda8cf4bb83f21a93b57b2a4a7dd1a2dd29a316769364c43fc55b89d2c53cc05f86e41f53e195f2b3 2020-06-05 20:20:45.976 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:45.976 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000155eda8cf4f710d719b033cf7702785da04b4d57d591e48f519b5928ebd99b112fad8f6d01073573e105 2020-06-05 20:20:46.179 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:46.180 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000165eda8cf4cec5c1e5175d7f31ee2369725376227bb1b294db289856a7e2ef95924a3eb0b7628bb39355 2020-06-05 20:20:46.384 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:46.384 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000175eda8cf47465442cd91c0715377359715ec8ffc5916d8033e88b2906e5f823a4f6dcd2c5371842fe9c 2020-06-05 20:20:46.588 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:46.589 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000185eda8cf49223545cf7b6cd52e2096bb9b91c73aaff198f859c50de0ae14cffee705dae51c8f3a8870d 2020-06-05 20:20:46.790 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:46.791 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000195eda8cf429cf81ca0081d722b5cfd61f1f3ab9bf2ecd9394f4298758c3353fb6efee36f7c20f1dc0f5 2020-06-05 20:20:46.994 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:46.995 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000001a5eda8cf42c2737a4422cae3ce7010ef9c5103c9cb6cf8ae61844d4cc923d2903c96ea25dca1749b5d5 2020-06-05 20:20:47.196 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:47.196 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000001b5eda8cf4c945c828f5918ee6e2c0b7eeef729f7ab7bb482cd3c62f132ef64e5a3405af39532a43fc07 2020-06-05 20:20:47.401 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:47.402 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000001c5eda8cf48764e878be515d90ff51e71ff1c0ddcc69c4026a78f5277431dd3b6ec03a4a89aa73939fb2 2020-06-05 20:20:47.612 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:47.613 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000001d5eda8cf423785d0c013a8557fe04ba1c196905fa20e9bb3245c0b64a5365678f0d7e8b1c89b4038776 2020-06-05 20:20:47.823 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:47.824 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000001e5eda8cf421763603c56cd03e9ff700053369c902221ca98085e52bc6b9c2c959228dd0e3839bdc91be 2020-06-05 20:20:48.029 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:48.030 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000001f5eda8cf466be13143e6c2386991bbc83c89270b737e3b6bdf175d6ab43d8d185fbf521bc75629f4fe5 2020-06-05 20:20:48.241 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:48.242 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000205eda8cf44da91046b63228e8dc4905943afd24a3bfa0b02bfc5a5a25f8e899a34290962a88a5b404bb 2020-06-05 20:20:48.445 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:48.446 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000215eda8cf404300e2a120ffb0508a7423f0241df3b1e2d23fc5c104c52e541548071d0059746093ab124 2020-06-05 20:20:48.661 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:48.662 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000225eda8cf4544aa022f7706ae28964902e6d665e6acf604bc8e20c9c2920f2b9b7dc8cc1b08b49172d6e 2020-06-05 20:20:48.869 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:48.870 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000235eda8cf4c0b0cb87c61474cd0a2a952f9dd4ec5aac386011325776ef27f14c5e04d6f9da92c253ff4d 2020-06-05 20:20:49.075 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:49.076 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000245eda8cf4abaafa14276b83568c98893929433abfa6c5dad23754bb7564ea8d08f746be2ba70a69dc26 2020-06-05 20:20:49.278 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:49.279 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000255eda8cf46334507a941fd99bf5cc2ae49e1098f5d2e2d1ea41a037df89350a7a35db4221152ae2a4d9 2020-06-05 20:20:49.486 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:49.486 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000265eda8cf404bc5fa868b1a436f33a581e8642c4c02ec2dd9bd95ef4a690db3a00bf34b0cf5a6ccb64f0 2020-06-05 20:20:49.694 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:49.696 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000275eda8cf432b13fefcabc930579087acdd1314ee8dce3c37d269479752315c4ca52e77dfff3dad85277 2020-06-05 20:20:49.902 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:49.903 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000285eda8cf4517dde73864f15658ef42461419e1a721afe590483daf663007c1a38c19094d4130e94fec8 2020-06-05 20:20:50.109 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:50.109 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000295eda8cf4ba4190cf2868c5464636655fc92f4669be02c8d3a80f58a9740b27473393331a2c28431918 2020-06-05 20:20:50.310 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:50.311 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000002a5eda8cf4df71e7cf8b49caa25e90510f44e6dc346e5db9a7e839fd98d46e83eb89ed4468f4761e401c 2020-06-05 20:20:50.511 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:50.512 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000002b5eda8cf40ce1637b3875ab6a2c30a4e5e5c67fb17b9a9cbbf147ed675f725831ec5a127a1bbe342376 2020-06-05 20:20:50.719 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:50.721 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000002c5eda8cf4cb245109d4f2522dc935ef699d133b64b5043a678352458d3367c331c80009d75ad66b259b 2020-06-05 20:20:50.926 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:50.927 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000002d5eda8cf4d2ce2d1271f90ab11923cb904c15d4402da21e8238f9b5c674c17ccfe372005cd2bbe1d38c 2020-06-05 20:20:51.128 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:51.129 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000002e5eda8cf4f84b7904345aa78bcb9f7362c557c6a1144f27f4b97b9166f0ef931ece7229128c9db4deeb 2020-06-05 20:20:51.338 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:51.338 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f0000002f5eda8cf4946ad442e50bb58ee5250a5444737eabee438e97b4390ca684610b7613f5fc079499ab1c3d 2020-06-05 20:20:51.543 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:51.543 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000305eda8cf484d38c5af3f37e569d897bf6ca135c33462ec580c41bd3942e42ec619df6c7d70bd5a784d7 2020-06-05 20:20:51.745 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: 2e9f74de26759d6f, pid: 0, [0 bytes]} 2020-06-05 20:20:51.746 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 382e9f74de26759d6f000000315eda8cf4f9b11ec36174a4dc962e54a146c6f4af1080dddc874534ca5577773ab33bff1624d41f3718 2020-06-05 20:20:51.959 ERROR OpenVPNSession.doReconnect():1290 - Trigger reconnection (error: negotiationTimeout) 2020-06-05 20:20:51.959 ERROR OpenVPNTunnelProvider.sessionDidStop():584 - Session did stop with error: negotiationTimeout 2020-06-05 20:20:51.961 ERROR OpenVPNSession.loopLink():410 - Failed LINK read: Error Domain=NSPOSIXErrorDomain Code=89 "Operation canceled" 2020-06-05 20:20:51.963 DEBUG NEUDPSocket.observeValueInTunnelQueue():147 - Socket state is cancelled (endpoint: 145.100.179.13:1195 -> 145.100.179.13:1195) 2020-06-05 20:20:51.963 INFO OpenVPNSession.cleanup():334 - Cleaning up... 2020-06-05 20:20:51.965 ERROR OpenVPNTunnelProvider.finishTunnelDisconnection():355 - Tunnel did stop (error: negotiationTimeout) 2020-06-05 20:20:51.967 DEBUG ConnectionStrategy.tryNextEndpoint():147 - Try next endpoint: 145.100.179.13:TCP:1194 2020-06-05 20:20:51.967 DEBUG OpenVPNTunnelProvider.socket():484 - Disconnection is recoverable, tunnel will reconnect in 1000 milliseconds... 2020-06-05 20:20:51.968 WARNING OpenVPNSession.loopLink():406 - Ignoring read from outdated LINK 2020-06-05 20:20:52.968 DEBUG OpenVPNTunnelProvider.socket():493 - Tunnel is about to reconnect... 2020-06-05 20:20:52.968 DEBUG OpenVPNTunnelProvider.reasserting():137 - Reasserting flag set 2020-06-05 20:20:52.968 INFO OpenVPNTunnelProvider.connectTunnel():317 - Creating link session 2020-06-05 20:20:52.968 DEBUG ConnectionStrategy.createSocket():159 - Pick current endpoint: 145.100.179.13:TCP:1194 2020-06-05 20:20:52.969 INFO OpenVPNTunnelProvider.connectTunnel():336 - Will connect to 145.100.179.13:1194 2020-06-05 20:20:52.970 DEBUG OpenVPNTunnelProvider.connectTunnel():339 - Socket type is NETCPSocket 2020-06-05 20:20:52.970 DEBUG NETCPSocket.observeValueInTunnelQueue():149 - Socket state is waiting (endpoint: 145.100.179.13:1194 -> in progress) 2020-06-05 20:20:57.970 DEBUG OpenVPNTunnelProvider.socketDidTimeout():423 - Socket timed out waiting for activity, cancelling... 2020-06-05 20:20:57.970 DEBUG ConnectionStrategy.tryNextEndpoint():147 - Try next endpoint: 145.100.179.13:TCP:443 2020-06-05 20:20:57.971 DEBUG NETCPSocket.observeValueInTunnelQueue():149 - Socket state is cancelled (endpoint: 145.100.179.13:1194 -> in progress) 2020-06-05 20:20:57.971 INFO OpenVPNSession.cleanup():334 - Cleaning up... 2020-06-05 20:20:57.971 INFO OpenVPNTunnelProvider.finishTunnelDisconnection():358 - Tunnel did stop on request 2020-06-05 20:20:57.971 DEBUG OpenVPNTunnelProvider.socket():484 - Disconnection is recoverable, tunnel will reconnect in 1000 milliseconds... 2020-06-05 20:20:58.971 DEBUG OpenVPNTunnelProvider.socket():493 - Tunnel is about to reconnect... 2020-06-05 20:20:58.971 DEBUG OpenVPNTunnelProvider.reasserting():137 - Reasserting flag set 2020-06-05 20:20:58.971 INFO OpenVPNTunnelProvider.connectTunnel():317 - Creating link session 2020-06-05 20:20:58.971 DEBUG ConnectionStrategy.createSocket():159 - Pick current endpoint: 145.100.179.13:TCP:443 2020-06-05 20:20:58.972 INFO OpenVPNTunnelProvider.connectTunnel():336 - Will connect to 145.100.179.13:443 2020-06-05 20:20:58.973 DEBUG OpenVPNTunnelProvider.connectTunnel():339 - Socket type is NETCPSocket 2020-06-05 20:20:58.973 DEBUG NETCPSocket.observeValueInTunnelQueue():149 - Socket state is waiting (endpoint: 145.100.179.13:443 -> in progress) 2020-06-05 20:21:03.973 DEBUG OpenVPNTunnelProvider.socketDidTimeout():423 - Socket timed out waiting for activity, cancelling... 2020-06-05 20:21:03.974 DEBUG ConnectionStrategy.tryNextEndpoint():144 - Exhausted endpoints 2020-06-05 20:21:03.974 DEBUG OpenVPNTunnelProvider.flushLog():816 - Flushing log... --- EOF --- 2020-06-05 20:21:05.215 INFO OpenVPNTunnelProvider.startTunnel():216 - Starting tunnel... 2020-06-05 20:21:05.226 INFO OpenVPNTunnelProvider+Configuration.print():642 - Protocols: [UDP:1195, TCP:1194, TCP:443] 2020-06-05 20:21:05.226 INFO OpenVPNTunnelProvider+Configuration.print():643 - Cipher: AES-256-GCM 2020-06-05 20:21:05.226 INFO OpenVPNTunnelProvider+Configuration.print():644 - Digest: HMAC-SHA1 2020-06-05 20:21:05.226 INFO OpenVPNTunnelProvider+Configuration.print():645 - Compression framing: disabled 2020-06-05 20:21:05.226 INFO OpenVPNTunnelProvider+Configuration.print():649 - Compression algorithm: disabled 2020-06-05 20:21:05.226 INFO OpenVPNTunnelProvider+Configuration.print():652 - Client verification: enabled 2020-06-05 20:21:05.227 INFO OpenVPNTunnelProvider+Configuration.print():657 - TLS wrapping: crypt 2020-06-05 20:21:05.227 INFO OpenVPNTunnelProvider+Configuration.print():662 - TLS security level: 3 2020-06-05 20:21:05.227 INFO OpenVPNTunnelProvider+Configuration.print():669 - Keep-alive interval: never 2020-06-05 20:21:05.227 INFO OpenVPNTunnelProvider+Configuration.print():674 - Keep-alive timeout: never 2020-06-05 20:21:05.227 INFO OpenVPNTunnelProvider+Configuration.print():679 - Renegotiation: never 2020-06-05 20:21:05.227 INFO OpenVPNTunnelProvider+Configuration.print():682 - Server EKU verification: enabled 2020-06-05 20:21:05.228 INFO OpenVPNTunnelProvider+Configuration.print():689 - Host SAN verification: disabled 2020-06-05 20:21:05.228 INFO OpenVPNTunnelProvider+Configuration.print():697 - Gateway: not configured 2020-06-05 20:21:05.228 INFO OpenVPNTunnelProvider+Configuration.print():702 - DNS: not configured 2020-06-05 20:21:05.228 INFO OpenVPNTunnelProvider+Configuration.print():408 - MTU: 1250 2020-06-05 20:21:05.228 INFO OpenVPNTunnelProvider+Configuration.print():409 - Debug: true 2020-06-05 20:21:05.228 INFO OpenVPNTunnelProvider+Configuration.print():410 - Masks private data: false 2020-06-05 20:21:05.236 DEBUG OpenVPNTunnelProvider.logCurrentSSID():826 - Current SSID: none (disconnected from WiFi) 2020-06-05 20:21:05.236 INFO OpenVPNTunnelProvider.connectTunnel():317 - Creating link session 2020-06-05 20:21:05.236 DEBUG ConnectionStrategy.createSocket():164 - No endpoints available, will resort to DNS resolution 2020-06-05 20:21:05.237 DEBUG ConnectionStrategy.createSocket():171 - DNS resolve hostname: internet.nl.eduvpn.org 2020-06-05 20:21:05.244 DEBUG ConnectionStrategy.createSocket():175 - DNS resolved addresses: ["145.100.179.13"] 2020-06-05 20:21:05.248 DEBUG ConnectionStrategy.unrolledEndpoints():122 - Unrolled endpoints: [145.100.179.13:UDP:1195, 145.100.179.13:TCP:1194, 145.100.179.13:TCP:443] 2020-06-05 20:21:05.248 DEBUG ConnectionStrategy.createSocket():190 - Pick current endpoint: 145.100.179.13:UDP:1195 2020-06-05 20:21:05.249 INFO OpenVPNTunnelProvider.connectTunnel():336 - Will connect to 145.100.179.13:1195 2020-06-05 20:21:05.250 DEBUG OpenVPNTunnelProvider.connectTunnel():339 - Socket type is NEUDPSocket 2020-06-05 20:21:05.252 DEBUG NEUDPSocket.observeValueInTunnelQueue():149 - Socket state is preparing (endpoint: 145.100.179.13:1195 -> in progress) 2020-06-05 20:21:05.252 DEBUG OpenVPNSession.setLink():261 - Starting VPN session 2020-06-05 20:21:05.252 DEBUG OpenVPNSession.hardReset():601 - Send hard reset 2020-06-05 20:21:05.253 DEBUG OpenVPNSession.hardReset():609 - Negotiation key index is 0 2020-06-05 20:21:05.253 DEBUG ControlChannel.enqueueOutboundPackets():159 - Control: Enqueued 1 packet [0] 2020-06-05 20:21:05.253 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {HARD_RESET_CLIENT_V2 | 0, sid: a1fc3faeb1a1263d, pid: 0, [0 bytes]} 2020-06-05 20:21:05.254 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (54 bytes): 38a1fc3faeb1a1263d000000015eda8d11cd308211b196ab78a52f37ce2359acb3f1d1e9fb3683ad884d43c601dd43b7ca49d68e1a12 2020-06-05 20:21:05.254 DEBUG NEUDPSocket.observeValueInTunnelQueue():147 - Socket state is ready (endpoint: 145.100.179.13:1195 -> 145.100.179.13:1195) 2020-06-05 20:21:05.332 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code HARD_RESET_SERVER_V2 and key 0 2020-06-05 20:21:05.332 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {HARD_RESET_SERVER_V2 | 0, sid: 8e0102c3a26c2060, acks: {[0], a1fc3faeb1a1263d}, pid: 0} 2020-06-05 20:21:05.332 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 0 2020-06-05 20:21:05.332 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: a1fc3faeb1a1263d, acks: {[0], 8e0102c3a26c2060}} 2020-06-05 20:21:05.333 DEBUG ControlChannel.remoteSessionId():50 - Control: Remote sessionId is 8e0102c3a26c2060 2020-06-05 20:21:05.333 DEBUG OpenVPNSession.handleControlPacket():785 - Start TLS handshake 2020-06-05 20:21:05.335 DEBUG OpenVPNSession.handleControlPacket():819 - TLS.connect: Pulled ciphertext (263 bytes) 2020-06-05 20:21:05.335 DEBUG ControlChannel.enqueueOutboundPackets():159 - Control: Enqueued 1 packet [1] 2020-06-05 20:21:05.335 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: a1fc3faeb1a1263d, pid: 1, [263 bytes]} 2020-06-05 20:21:05.336 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (317 bytes): 20a1fc3faeb1a1263d000000035eda8d1180808cf938a9622e16cd8b6778248b4426fdc550efa6af0cea17113c7735c72be5eb33e0bf80f884e920ec1dc8d5c065b9f636df74ca1f20a2cce3eb0f86cab346e85cd366469ee4e48ac726a22987f992fe29d54e92189d87a104b6b53d0be5d3f2154bf09b831f4a81cce938ecaf123ec5c9c31564f62d2c0904901528b17bad672b64d59c76d298296d122894e606c6b2395d3b19561892cd62cda5ff3570fad522e2a734750f4177edea4e3556eefac52ea79cba5ca4eef2272c36f08de9378b3fe04801f2542f3e60e478273fe90b162ef6ff81df6db3aa64a393d7e8ec46627e44e0a2968d9f88dfc2310be94a1c38dd988030ec2c53b043982372be99c408dfc0d42ade89ffcc7f6f2a227f29dd1641e76732180b33c49eb12730cff14e62224cc85c759292bd391b 2020-06-05 20:21:05.337 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 0 2020-06-05 20:21:05.367 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:21:05.368 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: 8e0102c3a26c2060, acks: {[1], a1fc3faeb1a1263d}, pid: 1, [1062 bytes]} 2020-06-05 20:21:05.368 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 1 2020-06-05 20:21:05.368 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: a1fc3faeb1a1263d, acks: {[1], 8e0102c3a26c2060}} 2020-06-05 20:21:05.369 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (1062 bytes) 2020-06-05 20:21:05.369 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 1 2020-06-05 20:21:05.370 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:21:05.371 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: 8e0102c3a26c2060, pid: 2, [1062 bytes]} 2020-06-05 20:21:05.371 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 2 2020-06-05 20:21:05.371 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: a1fc3faeb1a1263d, acks: {[2], 8e0102c3a26c2060}} 2020-06-05 20:21:05.371 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (1062 bytes) 2020-06-05 20:21:05.371 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:21:05.371 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: 8e0102c3a26c2060, pid: 3, [606 bytes]} 2020-06-05 20:21:05.372 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 3 2020-06-05 20:21:05.372 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: a1fc3faeb1a1263d, acks: {[3], 8e0102c3a26c2060}} 2020-06-05 20:21:05.372 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (606 bytes) 2020-06-05 20:21:05.381 DEBUG OpenVPNSession.handleControlPacket():846 - TLS.connect: Send pulled ciphertext (2662 bytes) 2020-06-05 20:21:05.381 DEBUG ControlChannel.enqueueOutboundPackets():157 - Control: Enqueued 3 packets [2-4] 2020-06-05 20:21:05.381 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: a1fc3faeb1a1263d, pid: 2, [1250 bytes]} 2020-06-05 20:21:05.381 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: a1fc3faeb1a1263d, pid: 3, [1250 bytes]} 2020-06-05 20:21:05.382 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: a1fc3faeb1a1263d, pid: 4, [162 bytes]} 2020-06-05 20:21:05.385 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (1304 bytes): 20a1fc3faeb1a1263d000000075eda8d117b0231c1f887596b3874048c9968db12e5a09f20925e66ee149a2d019bb410e4ab0fc13785726233aaf96b6b1892208ba8303d6fb7ef7e64545d01fb8951f7e98eeac1583562eb59f8d088d46821ad14c40a386788abe9c6d18fe3b261ae87adbd6e37eb047d047465375823b12af49120a0a1cf00aec7bef3c980117cd2626357fe1aa0ec1bd044849c13bb6d70b8e4822ac695f5e00af208f186df6fccf9ce411db83d9b30a4c319adf3b48d0d8447c956826df99117420f2b6a617fb1e8d75ea17c869c5632ab9c4e8a9a97066b680edaf4dd235debd7f995dafbd5fe3bf85088164d94970ca1e37fb2d8c8726e154fdc6449ff24fc72525fd1331ed630d8c498c649cd4a2c67964d29ea04c8cf241883b3891a18817c0a1840c07ce41dbd83dfb49c524f0493a14330be75039488d4cc3c2548a8675f026575ca84cd9ca80f62b78cfb047df2a22a365363b12c200fcd3ea0fa431863c016140e782fb7722a5f9125abca9bfe8d4e35198bc7929bb7d72e7df2021b942debd095bb10227cc9bb5eb641cbbb095c91bc22e994ba41615158af0771f8f1fa32d16528bdec45cc5a09fbe00628021334f807af4a8c71f1877b8ef33ffe771807f0166a750e85c5d03e3fc1449c55f06d1bc803cae7ec1a18605b67b0c8bc629337cdc9ffb166b377605baeb3cb2e4158c4f77d493431027bc6f49d8b30db7825bb51a5ed2eb5ff42c22c10b446f248de8f811fd4cf699f8ce729343001de6f4491cf968a797c8791776bca5cc0332280c474ad9554fe42b00c49c142afd1bac4f5746a115f6dc2e8be730132f39e53764ee95c7d7c755f57b8ff5b3aae55e40ba4fb8440839f04e116ee6119cc89c92230cc86b6965cf349c4d8942947b5ee6a5cc527ca064355f2fe9f1c0bd01cccbbc08138d1e51a842a662f618ac334874718fa8bff222d1130ac20a28c881aebd7c6e5dee801c7514e0807261c1ee82bfadb5b12d53d349be2bdf294d7824149d2a5401c552a1e00de077547cf2394f8425b38d3e594e35327af03182b3c6c2176dae4445bdf254050a0d794a385394f1f480fbebab6d8805eba19f022827eeea6216b7c07134fab413940ba08a4204a21348b15671e959d40c5bb7609771a062729efdf1778e17b17b827495d2bc42173cd40303448e43b86dfa8a62a5a7afcee2e5ee0c69e7f6baceaaca50d55cea09bf5c1611095e57d75f558b728588badc766f0eaa54416987499c823447ef3056ddfd3d4b9d6ea23edd4e0d9b169c5960ab49f04e23295a012caf01fece5360c01e400ca69131f98e6766f4256ec1b3e8575e8bcc13a4f24e6b6391090f891e4aac6dcd35f8cb3e2b6641362f8e383fb69294977437d9faf6dfa49cf753d8ced221f8c84f1d98d7340192aaa8895c61fa14a21ba07be76d271a488b532e6924b0728484dc5f44918ad6bc52f9b9bcb2bc964e7f2f3570422571a054a7bb4a6efe2ccc38d45badc431b782fc6659079cfc20b60b9a4832e6866a077d64ac30f8167cc5289946c4574044bfbc9d2dc9ca9916d0aa25c2ed0f33aeee86506f819093a4a6f2a99a4253b4bd1e1c95fc3e0a2d4e3c9076594890afa55672a43270bb128287d5984ab1cdbddc6ba267532211eafe5348c1566bd5c76815b1f662500e08800cb90b461d27ac09801435886b15aac7ac017292ef970a3ae30dd1a86bbe0a43843a3e3e122f527f9b4940e2b2baf0fbe9b7eeab06c6236bddcc30073c25c9d4a939786d31d2958cec00a41e48a8fd8533b3efb71c52ebf3ad86740eab76faa7aeea47bed3da826bc843409be 2020-06-05 20:21:05.392 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (1304 bytes): 20a1fc3faeb1a1263d000000085eda8d11101f3b74b98ed70bd93403c62b4b13164e0036a188aaee7759976e6bd63b66ffa14a6ff3103811d11e7c3eefc0e2f0d6354a0674c8dfe9cd3c0df334cef5fa82a86189ded4bf8c5a0a4174baea8331d4e86d5285b9a587d12ff1e8c7b8e2db5ac283395a79fdef4617c62ccc91ab925c46f6a9486de5b07afa6440515b8ebb6174c6c83b831fc659d7f53c2e7038bd1ea66fa7d855c5fb1c4b17f87ed3a021f8097ba1471db9d8f23631f5a47af18fb5d788b36aa2476aa0687a9ef73311ec282edabd549c223aa2748a9ff1348a127de8e66ef7070f295116cec5d16b91e203f977e83b0ec0ed23e95eb0c0d11a767e2167340ccd28756ed0ba5cdbda00f96a01cb261f34550d023585c50e0aed7a9302d1fce5ce14ef7f5769516060615cc6cda617c7908fd11d9c120f1cdc1234d3ee3350c6fa611f5f855076f29afaba81075056f1b86a22d0ec9ad1f8aed136ce3aa433c6bae718e7ba582f0353f32eb402f75302b846e4ed8110e8551475b31091039a6cd2126b697f36399814e86a4b6844ce9281cb61100bbeacd6517a28115481f27427c18f2a484667c95b53230c7fe509b85f632ab142f98098673060ebfcac3a92f82369adb87c3b107e57af90984290aafbdcdb1ae3554abcd6f317d698324632aa496f67d8a323544a23c88cb2b6943b3bd5bde9bd7c325cb4e297f22286e3a26c4b5aded51ebb25deee4a6891282e31222a04341c3179901a1559d1e4974ed54a18dd9f06c0fad682583b887f23e768a9bab4e52796098f9d96baf9e508673a22e6f18c5893184d03fb46a457a13674a63b7a3139a0855755e628610cc78b2543e3de8652eda40ae004db30de670546fc16809aa59842de0f37247ef5813fe165ca971dddd97f7903fbc75474ac22c769a1955c93be116f699da4476691c1dbfdb15fc518f07c721af79dd3cc075ff8d37ec1bb88db8fb062fbaf0743bc8d009dc4b0167dbcfdc7a24441bc33cf0c0ef336e080e9c396ddd4e7ae2a9ef3a51dab3a00cce8edfa9741f7f23966e436f2c7c916df89ba4257d1cb9551b228f7ff6977672bfc53970a8d6f158264ee40260c0b51e08e06d47df50a9630c56e579e5798db4c04f96927c4bf72e17638aaee968b78d3a2a1d4dbaf5b5fe6aa1e5095d76956e09752e4b285b386490736f6b52f709f3c2148d7680f014ddbfec9577e0d6afbd25f5ccee062f89f4b6972ccf0586f17769817127e7db96d0a1ff1d02d4be7bcbc58b1938f1c94ed0dac60035bc88602ce76b13f50ce068fecf5afdc513e9a7c68540313d9ddf5f9b6027a1b84b18b7b788ce7846f95d2bea74afd194cfb922fdbf0ca2a2601742f13d226656a6c639ad390f62c4ac13a24a073325d6bec45d62c1b9455e723d9436e1a8fd856daf525f13292d07064efb44e94a934618d9caf7223089d1ce4efc88ba44490c741f25825fda51739391ed20a702865d02bb13f1cc26df9d6dfb9816abe8b4fa2a25b1079e624fd0b71196ec3b6fc068eb34587441581f453e03567d367a3173dc35e96f7e401204209d1f10b030a5d4c18de09d34879aa586266bfd0043ee2bf70a886c917d72c41e95f0bfd0efa76963b85286f6bfa7fe1368c6f5325df2bbf5f19819280bb948b575292e265b20c5a0cb7f8b76ef2f3eb581a20344e5cbeb9c0b0821d88b384222ca1a6fd656646b7974adedc9c3aba51ed58a89943104ac53b98c294e197554e64fa8faf84d4d6efc732d1cd24d1abef8470579e0a630344af322cb07b803430f6a05666c2ae5d13c829a919211a6265fce16c3414918c1b17ddc2 2020-06-05 20:21:05.399 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (216 bytes): 20a1fc3faeb1a1263d000000095eda8d1142c4c867a8475b028c6a761c94e753697ea94d87014b900d8bf262a56b52979d6f9c5a2c4bed4c1f4b3efb8cda7d127752b4db6edef6bbb0bd37a067dab80a4f96552ae9f3dc590ca1a19082dc67c2675d81671de5ec954fdb572514e8a2d1f27f95a43dd503026234daf863444f84a867ce59bd5c5b45421c0b825ebcf2d8006f4e270bb77f293932b79fc7fab24a77f465e3ee9cc617a6738f8b40b81979fbc3f6a4ff6e516dcd0aaf3dee0bd3135779d1ea73bbfa886b991a9dda8fc30093f2030c95af1a6e 2020-06-05 20:21:05.400 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 2 2020-06-05 20:21:05.401 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 3 2020-06-05 20:21:05.424 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code ACK_V1 and key 0 2020-06-05 20:21:05.424 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {ACK_V1 | 0, sid: 8e0102c3a26c2060, acks: {[2], a1fc3faeb1a1263d}} 2020-06-05 20:21:05.424 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code ACK_V1 and key 0 2020-06-05 20:21:05.425 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {ACK_V1 | 0, sid: 8e0102c3a26c2060, acks: {[3], a1fc3faeb1a1263d}} 2020-06-05 20:21:05.426 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:21:05.427 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: 8e0102c3a26c2060, acks: {[4], a1fc3faeb1a1263d}, pid: 4, [51 bytes]} 2020-06-05 20:21:05.427 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 4 2020-06-05 20:21:05.427 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: a1fc3faeb1a1263d, acks: {[4], 8e0102c3a26c2060}} 2020-06-05 20:21:05.427 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (51 bytes) 2020-06-05 20:21:05.428 DEBUG OpenVPNSession.onTLSConnect():667 - TLS.connect: Handshake is complete 2020-06-05 20:21:05.428 DEBUG Authenticator.putAuth():146 - TLS.auth: Local options: V4,dev-type tun,keydir 1,cipher AES-256-GCM,auth SHA1,keysize 256,tls-crypt,key-method 2,tls-client 2020-06-05 20:21:05.429 DEBUG Authenticator.putAuth():164 - TLS.auth: Put plaintext (360 bytes) 2020-06-05 20:21:05.429 DEBUG OpenVPNSession.onTLSConnect():693 - TLS.auth: Pulled ciphertext (389 bytes) 2020-06-05 20:21:05.430 DEBUG ControlChannel.enqueueOutboundPackets():159 - Control: Enqueued 1 packet [5] 2020-06-05 20:21:05.430 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: a1fc3faeb1a1263d, pid: 5, [389 bytes]} 2020-06-05 20:21:05.432 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (443 bytes): 20a1fc3faeb1a1263d0000000b5eda8d116ebfdae98240cf7509f51a4b2e5641a98175cf1d8e1f256107fe8bfa7b9ee521f8242aad51e5ad92ef8ab7ffe7f25ec2887972847a139495702f7e718adfca180853a508de218799b86cdeeec9afcaa5c0331ee63903e3df94aa81090a5ca6bf07af26853ddf88eb60714fd2b477cb1a4308e9e5cb849bfe670e290baed27fa0c4512b1cf183faed19c3874fb12ead904d5a195eb11d58029c57514d74a616e684a76eece364a984887168c421eb6ea4cf3d2d96034e5ef238afd220149894cfe5ce606dff43c4ed8f5cf8a5ce45d2596559dfa5261a9b3410e3427aa4df93f706414eb3db8ce4de07c1d2cdd4693dc6a7f9e886d5a6f26deb83cbd1e2e24a60329aa88ec82e447a2a28f85639c26af8019df10b7501188b54020abb9cc3bdf41c4ef973bfe106444d5a72e653ed014622b0e0f51936ec5bbcd9e381f317105e603d7a6a1fa21091005b3f11972b7f75dc06dc1ad747df014a5b6b3f846abd702e5b19bfe80cdfac891f7ab018ab67acfec444d9ca302211cacc5ff986c7e71cb6fabc25997b2ba7e8bce4c4b676ceb4bb048179a023622a3e518abdfcab84f2617abb3cce8c2bf53639 2020-06-05 20:21:05.434 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 4 2020-06-05 20:21:05.451 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:21:05.451 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: 8e0102c3a26c2060, acks: {[5], a1fc3faeb1a1263d}, pid: 5, [235 bytes]} 2020-06-05 20:21:05.451 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 5 2020-06-05 20:21:05.451 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: a1fc3faeb1a1263d, acks: {[5], 8e0102c3a26c2060}} 2020-06-05 20:21:05.452 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (235 bytes) 2020-06-05 20:21:05.452 DEBUG OpenVPNSession.handleControlData():880 - Pulled plain control data (206 bytes) 2020-06-05 20:21:05.452 DEBUG Authenticator.parseAuthReply():209 - TLS.auth: Parsed server random 2020-06-05 20:21:05.452 DEBUG Authenticator.parseAuthReply():213 - TLS.auth: Parsed server options: "V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-256-GCM,auth [null-digest],keysize 256,key-method 2,tls-server" 2020-06-05 20:21:05.452 DEBUG OpenVPNSession.pushRequest():706 - TLS.ifconfig: Put plaintext (PUSH_REQUEST) 2020-06-05 20:21:05.452 DEBUG OpenVPNSession.pushRequest():722 - TLS.ifconfig: Send pulled ciphertext (42 bytes) 2020-06-05 20:21:05.452 DEBUG ControlChannel.enqueueOutboundPackets():159 - Control: Enqueued 1 packet [6] 2020-06-05 20:21:05.453 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: a1fc3faeb1a1263d, pid: 6, [42 bytes]} 2020-06-05 20:21:05.453 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (96 bytes): 20a1fc3faeb1a1263d0000000d5eda8d112a702d53f818e028cd1cc7864882e51bfa2a1904d68e33a2e569d1bab0608ed08d359cadc23c151ef87e07c258c5bbf990464eb91df839af51de647b433fd52767cbc5b29ba880d429fb5927a154f0 2020-06-05 20:21:05.454 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:21:05.454 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:21:05.454 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:21:05.454 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:21:05.454 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:21:05.454 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (0 bytes) 2020-06-05 20:21:05.454 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 5 2020-06-05 20:21:05.462 DEBUG ControlChannel.writeOutboundPackets():169 - Control: Skip writing packet with packetId 6 (sent on 2020-06-05 18:21:05 +0000, 0.009429931640625 seconds ago) 2020-06-05 20:21:05.681 DEBUG ControlChannel.writeOutboundPackets():174 - Control: Write control packet {CONTROL_V1 | 0, sid: a1fc3faeb1a1263d, pid: 6, [42 bytes]} 2020-06-05 20:21:05.682 DEBUG OpenVPNSession.flushControlQueue():1038 - Send control packet (96 bytes): 20a1fc3faeb1a1263d0000000e5eda8d114cfbdf1e7f19e720506ab710b824ad580fd892ddafd9b8d56c4c84b00194b4f48e4431665191ddba96de02a2e7667b54ed65630b2eae561764bb9f02fdf2df2c0b8448b4877d6d6ef31dcd4b0fc104 2020-06-05 20:21:05.848 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code ACK_V1 and key 0 2020-06-05 20:21:05.849 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {ACK_V1 | 0, sid: 8e0102c3a26c2060, acks: {[6], a1fc3faeb1a1263d}} 2020-06-05 20:21:05.849 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code CONTROL_V1 and key 0 2020-06-05 20:21:05.849 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {CONTROL_V1 | 0, sid: 8e0102c3a26c2060, pid: 6, [481 bytes]} 2020-06-05 20:21:05.849 DEBUG OpenVPNSession.sendAck():1204 - Send ack for received packetId 6 2020-06-05 20:21:05.849 DEBUG ControlChannel.writeAcks():217 - Control: Write ack packet {ACK_V1 | 0, sid: a1fc3faeb1a1263d, acks: {[6], 8e0102c3a26c2060}} 2020-06-05 20:21:05.849 DEBUG OpenVPNSession.handleControlPacket():840 - TLS.connect: Put received ciphertext (481 bytes) 2020-06-05 20:21:05.850 DEBUG OpenVPNSession.handleControlData():880 - Pulled plain control data (452 bytes) 2020-06-05 20:21:05.850 DEBUG OpenVPNSession.handleControlData():905 - Parsed control message (451 bytes) 2020-06-05 20:21:05.853 DEBUG OpenVPNSession.handleControlMessage():956 - Received PUSH_REPLY: "PUSH_REPLY,block-outside-dns,dhcp-option DNS 192.87.106.106,dhcp-option DNS 192.87.36.36,dhcp-option DNS 2001:610:1:800a:192:87:106:106,dhcp-option DNS 2001:610:3:200a:192:87:36:36,explicit-exit-notify 1,redirect-gateway def1 ipv6 block-local,tun-ipv6,route-gateway 145.90.228.65,topology subnet,ping 10,ping-restart 60,ifconfig-ipv6 2001:610:450:40::1:1032/112 2001:610:450:40::1:1,ifconfig 145.90.228.116 255.255.255.192,peer-id 9,cipher AES-256-GCM" 2020-06-05 20:21:05.853 DEBUG OpenVPNSession.setupEncryption():1086 - Set up encryption 2020-06-05 20:21:05.853 INFO OpenVPNSession.setupEncryption():1091 - Negotiated cipher: AES-256-GCM 2020-06-05 20:21:05.853 INFO OpenVPNSession.setupEncryption():1102 - Negotiated keep-alive interval: 10.0 seconds 2020-06-05 20:21:05.853 INFO OpenVPNSession.setupEncryption():1105 - Negotiated keep-alive timeout: 60.0 seconds 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.sessionDidStart():518 - Session did start 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.sessionDidStart():520 - Returned ifconfig parameters: 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.sessionDidStart():521 - Remote: 145.100.179.13 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.sessionDidStart():522 - IPv4: addr 145.90.228.116 netmask 255.255.255.192 gw 145.90.228.65 routes [] 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.sessionDidStart():523 - IPv6: addr 2001:610:450:40::1:1032/112 gw 2001:610:450:40::1:1 routes [] 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.sessionDidStart():525 - Gateway: ["blockLocal", "IPv4", "IPv6"] 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.sessionDidStart():530 - DNS: ["192.87.106.106", "192.87.36.36", "2001:610:1:800a:192:87:106:106", "2001:610:3:200a:192:87:36:36"] 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.sessionDidStart():537 - Search domains: not configured 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.bringNetworkUp():616 - Routing.IPv4: Setting default gateway to 145.90.228.65 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.bringNetworkUp():645 - Routing.IPv6: Setting default gateway to 2001:610:450:40::1:1 2020-06-05 20:21:05.854 INFO OpenVPNTunnelProvider.bringNetworkUp():677 - DNS: Using servers ["192.87.106.106", "192.87.36.36", "2001:610:1:800a:192:87:106:106", "2001:610:3:200a:192:87:36:36"] 2020-06-05 20:21:05.922 INFO OpenVPNTunnelProvider.bringNetworkUp():750 - Block local: Suppressing IPv4 route default/32 2020-06-05 20:21:05.922 INFO OpenVPNTunnelProvider.bringNetworkUp():750 - Block local: Suppressing IPv4 route default/32 2020-06-05 20:21:05.922 INFO OpenVPNTunnelProvider.bringNetworkUp():765 - Block local: Suppressing IPv6 route default/128 2020-06-05 20:21:05.922 INFO OpenVPNTunnelProvider.bringNetworkUp():765 - Block local: Suppressing IPv6 route default/128 2020-06-05 20:21:05.923 DEBUG ControlChannelSerializer.deserialize():64 - Control: Try read packet with code ACK_V1 and key 0 2020-06-05 20:21:05.924 DEBUG ControlChannel.readInboundPacket():103 - Control: Read packet {ACK_V1 | 0, sid: 8e0102c3a26c2060, acks: {[6], a1fc3faeb1a1263d}} 2020-06-05 20:21:05.924 DEBUG OpenVPNSession.sendAck():1231 - Ack successfully written to LINK for packetId 6 2020-06-05 20:21:06.327 DEBUG OpenVPNTunnelProvider.reasserting():137 - Reasserting flag cleared 2020-06-05 20:21:06.327 INFO OpenVPNTunnelProvider.sessionDidStart():569 - Tunnel interface is now UP