Failure to connect to internet

Ubunutu 13.04 Version: 0.5.1-69-g5210383e (5210383e) From virtualbox VM on Max

2014-06-02 13:38:10,665 - DEBUG - L#139 : leap:add_logger_handlers() - Leap handler plugged!


2014-06-02 13:38:10,665 - DEBUG - L#143 : leap:add_logger_handlers() - Setting logfile to bitmask.log 


2014-06-02 13:38:10,665 - DEBUG - L#149 : leap:add_logger_handlers() - File handler plugged!


2014-06-02 13:38:10,665 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] Log opened.


2014-06-02 13:38:10,675 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] ----------------------------------------


2014-06-02 13:38:10,675 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] Exception happened during processing of request from ('127.0.0.1', 57646)


2014-06-02 13:38:10,675 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] Traceback (most recent call last):


2014-06-02 13:38:10,675 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] File "/home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/lib/SocketServer.py", line 593, in process_request_thread


2014-06-02 13:38:10,677 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] self.finish_request(request, client_address)


2014-06-02 13:38:10,678 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] File "/home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/lib/protobuf/socketrpc/server.py", line 244, in finish_request


2014-06-02 13:38:10,680 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] request, client_address, self, self.socket_rpc_server)


2014-06-02 13:38:10,726 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] File "/home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/lib/protobuf/socketrpc/server.py", line 83, in __init__


2014-06-02 13:38:10,729 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] self, request, client_address, server)


2014-06-02 13:38:10,729 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] File "/home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/lib/SocketServer.py", line 649, in __init__


2014-06-02 13:38:10,731 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] self.handle()


2014-06-02 13:38:10,803 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] File "/home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/lib/protobuf/socketrpc/server.py", line 98, in handle


2014-06-02 13:38:10,806 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] self.request.shutdown(socket.SHUT_WR)


2014-06-02 13:38:10,807 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] File "/home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/lib/socket.py", line 224, in meth


2014-06-02 13:38:10,821 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] return getattr(self._sock,name)(*args)


2014-06-02 13:38:10,822 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] error: [Errno 107] Transport endpoint is not connected


2014-06-02 13:38:10,850 - DEBUG - L#53 : leap:write() - 2014-06-02 13:38:10-0400 [-] ----------------------------------------


2014-06-02 13:38:11,305 - DEBUG - L#72 : leap.bitmask.util.requirement_checker:check_requirements() - Checking requirements...


2014-06-02 13:38:11,358 - ERROR - L#62 : leap.bitmask.util.requirement_checker:get_requirements() - Requirements file not found. DistributionNotFound(Requirement.parse('leap.bitmask'),)


2014-06-02 13:38:11,358 - DEBUG - L#102 : leap.bitmask.util.requirement_checker:check_requirements() - Done


2014-06-02 13:38:11,358 - INFO - L#279 : leap:main() - ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~


2014-06-02 13:38:11,358 - INFO - L#280 : leap:main() - Bitmask version 0.5.1-69-g5210383


2014-06-02 13:38:11,358 - INFO - L#281 : leap:main() - leap.mail version unknown


2014-06-02 13:38:11,358 - INFO - L#282 : leap:main() - ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~


2014-06-02 13:38:11,358 - INFO - L#284 : leap:main() - Starting app


2014-06-02 13:38:12,655 - DEBUG - L#1505 : leap.bitmask.backend:start() - Starting worker...


2014-06-02 13:38:12,743 - DEBUG - L#58 : leap.bitmask.util.keyring_helpers:_get_keyring_with_fallback() - Selected keyring: 


2014-06-02 13:38:12,946 - DEBUG - L#59 : leap.bitmask.platform_init.initializers:init_platform() - Running initializer for Linux


2014-06-02 13:38:13,020 - DEBUG - L#107 : leap.bitmask.services.eip.conductor:start_eip_machine() - eip machine started


2014-06-02 13:38:13,021 - DEBUG - L#231 : leap.bitmask.services.mail.conductor:start_mail_machine() - Starting mail state machine...


2014-06-02 13:38:13,178 - DEBUG - L#59 : leap.bitmask.gui.statemachines:onEntry() - State Encrypted Internet::off entered. Emitting signal ...


2014-06-02 13:38:13,182 - DEBUG - L#59 : leap.bitmask.gui.statemachines:onEntry() - State Mail::off entered. Emitting signal ...


2014-06-02 13:38:13,236 - DEBUG - L#59 : leap.bitmask.gui.statemachines:onEntry() - State IMAP::off entered. Emitting signal ...


2014-06-02 13:38:13,240 - DEBUG - L#59 : leap.bitmask.gui.statemachines:onEntry() - State IMAP::off entered. Emitting signal ...


