I have been running openVPN on a RPi running Debian Stretch for several month without any problem. Suddenly it stops working, without me having done any changes to the system. It seems that I get an initial connection, but then it stops. With "verb 6" i get the following client log:
Fri Jul 27 11:03:14 2018 TCP/UDP: Preserving recently used remote address: [AF_INET]192.168.4.82:1194
Fri Jul 27 11:03:14 2018 Socket Buffers: R=[8192->8192] S=[8192->8192]
Fri Jul 27 11:03:14 2018 UDP link local: (not bound)
Fri Jul 27 11:03:14 2018 UDP link remote: [AF_INET]192.168.4.82:1194
Fri Jul 27 11:03:14 2018 MANAGEMENT: >STATE:1532682194,WAIT,,,,,,
Fri Jul 27 11:03:14 2018 UDP WRITE [54] to [AF_INET]192.168.4.82:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #1 ] [ ] pid=0 DATA len=0
Fri Jul 27 11:03:14 2018 UDP READ [0] from [AF_UNSPEC]: DATA UNDEF len=-1
Fri Jul 27 11:03:14 2018 UDP READ [66] from [AF_INET]192.168.4.82:1194: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 pid=[ #1 ] [ 0 ] pid=0 DATA len=0
Fri Jul 27 11:03:14 2018 MANAGEMENT: >STATE:1532682194,AUTH,,,,,,
Fri Jul 27 11:03:14 2018 TLS: Initial packet from [AF_INET]192.168.4.82:1194, sid=b283c53f d83dc036
Fri Jul 27 11:03:14 2018 UDP WRITE [62] to [AF_INET]192.168.4.82:1194: P_ACK_V1 kid=0 pid=[ #2 ] [ 0 ]
Fri Jul 27 11:03:14 2018 UDP WRITE [227] to [AF_INET]192.168.4.82:1194: P_CONTROL_V1 kid=0 pid=[ #3 ] [ ] pid=1 DATA len=173
Fri Jul 27 11:03:15 2018 UDP READ [1160] from [AF_INET]192.168.4.82:1194: P_CONTROL_V1 kid=0 pid=[ #2 ] [ 1 ] pid=1 DATA len=1094
Fri Jul 27 11:03:15 2018 UDP WRITE [62] to [AF_INET]192.168.4.82:1194: P_ACK_V1 kid=0 pid=[ #4 ] [ 1 ]
Fri Jul 27 11:03:15 2018 UDP READ [1113] from [AF_INET]192.168.4.82:1194: P_CONTROL_V1 kid=0 pid=[ #3 ] [ ] pid=2 DATA len=1059
Fri Jul 27 11:03:15 2018 VERIFY OK: depth=1, CN=ChangeMe
Fri Jul 27 11:03:15 2018 Validating certificate key usage
Fri Jul 27 11:03:15 2018 ++ Certificate has key usage 00a0, expects 00a0
Fri Jul 27 11:03:15 2018 VERIFY KU OK
Fri Jul 27 11:03:15 2018 Validating certificate extended key usage
Fri Jul 27 11:03:15 2018 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Fri Jul 27 11:03:15 2018 VERIFY EKU OK
Fri Jul 27 11:03:15 2018 VERIFY X509NAME OK: CN=server_XopdjEQCM57rWTlx
Fri Jul 27 11:03:15 2018 VERIFY OK: depth=0, CN=server_XopdjEQCM57rWTlx
Fri Jul 27 11:03:15 2018 UDP WRITE [1160] to [AF_INET]192.168.4.82:1194: P_CONTROL_V1 kid=0 pid=[ #5 ] [ 2 ] pid=2 DATA len=1094
Fri Jul 27 11:03:15 2018 UDP WRITE [1017] to [AF_INET]192.168.4.82:1194: P_CONTROL_V1 kid=0 pid=[ #6 ] [ ] pid=3 DATA len=963
Fri Jul 27 11:03:15 2018 UDP READ [62] from [AF_INET]192.168.4.82:1194: P_ACK_V1 kid=0 pid=[ #4 ] [ 2 ]
Fri Jul 27 11:03:18 2018 UDP WRITE [1017] to [AF_INET]192.168.4.82:1194: P_CONTROL_V1 kid=0 pid=[ #7 ] [ ] pid=3 DATA len=963
Fri Jul 27 11:03:22 2018 UDP WRITE [1017] to [AF_INET]192.168.4.82:1194: P_CONTROL_V1 kid=0 pid=[ #8 ] [ ] pid=3 DATA len=963
Fri Jul 27 11:03:30 2018 UDP WRITE [1017] to [AF_INET]192.168.4.82:1194: P_CONTROL_V1 kid=0 pid=[ #9 ] [ ] pid=3 DATA len=963
Just to demonstrate that there is some kind of connection to the openvpn server I show how the log file looks like when I change the IP adress to an IP adress (192.168.4.182) where there is no server (or anything at all):
Fri Jul 27 11:05:00 2018 TCP/UDP: Preserving recently used remote address: [AF_INET]192.168.4.182:1194
Fri Jul 27 11:05:00 2018 Socket Buffers: R=[8192->8192] S=[8192->8192]
Fri Jul 27 11:05:00 2018 UDP link local: (not bound)
Fri Jul 27 11:05:00 2018 UDP link remote: [AF_INET]192.168.4.182:1194
Fri Jul 27 11:05:00 2018 MANAGEMENT: >STATE:1532682300,WAIT,,,,,,
Fri Jul 27 11:05:00 2018 UDP WRITE [54] to [AF_INET]192.168.4.182:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #1 ] [ ] pid=0 DATA len=0
Fri Jul 27 11:05:00 2018 UDP READ [0] from [AF_UNSPEC]: DATA UNDEF len=-1
Fri Jul 27 11:05:02 2018 UDP WRITE [54] to [AF_INET]192.168.4.182:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #2 ] [ ] pid=0 DATA len=0
Fri Jul 27 11:05:06 2018 UDP WRITE [54] to [AF_INET]192.168.4.182:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #3 ] [ ] pid=0 DATA len=0
Fri Jul 27 11:05:15 2018 UDP WRITE [54] to [AF_INET]192.168.4.182:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #4 ] [ ] pid=0 DATA len=0
When I use the correct IP address I get some packets back, this starts at the following row:
Fri Jul 27 11:03:14 2018 TLS: Initial packet from [AF_INET]192.168.4.82:1194, sid=b283c53f d83dc036
Any ideas about what can have happened and how to resolve it would be appreciated!
