Project

General

Profile

Anomalie #1172 » 2013-01-11-openvpn.log

logs openvpn - Loïc Dachary, 01/11/2013 12:10 PM

 
1
Thu Dec 27 16:23:40 2012 us=698882 Current Parameter Settings:
2
Thu Dec 27 16:23:40 2012 us=698926   config = '/etc/openvpn/client.conf'
3
Thu Dec 27 16:23:40 2012 us=698934   mode = 0
4
Thu Dec 27 16:23:40 2012 us=698940   persist_config = DISABLED
5
Thu Dec 27 16:23:40 2012 us=698946   persist_mode = 1
6
Thu Dec 27 16:23:40 2012 us=698952   show_ciphers = DISABLED
7
Thu Dec 27 16:23:40 2012 us=698957   show_digests = DISABLED
8
Thu Dec 27 16:23:40 2012 us=698963   show_engines = DISABLED
9
Thu Dec 27 16:23:40 2012 us=698968   genkey = DISABLED
10
Thu Dec 27 16:23:40 2012 us=698974   key_pass_file = '[UNDEF]'
11
Thu Dec 27 16:23:40 2012 us=698979   show_tls_ciphers = DISABLED
12
Thu Dec 27 16:23:40 2012 us=698986 Connection profiles [default]:
13
Thu Dec 27 16:23:40 2012 us=698992   proto = udp
14
Thu Dec 27 16:23:40 2012 us=698998   local = '[UNDEF]'
15
Thu Dec 27 16:23:40 2012 us=699003   local_port = 0
16
Thu Dec 27 16:23:40 2012 us=699009   remote = 'pavot.april.org'
17
Thu Dec 27 16:23:40 2012 us=699014   remote_port = 1194
18
Thu Dec 27 16:23:40 2012 us=699020   remote_float = DISABLED
19
Thu Dec 27 16:23:40 2012 us=699025   bind_defined = DISABLED
20
Thu Dec 27 16:23:40 2012 us=699031   bind_local = DISABLED
21
Thu Dec 27 16:23:40 2012 us=699036 NOTE: --mute triggered...
22
Thu Dec 27 16:23:40 2012 us=699048 253 variation(s) on previous 20 message(s) suppressed by --mute
23
Thu Dec 27 16:23:40 2012 us=699055 OpenVPN 2.2.1 x86_64-linux-gnu [SSL] [LZO2] [EPOLL] [PKCS11] [eurephia] [MH] [PF_INET6] [IPv6 payload 20110424-2 (2.2RC2)] built on Mar 23 2012
24
Thu Dec 27 16:23:40 2012 us=699110 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
25
Thu Dec 27 16:23:40 2012 us=699696 LZO compression initialized
26
Thu Dec 27 16:23:40 2012 us=699750 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
27
Thu Dec 27 16:23:40 2012 us=699775 Socket Buffers: R=[229376->131072] S=[229376->131072]
28
Thu Dec 27 16:23:45 2012 us=705646 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
29
Thu Dec 27 16:23:45 2012 us=705727 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
30
Thu Dec 27 16:23:45 2012 us=705742 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
31
Thu Dec 27 16:23:45 2012 us=705773 Local Options hash (VER=V4): '41690919'
32
Thu Dec 27 16:23:45 2012 us=705791 Expected Remote Options hash (VER=V4): '530fdded'
33
Thu Dec 27 16:23:45 2012 us=708260 NOTE: UID/GID downgrade will be delayed because of --client, --pull, or --up-delay
34
Thu Dec 27 16:23:45 2012 us=708332 UDPv4 link local: [undef]
35
Thu Dec 27 16:23:45 2012 us=708377 UDPv4 link remote: [AF_INET]86.65.39.24:1194
36
Thu Dec 27 16:23:45 2012 us=714960 TLS: Initial packet from [AF_INET]86.65.39.24:1194, sid=0b77ab54 fa3689cf
37
Thu Dec 27 16:23:45 2012 us=751524 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
38
Thu Dec 27 16:23:45 2012 us=751792 VERIFY OK: nsCertType=SERVER
39
Thu Dec 27 16:23:45 2012 us=751813 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
40
Thu Dec 27 16:23:45 2012 us=820104 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
41
Thu Dec 27 16:23:45 2012 us=820141 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
42
Thu Dec 27 16:23:45 2012 us=820228 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
43
Thu Dec 27 16:23:45 2012 us=820244 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
44
Thu Dec 27 16:23:45 2012 us=820305 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
45
Thu Dec 27 16:23:45 2012 us=820338 [pavot] Peer Connection Initiated with [AF_INET]86.65.39.24:1194
46
Thu Dec 27 16:23:48 2012 us=94792 SENT CONTROL [pavot]: 'PUSH_REQUEST' (status=1)
47
Thu Dec 27 16:23:48 2012 us=101303 PUSH: Received control message: 'PUSH_REPLY,route 192.168.0.0 255.255.255.0,topology net30,ping 10,ping-restart 120,route 192.168.5.0 255.255.255.0,route 192.168.3.0 255.255.255.0,route 192.168.2.0 255.255.255.0,route 192.168.1.0 255.255.255.0,ifconfig 192.168.0.6 192.168.0.5'
48
Thu Dec 27 16:23:48 2012 us=101441 OPTIONS IMPORT: timers and/or timeouts modified
49
Thu Dec 27 16:23:48 2012 us=101460 OPTIONS IMPORT: --ifconfig/up options modified
50
Thu Dec 27 16:23:48 2012 us=101471 OPTIONS IMPORT: route options modified
51
Thu Dec 27 16:23:48 2012 us=101659 ROUTE default_gateway=10.145.4.4
52
Thu Dec 27 16:23:48 2012 us=102831 TUN/TAP device tun0 opened
53
Thu Dec 27 16:23:48 2012 us=102867 TUN/TAP TX queue length set to 100
54
Thu Dec 27 16:23:48 2012 us=102889 do_ifconfig, tt->ipv6=0, tt->did_ifconfig_ipv6_setup=0
55
Thu Dec 27 16:23:48 2012 us=102921 /sbin/ifconfig tun0 192.168.0.6 pointopoint 192.168.0.5 mtu 1500
56
Thu Dec 27 16:23:48 2012 us=105760 /etc/openvpn/route-flush tun0 1500 1542 192.168.0.6 192.168.0.5 init
57
Thu Dec 27 16:23:48 2012 us=110779 /sbin/route add -net 192.168.0.0 netmask 255.255.255.0 gw 192.168.0.5
58
Thu Dec 27 16:23:48 2012 us=114671 /sbin/route add -net 192.168.5.0 netmask 255.255.255.0 gw 192.168.0.5
59
Thu Dec 27 16:23:48 2012 us=115276 /sbin/route add -net 192.168.3.0 netmask 255.255.255.0 gw 192.168.0.5
60
Thu Dec 27 16:23:48 2012 us=115883 /sbin/route add -net 192.168.2.0 netmask 255.255.255.0 gw 192.168.0.5
61
Thu Dec 27 16:23:48 2012 us=117156 /sbin/route add -net 192.168.1.0 netmask 255.255.255.0 gw 192.168.0.5
62
Thu Dec 27 16:23:48 2012 us=117620 GID set to nogroup
63
Thu Dec 27 16:23:48 2012 us=117641 UID set to nobody
64
Thu Dec 27 16:23:48 2012 us=117651 Initialization Sequence Completed
65
Thu Dec 27 17:23:45 2012 us=465703 TLS: soft reset sec=0 bytes=10420400/0 pkts=31495/0
66
Thu Dec 27 17:23:45 2012 us=507404 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
67
Thu Dec 27 17:23:45 2012 us=507573 VERIFY OK: nsCertType=SERVER
68
Thu Dec 27 17:23:45 2012 us=507595 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
69
Thu Dec 27 17:23:45 2012 us=574559 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
70
Thu Dec 27 17:23:45 2012 us=574597 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
71
Thu Dec 27 17:23:45 2012 us=574685 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
72
Thu Dec 27 17:23:45 2012 us=574700 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
73
Thu Dec 27 17:23:45 2012 us=574760 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
74
Thu Dec 27 18:23:44 2012 us=813024 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
75
Thu Dec 27 18:23:44 2012 us=813204 VERIFY OK: nsCertType=SERVER
76
Thu Dec 27 18:23:44 2012 us=813226 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
77
Thu Dec 27 18:23:44 2012 us=880396 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
78
Thu Dec 27 18:23:44 2012 us=880435 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
79
Thu Dec 27 18:23:44 2012 us=880523 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
80
Thu Dec 27 18:23:44 2012 us=880538 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
81
Thu Dec 27 18:23:44 2012 us=880598 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
82
Thu Dec 27 19:23:44 2012 us=578009 TLS: soft reset sec=0 bytes=488970/0 pkts=3799/0
83
Thu Dec 27 19:23:44 2012 us=620278 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
84
Thu Dec 27 19:23:44 2012 us=620437 VERIFY OK: nsCertType=SERVER
85
Thu Dec 27 19:23:44 2012 us=620457 NOTE: --mute triggered...
86
Sun Dec 30 02:10:26 2012 us=238033 468 variation(s) on previous 20 message(s) suppressed by --mute
87
Sun Dec 30 02:10:26 2012 us=238101 [pavot] Inactivity timeout (--ping-restart), restarting
88
Sun Dec 30 02:10:26 2012 us=238462 TCP/UDP: Closing socket
89
Sun Dec 30 02:10:26 2012 us=238515 SIGUSR1[soft,ping-restart] received, process restarting
90
Sun Dec 30 02:10:26 2012 us=238539 Restart pause, 2 second(s)
91
Sun Dec 30 02:10:28 2012 us=238745 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
92
Sun Dec 30 02:10:28 2012 us=238818 Re-using SSL/TLS context
93
Sun Dec 30 02:10:28 2012 us=238847 LZO compression initialized
94
Sun Dec 30 02:10:28 2012 us=238916 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
95
Sun Dec 30 02:10:28 2012 us=238953 Socket Buffers: R=[229376->131072] S=[229376->131072]
96
Sun Dec 30 02:10:33 2012 us=244773 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
97
Sun Dec 30 02:10:33 2012 us=244860 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
98
Sun Dec 30 02:10:33 2012 us=244875 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
99
Sun Dec 30 02:10:33 2012 us=244898 Local Options hash (VER=V4): '41690919'
100
Sun Dec 30 02:10:33 2012 us=244916 Expected Remote Options hash (VER=V4): '530fdded'
101
Sun Dec 30 02:10:33 2012 us=244934 UDPv4 link local: [undef]
102
Sun Dec 30 02:10:33 2012 us=244951 UDPv4 link remote: [AF_INET]86.65.39.24:1194
103
Sun Dec 30 02:11:33 2012 us=85621 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
104
Sun Dec 30 02:11:33 2012 us=85671 TLS Error: TLS handshake failed
105
Sun Dec 30 02:11:33 2012 us=85769 TCP/UDP: Closing socket
106
Sun Dec 30 02:11:33 2012 us=85808 SIGUSR1[soft,tls-error] received, process restarting
107
Sun Dec 30 02:11:33 2012 us=85828 Restart pause, 2 second(s)
108
Sun Dec 30 02:11:35 2012 us=86243 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
109
Sun Dec 30 02:11:35 2012 us=86318 Re-using SSL/TLS context
110
Sun Dec 30 02:11:35 2012 us=86340 LZO compression initialized
111
Sun Dec 30 02:11:35 2012 us=86410 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
112
Sun Dec 30 02:11:35 2012 us=86450 Socket Buffers: R=[229376->131072] S=[229376->131072]
113
Sun Dec 30 02:11:40 2012 us=92205 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
114
Sun Dec 30 02:11:40 2012 us=92314 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
115
Sun Dec 30 02:11:40 2012 us=92347 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
116
Sun Dec 30 02:11:40 2012 us=92391 Local Options hash (VER=V4): '41690919'
117
Sun Dec 30 02:11:40 2012 us=92432 Expected Remote Options hash (VER=V4): '530fdded'
118
Sun Dec 30 02:11:40 2012 us=92469 UDPv4 link local: [undef]
119
Sun Dec 30 02:11:40 2012 us=92504 UDPv4 link remote: [AF_INET]86.65.39.24:1194
120
Sun Dec 30 02:12:10 2012 us=132414 TLS: Initial packet from [AF_INET]86.65.39.24:1194, sid=992de921 770f03fe
121
Sun Dec 30 02:12:10 2012 us=168469 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
122
Sun Dec 30 02:12:10 2012 us=168629 VERIFY OK: nsCertType=SERVER
123
Sun Dec 30 02:12:10 2012 us=168650 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
124
Sun Dec 30 02:12:10 2012 us=235979 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
125
Sun Dec 30 02:12:10 2012 us=236018 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
126
Sun Dec 30 02:12:10 2012 us=236136 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
127
Sun Dec 30 02:12:10 2012 us=236158 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
128
Sun Dec 30 02:12:10 2012 us=236220 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
129
Sun Dec 30 02:12:10 2012 us=236255 [pavot] Peer Connection Initiated with [AF_INET]86.65.39.24:1194
130
Sun Dec 30 02:12:12 2012 us=139364 SENT CONTROL [pavot]: 'PUSH_REQUEST' (status=1)
131
Sun Dec 30 02:12:12 2012 us=145922 PUSH: Received control message: 'PUSH_REPLY,route 192.168.0.0 255.255.255.0,topology net30,ping 10,ping-restart 120,route 192.168.5.0 255.255.255.0,route 192.168.3.0 255.255.255.0,route 192.168.2.0 255.255.255.0,route 192.168.1.0 255.255.255.0,ifconfig 192.168.0.6 192.168.0.5'
132
Sun Dec 30 02:12:12 2012 us=146040 OPTIONS IMPORT: timers and/or timeouts modified
133
Sun Dec 30 02:12:12 2012 us=146058 OPTIONS IMPORT: --ifconfig/up options modified
134
Sun Dec 30 02:12:12 2012 us=146070 OPTIONS IMPORT: route options modified
135
Sun Dec 30 02:12:12 2012 us=146082 Preserving previous TUN/TAP instance: tun0
136
Sun Dec 30 02:12:12 2012 us=146093 Initialization Sequence Completed
137
Sun Dec 30 03:12:10 2012 us=328193 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
138
Sun Dec 30 03:12:10 2012 us=328386 VERIFY OK: nsCertType=SERVER
139
Sun Dec 30 03:12:10 2012 us=328408 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
140
Sun Dec 30 03:12:19 2012 us=639258 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
141
Sun Dec 30 03:12:19 2012 us=639321 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
142
Sun Dec 30 03:12:19 2012 us=639410 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
143
Sun Dec 30 03:12:19 2012 us=639425 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
144
Sun Dec 30 03:12:19 2012 us=639488 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
145
Sun Dec 30 04:12:10 2012 us=760554 TLS: tls_process: killed expiring key
146
Sun Dec 30 04:12:19 2012 us=496867 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
147
Sun Dec 30 04:12:19 2012 us=497066 VERIFY OK: nsCertType=SERVER
148
Sun Dec 30 04:12:19 2012 us=497088 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
149
Sun Dec 30 04:12:19 2012 us=563276 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
150
Sun Dec 30 04:12:19 2012 us=563314 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
151
Sun Dec 30 04:12:19 2012 us=563403 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
152
Sun Dec 30 04:12:19 2012 us=563419 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
153
Sun Dec 30 04:12:19 2012 us=563479 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
154
Sun Dec 30 05:12:19 2012 us=244397 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
155
Sun Dec 30 05:12:19 2012 us=244574 VERIFY OK: nsCertType=SERVER
156
Sun Dec 30 05:12:19 2012 us=244595 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
157
Sun Dec 30 05:12:19 2012 us=315387 NOTE: --mute triggered...
158
Wed Jan  9 02:01:10 2013 us=10484 2039 variation(s) on previous 20 message(s) suppressed by --mute
159
Wed Jan  9 02:01:10 2013 us=10555 [pavot] Inactivity timeout (--ping-restart), restarting
160
Wed Jan  9 02:01:10 2013 us=10882 TCP/UDP: Closing socket
161
Wed Jan  9 02:01:10 2013 us=10927 SIGUSR1[soft,ping-restart] received, process restarting
162
Wed Jan  9 02:01:10 2013 us=10948 Restart pause, 2 second(s)
163
Wed Jan  9 02:01:12 2013 us=11094 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
164
Wed Jan  9 02:01:12 2013 us=11171 Re-using SSL/TLS context
165
Wed Jan  9 02:01:12 2013 us=11193 LZO compression initialized
166
Wed Jan  9 02:01:12 2013 us=11263 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
167
Wed Jan  9 02:01:12 2013 us=11322 Socket Buffers: R=[229376->131072] S=[229376->131072]
168
Wed Jan  9 02:01:17 2013 us=17100 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
169
Wed Jan  9 02:01:17 2013 us=17175 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
170
Wed Jan  9 02:01:17 2013 us=17189 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
171
Wed Jan  9 02:01:17 2013 us=17213 Local Options hash (VER=V4): '41690919'
172
Wed Jan  9 02:01:17 2013 us=17246 Expected Remote Options hash (VER=V4): '530fdded'
173
Wed Jan  9 02:01:17 2013 us=17266 UDPv4 link local: [undef]
174
Wed Jan  9 02:01:17 2013 us=17283 UDPv4 link remote: [AF_INET]86.65.39.24:1194
175
Wed Jan  9 02:01:17 2013 us=23842 TLS: Initial packet from [AF_INET]86.65.39.24:1194, sid=1a40acac bdb1db6d
176
Wed Jan  9 02:01:17 2013 us=59351 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
177
Wed Jan  9 02:01:17 2013 us=59510 VERIFY OK: nsCertType=SERVER
178
Wed Jan  9 02:01:17 2013 us=59531 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
179
Wed Jan  9 02:01:17 2013 us=126880 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
180
Wed Jan  9 02:01:17 2013 us=126918 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
181
Wed Jan  9 02:01:17 2013 us=127006 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
182
Wed Jan  9 02:01:17 2013 us=127022 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
183
Wed Jan  9 02:01:17 2013 us=127082 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
184
Wed Jan  9 02:01:17 2013 us=127117 [pavot] Peer Connection Initiated with [AF_INET]86.65.39.24:1194
185
Wed Jan  9 02:01:19 2013 us=208901 SENT CONTROL [pavot]: 'PUSH_REQUEST' (status=1)
186
Wed Jan  9 02:01:19 2013 us=215456 PUSH: Received control message: 'PUSH_REPLY,route 192.168.0.0 255.255.255.0,topology net30,ping 10,ping-restart 120,route 192.168.5.0 255.255.255.0,route 192.168.3.0 255.255.255.0,route 192.168.2.0 255.255.255.0,route 192.168.1.0 255.255.255.0,ifconfig 192.168.0.6 192.168.0.5'
187
Wed Jan  9 02:01:19 2013 us=215553 OPTIONS IMPORT: timers and/or timeouts modified
188
Wed Jan  9 02:01:19 2013 us=215570 OPTIONS IMPORT: --ifconfig/up options modified
189
Wed Jan  9 02:01:19 2013 us=215583 OPTIONS IMPORT: route options modified
190
Wed Jan  9 02:01:19 2013 us=215595 Preserving previous TUN/TAP instance: tun0
191
Wed Jan  9 02:01:19 2013 us=215607 Initialization Sequence Completed
192
Wed Jan  9 03:01:17 2013 us=555956 TLS: soft reset sec=0 bytes=24093827/0 pkts=34510/0
193
Wed Jan  9 03:01:17 2013 us=597453 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
194
Wed Jan  9 03:01:17 2013 us=597619 VERIFY OK: nsCertType=SERVER
195
Wed Jan  9 03:01:17 2013 us=597640 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
196
Wed Jan  9 03:01:17 2013 us=665221 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
197
Wed Jan  9 03:01:17 2013 us=665259 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
198
Wed Jan  9 03:01:17 2013 us=665346 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
199
Wed Jan  9 03:01:17 2013 us=665362 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
200
Wed Jan  9 03:01:17 2013 us=665422 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
201
Wed Jan  9 04:01:17 2013 us=527137 TLS: soft reset sec=0 bytes=665428/0 pkts=4932/0
202
Wed Jan  9 04:01:17 2013 us=568968 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
203
Wed Jan  9 04:01:17 2013 us=569128 VERIFY OK: nsCertType=SERVER
204
Wed Jan  9 04:01:17 2013 us=569149 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
205
Wed Jan  9 04:01:17 2013 us=636530 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
206
Wed Jan  9 04:01:17 2013 us=636569 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
207
Wed Jan  9 04:01:17 2013 us=636657 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
208
Wed Jan  9 04:01:17 2013 us=636673 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
209
Wed Jan  9 04:01:17 2013 us=636734 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
210
Wed Jan  9 05:01:17 2013 us=43543 TLS: soft reset sec=0 bytes=638411/0 pkts=4843/0
211
Wed Jan  9 05:01:17 2013 us=85294 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
212
Wed Jan  9 05:01:17 2013 us=85470 NOTE: --mute triggered...
213
Thu Jan 10 02:00:12 2013 us=82499 178 variation(s) on previous 20 message(s) suppressed by --mute
214
Thu Jan 10 02:00:12 2013 us=82541 [pavot] Inactivity timeout (--ping-restart), restarting
215
Thu Jan 10 02:00:12 2013 us=82891 TCP/UDP: Closing socket
216
Thu Jan 10 02:00:12 2013 us=82936 SIGUSR1[soft,ping-restart] received, process restarting
217
Thu Jan 10 02:00:12 2013 us=82956 Restart pause, 2 second(s)
218
Thu Jan 10 02:00:14 2013 us=83083 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
219
Thu Jan 10 02:00:14 2013 us=83155 Re-using SSL/TLS context
220
Thu Jan 10 02:00:14 2013 us=83177 LZO compression initialized
221
Thu Jan 10 02:00:14 2013 us=83246 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
222
Thu Jan 10 02:00:14 2013 us=83282 Socket Buffers: R=[229376->131072] S=[229376->131072]
223
Thu Jan 10 02:00:19 2013 us=89014 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
224
Thu Jan 10 02:00:19 2013 us=90122 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
225
Thu Jan 10 02:00:19 2013 us=90149 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
226
Thu Jan 10 02:00:19 2013 us=90174 Local Options hash (VER=V4): '41690919'
227
Thu Jan 10 02:00:19 2013 us=90193 Expected Remote Options hash (VER=V4): '530fdded'
228
Thu Jan 10 02:00:19 2013 us=90212 UDPv4 link local: [undef]
229
Thu Jan 10 02:00:19 2013 us=90230 UDPv4 link remote: [AF_INET]86.65.39.24:1194
230
Thu Jan 10 02:01:19 2013 us=152523 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
231
Thu Jan 10 02:01:19 2013 us=152594 TLS Error: TLS handshake failed
232
Thu Jan 10 02:01:19 2013 us=152690 TCP/UDP: Closing socket
233
Thu Jan 10 02:01:19 2013 us=152728 SIGUSR1[soft,tls-error] received, process restarting
234
Thu Jan 10 02:01:19 2013 us=152748 Restart pause, 2 second(s)
235
Thu Jan 10 02:01:21 2013 us=152975 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
236
Thu Jan 10 02:01:21 2013 us=153045 Re-using SSL/TLS context
237
Thu Jan 10 02:01:21 2013 us=153066 LZO compression initialized
238
Thu Jan 10 02:01:21 2013 us=153132 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
239
Thu Jan 10 02:01:21 2013 us=153168 Socket Buffers: R=[229376->131072] S=[229376->131072]
240
Thu Jan 10 02:01:26 2013 us=158883 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
241
Thu Jan 10 02:01:26 2013 us=158957 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
242
Thu Jan 10 02:01:26 2013 us=158972 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
243
Thu Jan 10 02:01:26 2013 us=158996 Local Options hash (VER=V4): '41690919'
244
Thu Jan 10 02:01:26 2013 us=159014 Expected Remote Options hash (VER=V4): '530fdded'
245
Thu Jan 10 02:01:26 2013 us=159033 UDPv4 link local: [undef]
246
Thu Jan 10 02:01:26 2013 us=159050 UDPv4 link remote: [AF_INET]86.65.39.24:1194
247
Thu Jan 10 02:02:26 2013 us=193077 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
248
Thu Jan 10 02:02:26 2013 us=193122 TLS Error: TLS handshake failed
249
Thu Jan 10 02:02:26 2013 us=193220 TCP/UDP: Closing socket
250
Thu Jan 10 02:02:26 2013 us=193259 SIGUSR1[soft,tls-error] received, process restarting
251
Thu Jan 10 02:02:26 2013 us=193279 Restart pause, 2 second(s)
252
Thu Jan 10 02:02:28 2013 us=193409 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
253
Thu Jan 10 02:02:28 2013 us=193477 Re-using SSL/TLS context
254
Thu Jan 10 02:02:28 2013 us=193498 LZO compression initialized
255
Thu Jan 10 02:02:28 2013 us=193583 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
256
Thu Jan 10 02:02:28 2013 us=193619 Socket Buffers: R=[229376->131072] S=[229376->131072]
257
Thu Jan 10 02:02:33 2013 us=199321 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
258
Thu Jan 10 02:02:33 2013 us=199397 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
259
Thu Jan 10 02:02:33 2013 us=199411 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
260
Thu Jan 10 02:02:33 2013 us=199434 Local Options hash (VER=V4): '41690919'
261
Thu Jan 10 02:02:33 2013 us=199453 Expected Remote Options hash (VER=V4): '530fdded'
262
Thu Jan 10 02:02:33 2013 us=199471 UDPv4 link local: [undef]
263
Thu Jan 10 02:02:33 2013 us=199488 UDPv4 link remote: [AF_INET]86.65.39.24:1194
264
Thu Jan 10 02:03:31 2013 us=545571 TLS: Initial packet from [AF_INET]86.65.39.24:1194, sid=7fd74e7b 96b9de13
265
Thu Jan 10 02:03:33 2013 us=98865 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
266
Thu Jan 10 02:03:33 2013 us=98925 TLS Error: TLS handshake failed
267
Thu Jan 10 02:03:33 2013 us=99015 TCP/UDP: Closing socket
268
Thu Jan 10 02:03:33 2013 us=99053 SIGUSR1[soft,tls-error] received, process restarting
269
Thu Jan 10 02:03:33 2013 us=99072 Restart pause, 2 second(s)
270
Thu Jan 10 02:03:35 2013 us=99237 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
271
Thu Jan 10 02:03:35 2013 us=99310 Re-using SSL/TLS context
272
Thu Jan 10 02:03:35 2013 us=99331 LZO compression initialized
273
Thu Jan 10 02:03:35 2013 us=99399 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
274
Thu Jan 10 02:03:35 2013 us=99438 Socket Buffers: R=[229376->131072] S=[229376->131072]
275
Thu Jan 10 02:03:40 2013 us=105100 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
276
Thu Jan 10 02:03:40 2013 us=105179 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
277
Thu Jan 10 02:03:40 2013 us=105194 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
278
Thu Jan 10 02:03:40 2013 us=105217 Local Options hash (VER=V4): '41690919'
279
Thu Jan 10 02:03:40 2013 us=105236 Expected Remote Options hash (VER=V4): '530fdded'
280
Thu Jan 10 02:03:40 2013 us=105254 UDPv4 link local: [undef]
281
Thu Jan 10 02:03:40 2013 us=105272 UDPv4 link remote: [AF_INET]86.65.39.24:1194
282
Thu Jan 10 02:04:29 2013 us=5894 TLS: Initial packet from [AF_INET]86.65.39.24:1194, sid=1e102e60 0a63fb7a
283
Thu Jan 10 02:04:32 2013 us=382518 VERIFY OK: depth=1, /C=FR/L=Paris/O=OpenVPN/CN=OpenVPN_CA/emailAddress=admins@april.org
284
Thu Jan 10 02:04:32 2013 us=382692 VERIFY OK: nsCertType=SERVER
285
Thu Jan 10 02:04:32 2013 us=382714 VERIFY OK: depth=0, /C=FR/L=Paris/O=OpenVPN/CN=pavot/emailAddress=admins@april.org
286
Thu Jan 10 02:04:32 2013 us=468690 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
287
Thu Jan 10 02:04:32 2013 us=468752 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
288
Thu Jan 10 02:04:32 2013 us=468840 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
289
Thu Jan 10 02:04:32 2013 us=468855 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
290
Thu Jan 10 02:04:32 2013 us=468917 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
291
Thu Jan 10 02:04:32 2013 us=468956 [pavot] Peer Connection Initiated with [AF_INET]86.65.39.24:1194
292
Thu Jan 10 02:04:34 2013 us=479121 SENT CONTROL [pavot]: 'PUSH_REQUEST' (status=1)
293
Thu Jan 10 02:04:35 2013 us=670724 PUSH: Received control message: 'PUSH_REPLY,route 192.168.0.0 255.255.255.0,topology net30,ping 10,ping-restart 120,route 192.168.5.0 255.255.255.0,route 192.168.3.0 255.255.255.0,route 192.168.2.0 255.255.255.0,route 192.168.1.0 255.255.255.0,ifconfig 192.168.0.34 192.168.0.33'
294
Thu Jan 10 02:04:35 2013 us=670874 OPTIONS IMPORT: timers and/or timeouts modified
295
Thu Jan 10 02:04:35 2013 us=670893 OPTIONS IMPORT: --ifconfig/up options modified
296
Thu Jan 10 02:04:35 2013 us=670904 OPTIONS IMPORT: route options modified
297
Thu Jan 10 02:04:35 2013 us=670917 Preserving previous TUN/TAP instance: tun0
298
Thu Jan 10 02:04:35 2013 us=670928 NOTE: Pulled options changed on restart, will need to close and reopen TUN/TAP device.
299
Thu Jan 10 02:04:35 2013 us=670957 /sbin/route del -net 192.168.1.0 netmask 255.255.255.0
300
SIOCDELRT: Operation not permitted
301
Thu Jan 10 02:04:35 2013 us=678270 ERROR: Linux route delete command failed: external program exited with error status: 7
302
Thu Jan 10 02:04:35 2013 us=678328 /sbin/route del -net 192.168.2.0 netmask 255.255.255.0
303
SIOCDELRT: Operation not permitted
304
Thu Jan 10 02:04:35 2013 us=679305 ERROR: Linux route delete command failed: external program exited with error status: 7
305
Thu Jan 10 02:04:35 2013 us=679359 /sbin/route del -net 192.168.3.0 netmask 255.255.255.0
306
SIOCDELRT: Operation not permitted
307
Thu Jan 10 02:04:35 2013 us=680337 ERROR: Linux route delete command failed: external program exited with error status: 7
308
Thu Jan 10 02:04:35 2013 us=680389 /sbin/route del -net 192.168.5.0 netmask 255.255.255.0
309
SIOCDELRT: Operation not permitted
310
Thu Jan 10 02:04:35 2013 us=681341 ERROR: Linux route delete command failed: external program exited with error status: 7
311
Thu Jan 10 02:04:35 2013 us=681392 /sbin/route del -net 192.168.0.0 netmask 255.255.255.0
312
SIOCDELRT: Operation not permitted
313
Thu Jan 10 02:04:35 2013 us=682378 ERROR: Linux route delete command failed: external program exited with error status: 7
314
Thu Jan 10 02:04:35 2013 us=682425 Closing TUN/TAP interface
315
Thu Jan 10 02:04:35 2013 us=682447 /sbin/ifconfig tun0 0.0.0.0
316
SIOCSIFADDR: Permission denied
317
SIOCSIFFLAGS: Permission denied
318
Thu Jan 10 02:04:35 2013 us=689329 Linux ip addr del failed: external program exited with error status: 255
319
Thu Jan 10 02:04:37 2013 us=966350 ROUTE default_gateway=10.145.4.4
320
Thu Jan 10 02:04:37 2013 us=966495 Note: Cannot ioctl TUNSETIFF tun: Operation not permitted (errno=1)
321
Thu Jan 10 02:04:37 2013 us=966513 do_ifconfig, tt->ipv6=0, tt->did_ifconfig_ipv6_setup=0
322
Thu Jan 10 02:04:37 2013 us=966542 /sbin/ifconfig  192.168.0.34 pointopoint 192.168.0.33 mtu 1500
323
SIOCSIFADDR: Permission denied
324
: ERROR while getting interface flags: No such device
325
SIOCSIFDSTADDR: Permission denied
326
: ERROR while getting interface flags: No such device
327
SIOCSIFMTU: Operation not permitted
328
Thu Jan 10 02:04:37 2013 us=967880 Linux ifconfig failed: external program exited with error status: 1
329
Thu Jan 10 02:04:37 2013 us=967919 Exiting
    (1-1/1)