2014-06-02 13:38:14,609 - DEBUG - L#295 : leap.bitmask.services.eip.eipconfig:get_client_cert_path() - Using OpenVPN cert /home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/config/leap/providers/demo.bitmask.net/keys/client/openvpn.pem


2014-06-02 13:38:14,658 - DEBUG - L#332 : leap.bitmask.gui.eip_status:set_eip_status() - 


2014-06-02 13:38:14,666 - DEBUG - L#332 : leap.bitmask.gui.eip_status:set_eip_status() - Starting...


2014-06-02 13:38:14,857 - DEBUG - L#66 : leap.bitmask.services.eip.eipbootstrapper:_download_config() - Downloading EIP config for demo.bitmask.net


2014-06-02 13:38:14,902 - DEBUG - L#127 : leap.bitmask.services:download_service_config() - Downloading EIP config from: https://api.demo.bitmask.net:4430/1/config/eip-service.json


2014-06-02 13:38:14,959 - DEBUG - L#221 : leap.bitmask.config.providerconfig:get_ca_cert_path() - Going to verify SSL against /home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/config/leap/providers/demo.bitmask.net/keys/ca/cacert.pem


2014-06-02 13:38:15,720 - DEBUG - L#160 : leap.bitmask.services:download_service_config() - EIP definition has not been modified


2014-06-02 13:38:15,729 - DEBUG - L#83 : leap.bitmask.services.eip.eipbootstrapper:_download_client_certificates() - Downloading EIP client certificate for demo.bitmask.net


2014-06-02 13:38:15,731 - DEBUG - L#59 : leap.bitmask.gui.statemachines:onEntry() - State Encrypted Internet::connecting entered. Emitting signal ...


2014-06-02 13:38:15,873 - DEBUG - L#295 : leap.bitmask.services.eip.eipconfig:get_client_cert_path() - Using OpenVPN cert /home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/config/leap/providers/demo.bitmask.net/keys/client/openvpn.pem


2014-06-02 13:38:15,944 - DEBUG - L#180 : leap.bitmask.services.eip.vpnprocess:start() - VPN: start


2014-06-02 13:38:16,267 - DEBUG - L#136 : leap.bitmask.services.eip.vpnlauncher:get_gateways() - Using gateways ips: 198.252.153.84, 85.17.92.167


2014-06-02 13:38:20,835 - DEBUG - L#136 : leap.bitmask.services.eip.vpnlauncher:get_gateways() - Using gateways ips: 198.252.153.84, 85.17.92.167


2014-06-02 13:38:20,873 - DEBUG - L#295 : leap.bitmask.services.eip.eipconfig:get_client_cert_path() - Using OpenVPN cert /home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/config/leap/providers/demo.bitmask.net/keys/client/openvpn.pem


2014-06-02 13:38:20,907 - DEBUG - L#295 : leap.bitmask.services.eip.eipconfig:get_client_cert_path() - Using OpenVPN cert /home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/config/leap/providers/demo.bitmask.net/keys/client/openvpn.pem


2014-06-02 13:38:20,936 - DEBUG - L#221 : leap.bitmask.config.providerconfig:get_ca_cert_path() - Going to verify SSL against /home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/config/leap/providers/demo.bitmask.net/keys/ca/cacert.pem


2014-06-02 13:38:23,383 - DEBUG - L#921 : leap.bitmask.services.eip.vpnprocess:getCommand() - Running VPN with command: 


2014-06-02 13:38:23,390 - DEBUG - L#922 : leap.bitmask.services.eip.vpnprocess:getCommand() - /usr/bin/pkexec /usr/sbin/bitmask-root openvpn start /usr/sbin/leap-openvpn --setenv LEAPOPENVPN 1 --nobind --remote 198.252.153.84 1194 udp --remote 85.17.92.167 1194 udp --client --dev tun --persist-key --tls-client --remote-cert-tls server --tls-cipher DHE-RSA-AES128-SHA --cipher AES-128-CBC --auth SHA1 --management-client-user mcnair --management-signal --management /tmp/leap-tmpMmAXSc/openvpn.socket unix --script-security 2 --cert /home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/config/leap/providers/demo.bitmask.net/keys/client/openvpn.pem --key /home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/config/leap/providers/demo.bitmask.net/keys/client/openvpn.pem --ca /home/mcnair/Desktop/Bitmask-linux64-2014-05-30-dde86d75/config/leap/providers/demo.bitmask.net/keys/ca/cacert.pem --ping 10 --ping-restart 30


2014-06-02 13:38:23,425 - DEBUG - L#565 : leap.bitmask.services.eip.vpnprocess:try_to_connect_to_management() - trying to connect to management


2014-06-02 13:38:23,430 - WARNING - L#498 : leap.bitmask.services.eip.vpnprocess:_connect_management() - Could not connect to OpenVPN yet: MissingSocketError()


