VPN met WatchGuard disconnect direct na verbinden

Status
Niet open voor verdere reacties.
B

bomfunkmcs

Gebruiker
Lid geworden
6 okt 2010
Berichten
30
Bij het verbinden met de VPN tool van Watchguard (Watchguard tool met SSL) word de verbinding direct verbroken op het moment dat die normaal verbonden zou moeten zijn. Dit gebeurt bij 1 persoon, anderen ondervinden hier geen last van. Ook heeft het voorheen prima gewerkt en is er volgens zeggen niks in het netwerk bij de desbetreffende persoon veranderd.

Het gaat hier om een VPN verbinding met een WatchGuard firewall. Voor de kenners hieronder de log van het verbinden. Ik heb het IP adres even veranderd naar 111.111.111.111 dit is dus geen fout ip, maar opzettelijk veranderd in de log file. Weet iemand wat hier mis gaat?
Code: 
2012-07-20T08:57:57.937 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:57:57.937 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:57.937 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:57.937 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:57.937 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:57.937 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:57.937 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:57.953 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:57:57.953 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:57:57.968 OVPN:>LOG:1342767474,D,[0] ev=0x00000104 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:57.968 OVPN:>LOG:1342767474,D,[1] ev=0x00000134 rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:57.968 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:57.968 OVPN:>LOG:1342767474,,872 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:57.968 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [22] from 111.111.111.111:8443: P_ACK_V1 kid=0 sid=75678a19 4b656226 [ 17 sid=e3f5847b 1f826a70 ]

2012-07-20T08:57:57.984 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_ACK_V1, IP=111.111.111.111:8443

2012-07-20T08:57:57.984 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:57.984 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:57.984 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:57.984 OVPN:>LOG:1342767474,D,ACK received for pid 17, deleting from send buffer

2012-07-20T08:57:57.984 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=3 current=0 : [21] 18 19 20

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,BIO read tls_read_ciphertext 100 bytes

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,ACK mark active outgoing ID 21

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,Outgoing Ciphertext -> Reliable

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=4 current=1 : [22] 18 19 20 21

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,ACK reliable_send ID 21 (size=104 to=2)

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,Reliable -> TCP/UDP

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [22] 18 19 20 21

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:57:58.000 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.187 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.203 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.203 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.203 OVPN:>LOG:1342767474,,9 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.203 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [22] from 111.111.111.111:8443: P_ACK_V1 kid=0 sid=75678a19 4b656226 [ 18 sid=e3f5847b 1f826a70 ]

2012-07-20T08:57:58.203 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_ACK_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.203 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.203 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.234 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.234 OVPN:>LOG:1342767474,D,ACK received for pid 18, deleting from send buffer

2012-07-20T08:57:58.234 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.234 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=114 wakeup=604800

2012-07-20T08:57:58.234 OVPN:>LOG:1342767474,D,BIO read tls_read_ciphertext 100 bytes

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,ACK mark active outgoing ID 22

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,Outgoing Ciphertext -> Reliable

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=114 wakeup=604800

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,ACK output sequence broken: [23] 22 19 20 21

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 0 [23] 22 19 20 21

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 1

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,STREAM: GET NEXT len=1591

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Receive immediate return [1591,24]

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,,13 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [114] to 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=e3f5847b 1f826a70 [ ] pid=21 DATA c504ba52 254259d6 71465e14 1e4017af 5a5abc6e 1dfdebe0 197cdd3f ae22331[more...]

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,STREAM: WRITE 114 offset=26

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [116]

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [116,116]

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 116

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=4 current=1 : [23] 22 19 20 21

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,ACK reliable_send ID 22 (size=104 to=2)

2012-07-20T08:57:58.250 OVPN:>LOG:1342767474,D,Reliable -> TCP/UDP

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [23] 22 19 20 21

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0003 arg=0x004775e4

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,WE_CTL n=3 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SR1|SW1 [1/244559]

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,WE_WAIT enter n=5 to=1245

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,[0] ev=0x00000104 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,,8 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [114] to 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=e3f5847b 1f826a70 [ ] pid=22 DATA 9efd97c3 2e4b3cb7 39356e83 a279a4c2 99b37143 00254824 bc2b1a9f 5c51579[more...]

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,STREAM: WRITE 114 offset=26

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [116]

