Mon Jan 15 01:59:15 2007 us=573000 OpenVPN 2.1_rc1 Win32-MinGW [SSL] [LZO2] built on Oct 31 2006
Mon Jan 15 01:59:15 2007 us=593000 PKCS#11: pkcs11_initialize - entered
Mon Jan 15 01:59:15 2007 us=593000 PKCS#11: pkcs11_initialize - return 0-'CKR_OK'
Mon Jan 15 01:59:15 2007 us=593000 WE_INIT maxevents=4 flags=0x00000002
Mon Jan 15 01:59:15 2007 us=593000 WE_INIT maxevents=4 capacity=8
Mon Jan 15 01:59:15 2007 us=593000 Control Channel Authentication: using 'static.key' as a OpenVPN static key file
Mon Jan 15 01:59:15 2007 us=593000 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Mon Jan 15 01:59:15 2007 us=593000 Outgoing Control Channel Authentication: HMAC KEY: 4bb7e9ed b6ca09d4 9a352c74 a54124e3 65832cf7
Mon Jan 15 01:59:15 2007 us=593000 Outgoing Control Channel Authentication: HMAC size=20 block_size=64
Mon Jan 15 01:59:15 2007 us=593000 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Mon Jan 15 01:59:15 2007 us=593000 Incoming Control Channel Authentication: HMAC KEY: 1466a69d 3aa1c342 cb1d8506 5773540c a7694b4d
Mon Jan 15 01:59:15 2007 us=603000 Incoming Control Channel Authentication: HMAC size=20 block_size=64
Mon Jan 15 01:59:15 2007 us=603000 LZO compression initialized
Mon Jan 15 01:59:15 2007 us=603000 MTU DYNAMIC mtu=0, flags=1, 0 -> 166
Mon Jan 15 01:59:15 2007 us=603000 TLS: tls_session_init: entry
Mon Jan 15 01:59:15 2007 us=603000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Mon Jan 15 01:59:15 2007 us=603000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Mon Jan 15 01:59:15 2007 us=603000 TLS: tls_session_init: new session object, sid=753a1f1b fd058120
Mon Jan 15 01:59:15 2007 us=603000 TLS: tls_session_init: entry
Mon Jan 15 01:59:15 2007 us=603000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Mon Jan 15 01:59:15 2007 us=603000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Mon Jan 15 01:59:15 2007 us=603000 TLS: tls_session_init: new session object, sid=362ebb9e ed6fb3ff
Mon Jan 15 01:59:15 2007 us=603000 Control Channel MTU parms [ L:1558 D:166 EF:66 EB:0 ET:0 EL:0 ]
Mon Jan 15 01:59:15 2007 us=613000 MTU DYNAMIC mtu=1450, flags=2, 1558 -> 1450
Mon Jan 15 01:59:15 2007 us=613000 REMOTE_LIST len=1 current=0
Mon Jan 15 01:59:15 2007 us=613000 [0] DNSDNSACCOUNT:1194
Mon Jan 15 01:59:15 2007 us=633000 RESOLVE_REMOTE flags=0x0101 phase=1 rrs=0 sig=-1 status=1
Mon Jan 15 01:59:15 2007 us=633000 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
Mon Jan 15 01:59:15 2007 us=633000 Local Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA1,keysize 256,tls-auth,key-method 2,tls-client'
Mon Jan 15 01:59:15 2007 us=633000 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 0,cipher AES-256-CBC,auth SHA1,keysize 256,tls-auth,key-method 2,tls-server'
Mon Jan 15 01:59:15 2007 us=633000 Local Options hash (VER=V4): '9e7066d2'
Mon Jan 15 01:59:15 2007 us=633000 Expected Remote Options hash (VER=V4): '162b04de'
Mon Jan 15 01:59:15 2007 us=633000 Socket Buffers: R=[8192->8192] S=[8192->8192]
Mon Jan 15 01:59:15 2007 us=633000 UDPv4 link local: [undef]
Mon Jan 15 01:59:15 2007 us=633000 UDPv4 link remote: MYIPADRESS:1194
Mon Jan 15 01:59:15 2007 us=633000 TIMER: coarse timer wakeup 1 seconds
Mon Jan 15 01:59:15 2007 us=633000 TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=753a1f1b fd058120, stored-sid=00000000 00000000, stored-ip=MYIPADRESS:1194
Mon Jan 15 01:59:15 2007 us=633000 TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 15 01:59:15 2007 us=633000 ACK mark active outgoing ID 0
Mon Jan 15 01:59:15 2007 us=633000 TLS: Initial Handshake, sid=753a1f1b fd058120
Mon Jan 15 01:59:15 2007 us=633000 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 15 01:59:15 2007 us=633000 ACK reliable_send ID 0 (size=4 to=2)
Mon Jan 15 01:59:15 2007 us=633000 Reliable -> TCP/UDP
Mon Jan 15 01:59:15 2007 us=643000 ACK reliable_send_timeout 2 [1] 0
Mon Jan 15 01:59:15 2007 us=643000 TLS: tls_process: timeout set to 2
Mon Jan 15 01:59:15 2007 us=643000 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=362ebb9e ed6fb3ff, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 15 01:59:15 2007 us=643000 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 15 01:59:15 2007 us=643000 RANDOM USEC=217019
Mon Jan 15 01:59:15 2007 us=643000 WE_CTL n=0 ev=0x00483be4 rwflags=0x0001 arg=0x00467664
Mon Jan 15 01:59:15 2007 us=643000 WIN32 I/O: Socket Receive error [1558]: Invalid argument (WSAEINVAL)
Mon Jan 15 01:59:15 2007 us=643000 WE_CTL n=1 ev=0x00d34c4c rwflags=0x0003 arg=0x0046765c
Mon Jan 15 01:59:15 2007 us=643000 I/O WAIT T?|T?|SR1|SW0 [1/217019]
Mon Jan 15 01:59:15 2007 us=643000 WE_WAIT enter n=3 to=1217
Mon Jan 15 01:59:15 2007 us=643000 [0] ev=0x00000720 rwflags=0x0001 arg=0x00467664
Mon Jan 15 01:59:15 2007 us=673000 [1] ev=0x00000714 rwflags=0x0002 arg=0x0046765c
Mon Jan 15 01:59:15 2007 us=673000 [2] ev=0x00000718 rwflags=0x0001 arg=0x0046765c
Mon Jan 15 01:59:15 2007 us=673000 WE_WAIT leave [1,0] rwflags=0x0002 arg=0x0046765c
Mon Jan 15 01:59:15 2007 us=673000 WE_WAIT leave [2,1] rwflags=0x0001 arg=0x0046765c
Mon Jan 15 01:59:15 2007 us=673000 event_wait returned 2
Mon Jan 15 01:59:15 2007 us=673000 I/O WAIT status=0x0003
Mon Jan 15 01:59:15 2007 us=673000 UDPv4 WRITE [42] to MYIPADRESS:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=753a1f1b fd058120 tls_hmac=f57a4d2e 28ea959a 8b539a2e e54e8514 3f5ac1fa pid=[ #1 / time = (1168822755) Mon Jan 15 01:59:15 2007 ] [ ] pid=0 DATA
Mon Jan 15 01:59:15 2007 us=703000 WIN32 I/O: Socket Send immediate return [42,42]
Mon Jan 15 01:59:15 2007 us=703000 UDPv4 write returned 42
Mon Jan 15 01:59:15 2007 us=703000 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=753a1f1b fd058120, stored-sid=00000000 00000000, stored-ip=MYIPADRESS:1194
Mon Jan 15 01:59:15 2007 us=703000 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 15 01:59:15 2007 us=703000 ACK reliable_can_send active=1 current=0 : [1] 0
Mon Jan 15 01:59:15 2007 us=703000 SSL state (connect): before/connect initialization
Mon Jan 15 01:59:15 2007 us=703000 SSL state (connect): SSLv3 write client hello A
Mon Jan 15 01:59:15 2007 us=703000 ACK reliable_send_timeout 2 [1] 0
Mon Jan 15 01:59:15 2007 us=703000 TLS: tls_process: timeout set to 2
Mon Jan 15 01:59:15 2007 us=703000 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=362ebb9e ed6fb3ff, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 15 01:59:15 2007 us=703000 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 15 01:59:15 2007 us=703000 WE_CTL n=0 ev=0x00483be4 rwflags=0x0001 arg=0x00467664
Mon Jan 15 01:59:15 2007 us=703000 WE_CTL n=1 ev=0x00d34c4c rwflags=0x0001 arg=0x0046765c
Mon Jan 15 01:59:15 2007 us=703000 I/O WAIT T?|T?|SR1|Sw1 [1/217019]
Mon Jan 15 01:59:15 2007 us=703000 WE_WAIT enter n=2 to=1217
Mon Jan 15 01:59:15 2007 us=703000 [0] ev=0x00000720 rwflags=0x0001 arg=0x00467664
Mon Jan 15 01:59:15 2007 us=703000 [1] ev=0x00000718 rwflags=0x0001 arg=0x0046765c
Mon Jan 15 01:59:15 2007 us=703000 WE_WAIT leave [1,0] rwflags=0x0001 arg=0x0046765c
Mon Jan 15 01:59:15 2007 us=703000 event_wait returned 1
Mon Jan 15 01:59:15 2007 us=703000 I/O WAIT status=0x0001
Mon Jan 15 01:59:15 2007 us=703000 WIN32 I/O: Socket Completion non-queued error: Invalid argument (WSAEINVAL) (errno=10022)
Mon Jan 15 01:59:15 2007 us=703000 UDPv4 read returned -1
Mon Jan 15 01:59:15 2007 us=703000 UDPv4 READ [-1] from [undef]: DATA UNDEF len=-1
Mon Jan 15 01:59:15 2007 us=703000 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=753a1f1b fd058120, stored-sid=00000000 00000000, stored-ip=MYIPADRESS:1194
Mon Jan 15 01:59:15 2007 us=703000 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 15 01:59:15 2007 us=703000 ACK reliable_can_send active=1 current=0 : [1] 0
Mon Jan 15 01:59:15 2007 us=703000 ACK reliable_send_timeout 2 [1] 0
Mon Jan 15 01:59:15 2007 us=703000 TLS: tls_process: timeout set to 2
Mon Jan 15 01:59:15 2007 us=703000 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=362ebb9e ed6fb3ff, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 15 01:59:15 2007 us=703000 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 15 01:59:15 2007 us=703000 WE_CTL n=0 ev=0x00483be4 rwflags=0x0001 arg=0x00467664
Mon Jan 15 01:59:15 2007 us=703000 WIN32 I/O: Socket Receive queued [1558]
Mon Jan 15 01:59:15 2007 us=703000 WE_CTL n=1 ev=0x00d34c4c rwflags=0x0001 arg=0x0046765c
Mon Jan 15 01:59:15 2007 us=703000 I/O WAIT T?|T?|SRQ|Sw1 [1/217019]
Mon Jan 15 01:59:15 2007 us=703000 WE_WAIT enter n=2 to=1217
Mon Jan 15 01:59:15 2007 us=703000 [0] ev=0x00000720 rwflags=0x0001 arg=0x00467664
Mon Jan 15 01:59:15 2007 us=703000 [1] ev=0x00000718 rwflags=0x0001 arg=0x0046765c
Mon Jan 15 01:59:16 2007 us=925000 event_wait returned 0
Mon Jan 15 01:59:16 2007 us=925000 I/O WAIT status=0x0020
Mon Jan 15 01:59:16 2007 us=925000 TIMER: coarse timer wakeup 1 seconds
Mon Jan 15 01:59:16 2007 us=925000 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=753a1f1b fd058120, stored-sid=00000000 00000000, stored-ip=MYIPADRESS:1194
Mon Jan 15 01:59:16 2007 us=925000 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 15 01:59:16 2007 us=925000 ACK reliable_can_send active=1 current=0 : [1] 0
Mon Jan 15 01:59:16 2007 us=925000 ACK reliable_send_timeout 1 [1] 0
Mon Jan 15 01:59:16 2007 us=925000 TLS: tls_process: timeout set to 1
Mon Jan 15 01:59:16 2007 us=925000 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=362ebb9e ed6fb3ff, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 15 01:59:16 2007 us=925000 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 15 01:59:16 2007 us=925000 WE_CTL n=0 ev=0x00483be4 rwflags=0x0001 arg=0x00467664
Mon Jan 15 01:59:16 2007 us=925000 WE_CTL n=1 ev=0x00d34c4c rwflags=0x0001 arg=0x0046765c
Mon Jan 15 01:59:16 2007 us=925000 I/O WAIT T?|T?|SRQ|Sw1 [1/217019]
Mon Jan 15 01:59:16 2007 us=925000 WE_WAIT enter n=2 to=1217
Mon Jan 15 01:59:16 2007 us=925000 [0] ev=0x00000720 rwflags=0x0001 arg=0x00467664
Mon Jan 15 01:59:16 2007 us=925000 [1] ev=0x00000718 rwflags=0x0001 arg=0x0046765c
Mon Jan 15 01:59:18 2007 us=147000 event_wait returned 0
Mon Jan 15 01:59:18 2007 us=147000 I/O WAIT status=0x0020
Mon Jan 15 01:59:18 2007 us=147000 TIMER: coarse timer wakeup 1 seconds
Mon Jan 15 01:59:18 2007 us=147000 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=753a1f1b fd058120, stored-sid=00000000 00000000, stored-ip=MYIPADRESS:1194
Mon Jan 15 01:59:18 2007 us=147000 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 15 01:59:18 2007 us=147000 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 15 01:59:18 2007 us=147000 ACK reliable_send ID 0 (size=4 to=2)
Mon Jan 15 01:59:18 2007 us=147000 Reliable -> TCP/UDP
Mon Jan 15 01:59:18 2007 us=147000 ACK reliable_send_timeout 2 [1] 0
Mon Jan 15 01:59:18 2007 us=147000 TLS: tls_process: timeout set to 2
Mon Jan 15 01:59:18 2007 us=147000 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=362ebb9e ed6fb3ff, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 15 01:59:18 2007 us=147000 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 15 01:59:18 2007 us=147000 WE_CTL n=0 ev=0x00483be4 rwflags=0x0001 arg=0x00467664
Mon Jan 15 01:59:18 2007 us=147000 WE_CTL n=1 ev=0x00d34c4c rwflags=0x0003 arg=0x0046765c
Mon Jan 15 01:59:18 2007 us=147000 I/O WAIT T?|T?|SRQ|SW1 [1/217019]
Mon Jan 15 01:59:18 2007 us=147000 WE_WAIT enter n=3 to=1217
Mon Jan 15 01:59:18 2007 us=147000 [0] ev=0x00000720 rwflags=0x0001 arg=0x00467664
Mon Jan 15 01:59:18 2007 us=147000 [1] ev=0x00000714 rwflags=0x0002 arg=0x0046765c
Mon Jan 15 01:59:18 2007 us=147000 [2] ev=0x00000718 rwflags=0x0001 arg=0x0046765c
Mon Jan 15 01:59:18 2007 us=147000 WE_WAIT leave [1,0] rwflags=0x0002 arg=0x0046765c
Mon Jan 15 01:59:18 2007 us=147000 event_wait returned 1
Mon Jan 15 01:59:18 2007 us=147000 I/O WAIT status=0x0002