2014-06-02 13:38:23,440 - DEBUG - L#510 : leap.bitmask.services.eip.vpnprocess:_connectCb() - Cannot connect to management...


2014-06-02 13:38:23,440 - DEBUG - L#442 : leap.bitmask.backend:start() - EIP: no errors


2014-06-02 13:38:23,827 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:23 2014 OpenVPN 2.3_git [git:master/0a48ae367f4de3d9+] x86_64-unknown-linux-gnu [SSL (OpenSSL)] [LZO] [EPOLL] [eurephia] [MH] [IPv6] built on Apr 22 2014


2014-06-02 13:38:23,841 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:23 2014 WARNING: you are using user/group/chroot/setcon without persist-tun -- this may cause restarts to fail


2014-06-02 13:38:23,843 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:23 2014 WARNING: you are using user/group/chroot/setcon without persist-key -- this may cause restarts to fail


2014-06-02 13:38:23,845 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:23 2014 Deprecated TLS cipher name 'DHE-RSA-AES128-SHA', please use IANA name 'TLS-DHE-RSA-WITH-AES-128-CBC-SHA'


2014-06-02 13:38:23,847 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:23 2014 NOTE: UID/GID downgrade will be delayed because of --client, --pull, or --up-delay


2014-06-02 13:38:23,852 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:23 2014 UDPv4 link local: [undef] Mon Jun 2 13:38:23 2014 UDPv4 link remote: [AF_INET]198.252.153.84:1194


2014-06-02 13:38:24,442 - DEBUG - L#565 : leap.bitmask.services.eip.vpnprocess:try_to_connect_to_management() - trying to connect to management


2014-06-02 13:38:24,454 - INFO - L#508 : leap.bitmask.services.eip.vpnprocess:_connectCb() - Connected to management


2014-06-02 13:38:24,465 - DEBUG - L#332 : leap.bitmask.gui.eip_status:set_eip_status() - Authenticating...


2014-06-02 13:38:25,471 - DEBUG - L#565 : leap.bitmask.services.eip.vpnprocess:try_to_connect_to_management() - trying to connect to management


2014-06-02 13:38:26,105 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:26 2014 [millipede.demo.bitmask.net] Peer Connection Initiated with [AF_INET]198.252.153.84:1194


2014-06-02 13:38:27,453 - DEBUG - L#332 : leap.bitmask.gui.eip_status:set_eip_status() - Retrieving configuration...


2014-06-02 13:38:28,561 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:28 2014 TUN/TAP device tun0 opened Mon Jun 2 13:38:28 2014 do_ifconfig, tt->ipv6=1, tt->did_ifconfig_ipv6_setup=1 Mon Jun 2 13:38:28 2014 /sbin/ifconfig tun0 10.42.0.2 netmask 255.255.248.0 mtu 1500 broadcast 10.42.7.255


2014-06-02 13:38:28,740 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:28 2014 /sbin/ifconfig tun0 add 2001:db8:123::1000/64


2014-06-02 13:38:28,912 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:28 2014 ERROR: Linux route add command failed: external program exited with error status: 7


2014-06-02 13:38:28,947 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:28 2014 add_route_ipv6(2000::/3 -> 2001:db8:123::1 metric -1) dev tun0


2014-06-02 13:38:28,969 - INFO - L#854 : leap.openvpn:outReceived() - Mon Jun 2 13:38:28 2014 GID set to nogroup Mon Jun 2 13:38:28 2014 UID set to nobody Mon Jun 2 13:38:28 2014 Initialization Sequence Completed


2014-06-02 13:38:28,972 - DEBUG - L#96 : leap.bitmask.services.eip.vpnprocess:watch() - pattern matched! Initialization Sequence Completed


2014-06-02 13:38:29,461 - DEBUG - L#1363 : leap.bitmask.gui.mainwindow:_maybe_run_soledad_setup_checks() - Provider does not offer MX, but it is enabled.


2014-06-02 13:38:29,463 - DEBUG - L#59 : leap.bitmask.gui.statemachines:onEntry() - State Encrypted Internet::on entered. Emitting signal ...


2014-06-02 13:38:29,466 - ERROR - L#1513 : leap.bitmask.gui.mainwindow:check_err() - >


2014-06-02 13:38:29,467 - ERROR - L#1514 : leap.bitmask.gui.mainwindow:check_err() - Can't resolve hostname.


2014-06-02 13:39:14,688 - DEBUG - L#1363 : leap.bitmask.gui.mainwindow:_maybe_run_soledad_setup_checks() - Provider does not offer MX, but it is enabled.


2014-06-02 13:43:14,133 - ERROR - L#242 : leap.bitmask.gui.loggerwindow:pastebin_err() - >

(from redmine: created on 2014-06-02, closed on 2014-06-19, relates #5738 (closed), relates #5725)