2012-07-20T08:57:58.265 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [116,116]

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 116

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=4 current=0 : [23] 22 19 20 21

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,ACK output sequence broken: [23] 22 19 20 21

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [23] 22 19 20 21

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SR1|Sw1 [1/244559]

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,[0] ev=0x00000104 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,[1] ev=0x00000134 rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,,11 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [22] from 111.111.111.111:8443: P_ACK_V1 kid=0 sid=75678a19 4b656226 [ 19 sid=e3f5847b 1f826a70 ]

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_ACK_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,ACK received for pid 19, deleting from send buffer

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.281 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=3 current=0 : [23] 22 20 21

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,BIO read tls_read_ciphertext 58 bytes

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,ACK mark active outgoing ID 23

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,Outgoing Ciphertext -> Reliable

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=4 current=1 : [24] 22 23 20 21

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,ACK reliable_send ID 23 (size=62 to=2)

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,Reliable -> TCP/UDP

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [24] 22 23 20 21

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,,15 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [72] to 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=e3f5847b 1f826a70 [ ] pid=23 DATA 03010001 01160301 00302b82 cc7ba472 718f595c 5461be4d e2740feb 17b6c28[more...]

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,STREAM: WRITE 72 offset=26

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [116]

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [74,74]

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 74

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=4 current=0 : [24] 22 23 20 21

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,ACK output sequence broken: [24] 22 23 20 21

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [24] 22 23 20 21

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.296 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,[0] ev=0x00000104 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,[1] ev=0x00000134 rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,,11 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [22] from 111.111.111.111:8443: P_ACK_V1 kid=0 sid=75678a19 4b656226 [ 20 sid=e3f5847b 1f826a70 ]

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_ACK_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,ACK received for pid 20, deleting from send buffer

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=3 current=0 : [24] 22 23 21

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [24] 22 23 21

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,STREAM: GET NEXT len=1591

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Receive queued [1591]

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:57:58.312 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,,13 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [22] from 111.111.111.111:8443: P_ACK_V1 kid=0 sid=75678a19 4b656226 [ 21 sid=e3f5847b 1f826a70 ]

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_ACK_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,ACK received for pid 21, deleting from send buffer

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=2 current=0 : [24] 22 23

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [24] 22 23

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,STREAM: GET NEXT len=1591

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Receive queued [1591]

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:57:58.328 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,,13 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [22] from 111.111.111.111:8443: P_ACK_V1 kid=0 sid=75678a19 4b656226 [ 22 sid=e3f5847b 1f826a70 ]

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_ACK_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,ACK received for pid 22, deleting from send buffer

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=1 current=0 : [24] 23

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [24] 23

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,STREAM: ADD length_added=0

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,STREAM: ADD returned TRUE, buf_len=126, residual_len=348

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,STREAM: RESIDUAL FULLY FORMED [YES], len=348

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0000 arg=0x004775e4

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,I/O WAIT status=0x0001

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,STREAM: GET FINAL len=126

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,,2 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [126] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ 23 sid=e3f5847b 1f826a70 ] pid=26 DATA 16030103 aa040003 a6000000 0003a071 4109d8b2 76110e30 93e206da 417e5b3[more...]

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.343 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK received for pid 23, deleting from send buffer

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK read ID 26 (buf->len=100)

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=0000001a id=0000001a ret=1
2012-07-20T08:57:58.359 OVPN:

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 26

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK acknowledge ID 26 (ack->len=1)

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 100 bytes

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK write ID 26 (ack->len=1, n=1)

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,Dedicated ACK -> TCP/UDP

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,,12 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [114] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ ] pid=27 DATA c525397a f9a70080 abe0bb57 e0b4db03 915936fc d32897cf 16f18593 8a7276a[more...]

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK read ID 27 (buf->len=100)

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=0000001b id=0000001b ret=1
2012-07-20T08:57:58.359 OVPN:

2012-07-20T08:57:58.359 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 27

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,ACK acknowledge ID 27 (ack->len=1)

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 100 bytes

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,STREAM: ADD length_added=0

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,STREAM: ADD returned TRUE, buf_len=114, residual_len=116

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,,7 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [114] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ ] pid=28 DATA 86729377 8325790b 157e4fa0 5df230e5 0a26d76a d7cfb68c bf6775e1 0a4129d[more...]

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,ACK read ID 28 (buf->len=100)

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=0000001c id=0000001c ret=1
2012-07-20T08:57:58.375 OVPN:

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 28

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,ACK acknowledge ID 28 (ack->len=2)

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800

