openvpn Client is not recognized by openvpn server
Posted: Fri Jul 13, 2018 11:54 am
Hi,
my client having IP 91.252.181.51 is not recognized by the server but in server logs I can found it,
so the problem seems to be that it is not recognized, can you help me to understand where is the problem from this log with error level = 7?
Thanks,
Antonio
root@solergycloud:~# cat /etc/openvpn/openvpn.log | grep 91.252.248.238
Fri Jul 13 13:35:24 2018 us=900847 91.252.248.238:6529 Re-using SSL/TLS context
Fri Jul 13 13:35:24 2018 us=900881 91.252.248.238:6529 LZO compression initialized
Fri Jul 13 13:35:24 2018 us=900892 91.252.248.238:6529 MTU DYNAMIC mtu=0, flags=1, 0 -> 138
Fri Jul 13 13:35:24 2018 us=900914 91.252.248.238:6529 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:24 2018 us=900975 91.252.248.238:6529 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:24 2018 us=900984 91.252.248.238:6529 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:24 2018 us=901008 91.252.248.238:6529 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:24 2018 us=901019 91.252.248.238:6529 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
Fri Jul 13 13:35:24 2018 us=901025 91.252.248.238:6529 MTU DYNAMIC mtu=1450, flags=2, 1542 -> 1450
Fri Jul 13 13:35:24 2018 us=901031 91.252.248.238:6529 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
Fri Jul 13 13:35:24 2018 us=901065 91.252.248.238:6529 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Fri Jul 13 13:35:24 2018 us=901071 91.252.248.238:6529 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Fri Jul 13 13:35:24 2018 us=901083 91.252.248.238:6529 Local Options hash (VER=V4): '530fdded'
Fri Jul 13 13:35:24 2018 us=901092 91.252.248.238:6529 Expected Remote Options hash (VER=V4): '41690919'
Fri Jul 13 13:35:24 2018 us=901103 91.252.248.238:6529 SENT PING
Fri Jul 13 13:35:24 2018 us=901116 91.252.248.238:6529 GET INST BY REAL: 91.252.248.238:6529 [created]
Fri Jul 13 13:35:24 2018 us=901128 91.252.248.238:6529 UDPv4 READ [14] from [AF_INET]91.252.248.238:6529: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Fri Jul 13 13:35:24 2018 us=901137 91.252.248.238:6529 TLS: Initial packet from [AF_INET]91.252.248.238:6529, sid=52ac7ce3 b1260378
Fri Jul 13 13:35:24 2018 us=901159 91.252.248.238:6529 UDPv4 WRITE [26] to [AF_INET]91.252.248.238:6529: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Fri Jul 13 13:35:24 2018 us=998920 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:24 2018 us=998933 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 0 ]
Fri Jul 13 13:35:24 2018 us=998973 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:24 2018 us=998984 91.252.248.238:6529 UDPv4 READ [114] from [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
Fri Jul 13 13:35:24 2018 us=999002 91.252.248.238:6529 UDPv4 WRITE [22] to [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 1 ]
Fri Jul 13 13:35:24 2018 us=999041 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:24 2018 us=999052 91.252.248.238:6529 UDPv4 READ [86] from [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=72
Fri Jul 13 13:35:25 2018 us=16308 91.252.248.238:6529 UDPv4 WRITE [126] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ 2 ] pid=1 DATA len=100
Fri Jul 13 13:35:25 2018 us=16455 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
Fri Jul 13 13:35:25 2018 us=16524 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=100
Fri Jul 13 13:35:25 2018 us=16573 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=100
Fri Jul 13 13:35:25 2018 us=16652 91.252.248.238:6529 ACK output sequence broken: [5] 1 2 3 4
Fri Jul 13 13:35:25 2018 us=123354 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=123399 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 1 ]
Fri Jul 13 13:35:25 2018 us=123440 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=5 DATA len=100
Fri Jul 13 13:35:25 2018 us=123485 91.252.248.238:6529 ACK output sequence broken: [6] 5 2 3 4
Fri Jul 13 13:35:25 2018 us=123501 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=123509 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 2 ]
Fri Jul 13 13:35:25 2018 us=123524 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=6 DATA len=100
Fri Jul 13 13:35:25 2018 us=123546 91.252.248.238:6529 ACK output sequence broken: [7] 5 6 3 4
Fri Jul 13 13:35:25 2018 us=123567 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=123575 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 3 ]
Fri Jul 13 13:35:25 2018 us=123588 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=7 DATA len=100
Fri Jul 13 13:35:25 2018 us=123606 91.252.248.238:6529 ACK output sequence broken: [8] 5 6 7 4
Fri Jul 13 13:35:25 2018 us=123636 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=123647 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 4 ]
Fri Jul 13 13:35:25 2018 us=123663 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=8 DATA len=100
Fri Jul 13 13:35:25 2018 us=123683 91.252.248.238:6529 ACK output sequence broken: [9] 5 6 7 8
Fri Jul 13 13:35:25 2018 us=219499 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=219544 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 5 ]
Fri Jul 13 13:35:25 2018 us=219584 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=9 DATA len=100
Fri Jul 13 13:35:25 2018 us=219632 91.252.248.238:6529 ACK output sequence broken: [10] 9 6 7 8
Fri Jul 13 13:35:25 2018 us=219655 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=219668 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 6 ]
Fri Jul 13 13:35:25 2018 us=219691 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=10 DATA len=100
Fri Jul 13 13:35:25 2018 us=219740 91.252.248.238:6529 ACK output sequence broken: [11] 9 10 7 8
Fri Jul 13 13:35:25 2018 us=219790 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=219816 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 7 ]
Fri Jul 13 13:35:25 2018 us=219845 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=11 DATA len=100
Fri Jul 13 13:35:25 2018 us=219890 91.252.248.238:6529 ACK output sequence broken: [12] 9 10 11 8
Fri Jul 13 13:35:25 2018 us=219906 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=219915 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 8 ]
Fri Jul 13 13:35:25 2018 us=219929 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=12 DATA len=100
Fri Jul 13 13:35:25 2018 us=219957 91.252.248.238:6529 ACK output sequence broken: [13] 9 10 11 12
Fri Jul 13 13:35:25 2018 us=321725 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=321813 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 9 ]
Fri Jul 13 13:35:25 2018 us=321877 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=13 DATA len=100
Fri Jul 13 13:35:25 2018 us=321953 91.252.248.238:6529 ACK output sequence broken: [14] 13 10 11 12
Fri Jul 13 13:35:25 2018 us=321997 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=322016 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 10 ]
Fri Jul 13 13:35:25 2018 us=322046 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=14 DATA len=100
Fri Jul 13 13:35:25 2018 us=322098 91.252.248.238:6529 ACK output sequence broken: [15] 13 14 11 12
Fri Jul 13 13:35:25 2018 us=322117 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=322125 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 11 ]
Fri Jul 13 13:35:25 2018 us=322141 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=15 DATA len=100
Fri Jul 13 13:35:25 2018 us=322164 91.252.248.238:6529 ACK output sequence broken: [16] 13 14 15 12
Fri Jul 13 13:35:25 2018 us=322187 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=322196 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 12 ]
Fri Jul 13 13:35:25 2018 us=322211 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=16 DATA len=100
Fri Jul 13 13:35:25 2018 us=322248 91.252.248.238:6529 ACK output sequence broken: [17] 13 14 15 16
Fri Jul 13 13:35:25 2018 us=431040 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=431087 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 13 ]
Fri Jul 13 13:35:25 2018 us=431128 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=17 DATA len=100
Fri Jul 13 13:35:25 2018 us=431196 91.252.248.238:6529 ACK output sequence broken: [18] 17 14 15 16
Fri Jul 13 13:35:25 2018 us=431215 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=431224 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 14 ]
Fri Jul 13 13:35:25 2018 us=431238 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=18 DATA len=100
Fri Jul 13 13:35:25 2018 us=431284 91.252.248.238:6529 ACK output sequence broken: [19] 17 18 15 16
Fri Jul 13 13:35:25 2018 us=431304 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=431317 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 15 ]
Fri Jul 13 13:35:25 2018 us=431338 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=19 DATA len=100
Fri Jul 13 13:35:25 2018 us=431380 91.252.248.238:6529 ACK output sequence broken: [20] 17 18 19 16
Fri Jul 13 13:35:25 2018 us=431398 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=431410 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 16 ]
Fri Jul 13 13:35:25 2018 us=431430 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=20 DATA len=100
Fri Jul 13 13:35:25 2018 us=431459 91.252.248.238:6529 ACK output sequence broken: [21] 17 18 19 20
Fri Jul 13 13:35:25 2018 us=544352 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=544396 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 17 ]
Fri Jul 13 13:35:25 2018 us=544437 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=21 DATA len=100
Fri Jul 13 13:35:25 2018 us=544510 91.252.248.238:6529 ACK output sequence broken: [22] 21 18 19 20
Fri Jul 13 13:35:25 2018 us=544528 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=544537 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 18 ]
Fri Jul 13 13:35:25 2018 us=544562 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=22 DATA len=100
Fri Jul 13 13:35:25 2018 us=544586 91.252.248.238:6529 ACK output sequence broken: [23] 21 22 19 20
Fri Jul 13 13:35:25 2018 us=544609 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=544617 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 19 ]
Fri Jul 13 13:35:25 2018 us=544631 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=23 DATA len=100
Fri Jul 13 13:35:25 2018 us=544655 91.252.248.238:6529 ACK output sequence broken: [24] 21 22 23 20
Fri Jul 13 13:35:25 2018 us=544668 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=544675 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 20 ]
Fri Jul 13 13:35:25 2018 us=544688 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=24 DATA len=100
Fri Jul 13 13:35:25 2018 us=544711 91.252.248.238:6529 ACK output sequence broken: [25] 21 22 23 24
Fri Jul 13 13:35:25 2018 us=643686 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=643731 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 21 ]
Fri Jul 13 13:35:25 2018 us=643771 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=25 DATA len=100
Fri Jul 13 13:35:25 2018 us=643808 91.252.248.238:6529 ACK output sequence broken: [26] 25 22 23 24
Fri Jul 13 13:35:27 2018 us=21068 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=25 DATA len=100
Fri Jul 13 13:35:27 2018 us=21198 91.252.248.238:6529 ACK output sequence broken: [26] 25 22 23 24
Fri Jul 13 13:35:27 2018 us=665700 91.252.248.238:6400 Re-using SSL/TLS context
Fri Jul 13 13:35:27 2018 us=665722 91.252.248.238:6400 LZO compression initialized
Fri Jul 13 13:35:27 2018 us=665729 91.252.248.238:6400 MTU DYNAMIC mtu=0, flags=1, 0 -> 138
Fri Jul 13 13:35:27 2018 us=665741 91.252.248.238:6400 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:27 2018 us=665821 91.252.248.238:6400 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:27 2018 us=665841 91.252.248.238:6400 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:27 2018 us=665867 91.252.248.238:6400 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:27 2018 us=665877 91.252.248.238:6400 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
Fri Jul 13 13:35:27 2018 us=665883 91.252.248.238:6400 MTU DYNAMIC mtu=1450, flags=2, 1542 -> 1450
Fri Jul 13 13:35:27 2018 us=665889 91.252.248.238:6400 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
Fri Jul 13 13:35:27 2018 us=665913 91.252.248.238:6400 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Fri Jul 13 13:35:27 2018 us=665918 91.252.248.238:6400 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Fri Jul 13 13:35:27 2018 us=665929 91.252.248.238:6400 Local Options hash (VER=V4): '530fdded'
Fri Jul 13 13:35:27 2018 us=665938 91.252.248.238:6400 Expected Remote Options hash (VER=V4): '41690919'
Fri Jul 13 13:35:27 2018 us=665946 91.252.248.238:6400 SENT PING
Fri Jul 13 13:35:27 2018 us=665961 91.252.248.238:6400 GET INST BY REAL: 91.252.248.238:6400 [created]
Fri Jul 13 13:35:27 2018 us=665980 91.252.248.238:6400 UDPv4 READ [14] from [AF_INET]91.252.248.238:6400: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Fri Jul 13 13:35:27 2018 us=665994 91.252.248.238:6400 TLS: Initial packet from [AF_INET]91.252.248.238:6400, sid=df839f45 a0572e21
Fri Jul 13 13:35:27 2018 us=666021 91.252.248.238:6400 UDPv4 WRITE [26] to [AF_INET]91.252.248.238:6400: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Fri Jul 13 13:35:27 2018 us=818995 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=819052 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 0 ]
Fri Jul 13 13:35:27 2018 us=819376 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=819403 91.252.248.238:6400 UDPv4 READ [114] from [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
Fri Jul 13 13:35:27 2018 us=819443 91.252.248.238:6400 UDPv4 WRITE [22] to [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 1 ]
Fri Jul 13 13:35:27 2018 us=819518 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=819534 91.252.248.238:6400 UDPv4 READ [86] from [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=72
Fri Jul 13 13:35:27 2018 us=826699 91.252.248.238:6400 UDPv4 WRITE [126] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ 2 ] pid=1 DATA len=100
Fri Jul 13 13:35:27 2018 us=826855 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
Fri Jul 13 13:35:27 2018 us=826907 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=100
Fri Jul 13 13:35:27 2018 us=826946 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=100
Fri Jul 13 13:35:27 2018 us=826976 91.252.248.238:6400 ACK output sequence broken: [5] 1 2 3 4
Fri Jul 13 13:35:27 2018 us=937108 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=937173 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 1 ]
Fri Jul 13 13:35:27 2018 us=937246 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=5 DATA len=100
Fri Jul 13 13:35:27 2018 us=937288 91.252.248.238:6400 ACK output sequence broken: [6] 5 2 3 4
Fri Jul 13 13:35:27 2018 us=937316 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=937325 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 2 ]
Fri Jul 13 13:35:27 2018 us=937343 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=6 DATA len=100
Fri Jul 13 13:35:27 2018 us=937390 91.252.248.238:6400 ACK output sequence broken: [7] 5 6 3 4
Fri Jul 13 13:35:27 2018 us=937407 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=937415 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 3 ]
Fri Jul 13 13:35:27 2018 us=937431 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=7 DATA len=100
Fri Jul 13 13:35:27 2018 us=937460 91.252.248.238:6400 ACK output sequence broken: [8] 5 6 7 4
Fri Jul 13 13:35:27 2018 us=937474 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=937483 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 4 ]
Fri Jul 13 13:35:27 2018 us=937500 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=8 DATA len=100
Fri Jul 13 13:35:27 2018 us=937543 91.252.248.238:6400 ACK output sequence broken: [9] 5 6 7 8
Fri Jul 13 13:35:28 2018 us=26102 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=22 DATA len=100
Fri Jul 13 13:35:28 2018 us=26248 91.252.248.238:6529 ACK output sequence broken: [26] 25 22 23 24
Fri Jul 13 13:35:28 2018 us=56282 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=56344 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 5 ]
Fri Jul 13 13:35:28 2018 us=56404 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=9 DATA len=100
Fri Jul 13 13:35:28 2018 us=56541 91.252.248.238:6400 ACK output sequence broken: [10] 9 6 7 8
Fri Jul 13 13:35:28 2018 us=56568 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=56585 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 6 ]
Fri Jul 13 13:35:28 2018 us=56611 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=10 DATA len=100
Fri Jul 13 13:35:28 2018 us=56662 91.252.248.238:6400 ACK output sequence broken: [11] 9 10 7 8
Fri Jul 13 13:35:28 2018 us=56684 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=56698 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 7 ]
Fri Jul 13 13:35:28 2018 us=56724 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=11 DATA len=100
Fri Jul 13 13:35:28 2018 us=56770 91.252.248.238:6400 ACK output sequence broken: [12] 9 10 11 8
Fri Jul 13 13:35:28 2018 us=56792 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=56807 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 8 ]
Fri Jul 13 13:35:28 2018 us=56831 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=12 DATA len=100
Fri Jul 13 13:35:28 2018 us=56874 91.252.248.238:6400 ACK output sequence broken: [13] 9 10 11 12
Fri Jul 13 13:35:28 2018 us=177436 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=177506 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 9 ]
Fri Jul 13 13:35:28 2018 us=177561 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=13 DATA len=100
Fri Jul 13 13:35:28 2018 us=177629 91.252.248.238:6400 ACK output sequence broken: [14] 13 10 11 12
Fri Jul 13 13:35:28 2018 us=177656 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=177668 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 10 ]
Fri Jul 13 13:35:28 2018 us=177692 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=14 DATA len=100
Fri Jul 13 13:35:28 2018 us=177741 91.252.248.238:6400 ACK output sequence broken: [15] 13 14 11 12
Fri Jul 13 13:35:28 2018 us=177793 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=177807 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 11 ]
Fri Jul 13 13:35:28 2018 us=177831 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=15 DATA len=100
Fri Jul 13 13:35:28 2018 us=177865 91.252.248.238:6400 ACK output sequence broken: [16] 13 14 15 12
Fri Jul 13 13:35:28 2018 us=177878 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=177885 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 12 ]
Fri Jul 13 13:35:28 2018 us=177898 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=16 DATA len=100
Fri Jul 13 13:35:28 2018 us=177932 91.252.248.238:6400 ACK output sequence broken: [17] 13 14 15 16
Fri Jul 13 13:35:28 2018 us=294882 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=294930 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 13 ]
Fri Jul 13 13:35:28 2018 us=294982 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=17 DATA len=100
Fri Jul 13 13:35:28 2018 us=295052 91.252.248.238:6400 ACK output sequence broken: [18] 17 14 15 16
Fri Jul 13 13:35:28 2018 us=295076 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=295090 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 14 ]
Fri Jul 13 13:35:28 2018 us=295113 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=18 DATA len=100
Fri Jul 13 13:35:28 2018 us=295157 91.252.248.238:6400 ACK output sequence broken: [19] 17 18 15 16
Fri Jul 13 13:35:28 2018 us=295173 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=295181 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 15 ]
Fri Jul 13 13:35:28 2018 us=295205 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=19 DATA len=100
Fri Jul 13 13:35:28 2018 us=295245 91.252.248.238:6400 ACK output sequence broken: [20] 17 18 19 16
Fri Jul 13 13:35:28 2018 us=295275 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=295288 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 16 ]
Fri Jul 13 13:35:28 2018 us=295309 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=20 DATA len=100
Fri Jul 13 13:35:28 2018 us=295346 91.252.248.238:6400 ACK output sequence broken: [21] 17 18 19 20
Fri Jul 13 13:35:28 2018 us=404207 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=404337 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 17 ]
Fri Jul 13 13:35:28 2018 us=404396 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=21 DATA len=100
Fri Jul 13 13:35:28 2018 us=404447 91.252.248.238:6400 ACK output sequence broken: [22] 21 18 19 20
Fri Jul 13 13:35:28 2018 us=404486 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=404501 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 18 ]
Fri Jul 13 13:35:28 2018 us=404526 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=22 DATA len=100
Fri Jul 13 13:35:28 2018 us=404574 91.252.248.238:6400 ACK output sequence broken: [23] 21 22 19 20
Fri Jul 13 13:35:28 2018 us=417715 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=417801 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 19 ]
Fri Jul 13 13:35:28 2018 us=417851 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=23 DATA len=100
Fri Jul 13 13:35:28 2018 us=417891 91.252.248.238:6400 ACK output sequence broken: [24] 21 22 23 20
Fri Jul 13 13:35:28 2018 us=417922 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=417936 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 20 ]
Fri Jul 13 13:35:28 2018 us=417962 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=24 DATA len=100
Fri Jul 13 13:35:28 2018 us=418000 91.252.248.238:6400 ACK output sequence broken: [25] 21 22 23 24
Fri Jul 13 13:35:28 2018 us=533390 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=533451 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 21 ]
Fri Jul 13 13:35:28 2018 us=533507 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=25 DATA len=100
Fri Jul 13 13:35:28 2018 us=533576 91.252.248.238:6400 ACK output sequence broken: [26] 25 22 23 24
Fri Jul 13 13:35:28 2018 us=945522 91.252.248.238:6722 Re-using SSL/TLS context
Fri Jul 13 13:35:28 2018 us=945544 91.252.248.238:6722 LZO compression initialized
Fri Jul 13 13:35:28 2018 us=945551 91.252.248.238:6722 MTU DYNAMIC mtu=0, flags=1, 0 -> 138
Fri Jul 13 13:35:28 2018 us=945563 91.252.248.238:6722 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:28 2018 us=945614 91.252.248.238:6722 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:28 2018 us=945628 91.252.248.238:6722 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:28 2018 us=945663 91.252.248.238:6722 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:28 2018 us=945678 91.252.248.238:6722 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
Fri Jul 13 13:35:28 2018 us=945686 91.252.248.238:6722 MTU DYNAMIC mtu=1450, flags=2, 1542 -> 1450
Fri Jul 13 13:35:28 2018 us=945697 91.252.248.238:6722 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
Fri Jul 13 13:35:28 2018 us=945729 91.252.248.238:6722 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Fri Jul 13 13:35:28 2018 us=945740 91.252.248.238:6722 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Fri Jul 13 13:35:28 2018 us=945786 91.252.248.238:6722 Local Options hash (VER=V4): '530fdded'
Fri Jul 13 13:35:28 2018 us=945801 91.252.248.238:6722 Expected Remote Options hash (VER=V4): '41690919'
Fri Jul 13 13:35:28 2018 us=945825 91.252.248.238:6722 SENT PING
Fri Jul 13 13:35:28 2018 us=945843 91.252.248.238:6722 GET INST BY REAL: 91.252.248.238:6722 [created]
Fri Jul 13 13:35:28 2018 us=945967 91.252.248.238:6722 UDPv4 READ [14] from [AF_INET]91.252.248.238:6722: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Fri Jul 13 13:35:28 2018 us=945991 91.252.248.238:6722 TLS: Initial packet from [AF_INET]91.252.248.238:6722, sid=eb02a63d a1b1755a
Fri Jul 13 13:35:28 2018 us=946021 91.252.248.238:6722 UDPv4 WRITE [26] to [AF_INET]91.252.248.238:6722: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Fri Jul 13 13:35:29 2018 us=44386 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=23 DATA len=100
Fri Jul 13 13:35:29 2018 us=44509 91.252.248.238:6529 ACK output sequence broken: [26] 25 22 23 24
Fri Jul 13 13:35:29 2018 us=45236 GET INST BY REAL: 91.252.248.238:6722 [succeeded]
Fri Jul 13 13:35:29 2018 us=45255 91.252.248.238:6722 UDPv4 READ [22] from [AF_INET]91.252.248.238:6722: P_ACK_V1 kid=0 [ 0 ]
Fri Jul 13 13:35:29 2018 us=45278 GET INST BY REAL: 91.252.248.238:6722 [succeeded]
Fri Jul 13 13:35:29 2018 us=45288 91.252.248.238:6722 UDPv4 READ [114] from [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
Fri Jul 13 13:35:29 2018 us=45305 91.252.248.238:6722 UDPv4 WRITE [22] to [AF_INET]91.252.248.238:6722: P_ACK_V1 kid=0 [ 1 ]
Fri Jul 13 13:35:29 2018 us=55366 GET INST BY REAL: 91.252.248.238:6722 [succeeded]
Fri Jul 13 13:35:29 2018 us=55426 91.252.248.238:6722 UDPv4 READ [86] from [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=72
Fri Jul 13 13:35:29 2018 us=63990 91.252.248.238:6722 UDPv4 WRITE [126] to [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ 2 ] pid=1 DATA len=100
Fri Jul 13 13:35:29 2018 us=64141 91.252.248.238:6722 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
Fri Jul 13 13:35:29 2018 us=64202 91.252.248.238:6722 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=100
Fri Jul 13 13:35:29 2018 us=64240 91.252.248.238:6722 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=100
my client having IP 91.252.181.51 is not recognized by the server but in server logs I can found it,
so the problem seems to be that it is not recognized, can you help me to understand where is the problem from this log with error level = 7?
Thanks,
Antonio
root@solergycloud:~# cat /etc/openvpn/openvpn.log | grep 91.252.248.238
Fri Jul 13 13:35:24 2018 us=900847 91.252.248.238:6529 Re-using SSL/TLS context
Fri Jul 13 13:35:24 2018 us=900881 91.252.248.238:6529 LZO compression initialized
Fri Jul 13 13:35:24 2018 us=900892 91.252.248.238:6529 MTU DYNAMIC mtu=0, flags=1, 0 -> 138
Fri Jul 13 13:35:24 2018 us=900914 91.252.248.238:6529 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:24 2018 us=900975 91.252.248.238:6529 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:24 2018 us=900984 91.252.248.238:6529 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:24 2018 us=901008 91.252.248.238:6529 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:24 2018 us=901019 91.252.248.238:6529 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
Fri Jul 13 13:35:24 2018 us=901025 91.252.248.238:6529 MTU DYNAMIC mtu=1450, flags=2, 1542 -> 1450
Fri Jul 13 13:35:24 2018 us=901031 91.252.248.238:6529 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
Fri Jul 13 13:35:24 2018 us=901065 91.252.248.238:6529 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Fri Jul 13 13:35:24 2018 us=901071 91.252.248.238:6529 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Fri Jul 13 13:35:24 2018 us=901083 91.252.248.238:6529 Local Options hash (VER=V4): '530fdded'
Fri Jul 13 13:35:24 2018 us=901092 91.252.248.238:6529 Expected Remote Options hash (VER=V4): '41690919'
Fri Jul 13 13:35:24 2018 us=901103 91.252.248.238:6529 SENT PING
Fri Jul 13 13:35:24 2018 us=901116 91.252.248.238:6529 GET INST BY REAL: 91.252.248.238:6529 [created]
Fri Jul 13 13:35:24 2018 us=901128 91.252.248.238:6529 UDPv4 READ [14] from [AF_INET]91.252.248.238:6529: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Fri Jul 13 13:35:24 2018 us=901137 91.252.248.238:6529 TLS: Initial packet from [AF_INET]91.252.248.238:6529, sid=52ac7ce3 b1260378
Fri Jul 13 13:35:24 2018 us=901159 91.252.248.238:6529 UDPv4 WRITE [26] to [AF_INET]91.252.248.238:6529: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Fri Jul 13 13:35:24 2018 us=998920 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:24 2018 us=998933 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 0 ]
Fri Jul 13 13:35:24 2018 us=998973 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:24 2018 us=998984 91.252.248.238:6529 UDPv4 READ [114] from [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
Fri Jul 13 13:35:24 2018 us=999002 91.252.248.238:6529 UDPv4 WRITE [22] to [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 1 ]
Fri Jul 13 13:35:24 2018 us=999041 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:24 2018 us=999052 91.252.248.238:6529 UDPv4 READ [86] from [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=72
Fri Jul 13 13:35:25 2018 us=16308 91.252.248.238:6529 UDPv4 WRITE [126] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ 2 ] pid=1 DATA len=100
Fri Jul 13 13:35:25 2018 us=16455 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
Fri Jul 13 13:35:25 2018 us=16524 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=100
Fri Jul 13 13:35:25 2018 us=16573 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=100
Fri Jul 13 13:35:25 2018 us=16652 91.252.248.238:6529 ACK output sequence broken: [5] 1 2 3 4
Fri Jul 13 13:35:25 2018 us=123354 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=123399 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 1 ]
Fri Jul 13 13:35:25 2018 us=123440 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=5 DATA len=100
Fri Jul 13 13:35:25 2018 us=123485 91.252.248.238:6529 ACK output sequence broken: [6] 5 2 3 4
Fri Jul 13 13:35:25 2018 us=123501 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=123509 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 2 ]
Fri Jul 13 13:35:25 2018 us=123524 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=6 DATA len=100
Fri Jul 13 13:35:25 2018 us=123546 91.252.248.238:6529 ACK output sequence broken: [7] 5 6 3 4
Fri Jul 13 13:35:25 2018 us=123567 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=123575 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 3 ]
Fri Jul 13 13:35:25 2018 us=123588 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=7 DATA len=100
Fri Jul 13 13:35:25 2018 us=123606 91.252.248.238:6529 ACK output sequence broken: [8] 5 6 7 4
Fri Jul 13 13:35:25 2018 us=123636 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=123647 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 4 ]
Fri Jul 13 13:35:25 2018 us=123663 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=8 DATA len=100
Fri Jul 13 13:35:25 2018 us=123683 91.252.248.238:6529 ACK output sequence broken: [9] 5 6 7 8
Fri Jul 13 13:35:25 2018 us=219499 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=219544 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 5 ]
Fri Jul 13 13:35:25 2018 us=219584 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=9 DATA len=100
Fri Jul 13 13:35:25 2018 us=219632 91.252.248.238:6529 ACK output sequence broken: [10] 9 6 7 8
Fri Jul 13 13:35:25 2018 us=219655 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=219668 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 6 ]
Fri Jul 13 13:35:25 2018 us=219691 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=10 DATA len=100
Fri Jul 13 13:35:25 2018 us=219740 91.252.248.238:6529 ACK output sequence broken: [11] 9 10 7 8
Fri Jul 13 13:35:25 2018 us=219790 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=219816 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 7 ]
Fri Jul 13 13:35:25 2018 us=219845 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=11 DATA len=100
Fri Jul 13 13:35:25 2018 us=219890 91.252.248.238:6529 ACK output sequence broken: [12] 9 10 11 8
Fri Jul 13 13:35:25 2018 us=219906 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=219915 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 8 ]
Fri Jul 13 13:35:25 2018 us=219929 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=12 DATA len=100
Fri Jul 13 13:35:25 2018 us=219957 91.252.248.238:6529 ACK output sequence broken: [13] 9 10 11 12
Fri Jul 13 13:35:25 2018 us=321725 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=321813 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 9 ]
Fri Jul 13 13:35:25 2018 us=321877 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=13 DATA len=100
Fri Jul 13 13:35:25 2018 us=321953 91.252.248.238:6529 ACK output sequence broken: [14] 13 10 11 12
Fri Jul 13 13:35:25 2018 us=321997 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=322016 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 10 ]
Fri Jul 13 13:35:25 2018 us=322046 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=14 DATA len=100
Fri Jul 13 13:35:25 2018 us=322098 91.252.248.238:6529 ACK output sequence broken: [15] 13 14 11 12
Fri Jul 13 13:35:25 2018 us=322117 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=322125 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 11 ]
Fri Jul 13 13:35:25 2018 us=322141 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=15 DATA len=100
Fri Jul 13 13:35:25 2018 us=322164 91.252.248.238:6529 ACK output sequence broken: [16] 13 14 15 12
Fri Jul 13 13:35:25 2018 us=322187 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=322196 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 12 ]
Fri Jul 13 13:35:25 2018 us=322211 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=16 DATA len=100
Fri Jul 13 13:35:25 2018 us=322248 91.252.248.238:6529 ACK output sequence broken: [17] 13 14 15 16
Fri Jul 13 13:35:25 2018 us=431040 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=431087 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 13 ]
Fri Jul 13 13:35:25 2018 us=431128 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=17 DATA len=100
Fri Jul 13 13:35:25 2018 us=431196 91.252.248.238:6529 ACK output sequence broken: [18] 17 14 15 16
Fri Jul 13 13:35:25 2018 us=431215 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=431224 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 14 ]
Fri Jul 13 13:35:25 2018 us=431238 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=18 DATA len=100
Fri Jul 13 13:35:25 2018 us=431284 91.252.248.238:6529 ACK output sequence broken: [19] 17 18 15 16
Fri Jul 13 13:35:25 2018 us=431304 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=431317 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 15 ]
Fri Jul 13 13:35:25 2018 us=431338 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=19 DATA len=100
Fri Jul 13 13:35:25 2018 us=431380 91.252.248.238:6529 ACK output sequence broken: [20] 17 18 19 16
Fri Jul 13 13:35:25 2018 us=431398 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=431410 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 16 ]
Fri Jul 13 13:35:25 2018 us=431430 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=20 DATA len=100
Fri Jul 13 13:35:25 2018 us=431459 91.252.248.238:6529 ACK output sequence broken: [21] 17 18 19 20
Fri Jul 13 13:35:25 2018 us=544352 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=544396 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 17 ]
Fri Jul 13 13:35:25 2018 us=544437 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=21 DATA len=100
Fri Jul 13 13:35:25 2018 us=544510 91.252.248.238:6529 ACK output sequence broken: [22] 21 18 19 20
Fri Jul 13 13:35:25 2018 us=544528 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=544537 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 18 ]
Fri Jul 13 13:35:25 2018 us=544562 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=22 DATA len=100
Fri Jul 13 13:35:25 2018 us=544586 91.252.248.238:6529 ACK output sequence broken: [23] 21 22 19 20
Fri Jul 13 13:35:25 2018 us=544609 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=544617 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 19 ]
Fri Jul 13 13:35:25 2018 us=544631 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=23 DATA len=100
Fri Jul 13 13:35:25 2018 us=544655 91.252.248.238:6529 ACK output sequence broken: [24] 21 22 23 20
Fri Jul 13 13:35:25 2018 us=544668 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=544675 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 20 ]
Fri Jul 13 13:35:25 2018 us=544688 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=24 DATA len=100
Fri Jul 13 13:35:25 2018 us=544711 91.252.248.238:6529 ACK output sequence broken: [25] 21 22 23 24
Fri Jul 13 13:35:25 2018 us=643686 GET INST BY REAL: 91.252.248.238:6529 [succeeded]
Fri Jul 13 13:35:25 2018 us=643731 91.252.248.238:6529 UDPv4 READ [22] from [AF_INET]91.252.248.238:6529: P_ACK_V1 kid=0 [ 21 ]
Fri Jul 13 13:35:25 2018 us=643771 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=25 DATA len=100
Fri Jul 13 13:35:25 2018 us=643808 91.252.248.238:6529 ACK output sequence broken: [26] 25 22 23 24
Fri Jul 13 13:35:27 2018 us=21068 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=25 DATA len=100
Fri Jul 13 13:35:27 2018 us=21198 91.252.248.238:6529 ACK output sequence broken: [26] 25 22 23 24
Fri Jul 13 13:35:27 2018 us=665700 91.252.248.238:6400 Re-using SSL/TLS context
Fri Jul 13 13:35:27 2018 us=665722 91.252.248.238:6400 LZO compression initialized
Fri Jul 13 13:35:27 2018 us=665729 91.252.248.238:6400 MTU DYNAMIC mtu=0, flags=1, 0 -> 138
Fri Jul 13 13:35:27 2018 us=665741 91.252.248.238:6400 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:27 2018 us=665821 91.252.248.238:6400 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:27 2018 us=665841 91.252.248.238:6400 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:27 2018 us=665867 91.252.248.238:6400 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:27 2018 us=665877 91.252.248.238:6400 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
Fri Jul 13 13:35:27 2018 us=665883 91.252.248.238:6400 MTU DYNAMIC mtu=1450, flags=2, 1542 -> 1450
Fri Jul 13 13:35:27 2018 us=665889 91.252.248.238:6400 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
Fri Jul 13 13:35:27 2018 us=665913 91.252.248.238:6400 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Fri Jul 13 13:35:27 2018 us=665918 91.252.248.238:6400 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Fri Jul 13 13:35:27 2018 us=665929 91.252.248.238:6400 Local Options hash (VER=V4): '530fdded'
Fri Jul 13 13:35:27 2018 us=665938 91.252.248.238:6400 Expected Remote Options hash (VER=V4): '41690919'
Fri Jul 13 13:35:27 2018 us=665946 91.252.248.238:6400 SENT PING
Fri Jul 13 13:35:27 2018 us=665961 91.252.248.238:6400 GET INST BY REAL: 91.252.248.238:6400 [created]
Fri Jul 13 13:35:27 2018 us=665980 91.252.248.238:6400 UDPv4 READ [14] from [AF_INET]91.252.248.238:6400: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Fri Jul 13 13:35:27 2018 us=665994 91.252.248.238:6400 TLS: Initial packet from [AF_INET]91.252.248.238:6400, sid=df839f45 a0572e21
Fri Jul 13 13:35:27 2018 us=666021 91.252.248.238:6400 UDPv4 WRITE [26] to [AF_INET]91.252.248.238:6400: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Fri Jul 13 13:35:27 2018 us=818995 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=819052 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 0 ]
Fri Jul 13 13:35:27 2018 us=819376 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=819403 91.252.248.238:6400 UDPv4 READ [114] from [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
Fri Jul 13 13:35:27 2018 us=819443 91.252.248.238:6400 UDPv4 WRITE [22] to [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 1 ]
Fri Jul 13 13:35:27 2018 us=819518 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=819534 91.252.248.238:6400 UDPv4 READ [86] from [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=72
Fri Jul 13 13:35:27 2018 us=826699 91.252.248.238:6400 UDPv4 WRITE [126] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ 2 ] pid=1 DATA len=100
Fri Jul 13 13:35:27 2018 us=826855 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
Fri Jul 13 13:35:27 2018 us=826907 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=100
Fri Jul 13 13:35:27 2018 us=826946 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=100
Fri Jul 13 13:35:27 2018 us=826976 91.252.248.238:6400 ACK output sequence broken: [5] 1 2 3 4
Fri Jul 13 13:35:27 2018 us=937108 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=937173 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 1 ]
Fri Jul 13 13:35:27 2018 us=937246 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=5 DATA len=100
Fri Jul 13 13:35:27 2018 us=937288 91.252.248.238:6400 ACK output sequence broken: [6] 5 2 3 4
Fri Jul 13 13:35:27 2018 us=937316 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=937325 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 2 ]
Fri Jul 13 13:35:27 2018 us=937343 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=6 DATA len=100
Fri Jul 13 13:35:27 2018 us=937390 91.252.248.238:6400 ACK output sequence broken: [7] 5 6 3 4
Fri Jul 13 13:35:27 2018 us=937407 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=937415 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 3 ]
Fri Jul 13 13:35:27 2018 us=937431 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=7 DATA len=100
Fri Jul 13 13:35:27 2018 us=937460 91.252.248.238:6400 ACK output sequence broken: [8] 5 6 7 4
Fri Jul 13 13:35:27 2018 us=937474 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:27 2018 us=937483 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 4 ]
Fri Jul 13 13:35:27 2018 us=937500 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=8 DATA len=100
Fri Jul 13 13:35:27 2018 us=937543 91.252.248.238:6400 ACK output sequence broken: [9] 5 6 7 8
Fri Jul 13 13:35:28 2018 us=26102 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=22 DATA len=100
Fri Jul 13 13:35:28 2018 us=26248 91.252.248.238:6529 ACK output sequence broken: [26] 25 22 23 24
Fri Jul 13 13:35:28 2018 us=56282 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=56344 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 5 ]
Fri Jul 13 13:35:28 2018 us=56404 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=9 DATA len=100
Fri Jul 13 13:35:28 2018 us=56541 91.252.248.238:6400 ACK output sequence broken: [10] 9 6 7 8
Fri Jul 13 13:35:28 2018 us=56568 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=56585 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 6 ]
Fri Jul 13 13:35:28 2018 us=56611 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=10 DATA len=100
Fri Jul 13 13:35:28 2018 us=56662 91.252.248.238:6400 ACK output sequence broken: [11] 9 10 7 8
Fri Jul 13 13:35:28 2018 us=56684 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=56698 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 7 ]
Fri Jul 13 13:35:28 2018 us=56724 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=11 DATA len=100
Fri Jul 13 13:35:28 2018 us=56770 91.252.248.238:6400 ACK output sequence broken: [12] 9 10 11 8
Fri Jul 13 13:35:28 2018 us=56792 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=56807 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 8 ]
Fri Jul 13 13:35:28 2018 us=56831 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=12 DATA len=100
Fri Jul 13 13:35:28 2018 us=56874 91.252.248.238:6400 ACK output sequence broken: [13] 9 10 11 12
Fri Jul 13 13:35:28 2018 us=177436 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=177506 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 9 ]
Fri Jul 13 13:35:28 2018 us=177561 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=13 DATA len=100
Fri Jul 13 13:35:28 2018 us=177629 91.252.248.238:6400 ACK output sequence broken: [14] 13 10 11 12
Fri Jul 13 13:35:28 2018 us=177656 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=177668 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 10 ]
Fri Jul 13 13:35:28 2018 us=177692 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=14 DATA len=100
Fri Jul 13 13:35:28 2018 us=177741 91.252.248.238:6400 ACK output sequence broken: [15] 13 14 11 12
Fri Jul 13 13:35:28 2018 us=177793 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=177807 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 11 ]
Fri Jul 13 13:35:28 2018 us=177831 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=15 DATA len=100
Fri Jul 13 13:35:28 2018 us=177865 91.252.248.238:6400 ACK output sequence broken: [16] 13 14 15 12
Fri Jul 13 13:35:28 2018 us=177878 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=177885 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 12 ]
Fri Jul 13 13:35:28 2018 us=177898 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=16 DATA len=100
Fri Jul 13 13:35:28 2018 us=177932 91.252.248.238:6400 ACK output sequence broken: [17] 13 14 15 16
Fri Jul 13 13:35:28 2018 us=294882 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=294930 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 13 ]
Fri Jul 13 13:35:28 2018 us=294982 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=17 DATA len=100
Fri Jul 13 13:35:28 2018 us=295052 91.252.248.238:6400 ACK output sequence broken: [18] 17 14 15 16
Fri Jul 13 13:35:28 2018 us=295076 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=295090 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 14 ]
Fri Jul 13 13:35:28 2018 us=295113 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=18 DATA len=100
Fri Jul 13 13:35:28 2018 us=295157 91.252.248.238:6400 ACK output sequence broken: [19] 17 18 15 16
Fri Jul 13 13:35:28 2018 us=295173 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=295181 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 15 ]
Fri Jul 13 13:35:28 2018 us=295205 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=19 DATA len=100
Fri Jul 13 13:35:28 2018 us=295245 91.252.248.238:6400 ACK output sequence broken: [20] 17 18 19 16
Fri Jul 13 13:35:28 2018 us=295275 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=295288 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 16 ]
Fri Jul 13 13:35:28 2018 us=295309 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=20 DATA len=100
Fri Jul 13 13:35:28 2018 us=295346 91.252.248.238:6400 ACK output sequence broken: [21] 17 18 19 20
Fri Jul 13 13:35:28 2018 us=404207 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=404337 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 17 ]
Fri Jul 13 13:35:28 2018 us=404396 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=21 DATA len=100
Fri Jul 13 13:35:28 2018 us=404447 91.252.248.238:6400 ACK output sequence broken: [22] 21 18 19 20
Fri Jul 13 13:35:28 2018 us=404486 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=404501 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 18 ]
Fri Jul 13 13:35:28 2018 us=404526 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=22 DATA len=100
Fri Jul 13 13:35:28 2018 us=404574 91.252.248.238:6400 ACK output sequence broken: [23] 21 22 19 20
Fri Jul 13 13:35:28 2018 us=417715 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=417801 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 19 ]
Fri Jul 13 13:35:28 2018 us=417851 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=23 DATA len=100
Fri Jul 13 13:35:28 2018 us=417891 91.252.248.238:6400 ACK output sequence broken: [24] 21 22 23 20
Fri Jul 13 13:35:28 2018 us=417922 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=417936 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 20 ]
Fri Jul 13 13:35:28 2018 us=417962 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=24 DATA len=100
Fri Jul 13 13:35:28 2018 us=418000 91.252.248.238:6400 ACK output sequence broken: [25] 21 22 23 24
Fri Jul 13 13:35:28 2018 us=533390 GET INST BY REAL: 91.252.248.238:6400 [succeeded]
Fri Jul 13 13:35:28 2018 us=533451 91.252.248.238:6400 UDPv4 READ [22] from [AF_INET]91.252.248.238:6400: P_ACK_V1 kid=0 [ 21 ]
Fri Jul 13 13:35:28 2018 us=533507 91.252.248.238:6400 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6400: P_CONTROL_V1 kid=0 [ ] pid=25 DATA len=100
Fri Jul 13 13:35:28 2018 us=533576 91.252.248.238:6400 ACK output sequence broken: [26] 25 22 23 24
Fri Jul 13 13:35:28 2018 us=945522 91.252.248.238:6722 Re-using SSL/TLS context
Fri Jul 13 13:35:28 2018 us=945544 91.252.248.238:6722 LZO compression initialized
Fri Jul 13 13:35:28 2018 us=945551 91.252.248.238:6722 MTU DYNAMIC mtu=0, flags=1, 0 -> 138
Fri Jul 13 13:35:28 2018 us=945563 91.252.248.238:6722 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:28 2018 us=945614 91.252.248.238:6722 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:28 2018 us=945628 91.252.248.238:6722 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:28 2018 us=945663 91.252.248.238:6722 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jul 13 13:35:28 2018 us=945678 91.252.248.238:6722 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
Fri Jul 13 13:35:28 2018 us=945686 91.252.248.238:6722 MTU DYNAMIC mtu=1450, flags=2, 1542 -> 1450
Fri Jul 13 13:35:28 2018 us=945697 91.252.248.238:6722 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
Fri Jul 13 13:35:28 2018 us=945729 91.252.248.238:6722 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Fri Jul 13 13:35:28 2018 us=945740 91.252.248.238:6722 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Fri Jul 13 13:35:28 2018 us=945786 91.252.248.238:6722 Local Options hash (VER=V4): '530fdded'
Fri Jul 13 13:35:28 2018 us=945801 91.252.248.238:6722 Expected Remote Options hash (VER=V4): '41690919'
Fri Jul 13 13:35:28 2018 us=945825 91.252.248.238:6722 SENT PING
Fri Jul 13 13:35:28 2018 us=945843 91.252.248.238:6722 GET INST BY REAL: 91.252.248.238:6722 [created]
Fri Jul 13 13:35:28 2018 us=945967 91.252.248.238:6722 UDPv4 READ [14] from [AF_INET]91.252.248.238:6722: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Fri Jul 13 13:35:28 2018 us=945991 91.252.248.238:6722 TLS: Initial packet from [AF_INET]91.252.248.238:6722, sid=eb02a63d a1b1755a
Fri Jul 13 13:35:28 2018 us=946021 91.252.248.238:6722 UDPv4 WRITE [26] to [AF_INET]91.252.248.238:6722: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Fri Jul 13 13:35:29 2018 us=44386 91.252.248.238:6529 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6529: P_CONTROL_V1 kid=0 [ ] pid=23 DATA len=100
Fri Jul 13 13:35:29 2018 us=44509 91.252.248.238:6529 ACK output sequence broken: [26] 25 22 23 24
Fri Jul 13 13:35:29 2018 us=45236 GET INST BY REAL: 91.252.248.238:6722 [succeeded]
Fri Jul 13 13:35:29 2018 us=45255 91.252.248.238:6722 UDPv4 READ [22] from [AF_INET]91.252.248.238:6722: P_ACK_V1 kid=0 [ 0 ]
Fri Jul 13 13:35:29 2018 us=45278 GET INST BY REAL: 91.252.248.238:6722 [succeeded]
Fri Jul 13 13:35:29 2018 us=45288 91.252.248.238:6722 UDPv4 READ [114] from [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
Fri Jul 13 13:35:29 2018 us=45305 91.252.248.238:6722 UDPv4 WRITE [22] to [AF_INET]91.252.248.238:6722: P_ACK_V1 kid=0 [ 1 ]
Fri Jul 13 13:35:29 2018 us=55366 GET INST BY REAL: 91.252.248.238:6722 [succeeded]
Fri Jul 13 13:35:29 2018 us=55426 91.252.248.238:6722 UDPv4 READ [86] from [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=72
Fri Jul 13 13:35:29 2018 us=63990 91.252.248.238:6722 UDPv4 WRITE [126] to [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ 2 ] pid=1 DATA len=100
Fri Jul 13 13:35:29 2018 us=64141 91.252.248.238:6722 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
Fri Jul 13 13:35:29 2018 us=64202 91.252.248.238:6722 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=100
Fri Jul 13 13:35:29 2018 us=64240 91.252.248.238:6722 UDPv4 WRITE [114] to [AF_INET]91.252.248.238:6722: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=100