OpenVPN Server is not able to ping client till client do some traffic

Need help configuring your VPN? Just post here and you'll get that help.

Moderators: TinCanTech, TinCanTech, TinCanTech, TinCanTech, TinCanTech, TinCanTech

Forum rules
Please use the [oconf] BB tag for openvpn Configurations. See viewtopic.php?f=30&t=21589 for an example.
Post Reply
mgallina
OpenVpn Newbie
Posts: 6
Joined: Mon Jun 07, 2021 2:01 pm

OpenVPN Server is not able to ping client till client do some traffic

Post by mgallina » Mon Jun 07, 2021 2:28 pm

Hello,
I have set up an OpenVPN server on an AWS EC2 instance and I have connected two clients (one Windows 10 PC and one Teltonika router).
The clients are able to connect to the server and they are able to ping the server. But the server is not able to ping the client till some traffics is initiated from the clients.
For example, I start a ping from the server to one client and it does not work; as soon as the client does some traffic towards the server, the ping starts to work. I have taken a tcpdump on the client side and do not see any packets till client-initiated traffic: after that, I can also see the server ping.

Server Config
# This is a comment
port 1194
proto udp
dev tun
ca ca.crt
cert server.crt
key server.key #
dh none
topology subnet
server 10.131.0.0 255.255.255.0
ifconfig-pool-persist /var/log/openvpn/ipp.txt
client-to-client
keepalive 300 900
tls-crypt ta.key
cipher AES-256-GCM
auth SHA256
compress lzo
user nobody
group nogroup
persist-key
persist-tun
status /var/log/openvpn/openvpn-status.log
status-version 2
log-append /var/log/openvpn/openvpn.log
verb 6
explicit-exit-notify 1
verify-client-cert require
plugin /usr/lib/x86_64-linux-gnu/openvpn/plugins/openvpn-plugin-auth-pam.so login



client.conf:
Client Conf

client
dev tun
proto udp
remote X.X.X.X 1194
resolv-retry infinite
nobind
user nobody
group nogroup
persist-key
persist-tun
remote-cert-tls server
cipher AES-256-GCM
auth SHA256
comp-lzo
verb 4
;key-direction 1
auth-user-pass /root/openvpn-auth.txt
<ca>
-----BEGIN CERTIFICATE-----
xxx
-----END CERTIFICATE-----

</ca>
<cert>
Certificate:
xxx
-----BEGIN CERTIFICATE-----
xxx
-----END CERTIFICATE-----
</cert>
<key>
-----BEGIN PRIVATE KEY-----
xxx
-----END PRIVATE KEY-----
</key>
<tls-crypt>
#
# 2048 bit OpenVPN static key
#
-----BEGIN OpenVPN Static key V1-----
xxx
-----END OpenVPN Static key V1-----
</tls-crypt>

auth-nocache
script-security 2
down /etc/openvpn/updown_dns
up /etc/openvpn/updown_dns


I have also enable the IP forwarding on server and add the following iptables rule:

Code: Select all

iptables -A POSTROUTING -s 10.131.0.0/24 -o eth0 -j MASQUERADE
A consequence of this behavior is that the client restarts the OpenVPN connection after the keepalive timeout is reached since it does not receive the server pings.

clients.log:

Code: Select all