2012-07-20T08:57:58.375 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 100 bytes

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,STREAM: ADD length_added=0

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,STREAM: ADD returned TRUE, buf_len=114, residual_len=0

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,,7 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [114] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ ] pid=29 DATA 5fb73895 66efc639 8b3ea2cd a5d309b6 0da80851 55c22f9f be76eacd 929d00b[more...]

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,ACK read ID 29 (buf->len=100)

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=0000001d id=0000001d ret=1
2012-07-20T08:57:58.390 OVPN:

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 29

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,ACK acknowledge ID 29 (ack->len=3)

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 100 bytes

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.390 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,STREAM: GET NEXT len=1591

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,,13 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [22] to 111.111.111.111:8443: P_ACK_V1 kid=0 sid=e3f5847b 1f826a70 [ 26 sid=75678a19 4b656226 ]

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,STREAM: WRITE 22 offset=31

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [74]

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [24,24]

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 24

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,ACK write ID 27 (ack->len=3, n=3)

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,ACK write ID 28 (ack->len=3, n=3)

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,ACK write ID 29 (ack->len=3, n=3)

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,Dedicated ACK -> TCP/UDP

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0003 arg=0x004775e4

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,WE_CTL n=3 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|SW1 [1/244559]

2012-07-20T08:57:58.406 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,,9 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [30] to 111.111.111.111:8443: P_ACK_V1 kid=0 sid=e3f5847b 1f826a70 [ 27 28 29 sid=75678a19 4b656226 ]

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,STREAM: WRITE 30 offset=31

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [24]

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [32,32]

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 32

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,[0] ev=0x00000104 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,[1] ev=0x00000134 rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,[2] ev=0x00000110 rwflags=0x0002 arg=0x004775f0

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,,10 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [114] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ ] pid=30 DATA b7c853e8 26effbad 0fc3ce80 1122c48c 18d705a6 79151c3a de37c7ed f17eaa7[more...]

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.421 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,ACK read ID 30 (buf->len=100)

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=0000001e id=0000001e ret=1
2012-07-20T08:57:58.437 OVPN:

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 30

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,ACK acknowledge ID 30 (ack->len=1)

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 100 bytes

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,ACK write ID 30 (ack->len=1, n=1)

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,Dedicated ACK -> TCP/UDP

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,,17 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [22] to 111.111.111.111:8443: P_ACK_V1 kid=0 sid=e3f5847b 1f826a70 [ 30 sid=75678a19 4b656226 ]

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,STREAM: WRITE 22 offset=31

2012-07-20T08:57:58.437 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [32]

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [24,24]

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 24

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,[0] ev=0x00000104 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,[1] ev=0x00000134 rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,[2] ev=0x00000110 rwflags=0x0002 arg=0x004775f0

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,,10 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [114] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ ] pid=31 DATA 003a3576 ad0ec629 253ed161 17286baf 8e8e79f9 4ac62f2d 07a1ff83 ddbe1ad[more...]

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,ACK read ID 31 (buf->len=100)

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=0000001f id=0000001f ret=1
2012-07-20T08:57:58.453 OVPN:

2012-07-20T08:57:58.453 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 31

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,ACK acknowledge ID 31 (ack->len=1)

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 100 bytes

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,ACK write ID 31 (ack->len=1, n=1)

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,Dedicated ACK -> TCP/UDP

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,,11 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [114] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ ] pid=32 DATA e2cac1e5 68d29b83 b3b3007f 0910d878 5599c845 e1525374 82f1fa1d 2bad0d5[more...]

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,ACK read ID 32 (buf->len=100)

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=00000020 id=00000020 ret=1
2012-07-20T08:57:58.468 OVPN:

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 32

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,ACK acknowledge ID 32 (ack->len=1)

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800

2012-07-20T08:57:58.468 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 100 bytes

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,STREAM: ADD length_added=0

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,STREAM: ADD returned TRUE, buf_len=114, residual_len=0

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,,7 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [114] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ ] pid=33 DATA f601f7e0 9e74fcea af21441a e45be8c5 422987fa bf5cc4c1 6567c726 dbf8f3c[more...]

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,ACK read ID 33 (buf->len=100)

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=00000021 id=00000021 ret=1
2012-07-20T08:57:58.484 OVPN:

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 33

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,ACK acknowledge ID 33 (ack->len=2)

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 100 bytes

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.484 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,STREAM: GET NEXT len=1591

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,,13 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [22] to 111.111.111.111:8443: P_ACK_V1 kid=0 sid=e3f5847b 1f826a70 [ 31 sid=75678a19 4b656226 ]

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,STREAM: WRITE 22 offset=31

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [24]

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [24,24]

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 24

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,ACK write ID 32 (ack->len=2, n=2)

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,ACK write ID 33 (ack->len=2, n=2)

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,Dedicated ACK -> TCP/UDP

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0003 arg=0x004775e4

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,WE_CTL n=3 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|SW1 [1/244559]

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,D,WE_WAIT enter n=5 to=1245

