Opened 6 months ago

Last modified 3 months ago

#1008 accepted Bug / Defect

iOS: OpenVPN stuck on "RESOLVING" when reconnecting after sleep using LTE

Reported by: sn2411 Owned by: Antonio
Priority: major Milestone:
Component: OpenVPN Connect Version: OpenVPN Connect for iOS v1.2.7
Severity: Not set (select this one, unless your'e a OpenVPN developer) Keywords:
Cc:

Description

After iOS sleep event is triggered, reconnection does not happen successfully, OpenVPN app shows status as "Looking up DNS Name" until "Connection timeout" occurs.

Issue happens when seamless tunnel is OFF, and when seamless tunnel is ON, and VPN is toggled on via the iOS settings app.

Issue does not occur when VPN is toggled on via OpenVPN app with seamless tunnel ON.

Log:

2018-01-31 18:43:03 OS Event: SLEEP
2018-01-31 18:43:03 EVENT: PAUSE
2018-01-31 18:43:12 OS Event: WAKEUP
2018-01-31 18:43:15 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-01-31 18:43:15 STANDARD RESUME
2018-01-31 18:43:15 EVENT: RESUME
2018-01-31 18:43:15 EVENT: RECONNECTING
2018-01-31 18:43:15 EVENT: RESOLVE
2018-01-31 18:43:15 OS Event: SLEEP
2018-01-31 18:43:15 EVENT: PAUSE
2018-01-31 18:44:17 OS Event: WAKEUP
2018-01-31 18:44:20 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-01-31 18:44:20 STANDARD RESUME
2018-01-31 18:44:20 EVENT: RESUME
2018-01-31 18:44:20 EVENT: RECONNECTING
2018-01-31 18:44:20 EVENT: RESOLVE
2018-01-31 18:44:30 Server poll timeout, trying next remote entry...
2018-01-31 18:44:30 EVENT: RECONNECTING
2018-01-31 18:44:30 EVENT: RESOLVE
2018-01-31 18:44:40 Server poll timeout, trying next remote entry...

Change History (23)

comment:1 Changed 6 months ago by Antonio

Component: Generic / unclassifiedOpenVPN Connect
Owner: set to Antonio
Status: newaccepted

comment:2 in reply to:  description ; Changed 6 months ago by Antonio

Replying to sn2411:

Issue does not occur when VPN is toggled on via OpenVPN app with seamless tunnel ON.

one clarification: does the above that when seamless tunnel if OFF you can see this issue also via the App?

comment:3 in reply to:  2 Changed 6 months ago by sn2411

Replying to ordex:

one clarification: does the above that when seamless tunnel if OFF you can see this issue also via the App?

That is correct, issue still occurs with seamless tunnel OFF and VPN toggled via OpenVPN Connect app.

comment:4 Changed 6 months ago by Antonio

could you please paste the full log from the first connection up until the problem, please?

comment:5 in reply to:  4 Changed 6 months ago by sn2411

Replying to ordex:

could you please paste the full log from the first connection up until the problem, please?

Here you go,

2018-01-31 19:43:47 ----- OpenVPN Start -----
OpenVPN core 3.1.2 ios arm64 64-bit built on Jan 23 2018 15:56:53
2018-01-31 19:43:47 Frame=512/2048/512 mssfix-ctrl=1250
2018-01-31 19:43:47 UNUSED OPTIONS
3 [sndbuf] [0] 
4 [rcvbuf] [0] 
6 [resolv-retry] [infinite] 
7 [nobind] 
8 [persist-key] 
9 [persist-tun] 
14 [block-outside-dns] 
16 [verb] [3] 

2018-01-31 19:43:47 EVENT: RESOLVE
2018-01-31 19:43:47 Contacting [server.ip.addr]:serverport/UDP via UDP
2018-01-31 19:43:47 EVENT: WAIT
2018-01-31 19:43:47 Connecting to [my.server.address]:serverport (server.ip.addr) via UDPv4
2018-01-31 19:43:47 EVENT: CONNECTING
2018-01-31 19:43:47 Tunnel Options:V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA512,keysize 256,tls-auth,key-method 2,tls-client
2018-01-31 19:43:47 Creds: UsernameEmpty/PasswordEmpty
2018-01-31 19:43:47 Peer Info:
IV_GUI_VER=net.openvpn.connect.ios 1.2.7-4
IV_VER=3.1.2
IV_PLAT=ios
IV_NCP=2
IV_TCPNL=1
IV_PROTO=2
IV_LZO_STUB=1
IV_COMP_STUB=1
IV_COMP_STUBv2=1
IV_IPv6=0
IV_AUTO_SESS=1

2018-01-31 19:43:47 VERIFY OK : depth=1
cert. version    : 3
serial number    : B7:F1:2A:3F:AA:C2:06:F4
issuer name      : CN=ChangeMe
subject name      : CN=ChangeMe
issued  on        : 2017-12-09 13:00:20
expires on        : 2027-12-07 13:00:20
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true
key usage        : Key Cert Sign, CRL Sign

2018-01-31 19:43:47 VERIFY OK : depth=0
cert. version    : 3
serial number    : 01
issuer name      : CN=ChangeMe
subject name      : CN=server
issued  on        : 2017-12-09 13:03:28
expires on        : 2027-12-07 13:03:28
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
subject alt name  : server
key usage        : Digital Signature, Key Encipherment
ext key usage    : TLS Web Server Authentication

2018-01-31 19:43:48 SSL Handshake: TLSv1.2/TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384
2018-01-31 19:43:48 Session is ACTIVE
2018-01-31 19:43:48 EVENT: GET_CONFIG
2018-01-31 19:43:48 Sending PUSH_REQUEST to server...
2018-01-31 19:43:48 OPTIONS:
0 [route] [192.168.1.0] [255.255.255.0] 
1 [dhcp-option] [DNS] [192.168.xxx.xxx] 
2 [route-gateway] [10.8.0.1] 
3 [topology] [subnet] 
4 [ping] [10] 
5 [ping-restart] [120] 
6 [ifconfig] [10.8.0.2] [255.255.255.0] 
7 [peer-id] [0] 
8 [cipher] [AES-256-GCM] 
9 [block-ipv6] 

2018-01-31 19:43:48 PROTOCOL OPTIONS:
  cipher: AES-256-GCM
  digest: SHA512
  compress: LZO_STUB
  peer ID: 0
2018-01-31 19:43:48 EVENT: ASSIGN_IP
2018-01-31 19:43:48 NIP: preparing TUN network settings
2018-01-31 19:43:48 NIP: init TUN network settings with endpoint: server.ip.addr
2018-01-31 19:43:48 NIP: adding IPv4 address to network settings 10.8.0.2/255.255.255.0
2018-01-31 19:43:48 NIP: adding (included) IPv4 route 10.8.0.0/24
2018-01-31 19:43:48 NIP: adding (included) IPv4 route 192.168.1.0/24
2018-01-31 19:43:48 NIP: adding DNS 192.168.xxx.xxx
2018-01-31 19:43:48 NIP: blocking all IPv6 traffic - not supported
2018-01-31 19:43:48 NIP: adding match domain ALL
2018-01-31 19:43:48 NIP: adding DNS specific routes:
2018-01-31 19:43:48 NIP: adding (included) IPv4 route 192.168.xxx.xxx/32
2018-01-31 19:43:48 Connected via NetworkExtensionTUN
2018-01-31 19:43:48 LZO-ASYM init swap=0 asym=1
2018-01-31 19:43:48 Comp-stub init swap=0
2018-01-31 19:43:48 EVENT: CONNECTED @my.server.address:serverport (server.ip.addr) via /UDPv4 on NetworkExtensionTUN/10.8.0.2/ gw=[/]
2018-01-31 19:46:03 OS Event: SLEEP
2018-01-31 19:46:03 EVENT: PAUSE
2018-01-31 19:46:10 OS Event: WAKEUP
2018-01-31 19:46:13 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-01-31 19:46:13 STANDARD RESUME
2018-01-31 19:46:13 EVENT: RESUME
2018-01-31 19:46:13 EVENT: RECONNECTING
2018-01-31 19:46:13 EVENT: RESOLVE
2018-01-31 19:46:13 OS Event: SLEEP
2018-01-31 19:46:13 EVENT: PAUSE
2018-01-31 19:47:05 OS Event: WAKEUP
2018-01-31 19:47:08 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-01-31 19:47:08 STANDARD RESUME
2018-01-31 19:47:08 EVENT: RESUME
2018-01-31 19:47:08 EVENT: RECONNECTING
2018-01-31 19:47:08 EVENT: RESOLVE
2018-01-31 19:47:19 Server poll timeout, trying next remote entry...
2018-01-31 19:47:19 EVENT: RECONNECTING
2018-01-31 19:47:19 EVENT: RESOLVE
2018-01-31 19:47:29 Server poll timeout, trying next remote entry...
2018-01-31 19:47:29 EVENT: RECONNECTING
2018-01-31 19:47:29 EVENT: RESOLVE
2018-01-31 19:47:40 Server poll timeout, trying next remote entry...
2018-01-31 19:47:40 EVENT: RECONNECTING
2018-01-31 19:47:40 EVENT: RESOLVE
2018-01-31 19:47:51 Server poll timeout, trying next remote entry...
2018-01-31 19:47:51 EVENT: RECONNECTING
2018-01-31 19:47:51 EVENT: RESOLVE
2018-01-31 19:48:02 Server poll timeout, trying next remote entry...
2018-01-31 19:48:02 EVENT: RECONNECTING
2018-01-31 19:48:02 EVENT: RESOLVE

comment:6 Changed 6 months ago by Antonio

May I know if your local network (the one you are connected to) is in the same range as the one you are pushing (192.168.1.0/24)?

Last edited 6 months ago by Antonio (previous) (diff)

comment:7 in reply to:  6 ; Changed 6 months ago by sn2411

Replying to ordex:

May I know if your local network (the one you are connected to) is in the same range as the one you are pushing (192.168.1.0/24)?

Yes, it is, DNS is on 192.168.1.xxx, apologies for the over-redaction!

comment:8 in reply to:  7 ; Changed 6 months ago by Antonio

Replying to sn2411:

Replying to ordex:

May I know if your local network (the one you are connected to) is in the same range as the one you are pushing (192.168.1.0/24)?

Yes, it is, DNS is on 192.168.1.xxx, apologies for the over-redaction!

sorry, I edited the question afterwards. Is also your device connected to a similar subnet (i.e. via wifi)?

comment:9 in reply to:  8 ; Changed 6 months ago by sn2411

Replying to ordex:

sorry, I edited the question afterwards. Is also your device connected to a similar subnet (i.e. via wifi)?

My iOS device was attempting all connections via cellular. Apologies, I should have mentioned earlier, all these behavior was only observed while on cellular.

comment:10 in reply to:  9 Changed 6 months ago by Antonio

Replying to sn2411:

Replying to ordex:

sorry, I edited the question afterwards. Is also your device connected to a similar subnet (i.e. via wifi)?

My iOS device was attempting all connections via cellular. Apologies, I should have mentioned earlier, all these behavior was only observed while on cellular.

Thanks! These are details we need to have in order to attempt replicating the issue.

comment:11 Changed 6 months ago by Antonio

Summary: Unable to reconnect after iOS sleepiOS: Unable to reconnect after sleep

comment:12 Changed 5 months ago by Antonio

Summary: iOS: Unable to reconnect after sleepiOS: OpenVPN stuck on "RESOLVING" hen reconnecting after sleep on LTE

comment:13 Changed 5 months ago by Antonio

Summary: iOS: OpenVPN stuck on "RESOLVING" hen reconnecting after sleep on LTEiOS: OpenVPN stuck on "RESOLVING" when reconnecting after sleep using LTE

comment:14 Changed 5 months ago by Antonio

I forgot to ask, but maybe you've mentioned this before: what device and what version of iOS are you using?

comment:15 in reply to:  14 Changed 5 months ago by sn2411

Replying to ordex:

I forgot to ask, but maybe you've mentioned this before: what device and what version of iOS are you using?

iPhone 7, iOS 11.2.5

comment:16 Changed 5 months ago by Antonio

does this still happen on 1.2.9?

comment:17 in reply to:  16 Changed 5 months ago by sn2411

Replying to ordex:

does this still happen on 1.2.9?

Yes it still does happen on version 1.2.9, logs as follows

2018-02-28 10:28:19 ----- OpenVPN Start -----
OpenVPN core 3.2 ios arm64 64-bit built on Feb 22 2018 12:39:28
2018-02-28 10:28:19 Frame=512/2048/512 mssfix-ctrl=1250
2018-02-28 10:28:19 UNUSED OPTIONS
3 [sndbuf] [0] 
4 [rcvbuf] [0] 
6 [resolv-retry] [infinite] 
7 [nobind] 
8 [persist-key] 
9 [persist-tun] 
14 [block-outside-dns] 
16 [verb] [3] 

2018-02-28 10:28:19 EVENT: RESOLVE
2018-02-28 10:28:19 Contacting [my.server.ip]:port/UDP via UDP
2018-02-28 10:28:19 EVENT: WAIT
2018-02-28 10:28:19 Connecting to [my.server.address]:port (my.server.ip) via UDPv4
2018-02-28 10:28:19 EVENT: CONNECTING
2018-02-28 10:28:19 Tunnel Options:V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA512,keysize 256,tls-auth,key-method 2,tls-client
2018-02-28 10:28:19 Creds: UsernameEmpty/PasswordEmpty
2018-02-28 10:28:19 Peer Info:
IV_GUI_VER=net.openvpn.connect.ios 1.2.9-0
IV_VER=3.2
IV_PLAT=ios
IV_NCP=2
IV_TCPNL=1
IV_PROTO=2
IV_LZO_STUB=1
IV_COMP_STUB=1
IV_COMP_STUBv2=1
IV_IPv6=0
IV_AUTO_SESS=1

2018-02-28 10:28:19 VERIFY OK : depth=1
cert. version    : 3
serial number    : C8:20:90:90:CA:F9:34:B2
issuer name      : CN=ChangeMe
subject name      : CN=ChangeMe
issued  on        : 2018-02-17 03:53:05
expires on        : 2028-02-15 03:53:05
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true
key usage        : Key Cert Sign, CRL Sign

2018-02-28 10:28:19 VERIFY OK : depth=0
cert. version    : 3
serial number    : B9:FF:DF:E4:D3:47:4D:A6:A5:6F:36:00:FB:28:A2:56
issuer name      : CN=ChangeMe
subject name      : CN=server
issued  on        : 2018-02-17 03:53:14
expires on        : 2028-02-15 03:53:14
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
subject alt name  : server
key usage        : Digital Signature, Key Encipherment
ext key usage    : TLS Web Server Authentication

2018-02-28 10:28:20 SSL Handshake: TLSv1.2/TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384
2018-02-28 10:28:20 Session is ACTIVE
2018-02-28 10:28:20 EVENT: GET_CONFIG
2018-02-28 10:28:20 Sending PUSH_REQUEST to server...
2018-02-28 10:28:20 OPTIONS:
0 [route] [192.168.1.0] [255.255.255.0] 
1 [dhcp-option] [DNS] [192.168.1.xxx] 
2 [route-gateway] [10.8.0.1] 
3 [topology] [subnet] 
4 [ping] [10] 
5 [ping-restart] [120] 
6 [ifconfig] [10.8.0.2] [255.255.255.0] 
7 [peer-id] [0] 
8 [cipher] [AES-256-GCM] 
9 [block-ipv6] 

2018-02-28 10:28:20 PROTOCOL OPTIONS:
  cipher: AES-256-GCM
  digest: SHA512
  compress: LZO_STUB
  peer ID: 0
2018-02-28 10:28:20 EVENT: ASSIGN_IP
2018-02-28 10:28:20 NIP: preparing TUN network settings
2018-02-28 10:28:20 NIP: init TUN network settings with endpoint: my.server.ip
2018-02-28 10:28:20 NIP: adding IPv4 address to network settings 10.8.0.2/255.255.255.0
2018-02-28 10:28:20 NIP: adding (included) IPv4 route 10.8.0.0/24
2018-02-28 10:28:20 NIP: adding (included) IPv4 route 192.168.1.0/24
2018-02-28 10:28:20 NIP: adding DNS 192.168.1.xxx
2018-02-28 10:28:20 NIP: blocking all IPv6 traffic - not supported
2018-02-28 10:28:20 NIP: adding match domain ALL
2018-02-28 10:28:20 NIP: adding DNS specific routes:
2018-02-28 10:28:20 NIP: adding (included) IPv4 route 192.168.1.xxx/32
2018-02-28 10:28:20 Connected via NetworkExtensionTUN
2018-02-28 10:28:20 LZO-ASYM init swap=0 asym=1
2018-02-28 10:28:20 Comp-stub init swap=0
2018-02-28 10:28:20 EVENT: CONNECTED @my.server.address:port (my.server.ip) via /UDPv4 on NetworkExtensionTUN/10.8.0.2/ gw=[/]
2018-02-28 10:28:34 OS Event: SLEEP
2018-02-28 10:28:34 EVENT: PAUSE
2018-02-28 10:28:35 OS Event: WAKEUP
2018-02-28 10:28:38 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-02-28 10:28:38 STANDARD RESUME
2018-02-28 10:28:38 EVENT: RESUME
2018-02-28 10:28:38 EVENT: RECONNECTING
2018-02-28 10:28:38 EVENT: RESOLVE
2018-02-28 10:28:38 OS Event: SLEEP
2018-02-28 10:28:38 EVENT: PAUSE
2018-02-28 10:28:48 OS Event: WAKEUP
2018-02-28 10:28:51 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-02-28 10:28:51 STANDARD RESUME
2018-02-28 10:28:51 EVENT: RESUME
2018-02-28 10:28:51 EVENT: RECONNECTING
2018-02-28 10:28:51 EVENT: RESOLVE
2018-02-28 10:28:58 OS Event: SLEEP
2018-02-28 10:28:58 EVENT: PAUSE
2018-02-28 10:29:07 OS Event: WAKEUP
2018-02-28 10:29:10 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-02-28 10:29:10 STANDARD RESUME
2018-02-28 10:29:10 EVENT: RESUME
2018-02-28 10:29:10 EVENT: RECONNECTING
2018-02-28 10:29:10 EVENT: RESOLVE
2018-02-28 10:29:14 OS Event: SLEEP
2018-02-28 10:29:14 EVENT: PAUSE
2018-02-28 10:30:10 OS Event: WAKEUP
2018-02-28 10:30:13 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-02-28 10:30:13 STANDARD RESUME
2018-02-28 10:30:13 EVENT: RESUME
2018-02-28 10:30:13 EVENT: RECONNECTING
2018-02-28 10:30:13 EVENT: RESOLVE
2018-02-28 10:30:23 Server poll timeout, trying next remote entry...
2018-02-28 10:30:23 EVENT: RECONNECTING
2018-02-28 10:30:23 EVENT: RESOLVE
2018-02-28 10:30:34 Server poll timeout, trying next remote entry...
2018-02-28 10:30:34 EVENT: RECONNECTING
2018-02-28 10:30:34 EVENT: RESOLVE
2018-02-28 10:30:45 Server poll timeout, trying next remote entry...
2018-02-28 10:30:45 EVENT: RECONNECTING
2018-02-28 10:30:45 EVENT: RESOLVE
2018-02-28 10:30:56 Server poll timeout, trying next remote entry...
2018-02-28 10:30:56 EVENT: RECONNECTING
2018-02-28 10:30:56 EVENT: RESOLVE
2018-02-28 10:31:07 Server poll timeout, trying next remote entry...
2018-02-28 10:31:07 EVENT: RECONNECTING
2018-02-28 10:31:07 EVENT: RESOLVE
2018-02-28 10:31:17 Server poll timeout, trying next remote entry...
2018-02-28 10:31:17 EVENT: RECONNECTING
2018-02-28 10:31:17 EVENT: RESOLVE
2018-02-28 10:31:28 Server poll timeout, trying next remote entry...
2018-02-28 10:31:28 EVENT: RECONNECTING
2018-02-28 10:31:28 EVENT: RESOLVE
2018-02-28 10:31:39 Server poll timeout, trying next remote entry...
2018-02-28 10:31:39 EVENT: RECONNECTING
2018-02-28 10:31:39 EVENT: RESOLVE
2018-02-28 10:31:49 Server poll timeout, trying next remote entry...
2018-02-28 10:31:49 EVENT: RECONNECTING
2018-02-28 10:31:49 EVENT: RESOLVE
2018-02-28 10:32:00 Server poll timeout, trying next remote entry...
2018-02-28 10:32:00 EVENT: RECONNECTING
2018-02-28 10:32:00 EVENT: RESOLVE
2018-02-28 10:32:10 Server poll timeout, trying next remote entry...
2018-02-28 10:32:10 EVENT: RECONNECTING
2018-02-28 10:32:10 EVENT: RESOLVE
2018-02-28 10:32:21 Server poll timeout, trying next remote entry...
2018-02-28 10:32:21 EVENT: RECONNECTING
2018-02-28 10:32:21 EVENT: RESOLVE
2018-02-28 10:32:31 Server poll timeout, trying next remote entry...
2018-02-28 10:32:31 EVENT: RECONNECTING
2018-02-28 10:32:31 EVENT: RESOLVE
2018-02-28 10:32:42 Server poll timeout, trying next remote entry...
2018-02-28 10:32:42 EVENT: RECONNECTING
2018-02-28 10:32:42 EVENT: RESOLVE
2018-02-28 10:32:53 Server poll timeout, trying next remote entry...
2018-02-28 10:32:53 EVENT: RECONNECTING
2018-02-28 10:32:53 EVENT: RESOLVE
2018-02-28 10:33:03 Server poll timeout, trying next remote entry...
2018-02-28 10:33:03 EVENT: RECONNECTING
2018-02-28 10:33:03 EVENT: RESOLVE
2018-02-28 10:33:14 Server poll timeout, trying next remote entry...
2018-02-28 10:33:14 EVENT: RECONNECTING
2018-02-28 10:33:14 EVENT: RESOLVE
2018-02-28 10:33:25 Server poll timeout, trying next remote entry...
2018-02-28 10:33:25 EVENT: RECONNECTING
2018-02-28 10:33:25 EVENT: RESOLVE
2018-02-28 10:33:36 Server poll timeout, trying next remote entry...
2018-02-28 10:33:36 EVENT: RECONNECTING
2018-02-28 10:33:36 EVENT: RESOLVE
2018-02-28 10:33:47 Server poll timeout, trying next remote entry...
2018-02-28 10:33:47 EVENT: RECONNECTING
2018-02-28 10:33:47 EVENT: RESOLVE
2018-02-28 10:33:58 Server poll timeout, trying next remote entry...
2018-02-28 10:33:58 EVENT: RECONNECTING
2018-02-28 10:33:58 EVENT: RESOLVE
2018-02-28 10:34:09 Server poll timeout, trying next remote entry...
2018-02-28 10:34:09 EVENT: RECONNECTING
2018-02-28 10:34:09 EVENT: RESOLVE
2018-02-28 10:34:16 EVENT: DISCONNECTED
2018-02-28 10:34:16 Raw stats on disconnect:
  BYTES_IN : 5148
  BYTES_OUT : 4371
  PACKETS_IN : 22
  PACKETS_OUT : 25
  TUN_BYTES_IN : 1017
  TUN_BYTES_OUT : 1240
  TUN_PACKETS_IN : 14
  TUN_PACKETS_OUT : 12
  N_PAUSE : 4
  N_RECONNECT : 26
2018-02-28 10:34:16 Performance stats on disconnect:
  CPU usage (microseconds): 348114
  Tunnel compression ratio (uplink): 4.29794
  Tunnel compression ratio (downlink): 4.15161
  Network bytes per CPU second: 27344
  Tunnel bytes per CPU second: 6483

comment:18 Changed 4 months ago by futurealecks

I just wanted to add we're also seeing this error with version 1.2.9 across a dozen or more devices. Most of these devices are running 11.2.x. Our logs pretty much look identical to the logs posted above. We have ours configured for TCP (above is UDP).

comment:19 Changed 3 months ago by alfxmpp

We are also seeing this with version 1.2.9, and its becoming a make or break issue for us. In fact, we are currently back to using IKEv2 in a production environment just because of this issue, though we'd rather use OpenVPN Connect. Configured for TCP, logs are similar.

comment:20 Changed 3 months ago by Antonio

can you guys please share the client configs? Can you also specify if seamless tunnel is ON and if the issue happens when connecting from the iOS settings, from the app or both?

comment:21 Changed 3 months ago by alfxmpp

We almost always connect from within the OpenVPN Connect app.

Seamless tunnel - OFF.
Reconnect on wakeup - ON.
Connection timeout - None
Protocol - TCP
Network state detection - active.

If I remember correctly we turned off Seamless tunnel a while back because it seemed like it was having more issues with it turned on. I'll have to wait till it fails again to send a log.

comment:22 Changed 3 months ago by sn2411

Hello, I've been tinkering around a little, and found adding a second address (pointing to the same IP) seems to increase the success rate of reconnection (reconnection happens successfully a few times, but issue occurs eventually VS reconnection outright failing previously).

Here are the settings in iOS:

Seamless tunnel: OFF
Reconnect on wakeup: ON
Protocol: UDP
Network state detection: Active

Connection is toggled via OpenVPN Connect app (logs were not captured otherwise)

Here's the .ovpn file I've been using:

client
dev tun
proto udp
sndbuf 0
rcvbuf 0
remote server.address.1 port
remote server.address.2 port #this line was not present before
resolv-retry infinite
nobind
persist-key
persist-tun
remote-cert-tls server
auth SHA512
cipher AES-256-CBC
comp-lzo
setenv opt block-outside-dns
key-direction 1
verb 3
<ca>
-----BEGIN CERTIFICATE-----

-----END CERTIFICATE-----
</ca>
<cert>
Certificate:
    
-----BEGIN CERTIFICATE-----

-----END CERTIFICATE-----
</cert>
<key>
-----BEGIN PRIVATE KEY-----

-----END PRIVATE KEY-----
</key>
<tls-auth>
-----BEGIN OpenVPN Static key V1-----

</tls-auth>

Here are the logs (sorry for the length!):

2018-04-04 11:16:12 ----- OpenVPN Start -----
OpenVPN core 3.2 ios arm64 64-bit built on Feb 22 2018 12:39:28
2018-04-04 11:16:12 Frame=512/2048/512 mssfix-ctrl=1250
2018-04-04 11:16:12 UNUSED OPTIONS
3 [sndbuf] [0] 
4 [rcvbuf] [0] 
7 [resolv-retry] [infinite] 
8 [nobind] 
9 [persist-key] 
10 [persist-tun] 
15 [block-outside-dns] 
17 [verb] [3] 

2018-04-04 11:16:12 EVENT: RESOLVE
2018-04-04 11:16:12 Contacting [my.server.ip]:port/UDP via UDP
2018-04-04 11:16:12 EVENT: WAIT
2018-04-04 11:16:12 Connecting to [server.address.1]:port (my.server.ip) via UDPv4
2018-04-04 11:16:12 EVENT: CONNECTING
2018-04-04 11:16:12 Tunnel Options:V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA512,keysize 256,tls-auth,key-method 2,tls-client
2018-04-04 11:16:12 Creds: UsernameEmpty/PasswordEmpty
2018-04-04 11:16:12 Peer Info:
IV_GUI_VER=net.openvpn.connect.ios 1.2.9-0
IV_VER=3.2
IV_PLAT=ios
IV_NCP=2
IV_TCPNL=1
IV_PROTO=2
IV_LZO_STUB=1
IV_COMP_STUB=1
IV_COMP_STUBv2=1
IV_IPv6=0
IV_AUTO_SESS=1

2018-04-04 11:16:12 VERIFY OK : depth=1
cert. version    : 3
serial number    : C8:20:90:90:CA:F9:34:B2
issuer name      : CN=ChangeMe
subject name      : CN=ChangeMe
issued  on        : 2018-02-17 03:53:05
expires on        : 2028-02-15 03:53:05
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true
key usage        : Key Cert Sign, CRL Sign

2018-04-04 11:16:12 VERIFY OK : depth=0
cert. version    : 3
serial number    : B9:FF:DF:E4:D3:47:4D:A6:A5:6F:36:00:FB:28:A2:56
issuer name      : CN=ChangeMe
subject name      : CN=server
issued  on        : 2018-02-17 03:53:14
expires on        : 2028-02-15 03:53:14
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
subject alt name  : server
key usage        : Digital Signature, Key Encipherment
ext key usage    : TLS Web Server Authentication

2018-04-04 11:16:13 SSL Handshake: TLSv1.2/TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384
2018-04-04 11:16:13 Session is ACTIVE
2018-04-04 11:16:13 EVENT: GET_CONFIG
2018-04-04 11:16:13 Sending PUSH_REQUEST to server...
2018-04-04 11:16:13 OPTIONS:
0 [route] [192.168.1.0] [255.255.255.0] 
1 [dhcp-option] [DNS] [192.168.1.xxx] 
2 [route-gateway] [10.8.0.1] 
3 [topology] [subnet] 
4 [ping] [10] 
5 [ping-restart] [120] 
6 [ifconfig] [10.8.0.2] [255.255.255.0] 
7 [peer-id] [0] 
8 [cipher] [AES-256-GCM] 
9 [block-ipv6] 

2018-04-04 11:16:13 PROTOCOL OPTIONS:
  cipher: AES-256-GCM
  digest: SHA512
  compress: LZO_STUB
  peer ID: 0
2018-04-04 11:16:13 EVENT: ASSIGN_IP
2018-04-04 11:16:13 NIP: preparing TUN network settings
2018-04-04 11:16:13 NIP: init TUN network settings with endpoint: my.server.ip
2018-04-04 11:16:13 NIP: adding IPv4 address to network settings 10.8.0.2/255.255.255.0
2018-04-04 11:16:13 NIP: adding (included) IPv4 route 10.8.0.0/24
2018-04-04 11:16:13 NIP: adding (included) IPv4 route 192.168.1.0/24
2018-04-04 11:16:13 NIP: adding DNS 192.168.1.xxx
2018-04-04 11:16:13 NIP: blocking all IPv6 traffic - not supported
2018-04-04 11:16:13 NIP: adding match domain ALL
2018-04-04 11:16:13 NIP: adding DNS specific routes:
2018-04-04 11:16:13 NIP: adding (included) IPv4 route 192.168.1.xxx/32
2018-04-04 11:16:13 Connected via NetworkExtensionTUN
2018-04-04 11:16:13 LZO-ASYM init swap=0 asym=1
2018-04-04 11:16:13 Comp-stub init swap=0
2018-04-04 11:16:13 EVENT: CONNECTED @server.address.1:port (my.server.ip) via /UDPv4 on NetworkExtensionTUN/10.8.0.2/ gw=[/]
2018-04-04 11:18:53 OS Event: SLEEP
2018-04-04 11:18:53 EVENT: PAUSE
2018-04-04 11:18:57 OS Event: WAKEUP
2018-04-04 11:19:00 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:19:00 STANDARD RESUME
2018-04-04 11:19:00 EVENT: RESUME
2018-04-04 11:19:00 EVENT: RECONNECTING
2018-04-04 11:19:00 EVENT: RESOLVE
2018-04-04 11:19:00 OS Event: SLEEP
2018-04-04 11:19:00 EVENT: PAUSE
2018-04-04 11:19:44 OS Event: WAKEUP
2018-04-04 11:19:47 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:19:47 STANDARD RESUME
2018-04-04 11:19:47 EVENT: RESUME
2018-04-04 11:19:47 EVENT: RECONNECTING
2018-04-04 11:19:47 EVENT: RESOLVE
2018-04-04 11:19:48 Contacting [my.server.ip]:port/UDP via UDP
2018-04-04 11:19:48 EVENT: WAIT
2018-04-04 11:19:48 Connecting to [server.address.2]:port (my.server.ip) via UDPv4
2018-04-04 11:19:48 EVENT: CONNECTING
2018-04-04 11:19:48 Tunnel Options:V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA512,keysize 256,tls-auth,key-method 2,tls-client
2018-04-04 11:19:48 Creds: UsernameEmpty/PasswordEmpty
2018-04-04 11:19:48 Peer Info:
IV_GUI_VER=net.openvpn.connect.ios 1.2.9-0
IV_VER=3.2
IV_PLAT=ios
IV_NCP=2
IV_TCPNL=1
IV_PROTO=2
IV_LZO_STUB=1
IV_COMP_STUB=1
IV_COMP_STUBv2=1
IV_IPv6=0
IV_AUTO_SESS=1

2018-04-04 11:19:48 VERIFY OK : depth=1
cert. version    : 3
serial number    : C8:20:90:90:CA:F9:34:B2
issuer name      : CN=ChangeMe
subject name      : CN=ChangeMe
issued  on        : 2018-02-17 03:53:05
expires on        : 2028-02-15 03:53:05
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true
key usage        : Key Cert Sign, CRL Sign

2018-04-04 11:19:48 VERIFY OK : depth=0
cert. version    : 3
serial number    : B9:FF:DF:E4:D3:47:4D:A6:A5:6F:36:00:FB:28:A2:56
issuer name      : CN=ChangeMe
subject name      : CN=server
issued  on        : 2018-02-17 03:53:14
expires on        : 2028-02-15 03:53:14
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
subject alt name  : server
key usage        : Digital Signature, Key Encipherment
ext key usage    : TLS Web Server Authentication

2018-04-04 11:19:48 SSL Handshake: TLSv1.2/TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384
2018-04-04 11:19:48 Session is ACTIVE
2018-04-04 11:19:48 EVENT: GET_CONFIG
2018-04-04 11:19:48 Sending PUSH_REQUEST to server...
2018-04-04 11:19:48 OPTIONS:
0 [route] [192.168.1.0] [255.255.255.0] 
1 [dhcp-option] [DNS] [192.168.1.xxx] 
2 [route-gateway] [10.8.0.1] 
3 [topology] [subnet] 
4 [ping] [10] 
5 [ping-restart] [120] 
6 [ifconfig] [10.8.0.2] [255.255.255.0] 
7 [peer-id] [0] 
8 [cipher] [AES-256-GCM] 
9 [block-ipv6] 

2018-04-04 11:19:48 PROTOCOL OPTIONS:
  cipher: AES-256-GCM
  digest: SHA512
  compress: LZO_STUB
  peer ID: 0
2018-04-04 11:19:48 EVENT: ASSIGN_IP
2018-04-04 11:19:48 NIP: preparing TUN network settings
2018-04-04 11:19:48 NIP: init TUN network settings with endpoint: my.server.ip
2018-04-04 11:19:48 NIP: adding IPv4 address to network settings 10.8.0.2/255.255.255.0
2018-04-04 11:19:48 NIP: adding (included) IPv4 route 10.8.0.0/24
2018-04-04 11:19:48 NIP: adding (included) IPv4 route 192.168.1.0/24
2018-04-04 11:19:48 NIP: adding DNS 192.168.1.xxx
2018-04-04 11:19:48 NIP: blocking all IPv6 traffic - not supported
2018-04-04 11:19:48 NIP: adding match domain ALL
2018-04-04 11:19:48 NIP: adding DNS specific routes:
2018-04-04 11:19:48 NIP: adding (included) IPv4 route 192.168.1.xxx/32
2018-04-04 11:19:48 Connected via NetworkExtensionTUN
2018-04-04 11:19:48 LZO-ASYM init swap=0 asym=1
2018-04-04 11:19:48 Comp-stub init swap=0
2018-04-04 11:19:48 EVENT: CONNECTED @server.address.2:port (my.server.ip) via /UDPv4 on NetworkExtensionTUN/10.8.0.2/ gw=[/]
2018-04-04 11:19:59 OS Event: SLEEP
2018-04-04 11:19:59 EVENT: PAUSE
2018-04-04 11:20:08 OS Event: WAKEUP
2018-04-04 11:20:11 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:20:11 STANDARD RESUME
2018-04-04 11:20:11 EVENT: RESUME
2018-04-04 11:20:11 EVENT: RECONNECTING
2018-04-04 11:20:11 EVENT: RESOLVE
2018-04-04 11:20:11 OS Event: SLEEP
2018-04-04 11:20:11 EVENT: PAUSE
2018-04-04 11:22:49 OS Event: WAKEUP
2018-04-04 11:22:52 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:22:52 STANDARD RESUME
2018-04-04 11:22:52 EVENT: RESUME
2018-04-04 11:22:52 EVENT: RECONNECTING
2018-04-04 11:22:52 EVENT: RESOLVE
2018-04-04 11:22:52 Contacting [my.server.ip]:port/UDP via UDP
2018-04-04 11:22:52 EVENT: WAIT
2018-04-04 11:22:52 Connecting to [server.address.1]:port (my.server.ip) via UDPv4
2018-04-04 11:22:52 EVENT: CONNECTING
2018-04-04 11:22:52 Tunnel Options:V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA512,keysize 256,tls-auth,key-method 2,tls-client
2018-04-04 11:22:52 Creds: UsernameEmpty/PasswordEmpty
2018-04-04 11:22:52 Peer Info:
IV_GUI_VER=net.openvpn.connect.ios 1.2.9-0
IV_VER=3.2
IV_PLAT=ios
IV_NCP=2
IV_TCPNL=1
IV_PROTO=2
IV_LZO_STUB=1
IV_COMP_STUB=1
IV_COMP_STUBv2=1
IV_IPv6=0
IV_AUTO_SESS=1

2018-04-04 11:22:52 VERIFY OK : depth=1
cert. version    : 3
serial number    : C8:20:90:90:CA:F9:34:B2
issuer name      : CN=ChangeMe
subject name      : CN=ChangeMe
issued  on        : 2018-02-17 03:53:05
expires on        : 2028-02-15 03:53:05
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true
key usage        : Key Cert Sign, CRL Sign

2018-04-04 11:22:52 VERIFY OK : depth=0
cert. version    : 3
serial number    : B9:FF:DF:E4:D3:47:4D:A6:A5:6F:36:00:FB:28:A2:56
issuer name      : CN=ChangeMe
subject name      : CN=server
issued  on        : 2018-02-17 03:53:14
expires on        : 2028-02-15 03:53:14
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
subject alt name  : server
key usage        : Digital Signature, Key Encipherment
ext key usage    : TLS Web Server Authentication

2018-04-04 11:22:53 SSL Handshake: TLSv1.2/TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384
2018-04-04 11:22:53 Session is ACTIVE
2018-04-04 11:22:53 EVENT: GET_CONFIG
2018-04-04 11:22:53 Sending PUSH_REQUEST to server...
2018-04-04 11:22:53 OPTIONS:
0 [route] [192.168.1.0] [255.255.255.0] 
1 [dhcp-option] [DNS] [192.168.1.xxx] 
2 [route-gateway] [10.8.0.1] 
3 [topology] [subnet] 
4 [ping] [10] 
5 [ping-restart] [120] 
6 [ifconfig] [10.8.0.2] [255.255.255.0] 
7 [peer-id] [0] 
8 [cipher] [AES-256-GCM] 
9 [block-ipv6] 

2018-04-04 11:22:53 PROTOCOL OPTIONS:
  cipher: AES-256-GCM
  digest: SHA512
  compress: LZO_STUB
  peer ID: 0
2018-04-04 11:22:53 EVENT: ASSIGN_IP
2018-04-04 11:22:53 NIP: preparing TUN network settings
2018-04-04 11:22:53 NIP: init TUN network settings with endpoint: my.server.ip
2018-04-04 11:22:53 NIP: adding IPv4 address to network settings 10.8.0.2/255.255.255.0
2018-04-04 11:22:53 NIP: adding (included) IPv4 route 10.8.0.0/24
2018-04-04 11:22:53 NIP: adding (included) IPv4 route 192.168.1.0/24
2018-04-04 11:22:53 NIP: adding DNS 192.168.1.xxx
2018-04-04 11:22:53 NIP: blocking all IPv6 traffic - not supported
2018-04-04 11:22:53 NIP: adding match domain ALL
2018-04-04 11:22:53 NIP: adding DNS specific routes:
2018-04-04 11:22:53 NIP: adding (included) IPv4 route 192.168.1.xxx/32
2018-04-04 11:22:53 Connected via NetworkExtensionTUN
2018-04-04 11:22:53 LZO-ASYM init swap=0 asym=1
2018-04-04 11:22:53 Comp-stub init swap=0
2018-04-04 11:22:53 EVENT: CONNECTED @server.address.1:port (my.server.ip) via /UDPv4 on NetworkExtensionTUN/10.8.0.2/ gw=[/]
2018-04-04 11:24:45 OS Event: SLEEP
2018-04-04 11:24:45 EVENT: PAUSE
2018-04-04 11:24:55 OS Event: WAKEUP
2018-04-04 11:24:58 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:24:58 STANDARD RESUME
2018-04-04 11:24:58 EVENT: RESUME
2018-04-04 11:24:58 EVENT: RECONNECTING
2018-04-04 11:24:58 EVENT: RESOLVE
2018-04-04 11:24:58 OS Event: SLEEP
2018-04-04 11:24:58 EVENT: PAUSE
2018-04-04 11:26:03 OS Event: WAKEUP
2018-04-04 11:26:06 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:26:06 STANDARD RESUME
2018-04-04 11:26:06 EVENT: RESUME
2018-04-04 11:26:06 EVENT: RECONNECTING
2018-04-04 11:26:06 EVENT: RESOLVE
2018-04-04 11:26:07 Contacting [my.server.ip]:port/UDP via UDP
2018-04-04 11:26:07 EVENT: WAIT
2018-04-04 11:26:07 Connecting to [server.address.2]:port (my.server.ip) via UDPv4
2018-04-04 11:26:07 EVENT: CONNECTING
2018-04-04 11:26:07 Tunnel Options:V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA512,keysize 256,tls-auth,key-method 2,tls-client
2018-04-04 11:26:07 Creds: UsernameEmpty/PasswordEmpty
2018-04-04 11:26:07 Peer Info:
IV_GUI_VER=net.openvpn.connect.ios 1.2.9-0
IV_VER=3.2
IV_PLAT=ios
IV_NCP=2
IV_TCPNL=1
IV_PROTO=2
IV_LZO_STUB=1
IV_COMP_STUB=1
IV_COMP_STUBv2=1
IV_IPv6=0
IV_AUTO_SESS=1

2018-04-04 11:26:07 VERIFY OK : depth=1
cert. version    : 3
serial number    : C8:20:90:90:CA:F9:34:B2
issuer name      : CN=ChangeMe
subject name      : CN=ChangeMe
issued  on        : 2018-02-17 03:53:05
expires on        : 2028-02-15 03:53:05
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true
key usage        : Key Cert Sign, CRL Sign

2018-04-04 11:26:07 VERIFY OK : depth=0
cert. version    : 3
serial number    : B9:FF:DF:E4:D3:47:4D:A6:A5:6F:36:00:FB:28:A2:56
issuer name      : CN=ChangeMe
subject name      : CN=server
issued  on        : 2018-02-17 03:53:14
expires on        : 2028-02-15 03:53:14
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
subject alt name  : server
key usage        : Digital Signature, Key Encipherment
ext key usage    : TLS Web Server Authentication

2018-04-04 11:26:07 SSL Handshake: TLSv1.2/TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384
2018-04-04 11:26:07 Session is ACTIVE
2018-04-04 11:26:07 EVENT: GET_CONFIG
2018-04-04 11:26:07 Sending PUSH_REQUEST to server...
2018-04-04 11:26:07 OPTIONS:
0 [route] [192.168.1.0] [255.255.255.0] 
1 [dhcp-option] [DNS] [192.168.1.xxx] 
2 [route-gateway] [10.8.0.1] 
3 [topology] [subnet] 
4 [ping] [10] 
5 [ping-restart] [120] 
6 [ifconfig] [10.8.0.2] [255.255.255.0] 
7 [peer-id] [0] 
8 [cipher] [AES-256-GCM] 
9 [block-ipv6] 

2018-04-04 11:26:07 PROTOCOL OPTIONS:
  cipher: AES-256-GCM
  digest: SHA512
  compress: LZO_STUB
  peer ID: 0
2018-04-04 11:26:07 EVENT: ASSIGN_IP
2018-04-04 11:26:07 NIP: preparing TUN network settings
2018-04-04 11:26:07 NIP: init TUN network settings with endpoint: my.server.ip
2018-04-04 11:26:07 NIP: adding IPv4 address to network settings 10.8.0.2/255.255.255.0
2018-04-04 11:26:07 NIP: adding (included) IPv4 route 10.8.0.0/24
2018-04-04 11:26:07 NIP: adding (included) IPv4 route 192.168.1.0/24
2018-04-04 11:26:07 NIP: adding DNS 192.168.1.xxx
2018-04-04 11:26:07 NIP: blocking all IPv6 traffic - not supported
2018-04-04 11:26:07 NIP: adding match domain ALL
2018-04-04 11:26:07 NIP: adding DNS specific routes:
2018-04-04 11:26:07 NIP: adding (included) IPv4 route 192.168.1.xxx/32
2018-04-04 11:26:07 Connected via NetworkExtensionTUN
2018-04-04 11:26:07 LZO-ASYM init swap=0 asym=1
2018-04-04 11:26:07 Comp-stub init swap=0
2018-04-04 11:26:07 EVENT: CONNECTED @server.address.2:port (my.server.ip) via /UDPv4 on NetworkExtensionTUN/10.8.0.2/ gw=[/]
2018-04-04 11:26:20 OS Event: SLEEP
2018-04-04 11:26:20 EVENT: PAUSE
2018-04-04 11:26:27 OS Event: WAKEUP
2018-04-04 11:26:30 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:26:30 STANDARD RESUME
2018-04-04 11:26:30 EVENT: RESUME
2018-04-04 11:26:30 EVENT: RECONNECTING
2018-04-04 11:26:30 EVENT: RESOLVE
2018-04-04 11:26:30 OS Event: SLEEP
2018-04-04 11:26:30 EVENT: PAUSE
2018-04-04 11:27:54 OS Event: WAKEUP
2018-04-04 11:27:57 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:27:57 STANDARD RESUME
2018-04-04 11:27:57 EVENT: RESUME
2018-04-04 11:27:57 EVENT: RECONNECTING
2018-04-04 11:27:57 EVENT: RESOLVE
2018-04-04 11:27:57 Contacting [my.server.ip]:port/UDP via UDP
2018-04-04 11:27:57 EVENT: WAIT
2018-04-04 11:27:57 Connecting to [server.address.1]:port (my.server.ip) via UDPv4
2018-04-04 11:27:57 EVENT: CONNECTING
2018-04-04 11:27:57 Tunnel Options:V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA512,keysize 256,tls-auth,key-method 2,tls-client
2018-04-04 11:27:57 Creds: UsernameEmpty/PasswordEmpty
2018-04-04 11:27:57 Peer Info:
IV_GUI_VER=net.openvpn.connect.ios 1.2.9-0
IV_VER=3.2
IV_PLAT=ios
IV_NCP=2
IV_TCPNL=1
IV_PROTO=2
IV_LZO_STUB=1
IV_COMP_STUB=1
IV_COMP_STUBv2=1
IV_IPv6=0
IV_AUTO_SESS=1

2018-04-04 11:27:57 VERIFY OK : depth=1
cert. version    : 3
serial number    : C8:20:90:90:CA:F9:34:B2
issuer name      : CN=ChangeMe
subject name      : CN=ChangeMe
issued  on        : 2018-02-17 03:53:05
expires on        : 2028-02-15 03:53:05
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true
key usage        : Key Cert Sign, CRL Sign

2018-04-04 11:27:57 VERIFY OK : depth=0
cert. version    : 3
serial number    : B9:FF:DF:E4:D3:47:4D:A6:A5:6F:36:00:FB:28:A2:56
issuer name      : CN=ChangeMe
subject name      : CN=server
issued  on        : 2018-02-17 03:53:14
expires on        : 2028-02-15 03:53:14
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
subject alt name  : server
key usage        : Digital Signature, Key Encipherment
ext key usage    : TLS Web Server Authentication

2018-04-04 11:27:57 SSL Handshake: TLSv1.2/TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384
2018-04-04 11:27:57 Session is ACTIVE
2018-04-04 11:27:57 EVENT: GET_CONFIG
2018-04-04 11:27:57 Sending PUSH_REQUEST to server...
2018-04-04 11:27:57 OPTIONS:
0 [route] [192.168.1.0] [255.255.255.0] 
1 [dhcp-option] [DNS] [192.168.1.xxx] 
2 [route-gateway] [10.8.0.1] 
3 [topology] [subnet] 
4 [ping] [10] 
5 [ping-restart] [120] 
6 [ifconfig] [10.8.0.2] [255.255.255.0] 
7 [peer-id] [0] 
8 [cipher] [AES-256-GCM] 
9 [block-ipv6] 

2018-04-04 11:27:57 PROTOCOL OPTIONS:
  cipher: AES-256-GCM
  digest: SHA512
  compress: LZO_STUB
  peer ID: 0
2018-04-04 11:27:57 EVENT: ASSIGN_IP
2018-04-04 11:27:57 NIP: preparing TUN network settings
2018-04-04 11:27:57 NIP: init TUN network settings with endpoint: my.server.ip
2018-04-04 11:27:57 NIP: adding IPv4 address to network settings 10.8.0.2/255.255.255.0
2018-04-04 11:27:57 NIP: adding (included) IPv4 route 10.8.0.0/24
2018-04-04 11:27:57 NIP: adding (included) IPv4 route 192.168.1.0/24
2018-04-04 11:27:57 NIP: adding DNS 192.168.1.xxx
2018-04-04 11:27:57 NIP: blocking all IPv6 traffic - not supported
2018-04-04 11:27:57 NIP: adding match domain ALL
2018-04-04 11:27:57 NIP: adding DNS specific routes:
2018-04-04 11:27:57 NIP: adding (included) IPv4 route 192.168.1.xxx/32
2018-04-04 11:27:57 Connected via NetworkExtensionTUN
2018-04-04 11:27:57 LZO-ASYM init swap=0 asym=1
2018-04-04 11:27:57 Comp-stub init swap=0
2018-04-04 11:27:57 EVENT: CONNECTED @server.address.1:port (my.server.ip) via /UDPv4 on NetworkExtensionTUN/10.8.0.2/ gw=[/]
2018-04-04 11:29:32 OS Event: SLEEP
2018-04-04 11:29:32 EVENT: PAUSE
2018-04-04 11:29:41 OS Event: WAKEUP
2018-04-04 11:29:44 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:29:44 STANDARD RESUME
2018-04-04 11:29:44 EVENT: RESUME
2018-04-04 11:29:44 EVENT: RECONNECTING
2018-04-04 11:29:44 EVENT: RESOLVE
2018-04-04 11:29:44 OS Event: SLEEP
2018-04-04 11:29:44 EVENT: PAUSE
2018-04-04 11:30:10 OS Event: WAKEUP
2018-04-04 11:30:13 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:30:13 STANDARD RESUME
2018-04-04 11:30:13 EVENT: RESUME
2018-04-04 11:30:13 EVENT: RECONNECTING
2018-04-04 11:30:13 EVENT: RESOLVE
2018-04-04 11:30:14 Contacting [my.server.ip]:port/UDP via UDP
2018-04-04 11:30:14 EVENT: WAIT
2018-04-04 11:30:14 Connecting to [server.address.2]:port (my.server.ip) via UDPv4
2018-04-04 11:30:14 EVENT: CONNECTING
2018-04-04 11:30:14 Tunnel Options:V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA512,keysize 256,tls-auth,key-method 2,tls-client
2018-04-04 11:30:14 Creds: UsernameEmpty/PasswordEmpty
2018-04-04 11:30:14 Peer Info:
IV_GUI_VER=net.openvpn.connect.ios 1.2.9-0
IV_VER=3.2
IV_PLAT=ios
IV_NCP=2
IV_TCPNL=1
IV_PROTO=2
IV_LZO_STUB=1
IV_COMP_STUB=1
IV_COMP_STUBv2=1
IV_IPv6=0
IV_AUTO_SESS=1

2018-04-04 11:30:14 VERIFY OK : depth=1
cert. version    : 3
serial number    : C8:20:90:90:CA:F9:34:B2
issuer name      : CN=ChangeMe
subject name      : CN=ChangeMe
issued  on        : 2018-02-17 03:53:05
expires on        : 2028-02-15 03:53:05
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true
key usage        : Key Cert Sign, CRL Sign

2018-04-04 11:30:14 VERIFY OK : depth=0
cert. version    : 3
serial number    : B9:FF:DF:E4:D3:47:4D:A6:A5:6F:36:00:FB:28:A2:56
issuer name      : CN=ChangeMe
subject name      : CN=server
issued  on        : 2018-02-17 03:53:14
expires on        : 2028-02-15 03:53:14
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
subject alt name  : server
key usage        : Digital Signature, Key Encipherment
ext key usage    : TLS Web Server Authentication

2018-04-04 11:30:15 SSL Handshake: TLSv1.2/TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384
2018-04-04 11:30:15 Session is ACTIVE
2018-04-04 11:30:15 EVENT: GET_CONFIG
2018-04-04 11:30:15 Sending PUSH_REQUEST to server...
2018-04-04 11:30:15 OPTIONS:
0 [route] [192.168.1.0] [255.255.255.0] 
1 [dhcp-option] [DNS] [192.168.1.xxx] 
2 [route-gateway] [10.8.0.1] 
3 [topology] [subnet] 
4 [ping] [10] 
5 [ping-restart] [120] 
6 [ifconfig] [10.8.0.2] [255.255.255.0] 
7 [peer-id] [0] 
8 [cipher] [AES-256-GCM] 
9 [block-ipv6] 

2018-04-04 11:30:15 PROTOCOL OPTIONS:
  cipher: AES-256-GCM
  digest: SHA512
  compress: LZO_STUB
  peer ID: 0
2018-04-04 11:30:15 EVENT: ASSIGN_IP
2018-04-04 11:30:15 NIP: preparing TUN network settings
2018-04-04 11:30:15 NIP: init TUN network settings with endpoint: my.server.ip
2018-04-04 11:30:15 NIP: adding IPv4 address to network settings 10.8.0.2/255.255.255.0
2018-04-04 11:30:15 NIP: adding (included) IPv4 route 10.8.0.0/24
2018-04-04 11:30:15 NIP: adding (included) IPv4 route 192.168.1.0/24
2018-04-04 11:30:15 NIP: adding DNS 192.168.1.xxx
2018-04-04 11:30:15 NIP: blocking all IPv6 traffic - not supported
2018-04-04 11:30:15 NIP: adding match domain ALL
2018-04-04 11:30:15 NIP: adding DNS specific routes:
2018-04-04 11:30:15 NIP: adding (included) IPv4 route 192.168.1.xxx/32
2018-04-04 11:30:15 Connected via NetworkExtensionTUN
2018-04-04 11:30:15 LZO-ASYM init swap=0 asym=1
2018-04-04 11:30:15 Comp-stub init swap=0
2018-04-04 11:30:15 EVENT: CONNECTED @server.address.2:port (my.server.ip) via /UDPv4 on NetworkExtensionTUN/10.8.0.2/ gw=[/]
2018-04-04 11:30:24 OS Event: SLEEP
2018-04-04 11:30:24 EVENT: PAUSE
2018-04-04 11:30:36 OS Event: WAKEUP
2018-04-04 11:30:39 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:30:39 STANDARD RESUME
2018-04-04 11:30:39 EVENT: RESUME
2018-04-04 11:30:39 EVENT: RECONNECTING
2018-04-04 11:30:39 EVENT: RESOLVE
2018-04-04 11:30:39 OS Event: SLEEP
2018-04-04 11:30:39 EVENT: PAUSE
2018-04-04 11:30:44 OS Event: WAKEUP
2018-04-04 11:30:47 RESUME TEST: Internet:ReachableViaWWAN/WR t------
2018-04-04 11:30:47 STANDARD RESUME
2018-04-04 11:30:47 EVENT: RESUME
2018-04-04 11:30:47 EVENT: RECONNECTING
2018-04-04 11:30:47 EVENT: RESOLVE
2018-04-04 11:30:57 Server poll timeout, trying next remote entry...
2018-04-04 11:30:57 EVENT: RECONNECTING
2018-04-04 11:30:57 EVENT: RESOLVE
2018-04-04 11:31:02 EVENT: CONNECTION_TIMEOUT [ERR]
2018-04-04 11:31:02 Raw stats on disconnect:
  BYTES_IN : 31786
  BYTES_OUT : 25960
  PACKETS_IN : 145
  PACKETS_OUT : 162
  TUN_BYTES_IN : 4992
  TUN_BYTES_OUT : 7702
  TUN_PACKETS_IN : 62
  TUN_PACKETS_OUT : 50
  CONNECTION_TIMEOUT : 1
  N_PAUSE : 12
  N_RECONNECT : 13
2018-04-04 11:31:02 Performance stats on disconnect:
  CPU usage (microseconds): 961558
  Tunnel compression ratio (uplink): 5.20032
  Tunnel compression ratio (downlink): 4.12698
  Network bytes per CPU second: 60054
  Tunnel bytes per CPU second: 13201
2018-04-04 11:31:02 EVENT: DISCONNECTED
2018-04-04 11:31:02 Raw stats on disconnect:
  BYTES_IN : 31786
  BYTES_OUT : 25960
  PACKETS_IN : 145
  PACKETS_OUT : 162
  TUN_BYTES_IN : 4992
  TUN_BYTES_OUT : 7702
  TUN_PACKETS_IN : 62
  TUN_PACKETS_OUT : 50
  CONNECTION_TIMEOUT : 1
  N_PAUSE : 12
  N_RECONNECT : 13
2018-04-04 11:31:02 Performance stats on disconnect:
  CPU usage (microseconds): 961558
  Tunnel compression ratio (uplink): 5.20032
  Tunnel compression ratio (downlink): 4.12698
  Network bytes per CPU second: 60054
  Tunnel bytes per CPU second: 13201

comment:23 Changed 3 months ago by alfxmpp

Here is my recent log (with possibly private info removed):

OpenVPN log
...
2018-04-05 12:11:53 OS Event: WAKEUP
2018-04-05 12:11:56 RESUME TEST: Internet:ReachableViaWiFi/-R t------
2018-04-05 12:11:56 STANDARD RESUME
2018-04-05 12:11:56 EVENT: RESUME
2018-04-05 12:11:56 EVENT: RECONNECTING
2018-04-05 12:11:56 EVENT: RESOLVE
2018-04-05 12:11:56 Contacting [my.server.ip]:myport/TCP via TCP
2018-04-05 12:11:56 EVENT: WAIT
2018-04-05 12:11:56 Connecting to [server.addressname]:myport (my.server.ip) via TCPv4
2018-04-05 12:11:57 EVENT: CONNECTING
2018-04-05 12:11:57 Tunnel Options:V4,dev-type tun,link-mtu 1560,tun-mtu 1500,proto TCPv4_CLIENT,comp-lzo,keydir 1,cipher AES-128-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-client
2018-04-05 12:11:57 Creds: UsernameEmpty/PasswordEmpty?
2018-04-05 12:11:57 Peer Info:
IV_GUI_VER=net.openvpn.connect.ios 1.2.9-0
IV_VER=3.2
IV_PLAT=ios
IV_NCP=2
IV_TCPNL=1
IV_PROTO=2
IV_LZO=1
IV_LZO_SWAP=1
IV_LZ4=1
IV_LZ4v2=1
IV_COMP_STUB=1
IV_COMP_STUBv2=1
IV_AUTO_SESS=1
UV_ID=myuvid
UV_NAME=myuvname
IV_HWADDR=myhwaddr

2018-04-05 12:11:57 VERIFY OK : depth=1
cert. version : 3
serial number : 4B:78:14:41:94:08:71:F4
issuer name : O=oname, CN=cnname
subject name : O=oname, CN=cnname
issued on : 2017-09-01 17:38:53
expires on : 2027-09-01 17:38:53
signed using : RSA with SHA-256
RSA key size : 4096 bits
basic constraints : CA=true
key usage : Key Cert Sign, CRL Sign

2018-04-05 12:11:57 VERIFY OK : depth=0
cert. version : 3
serial number : FA:4C:1B:81:FD:6C:6A:F3
issuer name : O=oname, CN=cnname
subject name : O=oname, CN=cnname
issued on : 2017-09-01 17:38:58
expires on : 2027-09-01 17:38:58
signed using : RSA with SHA-256
RSA key size : 4096 bits
basic constraints : CA=false
key usage : Digital Signature, Key Encipherment
ext key usage : TLS Web Server Authentication, TLS Web Client Authentication

2018-04-05 12:11:57 SSL Handshake: TLSv1.2/TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384
2018-04-05 12:11:57 Session is ACTIVE
2018-04-05 12:11:57 EVENT: GET_CONFIG
2018-04-05 12:11:57 Sending PUSH_REQUEST to server...
2018-04-05 12:11:57 OPTIONS:
0 [ping] [10]
1 [ping-restart] [60]
2 [comp-lzo] [no]
3 [route] [172.16.2.0] [255.255.255.0]
4 [route] [172.27.224.0] [255.255.255.0]
5 [route-gateway] [172.27.220.1]
6 [topology] [subnet]
7 [ifconfig] [172.27.220.47] [255.255.255.0]
8 [peer-id] [0]
9 [cipher] [AES-128-GCM]

2018-04-05 12:11:57 PROTOCOL OPTIONS:

cipher: AES-128-GCM
digest: SHA1
compress: LZO_STUB
peer ID: 0

2018-04-05 12:11:57 EVENT: ASSIGN_IP
2018-04-05 12:11:57 NIP: preparing TUN network settings
2018-04-05 12:11:57 NIP: init TUN network settings with endpoint: my.server.ip
2018-04-05 12:11:57 NIP: adding IPv4 address to network settings 172.27.220.47/255.255.255.0
2018-04-05 12:11:57 NIP: adding (included) IPv4 route 172.27.220.0/24
2018-04-05 12:11:57 NIP: adding (included) IPv4 route 172.16.2.0/24
2018-04-05 12:11:57 NIP: adding (included) IPv4 route 172.27.224.0/24
2018-04-05 12:11:57 Connected via NetworkExtensionTUN
2018-04-05 12:11:57 LZO-ASYM init swap=0 asym=1
2018-04-05 12:11:57 Comp-stub init swap=0
2018-04-05 12:11:57 EVENT: CONNECTED @server.addressname:myport (my.server.ip) via /TCPv4 on NetworkExtensionTUN/172.27.220.47/ gw=/
2018-04-05 12:16:18 OS Event: SLEEP
2018-04-05 12:16:18 EVENT: PAUSE
2018-04-05 12:16:20 OS Event: WAKEUP
2018-04-05 12:16:23 RESUME TEST: Internet:ReachableViaWiFi/-R t------
2018-04-05 12:16:23 STANDARD RESUME
2018-04-05 12:16:23 EVENT: RESUME
2018-04-05 12:16:23 EVENT: RECONNECTING
2018-04-05 12:16:23 EVENT: RESOLVE
2018-04-05 12:16:23 OS Event: SLEEP
2018-04-05 12:16:23 EVENT: PAUSE
2018-04-05 12:16:51 OS Event: WAKEUP
2018-04-05 12:16:54 RESUME TEST: Internet:ReachableViaWiFi/-R t------
2018-04-05 12:16:54 STANDARD RESUME
2018-04-05 12:16:54 EVENT: RESUME
2018-04-05 12:16:54 EVENT: RECONNECTING
2018-04-05 12:16:54 EVENT: RESOLVE
2018-04-05 12:16:58 Server poll timeout, trying next remote entry...
2018-04-05 12:16:58 EVENT: RECONNECTING
2018-04-05 12:16:58 EVENT: RESOLVE
2018-04-05 12:17:02 Server poll timeout, trying next remote entry...
2018-04-05 12:17:02 EVENT: RECONNECTING
2018-04-05 12:17:02 EVENT: RESOLVE
2018-04-05 12:17:06 Server poll timeout, trying next remote entry...
2018-04-05 12:17:06 EVENT: RECONNECTING
2018-04-05 12:17:06 EVENT: RESOLVE
2018-04-05 12:17:11 Server poll timeout, trying next remote entry...
2018-04-05 12:17:11 EVENT: RECONNECTING
2018-04-05 12:17:11 EVENT: RESOLVE
2018-04-05 12:17:15 Server poll timeout, trying next remote entry...
2018-04-05 12:17:15 EVENT: RECONNECTING
2018-04-05 12:17:15 EVENT: RESOLVE
2018-04-05 12:17:20 Server poll timeout, trying next remote entry...
2018-04-05 12:17:20 EVENT: RECONNECTING
2018-04-05 12:17:20 EVENT: RESOLVE
2018-04-05 12:17:24 Server poll timeout, trying next remote entry...
2018-04-05 12:17:24 EVENT: RECONNECTING
2018-04-05 12:17:24 EVENT: RESOLVE
2018-04-05 12:17:28 Server poll timeout, trying next remote entry...
2018-04-05 12:17:28 EVENT: RECONNECTING
2018-04-05 12:17:28 EVENT: RESOLVE
2018-04-05 12:17:32 Server poll timeout, trying next remote entry...
2018-04-05 12:17:32 EVENT: RECONNECTING
2018-04-05 12:17:32 EVENT: RESOLVE
2018-04-05 12:17:37 Server poll timeout, trying next remote entry...
2018-04-05 12:17:37 EVENT: RECONNECTING
2018-04-05 12:17:37 EVENT: RESOLVE
2018-04-05 12:17:41 Server poll timeout, trying next remote entry...
2018-04-05 12:17:41 EVENT: RECONNECTING
2018-04-05 12:17:41 EVENT: RESOLVE
2018-04-05 12:17:45 Server poll timeout, trying next remote entry...
2018-04-05 12:17:45 EVENT: RECONNECTING
2018-04-05 12:17:45 EVENT: RESOLVE
2018-04-05 12:17:50 Server poll timeout, trying next remote entry...
2018-04-05 12:17:50 EVENT: RECONNECTING
2018-04-05 12:17:50 EVENT: RESOLVE
2018-04-05 12:17:54 Server poll timeout, trying next remote entry...
2018-04-05 12:17:54 EVENT: RECONNECTING
2018-04-05 12:17:54 EVENT: RESOLVE
2018-04-05 12:17:58 Server poll timeout, trying next remote entry...
2018-04-05 12:17:58 EVENT: RECONNECTING
2018-04-05 12:17:58 EVENT: RESOLVE
2018-04-05 12:18:03 Server poll timeout, trying next remote entry...
2018-04-05 12:18:03 EVENT: RECONNECTING
2018-04-05 12:18:03 EVENT: RESOLVE
2018-04-05 12:18:07 Server poll timeout, trying next remote entry...
2018-04-05 12:18:07 EVENT: RECONNECTING
2018-04-05 12:18:07 EVENT: RESOLVE
2018-04-05 12:18:12 Server poll timeout, trying next remote entry...
2018-04-05 12:18:12 EVENT: RECONNECTING
2018-04-05 12:18:12 EVENT: RESOLVE
2018-04-05 12:18:16 Server poll timeout, trying next remote entry...
2018-04-05 12:18:16 EVENT: RECONNECTING
2018-04-05 12:18:16 EVENT: RESOLVE
2018-04-05 12:18:20 Server poll timeout, trying next remote entry...
2018-04-05 12:18:20 EVENT: RECONNECTING
2018-04-05 12:18:20 EVENT: RESOLVE
2018-04-05 12:18:25 Server poll timeout, trying next remote entry...
2018-04-05 12:18:25 EVENT: RECONNECTING
2018-04-05 12:18:25 EVENT: RESOLVE
2018-04-05 12:18:29 Server poll timeout, trying next remote entry...
2018-04-05 12:18:29 EVENT: RECONNECTING
2018-04-05 12:18:29 EVENT: RESOLVE
2018-04-05 12:18:33 Server poll timeout, trying next remote entry...
2018-04-05 12:18:33 EVENT: RECONNECTING
2018-04-05 12:18:33 EVENT: RESOLVE
2018-04-05 12:18:38 Server poll timeout, trying next remote entry...
2018-04-05 12:18:38 EVENT: RECONNECTING
2018-04-05 12:18:38 EVENT: RESOLVE
2018-04-05 12:18:42 Server poll timeout, trying next remote entry...
2018-04-05 12:18:42 EVENT: RECONNECTING
2018-04-05 12:18:42 EVENT: RESOLVE
2018-04-05 12:18:46 Server poll timeout, trying next remote entry...
2018-04-05 12:18:46 EVENT: RECONNECTING
2018-04-05 12:18:46 EVENT: RESOLVE
2018-04-05 12:18:51 Server poll timeout, trying next remote entry...
2018-04-05 12:18:51 EVENT: RECONNECTING
2018-04-05 12:18:51 EVENT: RESOLVE
2018-04-05 12:18:55 Server poll timeout, trying next remote entry...
2018-04-05 12:18:55 EVENT: RECONNECTING
2018-04-05 12:18:55 EVENT: RESOLVE
2018-04-05 12:18:59 Server poll timeout, trying next remote entry...
2018-04-05 12:18:59 EVENT: RECONNECTING
2018-04-05 12:18:59 EVENT: RESOLVE
2018-04-05 12:19:04 Server poll timeout, trying next remote entry...
2018-04-05 12:19:04 EVENT: RECONNECTING
2018-04-05 12:19:04 EVENT: RESOLVE
2018-04-05 12:19:08 Server poll timeout, trying next remote entry...
2018-04-05 12:19:08 EVENT: RECONNECTING
2018-04-05 12:19:08 EVENT: RESOLVE
2018-04-05 12:19:12 Server poll timeout, trying next remote entry...
2018-04-05 12:19:12 EVENT: RECONNECTING
2018-04-05 12:19:12 EVENT: RESOLVE
2018-04-05 12:19:16 Server poll timeout, trying next remote entry...
2018-04-05 12:19:16 EVENT: RECONNECTING
2018-04-05 12:19:16 EVENT: RESOLVE
2018-04-05 12:19:21 Server poll timeout, trying next remote entry...
2018-04-05 12:19:21 EVENT: RECONNECTING
2018-04-05 12:19:21 EVENT: RESOLVE
2018-04-05 12:19:25 Server poll timeout, trying next remote entry...
2018-04-05 12:19:25 EVENT: RECONNECTING
2018-04-05 12:19:25 EVENT: RESOLVE
2018-04-05 12:19:29 Server poll timeout, trying next remote entry...
2018-04-05 12:19:29 EVENT: RECONNECTING
2018-04-05 12:19:29 EVENT: RESOLVE
2018-04-05 12:19:33 Server poll timeout, trying next remote entry...
2018-04-05 12:19:33 EVENT: RECONNECTING
2018-04-05 12:19:33 EVENT: RESOLVE
2018-04-05 12:19:38 Server poll timeout, trying next remote entry...
2018-04-05 12:19:38 EVENT: RECONNECTING
2018-04-05 12:19:38 EVENT: RESOLVE
2018-04-05 12:19:42 Server poll timeout, trying next remote entry...
2018-04-05 12:19:42 EVENT: RECONNECTING
2018-04-05 12:19:42 EVENT: RESOLVE
2018-04-05 12:19:46 Server poll timeout, trying next remote entry...
2018-04-05 12:19:46 EVENT: RECONNECTING
2018-04-05 12:19:46 EVENT: RESOLVE
2018-04-05 12:19:50 Server poll timeout, trying next remote entry...
2018-04-05 12:19:50 EVENT: RECONNECTING
2018-04-05 12:19:50 EVENT: RESOLVE
2018-04-05 12:19:55 Server poll timeout, trying next remote entry...
2018-04-05 12:19:55 EVENT: RECONNECTING
2018-04-05 12:19:55 EVENT: RESOLVE
2018-04-05 12:19:59 Server poll timeout, trying next remote entry...
2018-04-05 12:19:59 EVENT: RECONNECTING
2018-04-05 12:19:59 EVENT: RESOLVE
2018-04-05 12:20:04 Server poll timeout, trying next remote entry...
2018-04-05 12:20:04 EVENT: RECONNECTING
2018-04-05 12:20:04 EVENT: RESOLVE
2018-04-05 12:20:08 Server poll timeout, trying next remote entry...
2018-04-05 12:20:08 EVENT: RECONNECTING
2018-04-05 12:20:08 EVENT: RESOLVE
2018-04-05 12:20:12 Server poll timeout, trying next remote entry...
2018-04-05 12:20:12 EVENT: RECONNECTING
2018-04-05 12:20:12 EVENT: RESOLVE
2018-04-05 12:20:16 Server poll timeout, trying next remote entry...
2018-04-05 12:20:16 EVENT: RECONNECTING
2018-04-05 12:20:16 EVENT: RESOLVE
2018-04-05 12:20:20 Server poll timeout, trying next remote entry...
2018-04-05 12:20:20 EVENT: RECONNECTING
2018-04-05 12:20:20 EVENT: RESOLVE
2018-04-05 12:20:25 Server poll timeout, trying next remote entry...
2018-04-05 12:20:25 EVENT: RECONNECTING
2018-04-05 12:20:25 EVENT: RESOLVE
2018-04-05 12:20:29 Server poll timeout, trying next remote entry...
2018-04-05 12:20:29 EVENT: RECONNECTING
2018-04-05 12:20:29 EVENT: RESOLVE
2018-04-05 12:20:33 Server poll timeout, trying next remote entry...
2018-04-05 12:20:33 EVENT: RECONNECTING
2018-04-05 12:20:33 EVENT: RESOLVE
2018-04-05 12:20:38 Server poll timeout, trying next remote entry...
2018-04-05 12:20:38 EVENT: RECONNECTING
2018-04-05 12:20:38 EVENT: RESOLVE
2018-04-05 12:20:42 Server poll timeout, trying next remote entry...
2018-04-05 12:20:42 EVENT: RECONNECTING
2018-04-05 12:20:42 EVENT: RESOLVE
2018-04-05 12:20:46 Server poll timeout, trying next remote entry...
2018-04-05 12:20:46 EVENT: RECONNECTING
2018-04-05 12:20:46 EVENT: RESOLVE
2018-04-05 12:20:50 Server poll timeout, trying next remote entry...
2018-04-05 12:20:50 EVENT: RECONNECTING
2018-04-05 12:20:50 EVENT: RESOLVE
2018-04-05 12:20:55 Server poll timeout, trying next remote entry...
2018-04-05 12:20:55 EVENT: RECONNECTING
2018-04-05 12:20:55 EVENT: RESOLVE
2018-04-05 12:20:59 Server poll timeout, trying next remote entry...
2018-04-05 12:20:59 EVENT: RECONNECTING
2018-04-05 12:20:59 EVENT: RESOLVE
2018-04-05 12:21:03 Server poll timeout, trying next remote entry...
2018-04-05 12:21:03 EVENT: RECONNECTING
2018-04-05 12:21:03 EVENT: RESOLVE
2018-04-05 12:21:08 Server poll timeout, trying next remote entry...
2018-04-05 12:21:08 EVENT: RECONNECTING
2018-04-05 12:21:08 EVENT: RESOLVE
2018-04-05 12:21:12 Server poll timeout, trying next remote entry...
2018-04-05 12:21:12 EVENT: RECONNECTING
2018-04-05 12:21:12 EVENT: RESOLVE
2018-04-05 12:21:17 Server poll timeout, trying next remote entry...
2018-04-05 12:21:17 EVENT: RECONNECTING
2018-04-05 12:21:17 EVENT: RESOLVE
2018-04-05 12:21:21 Server poll timeout, trying next remote entry...
2018-04-05 12:21:21 EVENT: RECONNECTING
2018-04-05 12:21:21 EVENT: RESOLVE
2018-04-05 12:21:25 Server poll timeout, trying next remote entry...
2018-04-05 12:21:25 EVENT: RECONNECTING
2018-04-05 12:21:25 EVENT: RESOLVE
2018-04-05 12:21:29 Server poll timeout, trying next remote entry...
2018-04-05 12:21:29 EVENT: RECONNECTING
2018-04-05 12:21:29 EVENT: RESOLVE
2018-04-05 12:21:34 Server poll timeout, trying next remote entry...
2018-04-05 12:21:34 EVENT: RECONNECTING
2018-04-05 12:21:34 EVENT: RESOLVE
2018-04-05 12:21:38 Server poll timeout, trying next remote entry...
2018-04-05 12:21:38 EVENT: RECONNECTING
2018-04-05 12:21:38 EVENT: RESOLVE
2018-04-05 12:21:42 Server poll timeout, trying next remote entry...
2018-04-05 12:21:42 EVENT: RECONNECTING
2018-04-05 12:21:42 EVENT: RESOLVE
2018-04-05 12:21:47 Server poll timeout, trying next remote entry...
2018-04-05 12:21:47 EVENT: RECONNECTING
2018-04-05 12:21:47 EVENT: RESOLVE
2018-04-05 12:21:51 Server poll timeout, trying next remote entry...
2018-04-05 12:21:51 EVENT: RECONNECTING
2018-04-05 12:21:51 EVENT: RESOLVE
2018-04-05 12:21:56 Server poll timeout, trying next remote entry...
2018-04-05 12:21:56 EVENT: RECONNECTING
2018-04-05 12:21:56 EVENT: RESOLVE
2018-04-05 12:21:58 OS Event: SLEEP
2018-04-05 12:21:58 EVENT: PAUSE
2018-04-05 12:22:20 OS Event: WAKEUP
2018-04-05 12:22:23 RESUME TEST: Internet:ReachableViaWiFi/-R t------
2018-04-05 12:22:23 STANDARD RESUME
2018-04-05 12:22:23 EVENT: RESUME
2018-04-05 12:22:23 EVENT: RECONNECTING
2018-04-05 12:22:23 EVENT: RESOLVE
2018-04-05 12:22:23 OS Event: SLEEP
2018-04-05 12:22:23 EVENT: PAUSE
2018-04-05 12:27:02 OS Event: WAKEUP
2018-04-05 12:27:05 RESUME TEST: Internet:ReachableViaWiFi/-R t------
2018-04-05 12:27:05 STANDARD RESUME
2018-04-05 12:27:05 EVENT: RESUME
2018-04-05 12:27:05 EVENT: RECONNECTING
2018-04-05 12:27:05 EVENT: RESOLVE
2018-04-05 12:27:10 Server poll timeout, trying next remote entry...
2018-04-05 12:27:10 EVENT: RECONNECTING
2018-04-05 12:27:10 EVENT: RESOLVE
2018-04-05 12:27:14 Server poll timeout, trying next remote entry...
2018-04-05 12:27:14 EVENT: RECONNECTING
2018-04-05 12:27:14 EVENT: RESOLVE
2018-04-05 12:27:18 Server poll timeout, trying next remote entry...
2018-04-05 12:27:18 EVENT: RECONNECTING
2018-04-05 12:27:18 EVENT: RESOLVE
2018-04-05 12:27:22 Server poll timeout, trying next remote entry...
2018-04-05 12:27:22 EVENT: RECONNECTING
2018-04-05 12:27:22 EVENT: RESOLVE
2018-04-05 12:27:27 Server poll timeout, trying next remote entry...
2018-04-05 12:27:27 EVENT: RECONNECTING
2018-04-05 12:27:27 EVENT: RESOLVE

Note: See TracTickets for help on using tickets.