2021-06-07 08:40:35 NOTE: --user option is not implemented on Windows
2021-06-07 08:40:35 NOTE: --group option is not implemented on Windows
2021-06-07 08:40:35 WARNING: Compression for receiving enabled. Compression has been used in the past to break encryption. Sent packets are not compressed unless "allow-compression yes" is also set.
2021-06-07 08:40:35 OpenVPN 2.5.0 x86_64-w64-mingw32 [SSL (OpenSSL)] [LZO] [LZ4] [PKCS11] [AEAD] built on Oct 28 2020
2021-06-07 08:40:35 Windows version 10.0 (Windows 10 or greater) 64bit
2021-06-07 08:40:35 library versions: OpenSSL 1.1.1h  22 Sep 2020, LZO 2.10
Enter Management Password:
2021-06-07 08:40:35 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:25340
2021-06-07 08:40:35 Need hold release from management interface, waiting...
2021-06-07 08:40:36 MANAGEMENT: Client connected from [AF_INET]127.0.0.1:25340
2021-06-07 08:40:36 MANAGEMENT: CMD 'state on'
2021-06-07 08:40:36 MANAGEMENT: CMD 'log all on'
2021-06-07 08:40:36 MANAGEMENT: CMD 'echo all on'
2021-06-07 08:40:36 MANAGEMENT: CMD 'bytecount 5'
2021-06-07 08:40:36 MANAGEMENT: CMD 'hold off'
2021-06-07 08:40:36 MANAGEMENT: CMD 'hold release'
2021-06-07 08:40:37 MANAGEMENT: CMD 'username "Auth" "test01"'
2021-06-07 08:40:37 MANAGEMENT: CMD 'password [...]'
2021-06-07 08:40:37 Outgoing Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
2021-06-07 08:40:37 Outgoing Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
2021-06-07 08:40:37 Incoming Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
2021-06-07 08:40:37 Incoming Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
2021-06-07 08:40:37 TCP/UDP: Preserving recently used remote address: [AF_INET]X.X.X.X:1194
2021-06-07 08:40:37 Socket Buffers: R=[65536->65536] S=[65536->65536]
2021-06-07 08:40:37 UDP link local: (not bound)
2021-06-07 08:40:37 UDP link remote: [AF_INET]X.X.X.X:1194
2021-06-07 08:40:37 MANAGEMENT: >STATE:1623048037,WAIT,,,,,,
2021-06-07 08:40:37 MANAGEMENT: >STATE:1623048037,AUTH,,,,,,
2021-06-07 08:40:37 TLS: Initial packet from [AF_INET]X.X.X.X:1194, sid=ef9a5bb8 9a9e4f03
2021-06-07 08:40:37 WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this
2021-06-07 08:40:37 VERIFY KU OK
2021-06-07 08:40:37 Validating certificate extended key usage
2021-06-07 08:40:37 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
2021-06-07 08:40:37 VERIFY EKU OK
2021-06-07 08:40:37 VERIFY OK: depth=0, CN=server
2021-06-07 08:40:37 Control Channel: TLSv1.3, cipher TLSv1.3 TLS_AES_256_GCM_SHA384, 384 bit EC, curve: secp384r1
2021-06-07 08:40:37 [server] Peer Connection Initiated with [AF_INET]X.X.X.X:1194
2021-06-07 08:40:39 MANAGEMENT: >STATE:1623048039,GET_CONFIG,,,,,,
2021-06-07 08:40:39 SENT CONTROL [server]: 'PUSH_REQUEST' (status=1)
2021-06-07 08:40:39 PUSH: Received control message: 'PUSH_REPLY,route-gateway 10.131.0.1,topology subnet,ping 300,ping-restart 900,ifconfig 10.131.0.3 255.255.255.0,peer-id 0,cipher AES-256-GCM'
2021-06-07 08:40:39 OPTIONS IMPORT: timers and/or timeouts modified
2021-06-07 08:40:39 OPTIONS IMPORT: --ifconfig/up options modified
2021-06-07 08:40:39 OPTIONS IMPORT: route-related options modified
2021-06-07 08:40:39 OPTIONS IMPORT: peer-id set
2021-06-07 08:40:39 OPTIONS IMPORT: adjusting link_mtu to 1625
2021-06-07 08:40:39 OPTIONS IMPORT: data channel crypto options modified
2021-06-07 08:40:39 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
2021-06-07 08:40:39 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
2021-06-07 08:40:39 interactive service msg_channel=788
2021-06-07 08:40:39 open_tun
2021-06-07 08:40:39 tap-windows6 device [Connessione alla rete locale (LAN) 2] opened
2021-06-07 08:40:39 TAP-Windows Driver Version 9.24 
2021-06-07 08:40:39 Set TAP-Windows TUN subnet mode network/local/netmask = 10.131.0.0/10.131.0.3/255.255.255.0 [SUCCEEDED]
2021-06-07 08:40:39 Notified TAP-Windows driver to set a DHCP IP/netmask of 10.131.0.3/255.255.255.0 on interface {412A8664-23A8-45C5-A165-23C062B30C13} [DHCP-serv: 10.131.0.254, lease-time: 31536000]
2021-06-07 08:40:39 Successful ARP Flush on interface [7] {412A8664-23A8-45C5-A165-23C062B30C13}
2021-06-07 08:40:39 MANAGEMENT: >STATE:1623048039,ASSIGN_IP,,10.131.0.3,,,,
2021-06-07 08:40:39 IPv4 MTU set to 1500 on interface 7 using service
2021-06-07 08:40:44 TEST ROUTES: 0/0 succeeded len=0 ret=1 a=0 u/d=up
2021-06-07 08:40:44 Initialization Sequence Completed
2021-06-07 08:40:44 MANAGEMENT: >STATE:1623048044,CONNECTED,SUCCESS,10.131.0.3,X.X.X.X,1194,,
2021-06-07 09:01:50 [server] Inactivity timeout (--ping-restart), restarting
2021-06-07 09:01:50 SIGUSR1[soft,ping-restart] received, process restarting
2021-06-07 09:01:50 MANAGEMENT: >STATE:1623049310,RECONNECTING,ping-restart,,,,,
2021-06-07 09:01:50 Restart pause, 5 second(s)
2021-06-07 09:01:55 Outgoing Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
2021-06-07 09:01:55 Outgoing Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
2021-06-07 09:01:55 Incoming Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
2021-06-07 09:01:55 Incoming Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
2021-06-07 09:01:55 TCP/UDP: Preserving recently used remote address: [AF_INET]X.X.X.X:1194
2021-06-07 09:01:55 Socket Buffers: R=[65536->65536] S=[65536->65536]
2021-06-07 09:01:55 UDP link local: (not bound)
2021-06-07 09:01:55 UDP link remote: [AF_INET]X.X.X.X:1194
2021-06-07 09:01:55 MANAGEMENT: >STATE:1623049315,WAIT,,,,,,
2021-06-07 09:01:55 MANAGEMENT: >STATE:1623049315,AUTH,,,,,,
2021-06-07 09:01:55 TLS: Initial packet from [AF_INET]X.X.X.X:1194, sid=b3f58b9b 07afec88
2021-06-07 09:01:55 VERIFY KU OK
2021-06-07 09:01:55 Validating certificate extended key usage
2021-06-07 09:01:55 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
2021-06-07 09:01:55 VERIFY EKU OK
2021-06-07 09:01:55 VERIFY OK: depth=0, CN=server
2021-06-07 09:01:56 Control Channel: TLSv1.3, cipher TLSv1.3 TLS_AES_256_GCM_SHA384, 384 bit EC, curve: secp384r1
2021-06-07 09:01:56 [server] Peer Connection Initiated with [AF_INET]X.X.X.X:1194
2021-06-07 09:01:57 MANAGEMENT: >STATE:1623049317,GET_CONFIG,,,,,,
2021-06-07 09:01:57 SENT CONTROL [server]: 'PUSH_REQUEST' (status=1)
2021-06-07 09:01:57 PUSH: Received control message: 'PUSH_REPLY,route-gateway 10.131.0.1,topology subnet,ping 300,ping-restart 900,ifconfig 10.131.0.3 255.255.255.0,peer-id 1,cipher AES-256-GCM'
2021-06-07 09:01:57 OPTIONS IMPORT: timers and/or timeouts modified
2021-06-07 09:01:57 OPTIONS IMPORT: --ifconfig/up options modified
2021-06-07 09:01:57 OPTIONS IMPORT: route-related options modified
2021-06-07 09:01:57 OPTIONS IMPORT: peer-id set
2021-06-07 09:01:57 OPTIONS IMPORT: adjusting link_mtu to 1625
2021-06-07 09:01:57 OPTIONS IMPORT: data channel crypto options modified
2021-06-07 09:01:57 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
2021-06-07 09:01:57 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
2021-06-07 09:01:57 Preserving previous TUN/TAP instance: Connessione alla rete locale (LAN) 2
2021-06-07 09:01:57 Initialization Sequence Completed
2021-06-07 09:01:57 MANAGEMENT: >STATE:1623049317,CONNECTED,SUCCESS,10.131.0.3,X.X.X.X,1194,,
server.log:

Code: Select all

