OpenVPN in 1.9

[roothorick]

I've got LGB 1.9 running on my droid, I have to say I'm rather impressed, but I've hit one issue...

I can't get OpenVPN to work. Everything's set up the way it's supposed to be as far as I can tell, but the connection never makes it to the server (nothing ever shows up in the logs serverside). I tried adding tun.ko from here to /system/lib/modules, but it still didn't fly. This is what I'm getting in logcat when I attempt the connection:

D/EAS SyncManager( 1623): !!! EAS SyncManager, onCreate
D/EAS SyncManager( 1623): Creating Intent with time(ms)=13814
D/EAS SyncManager( 1623): !!! EAS SyncManager, onStartCommand
D/EAS SyncManager( 1623): !!! EAS SyncManager, stopping self
D/EAS SyncManager( 1623): !!! EAS SyncManager, onDestroy
I/keystore( 1025): uid: 1000 action: t -> 1 state: 1 -> 1 retry: 4
D/VpnManager( 1585): succeeded to connect to VPN service
D/com.android.settings.vpn.AuthenticationActor( 1585): ~~~~~~ connect() succeeded!
I/SProxy_mtpd( 1962): Stop VPN daemon: mtpd
I/mtpd    ( 2019): Received signal 15
I/mtpd    ( 2019): Sending signal to pppd (pid = 2020)
D/VpnSettings( 1585): received connectivity: GhettoCluster: connected? CONNECTING   err=0
I/mtpd    ( 2019): Mtpd is terminated (status = 6)
D/SProxy_mtpd( 1962): stopping mtpd, success? true
I/SProxy_racoon( 1962): Stop VPN daemon: racoon
D/SProxy_racoon( 1962): racoon is stopped after 0 msec
D/SProxy_racoon( 1962): stopping racoon, success? true
D/VpnService( 1962):   Local IP: 10.224.120.224, if: ppp0
D/VpnService( 1962):        VPN UP: down
I/SProxy_mtpd( 1962): Start VPN daemon: mtpd
D/mtpd    ( 2082): Waiting for control socket
D/SProxy_mtpd( 1962): mtpd is running after 200 msec
D/mtpd    ( 2082): Received 31 arguments
I/mtpd    ( 2082): Using protocol openvpn
I/mtpd    ( 2082): Starting openvpn (pppox = -1)
I/mtpd    ( 2082): openvpn started (pid = 2083)
F/mtpd    ( 2083): Exec() Permission denied
I/mtpd    ( 2082): Received signal 17
I/mtpd    ( 2082): Pppd is terminated (status = 1)
I/mtpd    ( 2082): Mtpd is terminated (status = 33)
I/SProxy_mtpd( 1962): got data from control socket: 31
E/VpnService( 1962): onError()
E/VpnService( 1962): java.io.IOException: No such file or directory
E/VpnService( 1962):     at android.net.LocalSocketImpl.connectLocal(Native Method)
E/VpnService( 1962):     at android.net.LocalSocketImpl.connect(LocalSocketImpl.java:238)
E/VpnService( 1962):     at android.net.LocalSocket.connect(LocalSocket.java:98)
E/VpnService( 1962):     at com.android.server.vpn.OpenvpnService$OpenvpnThread.openSocket(OpenvpnService.java:247)
E/VpnService( 1962):     at com.android.server.vpn.OpenvpnService$OpenvpnThread.<init>(OpenvpnService.java:194)
E/VpnService( 1962):     at com.android.server.vpn.OpenvpnService.waitUntilConnectedOrTimedout(OpenvpnService.java:149)
E/VpnService( 1962):     at com.android.server.vpn.VpnService.onConnect(VpnService.java:148)
E/VpnService( 1962):     at com.android.server.vpn.VpnServiceBinder$2.run(VpnServiceBinder.java:118)
E/VpnService( 1962):     at java.lang.Thread.run(Thread.java:1019)
I/VpnService( 1962): disconnecting VPN...
D/VpnSettings( 1585): received connectivity: GhettoCluster: connected? DISCONNECTING   err=0
V/NotificationService( 1127): Active profile: Default
I/PROFILE ( 1127): Group: Gmail containing : com.android.server.vpn : false
I/PROFILE ( 1127): Group: Phone containing : com.android.server.vpn : false
I/PROFILE ( 1127): Group: Calendar containing : com.android.server.vpn : false
I/PROFILE ( 1127): Group: Email containing : com.android.server.vpn : false
I/PROFILE ( 1127): Group: SMS containing : com.android.server.vpn : false
V/ProfileManager( 1127): No active group, returning default: Other
V/NotificationService( 1127): Pkg: com.android.server.vpn group: Other
D/VpnService( 1962): onFinalCleanUp()
I/VpnService( 1962): restore original suffices --> null
D/VpnSettings( 1585): received connectivity: GhettoCluster: connected? IDLE   err=101

[roothorick]