2012-07-20T08:57:58.500 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,,8 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [26] to 111.111.111.111:8443: P_ACK_V1 kid=0 sid=e3f5847b 1f826a70 [ 32 33 sid=75678a19 4b656226 ]

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,STREAM: WRITE 26 offset=31

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [24]

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [28,28]

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 28

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,[0] ev=0x00000104 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,[1] ev=0x00000134 rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,[2] ev=0x00000110 rwflags=0x0002 arg=0x004775f0

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,,10 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [114] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ ] pid=34 DATA 18ed85ac 36f099c1 a6c198c3 b73c5ee4 316b7703 835c5704 ed863f17 d1e980d[more...]

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.515 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,ACK read ID 34 (buf->len=100)

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=00000022 id=00000022 ret=1
2012-07-20T08:57:58.531 OVPN:

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 34

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,ACK acknowledge ID 34 (ack->len=1)

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 100 bytes

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,ACK write ID 34 (ack->len=1, n=1)

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,Dedicated ACK -> TCP/UDP

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,,17 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [22] to 111.111.111.111:8443: P_ACK_V1 kid=0 sid=e3f5847b 1f826a70 [ 34 sid=75678a19 4b656226 ]

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,STREAM: WRITE 22 offset=31

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [28]

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [24,24]

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 24

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:57:58.531 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,[0] ev=0x00000104 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,[1] ev=0x00000134 rwflags=0x0001 arg=0x004775e4

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,[2] ev=0x00000110 rwflags=0x0002 arg=0x004775f0

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,,10 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [114] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ ] pid=35 DATA 73b32453 c957c014 f8946ba7 eb5c6c23 f7ec4372 0262aef7 a87ac1cb df926c6[more...]

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,ACK read ID 35 (buf->len=100)

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=00000023 id=00000023 ret=1
2012-07-20T08:57:58.546 OVPN:

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 35

2012-07-20T08:57:58.546 OVPN:>LOG:1342767474,D,ACK acknowledge ID 35 (ack->len=1)

2012-07-20T08:57:58.562 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 100 bytes

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,SSL state (connect): SSLv3 read server session ticket A

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,ACK write ID 35 (ack->len=1, n=1)

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,Dedicated ACK -> TCP/UDP

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,,18 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [22] to 111.111.111.111:8443: P_ACK_V1 kid=0 sid=e3f5847b 1f826a70 [ 35 sid=75678a19 4b656226 ]

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,STREAM: WRITE 22 offset=31

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [24]

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [24,24]

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 24

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:00.859 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 604800 [24]

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 59

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,[0] ev=0x00000104 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,[1] ev=0x00000134 rwflags=0x0001 arg=0x004775e4

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,[2] ev=0x00000110 rwflags=0x0002 arg=0x004775f0

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,,10 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [16] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ ] pid=36 DATA 97fb

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_CONTROL_V1, IP=111.111.111.111:8443

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,ACK read ID 36 (buf->len=2)

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,ACK RWBS rel->size=8 rel->packet_id=00000024 id=00000024 ret=1
2012-07-20T08:58:00.875 OVPN:

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,ACK mark active incoming ID 36

2012-07-20T08:58:00.875 OVPN:>LOG:1342767474,D,ACK acknowledge ID 36 (ack->len=1)

2012-07-20T08:58:00.890 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:00.890 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:00.890 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=0 current=0 : [24]

2012-07-20T08:58:00.890 OVPN:>LOG:1342767474,D,BIO write tls_write_ciphertext 2 bytes

2012-07-20T08:58:00.890 OVPN:>LOG:1342767474,D,Incoming Ciphertext -> TLS

2012-07-20T08:58:00.890 OVPN:>LOG:1342767474,D,SSL state (connect): SSLv3 read finished A

2012-07-20T08:58:00.890 OVPN:>LOG:1342767474,D,BIO write tls_write_plaintext 273 bytes

2012-07-20T08:58:00.890 OVPN:>LOG:1342767474,D,Outgoing Plaintext -> TLS