Mon Jun  7 06:40:41 2021 us=633303 MULTI: multi_create_instance called
Mon Jun  7 06:40:41 2021 us=634453 88.147.101.90:65295 Re-using SSL/TLS context
Mon Jun  7 06:40:41 2021 us=635203 88.147.101.90:65295 LZO compression initializing
Mon Jun  7 06:40:41 2021 us=637396 88.147.101.90:65295 Control Channel MTU parms [ L:1622 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Mon Jun  7 06:40:41 2021 us=637874 88.147.101.90:65295 Data Channel MTU parms [ L:1622 D:1450 EF:122 EB:406 ET:0 EL:3 ]
Mon Jun  7 06:40:41 2021 us=638527 88.147.101.90:65295 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1550,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-GCM,auth [null-digest],keysize 256,key-method 2,tls-server'
Mon Jun  7 06:40:41 2021 us=638541 88.147.101.90:65295 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1550,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-GCM,auth [null-digest],keysize 256,key-method 2,tls-client'
Mon Jun  7 06:40:41 2021 us=639225 88.147.101.90:65295 UDPv4 READ [54] from [AF_INET]88.147.101.90:65295: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=352 DATA len=40
Mon Jun  7 06:40:41 2021 us=639244 88.147.101.90:65295 TLS: Initial packet from [AF_INET]88.147.101.90:65295, sid=d494ca03 96115990
Mon Jun  7 06:40:41 2021 us=639274 88.147.101.90:65295 UDPv4 WRITE [66] to [AF_INET]88.147.101.90:65295: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ ] pid=352 DATA len=52
Mon Jun  7 06:40:41 2021 us=822291 88.147.101.90:65295 UDPv4 READ [62] from [AF_INET]88.147.101.90:65295: P_ACK_V1 kid=0 [ ]
Mon Jun  7 06:40:41 2021 us=822391 88.147.101.90:65295 UDPv4 READ [331] from [AF_INET]88.147.101.90:65295: P_CONTROL_V1 kid=0 [ ] pid=864 DATA len=317
Mon Jun  7 06:40:41 2021 us=826783 88.147.101.90:65295 UDPv4 WRITE [1128] to [AF_INET]88.147.101.90:65295: P_CONTROL_V1 kid=0 [ ] pid=608 DATA len=1114
Mon Jun  7 06:40:41 2021 us=829609 88.147.101.90:65295 UDPv4 WRITE [555] to [AF_INET]88.147.101.90:65295: P_CONTROL_V1 kid=0 [ ] pid=864 DATA len=541
Mon Jun  7 06:40:42 2021 us=13149 88.147.101.90:65295 UDPv4 READ [62] from [AF_INET]88.147.101.90:65295: P_ACK_V1 kid=0 [ ]
Mon Jun  7 06:40:42 2021 us=32959 88.147.101.90:65295 UDPv4 READ [1128] from [AF_INET]88.147.101.90:65295: P_CONTROL_V1 kid=0 [ ] pid=1376 DATA len=1114
Mon Jun  7 06:40:42 2021 us=33035 88.147.101.90:65295 UDPv4 WRITE [62] to [AF_INET]88.147.101.90:65295: P_ACK_V1 kid=0 [ ]
Mon Jun  7 06:40:42 2021 us=33091 88.147.101.90:65295 UDPv4 READ [768] from [AF_INET]88.147.101.90:65295: P_CONTROL_V1 kid=0 [ ] pid=1632 DATA len=754
Mon Jun  7 06:40:42 2021 us=33376 88.147.101.90:65295 VERIFY OK: depth=1, CN=Easy-RSA CA
Mon Jun  7 06:40:42 2021 us=34516 88.147.101.90:65295 VERIFY OK: depth=0, CN=client1
Mon Jun  7 06:40:42 2021 us=35673 88.147.101.90:65295 peer info: IV_VER=2.5.0
Mon Jun  7 06:40:42 2021 us=35690 88.147.101.90:65295 peer info: IV_PLAT=win
Mon Jun  7 06:40:42 2021 us=35698 88.147.101.90:65295 peer info: IV_PROTO=6
Mon Jun  7 06:40:42 2021 us=35705 88.147.101.90:65295 peer info: IV_NCP=2
Mon Jun  7 06:40:42 2021 us=35712 88.147.101.90:65295 peer info: IV_CIPHERS=AES-256-GCM:AES-128-GCM
Mon Jun  7 06:40:42 2021 us=35719 88.147.101.90:65295 peer info: IV_LZ4=1
Mon Jun  7 06:40:42 2021 us=35726 88.147.101.90:65295 peer info: IV_LZ4v2=1
Mon Jun  7 06:40:42 2021 us=35733 88.147.101.90:65295 peer info: IV_LZO=1
Mon Jun  7 06:40:42 2021 us=35739 88.147.101.90:65295 peer info: IV_COMP_STUB=1
Mon Jun  7 06:40:42 2021 us=35747 88.147.101.90:65295 peer info: IV_COMP_STUBv2=1
Mon Jun  7 06:40:42 2021 us=35753 88.147.101.90:65295 peer info: IV_TCPNL=1
Mon Jun  7 06:40:42 2021 us=35760 88.147.101.90:65295 peer info: IV_GUI_VER=OpenVPN_GUI_11
Mon Jun  7 06:40:42 2021 us=63039 88.147.101.90:65295 PLUGIN_CALL: POST /usr/lib/x86_64-linux-gnu/openvpn/plugins/openvpn-plugin-auth-pam.so/PLUGIN_AUTH_USER_PASS_VERIFY status=0
Mon Jun  7 06:40:42 2021 us=63091 88.147.101.90:65295 TLS: Username/Password authentication succeeded for username 'test01'
Mon Jun  7 06:40:42 2021 us=63168 88.147.101.90:65295 UDPv4 WRITE [224] to [AF_INET]88.147.101.90:65295: P_CONTROL_V1 kid=0 [ ] pid=1376 DATA len=210
Mon Jun  7 06:40:42 2021 us=63257 88.147.101.90:65295 UDPv4 WRITE [291] to [AF_INET]88.147.101.90:65295: P_CONTROL_V1 kid=0 [ ] pid=1632 DATA len=277
Mon Jun  7 06:40:42 2021 us=246044 88.147.101.90:65295 UDPv4 READ [62] from [AF_INET]88.147.101.90:65295: P_ACK_V1 kid=0 [ ]
Mon Jun  7 06:40:42 2021 us=246127 88.147.101.90:65295 UDPv4 READ [62] from [AF_INET]88.147.101.90:65295: P_ACK_V1 kid=0 [ ]
Mon Jun  7 06:40:42 2021 us=246226 88.147.101.90:65295 Control Channel: TLSv1.3, cipher TLSv1.3 TLS_AES_256_GCM_SHA384, 384 bit EC, curve: secp384r1
Mon Jun  7 06:40:42 2021 us=246250 88.147.101.90:65295 [client1] Peer Connection Initiated with [AF_INET]88.147.101.90:65295
Mon Jun  7 06:40:42 2021 us=246275 client1/88.147.101.90:65295 MULTI_sva: pool returned IPv4=10.131.0.3, IPv6=(Not enabled)
Mon Jun  7 06:40:42 2021 us=246305 client1/88.147.101.90:65295 MULTI: Learn: 10.131.0.3 -> client1/88.147.101.90:65295
Mon Jun  7 06:40:42 2021 us=246314 client1/88.147.101.90:65295 MULTI: primary virtual IP for client1/88.147.101.90:65295: 10.131.0.3
Mon Jun  7 06:40:43 2021 us=458735 client1/88.147.101.90:65295 UDPv4 READ [89] from [AF_INET]88.147.101.90:65295: P_CONTROL_V1 kid=0 [ ] pid=2400 DATA len=75
Mon Jun  7 06:40:43 2021 us=458821 client1/88.147.101.90:65295 PUSH: Received control message: 'PUSH_REQUEST'
Mon Jun  7 06:40:43 2021 us=458859 client1/88.147.101.90:65295 SENT CONTROL [client1]: 'PUSH_REPLY,route-gateway 10.131.0.1,topology subnet,ping 300,ping-restart 900,ifconfig 10.131.0.3 255.255.255.0,peer-id 0,cipher AES-256-GCM' (status=1)
Mon Jun  7 06:40:43 2021 us=458880 client1/88.147.101.90:65295 Data Channel MTU parms [ L:1550 D:1450 EF:50 EB:406 ET:0 EL:3 ]
Mon Jun  7 06:40:43 2021 us=458964 client1/88.147.101.90:65295 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Mon Jun  7 06:40:43 2021 us=458976 client1/88.147.101.90:65295 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Mon Jun  7 06:40:43 2021 us=458995 client1/88.147.101.90:65295 UDPv4 WRITE [62] to [AF_INET]88.147.101.90:65295: P_ACK_V1 kid=0 [ ]
Mon Jun  7 06:40:43 2021 us=459055 client1/88.147.101.90:65295 UDPv4 WRITE [217] to [AF_INET]88.147.101.90:65295: P_CONTROL_V1 kid=0 [ ] pid=2144 DATA len=203
Mon Jun  7 06:40:43 2021 us=676858 client1/88.147.101.90:65295 UDPv4 READ [62] from [AF_INET]88.147.101.90:65295: P_ACK_V1 kid=0 [ ]
Mon Jun  7 06:40:43 2021 us=715415 client1/88.147.101.90:65295 UDPv4 READ [176] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=175
Mon Jun  7 06:40:43 2021 us=729806 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:43 2021 us=734135 client1/88.147.101.90:65295 UDPv4 READ [121] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=120
Mon Jun  7 06:40:43 2021 us=736814 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:43 2021 us=736846 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:43 2021 us=736998 client1/88.147.101.90:65295 UDPv4 READ [107] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=106
Mon Jun  7 06:40:43 2021 us=737024 client1/88.147.101.90:65295 UDPv4 READ [145] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=144
Mon Jun  7 06:40:43 2021 us=739430 client1/88.147.101.90:65295 UDPv4 READ [107] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=106
Mon Jun  7 06:40:43 2021 us=739457 client1/88.147.101.90:65295 UDPv4 READ [145] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=144
Mon Jun  7 06:40:43 2021 us=739475 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:43 2021 us=759379 client1/88.147.101.90:65295 UDPv4 READ [1168] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=1167
Mon Jun  7 06:40:43 2021 us=902645 client1/88.147.101.90:65295 UDPv4 READ [89] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=88
Mon Jun  7 06:40:43 2021 us=902699 client1/88.147.101.90:65295 MULTI: bad source address from client [::], packet dropped
Mon Jun  7 06:40:43 2021 us=902733 client1/88.147.101.90:65295 UDPv4 READ [73] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=72
Mon Jun  7 06:40:43 2021 us=902752 client1/88.147.101.90:65295 UDPv4 READ [161] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=160
Mon Jun  7 06:40:43 2021 us=917127 client1/88.147.101.90:65295 UDPv4 READ [1168] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=1167
Mon Jun  7 06:40:43 2021 us=981380 client1/88.147.101.90:65295 UDPv4 READ [200] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=199
Mon Jun  7 06:40:44 2021 us=44635 client1/88.147.101.90:65295 UDPv4 READ [194] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=193
Mon Jun  7 06:40:44 2021 us=72347 client1/88.147.101.90:65295 UDPv4 READ [192] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=191
Mon Jun  7 06:40:44 2021 us=244875 client1/88.147.101.90:65295 UDPv4 READ [1168] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=1167
Mon Jun  7 06:40:44 2021 us=398768 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:44 2021 us=710022 client1/88.147.101.90:65295 UDPv4 READ [1168] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=1167
Mon Jun  7 06:40:44 2021 us=718111 client1/88.147.101.90:65295 UDPv4 READ [176] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=175
Mon Jun  7 06:40:44 2021 us=904045 client1/88.147.101.90:65295 UDPv4 READ [97] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=96
Mon Jun  7 06:40:44 2021 us=904137 client1/88.147.101.90:65295 UDPv4 READ [81] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=80
Mon Jun  7 06:40:44 2021 us=906900 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:44 2021 us=906988 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:44 2021 us=917571 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:44 2021 us=917607 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:44 2021 us=918818 client1/88.147.101.90:65295 UDPv4 READ [107] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=106
Mon Jun  7 06:40:44 2021 us=918906 client1/88.147.101.90:65295 UDPv4 READ [145] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=144
Mon Jun  7 06:40:44 2021 us=919428 client1/88.147.101.90:65295 UDPv4 READ [107] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=106
Mon Jun  7 06:40:44 2021 us=919455 client1/88.147.101.90:65295 UDPv4 READ [145] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=144
Mon Jun  7 06:40:45 2021 us=400482 client1/88.147.101.90:65295 UDPv4 READ [121] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=120
Mon Jun  7 06:40:45 2021 us=781137 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:45 2021 us=781241 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:45 2021 us=781263 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:45 2021 us=781279 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:45 2021 us=781294 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:45 2021 us=781308 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:45 2021 us=781322 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:45 2021 us=781335 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:45 2021 us=781349 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:45 2021 us=781363 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:45 2021 us=783112 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:45 2021 us=783149 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:45 2021 us=913990 client1/88.147.101.90:65295 UDPv4 READ [81] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=80
Mon Jun  7 06:40:46 2021 us=203694 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:46 2021 us=203733 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:46 2021 us=203749 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:46 2021 us=203763 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:46 2021 us=284114 client1/88.147.101.90:65295 UDPv4 READ [108] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=107
Mon Jun  7 06:40:46 2021 us=284351 client1/88.147.101.90:65295 UDPv4 READ [104] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=103
Mon Jun  7 06:40:46 2021 us=284379 client1/88.147.101.90:65295 UDPv4 READ [102] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=101
Mon Jun  7 06:40:46 2021 us=284396 client1/88.147.101.90:65295 UDPv4 READ [98] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=97
Mon Jun  7 06:40:46 2021 us=284411 client1/88.147.101.90:65295 UDPv4 READ [105] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=104
Mon Jun  7 06:40:46 2021 us=284424 client1/88.147.101.90:65295 UDPv4 READ [104] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=103
Mon Jun  7 06:40:46 2021 us=284438 client1/88.147.101.90:65295 UDPv4 READ [105] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=104
Mon Jun  7 06:40:46 2021 us=284452 client1/88.147.101.90:65295 UDPv4 READ [108] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=107
Mon Jun  7 06:40:46 2021 us=284465 client1/88.147.101.90:65295 UDPv4 READ [99] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=98
Mon Jun  7 06:40:46 2021 us=698190 client1/88.147.101.90:65295 UDPv4 READ [102] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=101
Mon Jun  7 06:40:46 2021 us=698256 client1/88.147.101.90:65295 UDPv4 READ [98] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=97
Mon Jun  7 06:40:46 2021 us=698276 client1/88.147.101.90:65295 UDPv4 READ [99] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=98
Mon Jun  7 06:40:46 2021 us=730252 client1/88.147.101.90:65295 UDPv4 READ [176] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=175
Mon Jun  7 06:40:46 2021 us=790777 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:46 2021 us=790963 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:46 2021 us=790989 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:46 2021 us=791005 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:46 2021 us=791020 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:46 2021 us=791034 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:46 2021 us=791048 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:46 2021 us=791315 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:47 2021 us=123445 client1/88.147.101.90:65295 UDPv4 READ [200] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=199
Mon Jun  7 06:40:47 2021 us=178071 client1/88.147.101.90:65295 UDPv4 READ [194] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=193
Mon Jun  7 06:40:47 2021 us=209545 client1/88.147.101.90:65295 UDPv4 READ [192] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=191
Mon Jun  7 06:40:47 2021 us=286284 client1/88.147.101.90:65295 UDPv4 READ [105] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=104
Mon Jun  7 06:40:47 2021 us=286574 client1/88.147.101.90:65295 UDPv4 READ [104] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=103
Mon Jun  7 06:40:47 2021 us=286600 client1/88.147.101.90:65295 UDPv4 READ [105] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=104
Mon Jun  7 06:40:47 2021 us=286615 client1/88.147.101.90:65295 UDPv4 READ [104] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=103
Mon Jun  7 06:40:47 2021 us=286630 client1/88.147.101.90:65295 UDPv4 READ [108] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=107
Mon Jun  7 06:40:47 2021 us=286644 client1/88.147.101.90:65295 UDPv4 READ [108] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=107
Mon Jun  7 06:40:48 2021 us=945341 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:48 2021 us=945410 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:48 2021 us=945429 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:48 2021 us=950212 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:48 2021 us=950261 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:48 2021 us=950280 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:48 2021 us=950298 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:48 2021 us=950313 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:48 2021 us=950326 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:48 2021 us=951536 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:48 2021 us=951561 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:48 2021 us=951672 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:49 2021 us=368064 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:49 2021 us=368130 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:49 2021 us=368149 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:49 2021 us=368166 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:49 2021 us=456797 client1/88.147.101.90:65295 UDPv4 READ [110] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=109
Mon Jun  7 06:40:49 2021 us=458793 client1/88.147.101.90:65295 UDPv4 READ [111] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=110
Mon Jun  7 06:40:49 2021 us=458822 client1/88.147.101.90:65295 UDPv4 READ [104] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=103
Mon Jun  7 06:40:49 2021 us=458837 client1/88.147.101.90:65295 UDPv4 READ [107] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=106
Mon Jun  7 06:40:49 2021 us=458851 client1/88.147.101.90:65295 UDPv4 READ [105] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=104
Mon Jun  7 06:40:49 2021 us=458865 client1/88.147.101.90:65295 UDPv4 READ [107] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=106
Mon Jun  7 06:40:49 2021 us=458880 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:49 2021 us=458894 client1/88.147.101.90:65295 UDPv4 READ [110] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=109
Mon Jun  7 06:40:49 2021 us=458908 client1/88.147.101.90:65295 UDPv4 READ [111] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=110
Mon Jun  7 06:40:49 2021 us=864444 client1/88.147.101.90:65295 UDPv4 READ [105] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=104
Mon Jun  7 06:40:49 2021 us=864511 client1/88.147.101.90:65295 UDPv4 READ [104] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=103
Mon Jun  7 06:40:49 2021 us=864529 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:49 2021 us=963902 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:49 2021 us=963981 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:49 2021 us=964002 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:49 2021 us=964017 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:49 2021 us=964031 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:49 2021 us=964059 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:49 2021 us=964074 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:49 2021 us=964087 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:50 2021 us=456558 client1/88.147.101.90:65295 UDPv4 READ [107] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=106
Mon Jun  7 06:40:50 2021 us=456625 client1/88.147.101.90:65295 UDPv4 READ [107] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=106
Mon Jun  7 06:40:50 2021 us=456643 client1/88.147.101.90:65295 UDPv4 READ [111] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=110
Mon Jun  7 06:40:50 2021 us=456658 client1/88.147.101.90:65295 UDPv4 READ [111] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=110
Mon Jun  7 06:40:50 2021 us=456673 client1/88.147.101.90:65295 UDPv4 READ [110] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=109
Mon Jun  7 06:40:50 2021 us=741982 client1/88.147.101.90:65295 UDPv4 READ [176] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=175
Mon Jun  7 06:40:53 2021 us=782325 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:40:53 2021 us=908485 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:40:54 2021 us=141814 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:40:54 2021 us=592900 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:40:55 2021 us=477805 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:40:57 2021 us=234195 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:40:57 2021 us=972316 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:57 2021 us=972435 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:57 2021 us=972460 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:57 2021 us=972477 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:57 2021 us=972491 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:57 2021 us=972504 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:57 2021 us=972518 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:57 2021 us=972532 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:57 2021 us=972545 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:57 2021 us=972574 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:57 2021 us=972589 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:57 2021 us=972602 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:58 2021 us=387401 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:58 2021 us=387470 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:58 2021 us=387488 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:58 2021 us=387504 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:40:58 2021 us=747552 client1/88.147.101.90:65295 UDPv4 READ [176] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=175
Mon Jun  7 06:40:58 2021 us=978916 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:58 2021 us=979379 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:58 2021 us=979415 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:58 2021 us=979433 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:58 2021 us=979448 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:58 2021 us=979464 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:58 2021 us=979478 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:58 2021 us=979492 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:40:59 2021 us=247591 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:41:14 2021 us=746143 client1/88.147.101.90:65295 UDPv4 READ [176] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=175
Mon Jun  7 06:41:46 2021 us=751899 client1/88.147.101.90:65295 UDPv4 READ [176] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=175
Mon Jun  7 06:41:48 2021 us=881045 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:41:48 2021 us=882515 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:41:48 2021 us=882691 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:41:49 2021 us=303915 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:41:49 2021 us=890787 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:41:49 2021 us=890855 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:41:54 2021 us=572602 client1/88.147.101.90:65295 UDPv4 WRITE [73] to [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=72
Mon Jun  7 06:42:37 2021 us=225274 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:42:37 2021 us=227694 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:42:37 2021 us=227781 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:42:37 2021 us=646189 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:42:38 2021 us=233111 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:42:38 2021 us=233175 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:43:48 2021 us=923873 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:43:49 2021 us=345189 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:43:49 2021 us=929612 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:43:49 2021 us=929674 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:46:30 2021 us=316663 client1/88.147.101.90:65295 UDPv4 READ [113] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=112
Mon Jun  7 06:46:31 2021 us=328525 client1/88.147.101.90:65295 UDPv4 READ [113] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=112
Mon Jun  7 06:46:33 2021 us=318608 client1/88.147.101.90:65295 UDPv4 READ [113] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=112
Mon Jun  7 06:46:36 2021 us=262281 client1/88.147.101.90:65295 UDPv4 READ [106] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=105
Mon Jun  7 06:46:36 2021 us=262351 client1/88.147.101.90:65295 UDPv4 READ [100] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=99
Mon Jun  7 06:46:36 2021 us=262378 client1/88.147.101.90:65295 UDPv4 READ [104] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=103
Mon Jun  7 06:46:36 2021 us=263666 client1/88.147.101.90:65295 UDPv4 READ [98] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=97
Mon Jun  7 06:46:36 2021 us=263694 client1/88.147.101.90:65295 UDPv4 READ [109] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=108
Mon Jun  7 06:46:36 2021 us=263717 client1/88.147.101.90:65295 UDPv4 READ [103] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=102
Mon Jun  7 06:46:36 2021 us=264272 client1/88.147.101.90:65295 UDPv4 READ [104] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=103
Mon Jun  7 06:46:36 2021 us=264309 client1/88.147.101.90:65295 UDPv4 READ [109] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=108
Mon Jun  7 06:46:36 2021 us=264329 client1/88.147.101.90:65295 UDPv4 READ [106] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=105
Mon Jun  7 06:46:36 2021 us=682981 client1/88.147.101.90:65295 UDPv4 READ [100] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=99
Mon Jun  7 06:46:36 2021 us=684548 client1/88.147.101.90:65295 UDPv4 READ [103] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=102
Mon Jun  7 06:46:36 2021 us=684580 client1/88.147.101.90:65295 UDPv4 READ [98] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=97
Mon Jun  7 06:46:37 2021 us=271156 client1/88.147.101.90:65295 UDPv4 READ [106] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=105
Mon Jun  7 06:46:37 2021 us=271240 client1/88.147.101.90:65295 UDPv4 READ [104] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=103
Mon Jun  7 06:46:37 2021 us=273533 client1/88.147.101.90:65295 UDPv4 READ [109] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=108
Mon Jun  7 06:46:37 2021 us=273885 client1/88.147.101.90:65295 UDPv4 READ [106] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=105
Mon Jun  7 06:46:37 2021 us=273913 client1/88.147.101.90:65295 UDPv4 READ [109] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=108
Mon Jun  7 06:46:37 2021 us=273937 client1/88.147.101.90:65295 UDPv4 READ [104] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=103
Mon Jun  7 06:46:54 2021 us=561818 client1/88.147.101.90:65295 UDPv4 WRITE [41] to [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=40
Mon Jun  7 06:47:37 2021 us=546047 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:47:37 2021 us=546119 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:47:37 2021 us=546141 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:47:37 2021 us=968712 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:47:38 2021 us=559503 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:47:38 2021 us=559573 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:49:50 2021 us=365896 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:49:50 2021 us=365972 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:49:50 2021 us=365993 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:49:50 2021 us=786317 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:49:51 2021 us=362870 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:49:51 2021 us=375264 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:49:59 2021 us=372651 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:49:59 2021 us=372720 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:49:59 2021 us=372740 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:49:59 2021 us=791645 client1/88.147.101.90:65295 UDPv4 READ [95] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=94
Mon Jun  7 06:50:00 2021 us=380068 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:50:00 2021 us=380130 client1/88.147.101.90:65295 UDPv4 READ [101] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=100
Mon Jun  7 06:50:20 2021 us=303913 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:50:20 2021 us=409306 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:50:20 2021 us=633305 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:50:21 2021 us=63504 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:50:21 2021 us=913441 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:50:23 2021 us=615688 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:50:25 2021 us=616377 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 06:51:54 2021 us=293194 client1/88.147.101.90:65295 UDPv4 WRITE [41] to [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=40
Mon Jun  7 06:55:25 2021 us=898830 client1/88.147.101.90:65295 UDPv4 READ [41] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=40
Mon Jun  7 06:56:54 2021 us=880054 client1/88.147.101.90:65295 UDPv4 WRITE [41] to [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=40
Mon Jun  7 07:00:26 2021 us=357683 client1/88.147.101.90:65295 UDPv4 READ [41] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=40
Mon Jun  7 07:00:30 2021 us=323241 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 07:00:30 2021 us=428671 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 07:00:30 2021 us=640604 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 07:00:31 2021 us=69890 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 07:00:31 2021 us=902898 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 07:00:33 2021 us=561080 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 07:00:35 2021 us=572490 client1/88.147.101.90:65295 UDPv4 READ [729] from [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=728
Mon Jun  7 07:01:54 2021 us=321120 client1/88.147.101.90:65295 UDPv4 WRITE [41] to [AF_INET]88.147.101.90:65295: P_DATA_V2 kid=0 DATA len=40
Mon Jun  7 07:01:59 2021 us=824441 MULTI: multi_create_instance called
Mon Jun  7 07:01:59 2021 us=824499 88.147.101.90:55488 Re-using SSL/TLS context
Mon Jun  7 07:01:59 2021 us=824513 88.147.101.90:55488 LZO compression initializing
Mon Jun  7 07:01:59 2021 us=824583 88.147.101.90:55488 Control Channel MTU parms [ L:1622 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Mon Jun  7 07:01:59 2021 us=824594 88.147.101.90:55488 Data Channel MTU parms [ L:1622 D:1450 EF:122 EB:406 ET:0 EL:3 ]
Mon Jun  7 07:01:59 2021 us=824624 88.147.101.90:55488 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1550,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-GCM,auth [null-digest],keysize 256,key-method 2,tls-server'
Mon Jun  7 07:01:59 2021 us=824633 88.147.101.90:55488 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1550,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-GCM,auth [null-digest],keysize 256,key-method 2,tls-client'
Mon Jun  7 07:01:59 2021 us=824656 88.147.101.90:55488 UDPv4 READ [54] from [AF_INET]88.147.101.90:55488: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=352 DATA len=40
Mon Jun  7 07:01:59 2021 us=824668 88.147.101.90:55488 TLS: Initial packet from [AF_INET]88.147.101.90:55488, sid=0c6438c0 941611b1
Mon Jun  7 07:01:59 2021 us=824693 88.147.101.90:55488 UDPv4 WRITE [66] to [AF_INET]88.147.101.90:55488: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ ] pid=352 DATA len=52
Mon Jun  7 07:02:00 2021 us=3046 88.147.101.90:55488 UDPv4 READ [62] from [AF_INET]88.147.101.90:55488: P_ACK_V1 kid=0 [ ]
Mon Jun  7 07:02:00 2021 us=10980 88.147.101.90:55488 UDPv4 READ [331] from [AF_INET]88.147.101.90:55488: P_CONTROL_V1 kid=0 [ ] pid=864 DATA len=317
Mon Jun  7 07:02:00 2021 us=14012 88.147.101.90:55488 UDPv4 WRITE [1128] to [AF_INET]88.147.101.90:55488: P_CONTROL_V1 kid=0 [ ] pid=608 DATA len=1114
Mon Jun  7 07:02:00 2021 us=14332 88.147.101.90:55488 UDPv4 WRITE [556] to [AF_INET]88.147.101.90:55488: P_CONTROL_V1 kid=0 [ ] pid=864 DATA len=542
Mon Jun  7 07:02:00 2021 us=198453 88.147.101.90:55488 UDPv4 READ [62] from [AF_INET]88.147.101.90:55488: P_ACK_V1 kid=0 [ ]
Mon Jun  7 07:02:00 2021 us=200670 88.147.101.90:55488 UDPv4 READ [1128] from [AF_INET]88.147.101.90:55488: P_CONTROL_V1 kid=0 [ ] pid=1376 DATA len=1114
Mon Jun  7 07:02:00 2021 us=200732 88.147.101.90:55488 UDPv4 WRITE [62] to [AF_INET]88.147.101.90:55488: P_ACK_V1 kid=0 [ ]
Mon Jun  7 07:02:00 2021 us=200869 88.147.101.90:55488 UDPv4 READ [766] from [AF_INET]88.147.101.90:55488: P_CONTROL_V1 kid=0 [ ] pid=1632 DATA len=752
Mon Jun  7 07:02:00 2021 us=201157 88.147.101.90:55488 VERIFY OK: depth=1, CN=Easy-RSA CA
Mon Jun  7 07:02:00 2021 us=202288 88.147.101.90:55488 VERIFY OK: depth=0, CN=client1
Mon Jun  7 07:02:00 2021 us=203455 88.147.101.90:55488 peer info: IV_VER=2.5.0
Mon Jun  7 07:02:00 2021 us=203472 88.147.101.90:55488 peer info: IV_PLAT=win
Mon Jun  7 07:02:00 2021 us=203479 88.147.101.90:55488 peer info: IV_PROTO=6
Mon Jun  7 07:02:00 2021 us=203486 88.147.101.90:55488 peer info: IV_NCP=2
Mon Jun  7 07:02:00 2021 us=203494 88.147.101.90:55488 peer info: IV_CIPHERS=AES-256-GCM:AES-128-GCM
Mon Jun  7 07:02:00 2021 us=203501 88.147.101.90:55488 peer info: IV_LZ4=1
Mon Jun  7 07:02:00 2021 us=203507 88.147.101.90:55488 peer info: IV_LZ4v2=1
Mon Jun  7 07:02:00 2021 us=203514 88.147.101.90:55488 peer info: IV_LZO=1
Mon Jun  7 07:02:00 2021 us=203521 88.147.101.90:55488 peer info: IV_COMP_STUB=1
Mon Jun  7 07:02:00 2021 us=203528 88.147.101.90:55488 peer info: IV_COMP_STUBv2=1
Mon Jun  7 07:02:00 2021 us=203535 88.147.101.90:55488 peer info: IV_TCPNL=1
Mon Jun  7 07:02:00 2021 us=203542 88.147.101.90:55488 peer info: IV_GUI_VER=OpenVPN_GUI_11
Mon Jun  7 07:02:00 2021 us=212812 88.147.101.90:55488 PLUGIN_CALL: POST /usr/lib/x86_64-linux-gnu/openvpn/plugins/openvpn-plugin-auth-pam.so/PLUGIN_AUTH_USER_PASS_VERIFY status=0
Mon Jun  7 07:02:00 2021 us=212862 88.147.101.90:55488 TLS: Username/Password authentication succeeded for username 'test01'
Mon Jun  7 07:02:00 2021 us=212932 88.147.101.90:55488 UDPv4 WRITE [224] to [AF_INET]88.147.101.90:55488: P_CONTROL_V1 kid=0 [ ] pid=1376 DATA len=210
Mon Jun  7 07:02:00 2021 us=213155 88.147.101.90:55488 UDPv4 WRITE [291] to [AF_INET]88.147.101.90:55488: P_CONTROL_V1 kid=0 [ ] pid=1632 DATA len=277
Mon Jun  7 07:02:00 2021 us=410523 88.147.101.90:55488 UDPv4 READ [62] from [AF_INET]88.147.101.90:55488: P_ACK_V1 kid=0 [ ]
Mon Jun  7 07:02:00 2021 us=410603 88.147.101.90:55488 UDPv4 READ [62] from [AF_INET]88.147.101.90:55488: P_ACK_V1 kid=0 [ ]
Mon Jun  7 07:02:00 2021 us=410624 88.147.101.90:55488 Control Channel: TLSv1.3, cipher TLSv1.3 TLS_AES_256_GCM_SHA384, 384 bit EC, curve: secp384r1
Mon Jun  7 07:02:00 2021 us=410645 88.147.101.90:55488 [client1] Peer Connection Initiated with [AF_INET]88.147.101.90:55488
Mon Jun  7 07:02:00 2021 us=410772 MULTI: new connection by client 'client1' will cause previous active sessions by this client to be dropped.  Remember to use the --duplicate-cn option if you want multiple clients using the same certificate or username to concurrently connect.
Mon Jun  7 07:02:00 2021 us=410790 MULTI_sva: pool returned IPv4=10.131.0.3, IPv6=(Not enabled)
Mon Jun  7 07:02:00 2021 us=410818 MULTI: Learn: 10.131.0.3 -> client1/88.147.101.90:55488
Mon Jun  7 07:02:00 2021 us=410827 MULTI: primary virtual IP for client1/88.147.101.90:55488: 10.131.0.3
Mon Jun  7 07:02:01 2021 us=525818 client1/88.147.101.90:55488 UDPv4 READ [89] from [AF_INET]88.147.101.90:55488: P_CONTROL_V1 kid=0 [ ] pid=2400 DATA len=75
Mon Jun  7 07:02:01 2021 us=525905 client1/88.147.101.90:55488 PUSH: Received control message: 'PUSH_REQUEST'
Mon Jun  7 07:02:01 2021 us=525942 client1/88.147.101.90:55488 SENT CONTROL [client1]: 'PUSH_REPLY,route-gateway 10.131.0.1,topology subnet,ping 300,ping-restart 900,ifconfig 10.131.0.3 255.255.255.0,peer-id 1,cipher AES-256-GCM' (status=1)
Mon Jun  7 07:02:01 2021 us=525972 client1/88.147.101.90:55488 Data Channel MTU parms [ L:1550 D:1450 EF:50 EB:406 ET:0 EL:3 ]
Mon Jun  7 07:02:01 2021 us=526037 client1/88.147.101.90:55488 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Mon Jun  7 07:02:01 2021 us=526047 client1/88.147.101.90:55488 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Mon Jun  7 07:02:01 2021 us=526065 client1/88.147.101.90:55488 UDPv4 WRITE [62] to [AF_INET]88.147.101.90:55488: P_ACK_V1 kid=0 [ ]
Mon Jun  7 07:02:01 2021 us=526212 client1/88.147.101.90:55488 UDPv4 WRITE [217] to [AF_INET]88.147.101.90:55488: P_CONTROL_V1 kid=0 [ ] pid=2144 DATA len=203
Mon Jun  7 07:02:01 2021 us=706607 client1/88.147.101.90:55488 UDPv4 READ [62] from [AF_INET]88.147.101.90:55488: P_ACK_V1 kid=0 [ ]
I have tried to change a lot of configuration options but without luck.
Did someone have the same issue? I really need a hint to solve it.

Thanks!
Last edited by mgallina on Mon Jun 07, 2021 2:35 pm, edited 1 time in total.

TinCanTech
OpenVPN Protagonist
Posts: 11137
Joined: Fri Jun 03, 2016 1:17 pm

Re: OpenVPN Server is not able to ping client till client do some traffic

Post by TinCanTech » Mon Jun 07, 2021 2:34 pm

Your --keepalive is too high.

mgallina
OpenVpn Newbie
Posts: 6
Joined: Mon Jun 07, 2021 2:01 pm

Re: OpenVPN Server is not able to ping client till client do some traffic

Post by mgallina » Mon Jun 07, 2021 2:38 pm

Hi TinCanTech,
thanks for the reply.
I configured keepalive in that way because I would like to minimize the traffic on idle connections (we are using OpenVPN for IoT environments with 4G connection). So I am trying to minimize also the ping packets.
Do you have any hint for this?

However, I don't expect that the server is not able to reach the clients till client-initiated traffic; is it the normal behavior?

Thanks!

TinCanTech
OpenVPN Protagonist
Posts: 11137
Joined: Fri Jun 03, 2016 1:17 pm

Re: OpenVPN Server is not able to ping client till client do some traffic

Post by TinCanTech » Mon Jun 07, 2021 3:06 pm

mgallina wrote:
Mon Jun 07, 2021 2:38 pm
I don't expect that the server is not able to reach the clients till client-initiated traffic
:roll:
mgallina wrote:
Mon Jun 07, 2021 2:38 pm
is it the normal behavior
In your situation, yes that is normal.

mgallina
OpenVpn Newbie
Posts: 6
Joined: Mon Jun 07, 2021 2:01 pm

Re: OpenVPN Server is not able to ping client till client do some traffic

Post by mgallina » Tue Jun 08, 2021 6:41 am

The behavior is not completely clear to me. If I take a tcpdump trace on the EC2 instance (server), I see the ping (both control and data ping) is sent by the server, but it never arrives at the client (checking with another tcpdump trace on the client side).
Is this behavior due to OpenVPN or to AWS EC2?

The "keepalive 300 900" works fine if the client sends the control ping immediately before the server because in that way the communication is opened by the client.

mgallina
OpenVpn Newbie
Posts: 6
Joined: Mon Jun 07, 2021 2:01 pm

Re: OpenVPN Server is not able to ping client till client do some traffic

Post by mgallina » Tue Jun 08, 2021 1:06 pm

Ok, I got it. :)

TinCanTech
OpenVPN Protagonist
Posts: 11137
Joined: Fri Jun 03, 2016 1:17 pm

Re: OpenVPN Server is not able to ping client till client do some traffic

Post by TinCanTech » Tue Jun 08, 2021 6:31 pm

mgallina wrote:
Tue Jun 08, 2021 1:06 pm
Ok, I got it. :)
You would be surprised by how few do get it ..

If you are paying for data then I can save you even more.
My email is: tincantech at protonmail dot com

Post Reply