I recently upgraded our OVPN server from ROS 7.14.3 to 7.15.1 and since upgrading it my MikroTik client is unable to form a successful connection. The client is also running 7.15.1 and was also running this before the server upgrade. Before the server upgrade the client could connect without problems.
I am not pushing any routes through, so my config would have been the default pre-pushing-route updates that got upgraded as part of the normal upgrade process.
This is a MikroTik to MikroTik TCP OVPN connection that has not changed in its configuration in a very long time. I have tried rebooting both sides and this has not resolved the issue.
Both sides are running ROS 7.15.1.
The server tries to assign IP address 10.0.0.79 to the client and the client has no IP address or route that conflicts with that.
I’ve submitted supouts of both client and server to MikroTik support.
Log on server side:
08:10:14 ovpn,info connection established from 1.2.3.4, port: 58894 to 10.250.250.2
08:10:14 ovpn,debug,packet ovpn-debug: sent P_CONTROL_HARD_RESET_SERVER_V2 kid=0 sid=da3b6c555fc0a354 pid=0 DATA len=0
08:10:14 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=dd4ed96516a276f2 pid=0 DATA len=0
08:10:14 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=da3b6c555fc0a354 [0 sid=dd4ed96516a276f2] DATA len=0
08:10:15 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=1 DATA len=136
08:10:15 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=da3b6c555fc0a354 [1 sid=dd4ed96516a276f2] DATA len=0
08:10:15 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=1 DATA len=1400
08:10:15 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=2 DATA len=713
08:10:15 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=dd4ed96516a276f2 [0 sid=da3b6c555fc0a354] DATA len=0
08:10:16 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=dd4ed96516a276f2 [1 sid=da3b6c555fc0a354] DATA len=0
08:10:16 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=dd4ed96516a276f2 [2 sid=da3b6c555fc0a354] DATA len=0
08:10:16 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=2 DATA len=1400
08:10:16 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=da3b6c555fc0a354 [2 sid=dd4ed96516a276f2] DATA len=0
08:10:16 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=3 DATA len=662
08:10:16 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=da3b6c555fc0a354 [3 sid=dd4ed96516a276f2] DATA len=0
08:10:16 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=3 DATA len=51
08:10:17 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=3 DATA len=662
08:10:17 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=da3b6c555fc0a354 [3 sid=dd4ed96516a276f2] DATA len=0
08:10:17 ovpn,debug,packet ovpn-debug: re-sent P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=3 DATA len=51
08:10:17 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=dd4ed96516a276f2 [3 sid=da3b6c555fc0a354] DATA len=0
08:10:17 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=4 DATA len=320
08:10:17 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=da3b6c555fc0a354 [4 sid=dd4ed96516a276f2] DATA len=0
08:10:17 ovpn,info 1.2.3.4: using encoding - AES-256-CBC/SHA1
08:10:17 ovpn,debug ovpn-debug: peer info: IV_PROTO=2
08:10:17 ovpn,info,account client4 logged in, 10.0.0.76 from 1.2.3.4
08:10:17 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=4 DATA len=227
08:10:18 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=dd4ed96516a276f2 [3 sid=da3b6c555fc0a354] DATA len=0
08:10:18 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=dd4ed96516a276f2 [4 sid=da3b6c555fc0a354] DATA len=0
08:10:18 ovpn,info <ovpn-client4>: connected
08:10:18 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=5 DATA len=42
08:10:18 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=da3b6c555fc0a354 [5 sid=dd4ed96516a276f2] DATA len=0
08:10:18 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=5 DATA len=239
08:10:19 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=6 DATA len=42
08:10:19 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=da3b6c555fc0a354 [6 sid=dd4ed96516a276f2] DATA len=0
08:10:19 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=6 DATA len=239
08:10:19 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=dd4ed96516a276f2 [5 sid=da3b6c555fc0a354] DATA len=0
08:10:19 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=6 DATA len=42
08:10:19 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=da3b6c555fc0a354 [6 sid=dd4ed96516a276f2] DATA len=0
08:10:19 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=dd4ed96516a276f2 [6 sid=da3b6c555fc0a354] DATA len=0
08:10:19 ovpn,info <1.2.3.4>: disconnected <peer disconnected>
08:10:19 ovpn,info <ovpn-client4>: terminating... - peer disconnected
08:10:20 ovpn,info,account client4 logged out, 2 0 0 0 0 from 1.2.3.4
08:10:20 ovpn,info <ovpn-client4>: disconnected
Log on client side:
14:10:14 ovpn,info ovpn-server: initializing...
14:10:14 ovpn,info ovpn-server: connecting...
14:10:14 ovpn,debug,packet ovpn-debug: sent P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=dd4ed96516a276f2 pid=0 DATA len=0
14:10:14 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=1 DATA len=136
14:10:15 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL_HARD_RESET_SERVER_V2 kid=0 sid=da3b6c555fc0a354 pid=0 DATA len=0
14:10:15 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=dd4ed96516a276f2 [0 sid=da3b6c555fc0a354] DATA len=0
14:10:15 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=da3b6c555fc0a354 [0 sid=dd4ed96516a276f2] DATA len=0
14:10:15 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=da3b6c555fc0a354 [1 sid=dd4ed96516a276f2] DATA len=0
14:10:15 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=1 DATA len=1400
14:10:15 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=dd4ed96516a276f2 [1 sid=da3b6c555fc0a354] DATA len=0
14:10:15 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=2 DATA len=713
14:10:15 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=dd4ed96516a276f2 [2 sid=da3b6c555fc0a354] DATA len=0
14:10:15 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=2 DATA len=1400
14:10:15 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=3 DATA len=662
14:10:16 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=da3b6c555fc0a354 [2 sid=dd4ed96516a276f2] DATA len=0
14:10:16 ovpn,debug,packet ovpn-debug: re-sent P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=3 DATA len=662
14:10:17 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=da3b6c555fc0a354 [3 sid=dd4ed96516a276f2] DATA len=0
14:10:17 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=3 DATA len=51
14:10:17 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=dd4ed96516a276f2 [3 sid=da3b6c555fc0a354] DATA len=0
14:10:17 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=4 DATA len=320
14:10:17 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=da3b6c555fc0a354 [3 sid=dd4ed96516a276f2] DATA len=0
14:10:17 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=3 DATA len=51
14:10:17 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=dd4ed96516a276f2 [3 sid=da3b6c555fc0a354] DATA len=0
14:10:17 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=da3b6c555fc0a354 [4 sid=dd4ed96516a276f2] DATA len=0
14:10:17 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=4 DATA len=227
14:10:17 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=dd4ed96516a276f2 [4 sid=da3b6c555fc0a354] DATA len=0
14:10:17 ovpn,info ovpn-server: using encoding - AES-256-CBC/SHA1
14:10:17 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=5 DATA len=42
14:10:18 ovpn,debug,packet ovpn-debug: sent P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=6 DATA len=42
14:10:18 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=da3b6c555fc0a354 [5 sid=dd4ed96516a276f2] DATA len=0
14:10:19 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=5 DATA len=239
14:10:19 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=dd4ed96516a276f2 [5 sid=da3b6c555fc0a354] DATA len=0
14:10:19 ovpn,info ovpn-server: connected
14:10:19 ovpn,debug,packet ovpn-debug: re-sent P_CONTROL kid=0 sid=dd4ed96516a276f2 pid=6 DATA len=42
14:10:19 ovpn,debug,packet ovpn-debug: rcvd P_ACK kid=0 sid=da3b6c555fc0a354 [6 sid=dd4ed96516a276f2] DATA len=0
14:10:19 ovpn,debug,packet ovpn-debug: rcvd P_CONTROL kid=0 sid=da3b6c555fc0a354 pid=6 DATA len=239
14:10:19 ovpn,debug,packet ovpn-debug: sent P_ACK kid=0 sid=dd4ed96516a276f2 [6 sid=da3b6c555fc0a354] DATA len=0
14:10:19 ovpn,error could not add address: already have such address (6)
14:10:19 ovpn,info ovpn-server: disconnected <could not add address: already have such address (6)>
14:10:19 ovpn,info ovpn-server: terminating... - could not add address: already have such address (6)
14:10:19 ovpn,info ovpn-server: disconnected
On the client side I tried with "Don’t Add Pushed Routes (route-nopull) on and off. It didn’t help. I confirmed that there were no IP conflicts or route artifacts on either server or client side.
On the server side I tried to push a route across to see if that would help, but it didn’t. I also tried with “Push Routes:” blank and it didn’t help either.