Projet

Général

Profil

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

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

 
Thu Dec 27 16:23:40 2012 us=698882 Current Parameter Settings:
Thu Dec 27 16:23:40 2012 us=698926 config = '/etc/openvpn/client.conf'
Thu Dec 27 16:23:40 2012 us=698934 mode = 0
Thu Dec 27 16:23:40 2012 us=698940 persist_config = DISABLED
Thu Dec 27 16:23:40 2012 us=698946 persist_mode = 1
Thu Dec 27 16:23:40 2012 us=698952 show_ciphers = DISABLED
Thu Dec 27 16:23:40 2012 us=698957 show_digests = DISABLED
Thu Dec 27 16:23:40 2012 us=698963 show_engines = DISABLED
Thu Dec 27 16:23:40 2012 us=698968 genkey = DISABLED
Thu Dec 27 16:23:40 2012 us=698974 key_pass_file = '[UNDEF]'
Thu Dec 27 16:23:40 2012 us=698979 show_tls_ciphers = DISABLED
Thu Dec 27 16:23:40 2012 us=698986 Connection profiles [default]:
Thu Dec 27 16:23:40 2012 us=698992 proto = udp
Thu Dec 27 16:23:40 2012 us=698998 local = '[UNDEF]'
Thu Dec 27 16:23:40 2012 us=699003 local_port = 0
Thu Dec 27 16:23:40 2012 us=699009 remote = 'pavot.april.org'
Thu Dec 27 16:23:40 2012 us=699014 remote_port = 1194
Thu Dec 27 16:23:40 2012 us=699020 remote_float = DISABLED
Thu Dec 27 16:23:40 2012 us=699025 bind_defined = DISABLED
Thu Dec 27 16:23:40 2012 us=699031 bind_local = DISABLED
Thu Dec 27 16:23:40 2012 us=699036 NOTE: --mute triggered...
Thu Dec 27 16:23:40 2012 us=699048 253 variation(s) on previous 20 message(s) suppressed by --mute
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
Thu Dec 27 16:23:40 2012 us=699110 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Dec 27 16:23:40 2012 us=699696 LZO compression initialized
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 ]
Thu Dec 27 16:23:40 2012 us=699775 Socket Buffers: R=[229376->131072] S=[229376->131072]
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 ]
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'
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'
Thu Dec 27 16:23:45 2012 us=705773 Local Options hash (VER=V4): '41690919'
Thu Dec 27 16:23:45 2012 us=705791 Expected Remote Options hash (VER=V4): '530fdded'
Thu Dec 27 16:23:45 2012 us=708260 NOTE: UID/GID downgrade will be delayed because of --client, --pull, or --up-delay
Thu Dec 27 16:23:45 2012 us=708332 UDPv4 link local: [undef]
Thu Dec 27 16:23:45 2012 us=708377 UDPv4 link remote: [AF_INET]86.65.39.24:1194
Thu Dec 27 16:23:45 2012 us=714960 TLS: Initial packet from [AF_INET]86.65.39.24:1194, sid=0b77ab54 fa3689cf
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
Thu Dec 27 16:23:45 2012 us=751792 VERIFY OK: nsCertType=SERVER
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
Thu Dec 27 16:23:45 2012 us=820104 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Thu Dec 27 16:23:45 2012 us=820141 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Thu Dec 27 16:23:45 2012 us=820228 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Thu Dec 27 16:23:45 2012 us=820244 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Thu Dec 27 16:23:45 2012 us=820305 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
Thu Dec 27 16:23:45 2012 us=820338 [pavot] Peer Connection Initiated with [AF_INET]86.65.39.24:1194
Thu Dec 27 16:23:48 2012 us=94792 SENT CONTROL [pavot]: 'PUSH_REQUEST' (status=1)
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'
Thu Dec 27 16:23:48 2012 us=101441 OPTIONS IMPORT: timers and/or timeouts modified
Thu Dec 27 16:23:48 2012 us=101460 OPTIONS IMPORT: --ifconfig/up options modified
Thu Dec 27 16:23:48 2012 us=101471 OPTIONS IMPORT: route options modified
Thu Dec 27 16:23:48 2012 us=101659 ROUTE default_gateway=10.145.4.4
Thu Dec 27 16:23:48 2012 us=102831 TUN/TAP device tun0 opened
Thu Dec 27 16:23:48 2012 us=102867 TUN/TAP TX queue length set to 100
Thu Dec 27 16:23:48 2012 us=102889 do_ifconfig, tt->ipv6=0, tt->did_ifconfig_ipv6_setup=0
Thu Dec 27 16:23:48 2012 us=102921 /sbin/ifconfig tun0 192.168.0.6 pointopoint 192.168.0.5 mtu 1500
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
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
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
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
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
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
Thu Dec 27 16:23:48 2012 us=117620 GID set to nogroup
Thu Dec 27 16:23:48 2012 us=117641 UID set to nobody
Thu Dec 27 16:23:48 2012 us=117651 Initialization Sequence Completed
Thu Dec 27 17:23:45 2012 us=465703 TLS: soft reset sec=0 bytes=10420400/0 pkts=31495/0
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
Thu Dec 27 17:23:45 2012 us=507573 VERIFY OK: nsCertType=SERVER
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
Thu Dec 27 17:23:45 2012 us=574559 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Thu Dec 27 17:23:45 2012 us=574597 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Thu Dec 27 17:23:45 2012 us=574685 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Thu Dec 27 17:23:45 2012 us=574700 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Thu Dec 27 17:23:45 2012 us=574760 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
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
Thu Dec 27 18:23:44 2012 us=813204 VERIFY OK: nsCertType=SERVER
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
Thu Dec 27 18:23:44 2012 us=880396 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Thu Dec 27 18:23:44 2012 us=880435 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Thu Dec 27 18:23:44 2012 us=880523 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Thu Dec 27 18:23:44 2012 us=880538 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Thu Dec 27 18:23:44 2012 us=880598 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
Thu Dec 27 19:23:44 2012 us=578009 TLS: soft reset sec=0 bytes=488970/0 pkts=3799/0
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
Thu Dec 27 19:23:44 2012 us=620437 VERIFY OK: nsCertType=SERVER
Thu Dec 27 19:23:44 2012 us=620457 NOTE: --mute triggered...
Sun Dec 30 02:10:26 2012 us=238033 468 variation(s) on previous 20 message(s) suppressed by --mute
Sun Dec 30 02:10:26 2012 us=238101 [pavot] Inactivity timeout (--ping-restart), restarting
Sun Dec 30 02:10:26 2012 us=238462 TCP/UDP: Closing socket
Sun Dec 30 02:10:26 2012 us=238515 SIGUSR1[soft,ping-restart] received, process restarting
Sun Dec 30 02:10:26 2012 us=238539 Restart pause, 2 second(s)
Sun Dec 30 02:10:28 2012 us=238745 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Sun Dec 30 02:10:28 2012 us=238818 Re-using SSL/TLS context
Sun Dec 30 02:10:28 2012 us=238847 LZO compression initialized
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 ]
Sun Dec 30 02:10:28 2012 us=238953 Socket Buffers: R=[229376->131072] S=[229376->131072]
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 ]
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'
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'
Sun Dec 30 02:10:33 2012 us=244898 Local Options hash (VER=V4): '41690919'
Sun Dec 30 02:10:33 2012 us=244916 Expected Remote Options hash (VER=V4): '530fdded'
Sun Dec 30 02:10:33 2012 us=244934 UDPv4 link local: [undef]
Sun Dec 30 02:10:33 2012 us=244951 UDPv4 link remote: [AF_INET]86.65.39.24:1194
Sun Dec 30 02:11:33 2012 us=85621 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Sun Dec 30 02:11:33 2012 us=85671 TLS Error: TLS handshake failed
Sun Dec 30 02:11:33 2012 us=85769 TCP/UDP: Closing socket
Sun Dec 30 02:11:33 2012 us=85808 SIGUSR1[soft,tls-error] received, process restarting
Sun Dec 30 02:11:33 2012 us=85828 Restart pause, 2 second(s)
Sun Dec 30 02:11:35 2012 us=86243 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Sun Dec 30 02:11:35 2012 us=86318 Re-using SSL/TLS context
Sun Dec 30 02:11:35 2012 us=86340 LZO compression initialized
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 ]
Sun Dec 30 02:11:35 2012 us=86450 Socket Buffers: R=[229376->131072] S=[229376->131072]
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 ]
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'
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'
Sun Dec 30 02:11:40 2012 us=92391 Local Options hash (VER=V4): '41690919'
Sun Dec 30 02:11:40 2012 us=92432 Expected Remote Options hash (VER=V4): '530fdded'
Sun Dec 30 02:11:40 2012 us=92469 UDPv4 link local: [undef]
Sun Dec 30 02:11:40 2012 us=92504 UDPv4 link remote: [AF_INET]86.65.39.24:1194
Sun Dec 30 02:12:10 2012 us=132414 TLS: Initial packet from [AF_INET]86.65.39.24:1194, sid=992de921 770f03fe
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
Sun Dec 30 02:12:10 2012 us=168629 VERIFY OK: nsCertType=SERVER
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
Sun Dec 30 02:12:10 2012 us=235979 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Sun Dec 30 02:12:10 2012 us=236018 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Sun Dec 30 02:12:10 2012 us=236136 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Sun Dec 30 02:12:10 2012 us=236158 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Sun Dec 30 02:12:10 2012 us=236220 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
Sun Dec 30 02:12:10 2012 us=236255 [pavot] Peer Connection Initiated with [AF_INET]86.65.39.24:1194
Sun Dec 30 02:12:12 2012 us=139364 SENT CONTROL [pavot]: 'PUSH_REQUEST' (status=1)
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'
Sun Dec 30 02:12:12 2012 us=146040 OPTIONS IMPORT: timers and/or timeouts modified
Sun Dec 30 02:12:12 2012 us=146058 OPTIONS IMPORT: --ifconfig/up options modified
Sun Dec 30 02:12:12 2012 us=146070 OPTIONS IMPORT: route options modified
Sun Dec 30 02:12:12 2012 us=146082 Preserving previous TUN/TAP instance: tun0
Sun Dec 30 02:12:12 2012 us=146093 Initialization Sequence Completed
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
Sun Dec 30 03:12:10 2012 us=328386 VERIFY OK: nsCertType=SERVER
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
Sun Dec 30 03:12:19 2012 us=639258 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Sun Dec 30 03:12:19 2012 us=639321 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Sun Dec 30 03:12:19 2012 us=639410 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Sun Dec 30 03:12:19 2012 us=639425 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Sun Dec 30 03:12:19 2012 us=639488 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
Sun Dec 30 04:12:10 2012 us=760554 TLS: tls_process: killed expiring key
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
Sun Dec 30 04:12:19 2012 us=497066 VERIFY OK: nsCertType=SERVER
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
Sun Dec 30 04:12:19 2012 us=563276 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Sun Dec 30 04:12:19 2012 us=563314 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Sun Dec 30 04:12:19 2012 us=563403 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Sun Dec 30 04:12:19 2012 us=563419 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Sun Dec 30 04:12:19 2012 us=563479 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
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
Sun Dec 30 05:12:19 2012 us=244574 VERIFY OK: nsCertType=SERVER
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
Sun Dec 30 05:12:19 2012 us=315387 NOTE: --mute triggered...
Wed Jan 9 02:01:10 2013 us=10484 2039 variation(s) on previous 20 message(s) suppressed by --mute
Wed Jan 9 02:01:10 2013 us=10555 [pavot] Inactivity timeout (--ping-restart), restarting
Wed Jan 9 02:01:10 2013 us=10882 TCP/UDP: Closing socket
Wed Jan 9 02:01:10 2013 us=10927 SIGUSR1[soft,ping-restart] received, process restarting
Wed Jan 9 02:01:10 2013 us=10948 Restart pause, 2 second(s)
Wed Jan 9 02:01:12 2013 us=11094 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Wed Jan 9 02:01:12 2013 us=11171 Re-using SSL/TLS context
Wed Jan 9 02:01:12 2013 us=11193 LZO compression initialized
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 ]
Wed Jan 9 02:01:12 2013 us=11322 Socket Buffers: R=[229376->131072] S=[229376->131072]
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 ]
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'
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'
Wed Jan 9 02:01:17 2013 us=17213 Local Options hash (VER=V4): '41690919'
Wed Jan 9 02:01:17 2013 us=17246 Expected Remote Options hash (VER=V4): '530fdded'
Wed Jan 9 02:01:17 2013 us=17266 UDPv4 link local: [undef]
Wed Jan 9 02:01:17 2013 us=17283 UDPv4 link remote: [AF_INET]86.65.39.24:1194
Wed Jan 9 02:01:17 2013 us=23842 TLS: Initial packet from [AF_INET]86.65.39.24:1194, sid=1a40acac bdb1db6d
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
Wed Jan 9 02:01:17 2013 us=59510 VERIFY OK: nsCertType=SERVER
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
Wed Jan 9 02:01:17 2013 us=126880 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Wed Jan 9 02:01:17 2013 us=126918 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Jan 9 02:01:17 2013 us=127006 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Wed Jan 9 02:01:17 2013 us=127022 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Jan 9 02:01:17 2013 us=127082 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
Wed Jan 9 02:01:17 2013 us=127117 [pavot] Peer Connection Initiated with [AF_INET]86.65.39.24:1194
Wed Jan 9 02:01:19 2013 us=208901 SENT CONTROL [pavot]: 'PUSH_REQUEST' (status=1)
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'
Wed Jan 9 02:01:19 2013 us=215553 OPTIONS IMPORT: timers and/or timeouts modified
Wed Jan 9 02:01:19 2013 us=215570 OPTIONS IMPORT: --ifconfig/up options modified
Wed Jan 9 02:01:19 2013 us=215583 OPTIONS IMPORT: route options modified
Wed Jan 9 02:01:19 2013 us=215595 Preserving previous TUN/TAP instance: tun0
Wed Jan 9 02:01:19 2013 us=215607 Initialization Sequence Completed
Wed Jan 9 03:01:17 2013 us=555956 TLS: soft reset sec=0 bytes=24093827/0 pkts=34510/0
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
Wed Jan 9 03:01:17 2013 us=597619 VERIFY OK: nsCertType=SERVER
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
Wed Jan 9 03:01:17 2013 us=665221 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Wed Jan 9 03:01:17 2013 us=665259 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Jan 9 03:01:17 2013 us=665346 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Wed Jan 9 03:01:17 2013 us=665362 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Jan 9 03:01:17 2013 us=665422 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
Wed Jan 9 04:01:17 2013 us=527137 TLS: soft reset sec=0 bytes=665428/0 pkts=4932/0
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
Wed Jan 9 04:01:17 2013 us=569128 VERIFY OK: nsCertType=SERVER
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
Wed Jan 9 04:01:17 2013 us=636530 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Wed Jan 9 04:01:17 2013 us=636569 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Jan 9 04:01:17 2013 us=636657 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Wed Jan 9 04:01:17 2013 us=636673 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Jan 9 04:01:17 2013 us=636734 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
Wed Jan 9 05:01:17 2013 us=43543 TLS: soft reset sec=0 bytes=638411/0 pkts=4843/0
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
Wed Jan 9 05:01:17 2013 us=85470 NOTE: --mute triggered...
Thu Jan 10 02:00:12 2013 us=82499 178 variation(s) on previous 20 message(s) suppressed by --mute
Thu Jan 10 02:00:12 2013 us=82541 [pavot] Inactivity timeout (--ping-restart), restarting
Thu Jan 10 02:00:12 2013 us=82891 TCP/UDP: Closing socket
Thu Jan 10 02:00:12 2013 us=82936 SIGUSR1[soft,ping-restart] received, process restarting
Thu Jan 10 02:00:12 2013 us=82956 Restart pause, 2 second(s)
Thu Jan 10 02:00:14 2013 us=83083 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Jan 10 02:00:14 2013 us=83155 Re-using SSL/TLS context
Thu Jan 10 02:00:14 2013 us=83177 LZO compression initialized
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 ]
Thu Jan 10 02:00:14 2013 us=83282 Socket Buffers: R=[229376->131072] S=[229376->131072]
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 ]
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'
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'
Thu Jan 10 02:00:19 2013 us=90174 Local Options hash (VER=V4): '41690919'
Thu Jan 10 02:00:19 2013 us=90193 Expected Remote Options hash (VER=V4): '530fdded'
Thu Jan 10 02:00:19 2013 us=90212 UDPv4 link local: [undef]
Thu Jan 10 02:00:19 2013 us=90230 UDPv4 link remote: [AF_INET]86.65.39.24:1194
Thu Jan 10 02:01:19 2013 us=152523 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Jan 10 02:01:19 2013 us=152594 TLS Error: TLS handshake failed
Thu Jan 10 02:01:19 2013 us=152690 TCP/UDP: Closing socket
Thu Jan 10 02:01:19 2013 us=152728 SIGUSR1[soft,tls-error] received, process restarting
Thu Jan 10 02:01:19 2013 us=152748 Restart pause, 2 second(s)
Thu Jan 10 02:01:21 2013 us=152975 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Jan 10 02:01:21 2013 us=153045 Re-using SSL/TLS context
Thu Jan 10 02:01:21 2013 us=153066 LZO compression initialized
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 ]
Thu Jan 10 02:01:21 2013 us=153168 Socket Buffers: R=[229376->131072] S=[229376->131072]
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 ]
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'
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'
Thu Jan 10 02:01:26 2013 us=158996 Local Options hash (VER=V4): '41690919'
Thu Jan 10 02:01:26 2013 us=159014 Expected Remote Options hash (VER=V4): '530fdded'
Thu Jan 10 02:01:26 2013 us=159033 UDPv4 link local: [undef]
Thu Jan 10 02:01:26 2013 us=159050 UDPv4 link remote: [AF_INET]86.65.39.24:1194
Thu Jan 10 02:02:26 2013 us=193077 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Jan 10 02:02:26 2013 us=193122 TLS Error: TLS handshake failed
Thu Jan 10 02:02:26 2013 us=193220 TCP/UDP: Closing socket
Thu Jan 10 02:02:26 2013 us=193259 SIGUSR1[soft,tls-error] received, process restarting
Thu Jan 10 02:02:26 2013 us=193279 Restart pause, 2 second(s)
Thu Jan 10 02:02:28 2013 us=193409 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Jan 10 02:02:28 2013 us=193477 Re-using SSL/TLS context
Thu Jan 10 02:02:28 2013 us=193498 LZO compression initialized
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 ]
Thu Jan 10 02:02:28 2013 us=193619 Socket Buffers: R=[229376->131072] S=[229376->131072]
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 ]
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'
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'
Thu Jan 10 02:02:33 2013 us=199434 Local Options hash (VER=V4): '41690919'
Thu Jan 10 02:02:33 2013 us=199453 Expected Remote Options hash (VER=V4): '530fdded'
Thu Jan 10 02:02:33 2013 us=199471 UDPv4 link local: [undef]
Thu Jan 10 02:02:33 2013 us=199488 UDPv4 link remote: [AF_INET]86.65.39.24:1194
Thu Jan 10 02:03:31 2013 us=545571 TLS: Initial packet from [AF_INET]86.65.39.24:1194, sid=7fd74e7b 96b9de13
Thu Jan 10 02:03:33 2013 us=98865 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Jan 10 02:03:33 2013 us=98925 TLS Error: TLS handshake failed
Thu Jan 10 02:03:33 2013 us=99015 TCP/UDP: Closing socket
Thu Jan 10 02:03:33 2013 us=99053 SIGUSR1[soft,tls-error] received, process restarting
Thu Jan 10 02:03:33 2013 us=99072 Restart pause, 2 second(s)
Thu Jan 10 02:03:35 2013 us=99237 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Jan 10 02:03:35 2013 us=99310 Re-using SSL/TLS context
Thu Jan 10 02:03:35 2013 us=99331 LZO compression initialized
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 ]
Thu Jan 10 02:03:35 2013 us=99438 Socket Buffers: R=[229376->131072] S=[229376->131072]
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 ]
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'
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'
Thu Jan 10 02:03:40 2013 us=105217 Local Options hash (VER=V4): '41690919'
Thu Jan 10 02:03:40 2013 us=105236 Expected Remote Options hash (VER=V4): '530fdded'
Thu Jan 10 02:03:40 2013 us=105254 UDPv4 link local: [undef]
Thu Jan 10 02:03:40 2013 us=105272 UDPv4 link remote: [AF_INET]86.65.39.24:1194
Thu Jan 10 02:04:29 2013 us=5894 TLS: Initial packet from [AF_INET]86.65.39.24:1194, sid=1e102e60 0a63fb7a
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
Thu Jan 10 02:04:32 2013 us=382692 VERIFY OK: nsCertType=SERVER
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
Thu Jan 10 02:04:32 2013 us=468690 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Thu Jan 10 02:04:32 2013 us=468752 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Thu Jan 10 02:04:32 2013 us=468840 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Thu Jan 10 02:04:32 2013 us=468855 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Thu Jan 10 02:04:32 2013 us=468917 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
Thu Jan 10 02:04:32 2013 us=468956 [pavot] Peer Connection Initiated with [AF_INET]86.65.39.24:1194
Thu Jan 10 02:04:34 2013 us=479121 SENT CONTROL [pavot]: 'PUSH_REQUEST' (status=1)
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'
Thu Jan 10 02:04:35 2013 us=670874 OPTIONS IMPORT: timers and/or timeouts modified
Thu Jan 10 02:04:35 2013 us=670893 OPTIONS IMPORT: --ifconfig/up options modified
Thu Jan 10 02:04:35 2013 us=670904 OPTIONS IMPORT: route options modified
Thu Jan 10 02:04:35 2013 us=670917 Preserving previous TUN/TAP instance: tun0
Thu Jan 10 02:04:35 2013 us=670928 NOTE: Pulled options changed on restart, will need to close and reopen TUN/TAP device.
Thu Jan 10 02:04:35 2013 us=670957 /sbin/route del -net 192.168.1.0 netmask 255.255.255.0
SIOCDELRT: Operation not permitted
Thu Jan 10 02:04:35 2013 us=678270 ERROR: Linux route delete command failed: external program exited with error status: 7
Thu Jan 10 02:04:35 2013 us=678328 /sbin/route del -net 192.168.2.0 netmask 255.255.255.0
SIOCDELRT: Operation not permitted
Thu Jan 10 02:04:35 2013 us=679305 ERROR: Linux route delete command failed: external program exited with error status: 7
Thu Jan 10 02:04:35 2013 us=679359 /sbin/route del -net 192.168.3.0 netmask 255.255.255.0
SIOCDELRT: Operation not permitted
Thu Jan 10 02:04:35 2013 us=680337 ERROR: Linux route delete command failed: external program exited with error status: 7
Thu Jan 10 02:04:35 2013 us=680389 /sbin/route del -net 192.168.5.0 netmask 255.255.255.0
SIOCDELRT: Operation not permitted
Thu Jan 10 02:04:35 2013 us=681341 ERROR: Linux route delete command failed: external program exited with error status: 7
Thu Jan 10 02:04:35 2013 us=681392 /sbin/route del -net 192.168.0.0 netmask 255.255.255.0
SIOCDELRT: Operation not permitted
Thu Jan 10 02:04:35 2013 us=682378 ERROR: Linux route delete command failed: external program exited with error status: 7
Thu Jan 10 02:04:35 2013 us=682425 Closing TUN/TAP interface
Thu Jan 10 02:04:35 2013 us=682447 /sbin/ifconfig tun0 0.0.0.0
SIOCSIFADDR: Permission denied
SIOCSIFFLAGS: Permission denied
Thu Jan 10 02:04:35 2013 us=689329 Linux ip addr del failed: external program exited with error status: 255
Thu Jan 10 02:04:37 2013 us=966350 ROUTE default_gateway=10.145.4.4
Thu Jan 10 02:04:37 2013 us=966495 Note: Cannot ioctl TUNSETIFF tun: Operation not permitted (errno=1)
Thu Jan 10 02:04:37 2013 us=966513 do_ifconfig, tt->ipv6=0, tt->did_ifconfig_ipv6_setup=0
Thu Jan 10 02:04:37 2013 us=966542 /sbin/ifconfig 192.168.0.34 pointopoint 192.168.0.33 mtu 1500
SIOCSIFADDR: Permission denied
: ERROR while getting interface flags: No such device
SIOCSIFDSTADDR: Permission denied
: ERROR while getting interface flags: No such device
SIOCSIFMTU: Operation not permitted
Thu Jan 10 02:04:37 2013 us=967880 Linux ifconfig failed: external program exited with error status: 1
Thu Jan 10 02:04:37 2013 us=967919 Exiting
    (1-1/1)