So, apparently openvpn isn't available on this ROM. I found this thread, downloaded a static binary of openvpn, added it to /system/bin, and now it fails a lot faster, and logcat is a lot more interesting:

I/keystore( 1017): uid: 1000 action: t -> 1 state: 1 -> 1 retry: 4
D/VpnManager( 4938): succeeded to connect to VPN service
D/com.android.settings.vpn.AuthenticationActor( 4938): ~~~~~~ connect() succeeded!
W/InputManagerService( 1107): Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@40521c00
D/VpnSettings( 4938): received connectivity: GhettoCluster: connected? CONNECTING   err=0
I/SProxy_mtpd( 5122): Stop VPN daemon: mtpd
D/SProxy_mtpd( 5122): mtpd is stopped after 0 msec
D/SProxy_mtpd( 5122): stopping mtpd, success? true
I/SProxy_racoon( 5122): Stop VPN daemon: racoon
D/SProxy_racoon( 5122): racoon is stopped after 0 msec
D/SProxy_racoon( 5122): stopping racoon, success? true
D/VpnService( 5122):   Local IP: 192.168.3.128, if: tiwlan0
D/VpnService( 5122):        VPN UP: down
I/SProxy_mtpd( 5122): Start VPN daemon: mtpd
D/mtpd    ( 5289): Waiting for control socket
D/SProxy_mtpd( 5122): mtpd is running after 200 msec
D/mtpd    ( 5289): Received 31 arguments
I/mtpd    ( 5289): Using protocol openvpn
I/mtpd    ( 5289): Starting openvpn (pppox = -1)
I/mtpd    ( 5289): openvpn started (pid = 5290)
I/SProxy_mtpd( 5122): got data from control socket: 31
I/System.out( 5122): THREAD Thread[Thread-17,5,main] RUNNING
I/mtpd    ( 5289): Received signal 17
I/mtpd    ( 5289): Pppd is terminated (status = 1)
I/mtpd    ( 5289): Mtpd is terminated (status = 33)
I/openvpn ( 5122): WARNING: you are using user/group/chroot/setcon without persist-tun -- this may cause restarts to fail
I/openvpn ( 5122): WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
E/VpnService( 5122): onError()
E/VpnService( 5122): java.io.IOException: Broken pipe
E/VpnService( 5122):     at android.net.LocalSocketImpl.writeba_native(Native Method)
E/VpnService( 5122):     at android.net.LocalSocketImpl.access$600(LocalSocketImpl.java:29)
E/VpnService( 5122):     at android.net.LocalSocketImpl$SocketOutputStream.write(LocalSocketImpl.java:126)
E/VpnService( 5122):     at android.net.LocalSocketImpl$SocketOutputStream.write(LocalSocketImpl.java:113)
E/VpnService( 5122):     at com.android.server.vpn.OpenvpnService$OpenvpnThread.sendAsync(OpenvpnService.java:282)
E/VpnService( 5122):     at com.android.server.vpn.OpenvpnService$OpenvpnThread.send(OpenvpnService.java:287)
E/VpnService( 5122):     at com.android.server.vpn.OpenvpnService$OpenvpnThread.openvpnStart(OpenvpnService.java:205)
E/VpnService( 5122):     at com.android.server.vpn.OpenvpnService.waitUntilConnectedOrTimedout(OpenvpnService.java:150)
E/VpnService( 5122):     at com.android.server.vpn.VpnService.onConnect(VpnService.java:148)
E/VpnService( 5122):     at com.android.server.vpn.VpnServiceBinder$2.run(VpnServiceBinder.java:118)
E/VpnService( 5122):     at java.lang.Thread.run(Thread.java:1019)
I/openvpn ( 5122): NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
I/VpnService( 5122): disconnecting VPN...
I/openvpn ( 5122): Cannot load inline certificate file: error:0906D06C:PEM routines:PEM_read_bio:no start line
I/System.out( 5122): THREAD Thread[Thread-17,5,main] TERMINATED
D/VpnSettings( 4938): received connectivity: GhettoCluster: connected? DISCONNECTING   err=0

V/NotificationService( 1107): Active profile: Default
I/PROFILE ( 1107): Group: Gmail containing : com.android.server.vpn : false
I/PROFILE ( 1107): Group: Phone containing : com.android.server.vpn : false
I/PROFILE ( 1107): Group: Calendar containing : com.android.server.vpn : false
I/PROFILE ( 1107): Group: Email containing : com.android.server.vpn : false
I/PROFILE ( 1107): Group: SMS containing : com.android.server.vpn : false
V/ProfileManager( 1107): No active group, returning default: Other
V/NotificationService( 1107): Pkg: com.android.server.vpn group: Other
D/VpnService( 5122): onFinalCleanUp()
I/VpnService( 5122): restore original suffices --> null
D/VpnSettings( 4938): received connectivity: GhettoCluster: connected? IDLE   err=101