2012-07-20T08:58:01.109 OVPN:>LOG:1342767474,D,BIO read tls_read_ciphertext 100 bytes

2012-07-20T08:58:01.109 OVPN:>LOG:1342767474,D,ACK mark active outgoing ID 24

2012-07-20T08:58:01.109 OVPN:>LOG:1342767474,D,Outgoing Ciphertext -> Reliable

2012-07-20T08:58:01.125 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.125 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:58:01.125 OVPN:>LOG:1342767474,,24 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:58:01.140 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [126] to 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=e3f5847b 1f826a70 [ 36 sid=75678a19 4b656226 ] pid=24 DATA 17030100 2084f290 cf03189e c44f6e18 89a4f9be 6826f567 857f4eb4 41e8a9a[more...]

2012-07-20T08:58:01.140 OVPN:>LOG:1342767474,D,STREAM: WRITE 126 offset=14

2012-07-20T08:58:01.171 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [24]

2012-07-20T08:58:01.171 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [128,128]

2012-07-20T08:58:01.171 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 128

2012-07-20T08:58:01.171 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:01.171 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.171 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=1 current=0 : [25] 24

2012-07-20T08:58:01.171 OVPN:>LOG:1342767474,D,BIO read tls_read_ciphertext 100 bytes

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,ACK mark active outgoing ID 25

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,Outgoing Ciphertext -> Reliable

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=2 current=1 : [26] 24 25

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,ACK reliable_send ID 25 (size=104 to=2)

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,Reliable -> TCP/UDP

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [26] 24 25

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,,12 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [114] to 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=e3f5847b 1f826a70 [ ] pid=25 DATA 8f6a597f 475c91c7 56ff7911 29cc978e f96d7333 9ecff22f 68bf7318 fff9e70[more...]

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,STREAM: WRITE 114 offset=26

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [128]

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [116,116]

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 116

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=2 current=0 : [26] 24 25

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,BIO read tls_read_ciphertext 100 bytes

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,ACK mark active outgoing ID 26

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,Outgoing Ciphertext -> Reliable

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=3 current=1 : [27] 24 25 26

2012-07-20T08:58:01.187 OVPN:>LOG:1342767474,D,ACK reliable_send ID 26 (size=104 to=2)

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,Reliable -> TCP/UDP

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [27] 24 25 26

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,,12 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [114] to 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=e3f5847b 1f826a70 [ ] pid=26 DATA 72378223 d02bd5f5 c6f6e710 ce41dcd9 a154d829 353058e0 64d12f3c 48a3f9a[more...]

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,STREAM: WRITE 114 offset=26

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [116]

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [116,116]

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 116

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=3 current=0 : [27] 24 25 26

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,BIO read tls_read_ciphertext 46 bytes

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,ACK mark active outgoing ID 27

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,Outgoing Ciphertext -> Reliable

2012-07-20T08:58:01.203 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=4 current=1 : [28] 24 25 26 27

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,ACK reliable_send ID 27 (size=50 to=2)

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,Reliable -> TCP/UDP

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [28] 24 25 26 27

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,,12 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,TCPv4_CLIENT WRITE [60] to 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=e3f5847b 1f826a70 [ ] pid=27 DATA 77a9bc4c 7b901ced ae3ba4b5 4231b468 795d1cca 0cf229e8 f1312a0c cc0c0c9[more...]

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,STREAM: WRITE 60 offset=26

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Completion non-queued success [116]

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Send immediate return [62,62]

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,TCPv4_CLIENT write returned 62

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=4 current=0 : [28] 24 25 26 27

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,ACK output sequence broken: [28] 24 25 26 27

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [28] 24 25 26 27

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:58:01.218 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,[0] ev=0x00000104 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,[1] ev=0x00000134 rwflags=0x0001 arg=0x004775e4

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,,11 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [22] from 111.111.111.111:8443: P_ACK_V1 kid=0 sid=75678a19 4b656226 [ 24 sid=e3f5847b 1f826a70 ]

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_ACK_V1, IP=111.111.111.111:8443

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,ACK received for pid 24, deleting from send buffer

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=3 current=0 : [28] 25 26 27

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [28] 25 26 27

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,STREAM: SET NEXT, buf=[96,0] next=[96,1591] len=-1 maxlen=1591

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,STREAM: GET NEXT len=1591

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,WIN32 I/O: Socket Receive queued [1591]

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0001 arg=0x004775e4

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,WE_CTL n=2 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,I/O WAIT T?|T?|SRQ|Sw1 [1/244559]

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,WE_WAIT enter n=4 to=1245

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,,13 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [22] from 111.111.111.111:8443: P_ACK_V1 kid=0 sid=75678a19 4b656226 [ 25 sid=e3f5847b 1f826a70 ]

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_ACK_V1, IP=111.111.111.111:8443

2012-07-20T08:58:01.234 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,ACK received for pid 25, deleting from send buffer

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=2 current=0 : [28] 26 27

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [28] 26 27

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,STREAM: ADD length_added=0

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,STREAM: ADD returned TRUE, buf_len=22, residual_len=326

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,STREAM: RESIDUAL FULLY FORMED [YES], len=326

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0000 arg=0x004775e4

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,I/O WAIT status=0x0001

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,STREAM: GET FINAL len=22

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,,2 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [22] from 111.111.111.111:8443: P_ACK_V1 kid=0 sid=75678a19 4b656226 [ 26 sid=e3f5847b 1f826a70 ]

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: control channel, op=P_ACK_V1, IP=111.111.111.111:8443

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, rec-sid=75678a19 4b656226, rec-ip=111.111.111.111:8443, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: found match, session[0], sid=75678a19 4b656226

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: received control channel packet s#=0 sid=75678a19 4b656226

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,ACK received for pid 26, deleting from send buffer

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=e3f5847b 1f826a70, stored-sid=75678a19 4b656226, stored-ip=111.111.111.111:8443

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800

2012-07-20T08:58:01.250 OVPN:>LOG:1342767474,D,ACK reliable_can_send active=1 current=0 : [28] 27

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,ACK reliable_send_timeout 2 [28] 27

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,TLS: tls_process: timeout set to 2

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=020779f6 15cfe8c8, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,WE_CTL n=0 ev=0x0049d0d4 rwflags=0x0001 arg=0x004775ec

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,STREAM: ADD length_added=0

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,STREAM: ADD returned TRUE, buf_len=126, residual_len=198

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,STREAM: RESIDUAL FULLY FORMED [YES], len=198

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00decbdc rwflags=0x0000 arg=0x004775e4

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,WE_CTL n=1 ev=0x00de5bc4 rwflags=0x0003 arg=0x004775f0

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,I/O WAIT status=0x0001

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,STREAM: GET FINAL len=126

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,,NOTE: --mute triggered...

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,,2 variation(s) on previous 20 message(s) suppressed by --mute

2012-07-20T08:58:01.265 OVPN:>LOG:1342767474,D,TCPv4_CLIENT READ [126] from 111.111.111.111:8443: P_CONTROL_V1 kid=0 sid=75678a19 4b656226 [ 27 sid=e3f5847b 1f826a70 ] pid=37 DATA 17030100 20b6675e 9359703f f12391a5 5468cd10 1844c1a5 ed6ebc55 46c8eb3[more...]

2012-07-20T08:58:01.328 Connection Closed.
 
Laatst bewerkt door een moderator:
Na 141 x aan mijn scrollwieltje gedraaid te hebben je log maar even in codetaq gezet:)
 
Had je die log niet beter in een .txt bestand kunnen oploaden, en als bijlage bij je posting kunnen doen? Je posting is nu wel heel erg lang geworden(!)
Niet dat ik een directe oplossing heb voor je probleem, maar vraag je eens af of op het LAN-netwerk waarmee verbonden wordt niet dezelfde ip-reeks wordt gebruikt als bij de gebruiker die probeert te verbinden (voorbeeld: Stel dat de gebruiker het 192.168.1.x netwerk thuis gebruikt en op het bedrijfs-LAN ook 192.168.1.x wordt gebruikt, dan gaat de VPN nooit (zinvol) werken).

Tijs.
 
Haha excuses voor de lange log, ik heb medelijden met je scrollwieltje:) Zal er de volgende keer rekening mee houden.

dnties, bedankt voor je reactie. Het heeft echter wel altijd gewerkt met dezelfde configuratie, maar ik zal even nagaan of de gebruiker inderdaad in dezelfde range zit.
 
Wat je verder kunt proberen is een log te maken van iemand die wél succesvol kan verbinden.
Die dan (regel voor regel) naast die leggen die mislukt is (=verschilanalyse). Wellicht kun je dan beter op Google zoeken (of support vragen bij de leverancier).

Tijs.
 
Status
Niet open voor verdere reacties.
                    Steun ons                    

Nieuwste berichten

Terug
Bovenaan Onderaan