Batteries included
Post Reply
m4f1050
Posts: 9
Joined: 02 Mar 2016, 03:54

PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with old fw

Post by m4f1050 » 22 May 2017, 23:46

Long story short, I was running KitKat 4.4.4 with PPP Widget v1 and had to downgrade my modem's firmware to get it to work.
Now I got it to work on 5.1.1 and PPP Widget v3.
It connects but no internet.

Code: Select all

Jan 1, 2012 00:04:02

Device manufacturer string: rockchip
New device on /dev/bus/usb/002/003
 Class of interface 0 is 0x08
Looking at USB device 12d1:14fe with class 8
 Device top dir: /sys/bus/usb/devices/2-1.2, active cfg: 1
Active configuration ID: 1
Device layout (config 1) - cl:00  sc:00  pr:00
 if:0 as:0 -  cl:08  sc:06  pr:50 -  bo:01  bi:81
 if:1 as:0 -  cl:08  sc:06  pr:50 -  bo:02  bi:82

Found 0 serial ports
No dedicated modem port found
Preparing to switch mode of USB device 12d1:14fe ...
Device info:
 VID=	 12d1
 PID=	 14fe
 Manufact.= HUAWEI Technology
   Product= HUAWEI Mobile
Found matching configuration in collection: 12d1.14fe
Go for alternate Huawei mode as configured ...
Wait for 200 ms
Bulk message 1: 55534243123456780000000000000011063000000100010000000000000000
  transmitted
New device on /dev/bus/usb/002/004
 Class of interface 0 is 0xFF
Looking at USB device 12d1:1001 with class 255
 Device top dir: /sys/bus/usb/devices/2-1.2, active cfg: 1
Active configuration ID: 1
Device layout (config 1) - cl:00  sc:00  pr:00
 if:0 as:0 -  cl:ff  sc:ff  pr:ff -  ir:81  bi:82  bo:01
 if:1 as:0 -  cl:ff  sc:ff  pr:ff -  bi:83  bo:02
 if:2 as:0 -  cl:ff  sc:ff  pr:ff -  bi:84  bo:03

Found 3 serial ports
Opening port type SERIAL

Code: Select all

Jan 1, 2012 00:04:11

Starting modem command sequence ...
>
AT+CFUN?
<
+CFUN: 1

OK
>
AT+CGDCONT=1,"IP","fast.t-mobile.com"
<
OK
>
ATD*99#
<
CONNECT
====
Dialling complete


Now start PPP negotiation ...
>
ff03c021010000140206000000000506f7adb02907020802a565
<
ff03c0210101001d08020702020600000000010405dc0506000005430305c223055c67ff03c021020000140206000000000506f7adb029070208024e0c
>
ff03c021030100080304c0232248
<
ff03c0210102001c08020702020600000000010405dc0506000005430304c023692e
>
ff03c0210202001c08020702020600000000010405dc0506000005430304c0235a74
>
c0230101000600003b3f
<
c023020100100b4772656574696e67732121be44802101010004bb99
>
802101000016030600000000810600000000830600000000627c
<
802103000010810600000000830600000000cb8b
>
8021010100160306000000008106000000008306000000000e4b
<
802103010010810600000000830600000000e1c3
>
802101020016030600000000810600000000830600000000ba12
<
8021030200108106000000008306000000009f1b
>
802101030016030600000000810600000000830600000000d625
<
80210303001603061a4e199681060ab1002283060ab100d29833
>
80210104001603061a4e199681060ab1002283060ab100d237ed
<
80210204001603061a4e199681060ab1002283060ab100d2c11e
 
Local IP: 26.78.25.150/32
 Peer IP: 10.64.64.64
    DNS1: 10.177.0.34
    DNS2: 

Send initial IP packet ...
No IP reply for 3 seconds. Just assume we are online
PPP negotiation finished
VPN setup complete
VPN network device is up
Network notification disabled (limited access)

Connection is ready

Code: Select all

root@rk3188:/ # ip route list table tun0
default dev tun0 scope link
root@rk3188:/ #
Last edited by m4f1050 on 23 May 2017, 17:22, edited 2 times in total.

m4f1050
Posts: 9
Joined: 02 Mar 2016, 03:54

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by m4f1050 » 23 May 2017, 05:53

This is the PPP Widget v1 USB log on KK 4.4.4:

Code: Select all


PPP Widget version 1.3.5 TEST
USB_ModeSwitch log from Tue May  23 00:03:22 EDT 2017

Raw args from call: 1-1/1-1:1.0
Using external storage dir /mnt/internal_sd/pppwidget

Using top device dir /sys/bus/usb/devices/1-1
 Reading attributes from /sys/bus/usb/devices/1-1/ ...
----------------
USB values from sysfs:
  manufacturer    HUAWEI Technology
  product    HUAWEI Mobile
  serial    
----------------
bNumConfigurations is 1 - don't check for active configuration
SCSI attributes not needed, moving on
checking config: /data/data/de.draisberghof.pppwidget/app_tmp/12d1.#android
! matched. Reading config data
 devList 1: 
config: TargetVendor set to 12d1
Driver module is "option", ID path is /sys/bus/usb-serial/drivers/option1

Command to be run:
usb_modeswitch -W -D -s 20 -u -1 -b 1 -g 2 -v 12d1 -p 14fe -f $cB

Verbose debug output of usb_modeswitch and libusb follows
(Note that some USB errors are to be expected in the process)
--------------------------------

Read long config from command line

 * usb_modeswitch: handle USB devices with multiple modes
 * Version 2.2.0 (C) Josua Dietze 2014
 * Based on libusb1/libusbx

 ! PLEASE REPORT NEW CONFIGURATIONS !

DefaultVendor=  0x12d1
DefaultProduct= 0x14fe
TargetVendor=   0x12d1
MessageContent="55534243123456780000000000000011063000000100010000000000000000"
NeedResponse=0
Success check enabled, max. wait time 20 seconds
System integration mode enabled

Use given bus/device number: 001/002 ...
Look for default devices ...
 bus/device number matched
  found USB ID 12d1:14fe
   vendor ID matched
   product ID matched
 Found devices in default mode (1)
Use interface number 0
Use endpoints 0x01 (out) and 0x81 (in)

USB description data (for identification)
-------------------------
Manufacturer: HUAWEI Technology
     Product: HUAWEI Mobile
  Serial No.: not provided
-------------------------
Looking for active driver ...
 No active driver found. Detached before or never attached
Set up interface 0
Use endpoint 0x01 for message sending ...
Trying to send message 1 to endpoint 0x01 ...
 OK, message successfully sent
Reset response endpoint 0x81
 Could not reset endpoint (probably harmless): -99
Reset message endpoint 0x01
 Could not reset endpoint (probably harmless): -4
Bus/dev search active, refer success check to wrapper. Bye!

ok:busdev
--------------------------------
(end of usb_modeswitch output)

Checking success of mode switch for max. 20 seconds ...
 Reading attributes from /sys/bus/usb/devices/1-1/1-1:1.0 ...
 Mode switch has completed
Mode switching was successful, found 12d1:1001 (HUAWEI Technology: HUAWEI Mobile)
Device class of first interface is ff
Now checking for bound driver ...
 Driver has bound, device is known

All done, exiting
This is the PPP log:

Code: Select all

Using port ttyUSB0 for connection
ATE1
OK
AT+CFUN=1
OK
AT+CGDCONT=1,"IP","fast.t-mobile.com"
OK
ATD*99#
CONNECT
Report from chat:

chat:  May 23 00:14:28 CONNECT
Serial connection established.
using channel 2
Using interface ppp0
Connect: ppp0 <--> /dev/ttyUSB0
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xb0fbe0aa>]
rcvd [LCP ConfReq id=0x1 <accomp> <pcomp> <asyncmap 0x0> <mru 1500> <magic 0x545> <auth chap MD5>]
No auth is possible
sent [LCP ConfRej id=0x1 <accomp> <pcomp> <auth chap MD5>]
rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xb0fbe0aa>]
rcvd [LCP ConfReq id=0x2 <asyncmap 0x0> <mru 1500> <magic 0x545>]
sent [LCP ConfAck id=0x2 <asyncmap 0x0> <mru 1500> <magic 0x545>]
sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
rcvd [IPCP ConfReq id=0x1]
sent [IPCP ConfNak id=0x1 <addr 0.0.0.0>]
rcvd [IPCP ConfNak id=0x1 <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
rcvd [IPCP ConfReq id=0x2]
sent [IPCP ConfAck id=0x2]
rcvd [IPCP ConfNak id=0x2 <addr 100.130.6.202> <ms-dns1 10.177.0.34> <ms-dns3 10.177.0.210>]
sent [IPCP ConfReq id=0x3 <addr 100.130.6.202> <ms-dns1 10.177.0.34> <ms-dns3 10.177.0.210>]
rcvd [IPCP ConfAck id=0x3 <addr 100.130.6.202> <ms-dns1 10.177.0.34> <ms-dns3 10.177.0.210>]
Could not determine remote IP address: defaulting to 10.64.64.64
local  IP address 100.130.6.202
remote IP address 10.64.64.64
primary   DNS address 10.177.0.34
secondary DNS address 10.177.0.210
Script /data/data/de.draisberghof.pppwidget/app_bin/ip-up started (pid 6043)
Script /data/data/de.draisberghof.pppwidget/app_bin/ip-up finished (pid 6043), status = 0x0
Terminating on signal 15
Connect time 0.1 minutes.
Sent 67 bytes, received 0 bytes.
Script /data/data/de.draisberghof.pppwidget/app_bin/ip-down started (pid 6089)
sent [LCP TermReq id=0x2 "User request"]
rcvd [LCP TermAck id=0x2]
Connection terminated.
Script /data/data/de.draisberghof.pppwidget/app_bin/ip-down finished (pid 6089), status = 0xff

Last edited by m4f1050 on 23 May 2017, 17:21, edited 1 time in total.

m4f1050
Posts: 9
Joined: 02 Mar 2016, 03:54

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by m4f1050 » 23 May 2017, 07:39

This is PPP Widget 2 logs:

Code: Select all

May 23, 2017 1:32:49 AM

Device manufacturer string: rockchip
New device on /dev/bus/usb/001/002
 Device ID 12d1:1001
 Device top dir: /sys/bus/usb/devices/1-1, active cfg: 1
Device layout (current config) - cl:00  sc:00  pr:00
 if:0 as:0 -  cl:ff  sc:ff  pr:ff -  ir:81  bi:82  bo:01
 if:1 as:0 -  cl:ff  sc:ff  pr:ff -  bi:83  bo:02
 if:2 as:0 -  cl:ff  sc:ff  pr:ff -  bi:84  bo:03
Found vendor-specific interface
Load VendorSpec driver, configuration 0
Device ready for modem initialization, using iface 0

Code: Select all

May 23, 2017 1:35:54 AM

Start chat sequence for dialling ...
>
ATE0
<
OK
>
AT+CFUN?
<
+CFUN: 1

OK
>
AT+CGDCONT=1,"IP","fast.t-mobile.com"
<
OK
>
ATD*99#
<
CONNECT
====
Dialling complete


Now start PPP negotiation ...
>
ff03c021010000140206000000000506f7adb02907020802a565
<
ff03c0210101001d08020702020600000000010405dc0506000005470305c22305f077ff03c021020000140206000000000506f7adb029070208024e0c
>
ff03c021030100080304c0232248
<
ff03c0210102001c08020702020600000000010405dc0506000005470304c0237903
>
ff03c0210202001c08020702020600000000010405dc0506000005470304c0234a59
>
c0230101000600003b3f
<
c023020100100b4772656574696e67732121be44802101010004bb99
>
802101000016030600000000810600000000830600000000627c
<
802103000010810600000000830600000000cb8b
(IPCP Timer up, 1000 ms)
>
8021010100160306000000008106000000008306000000000e4b
<
8021030100160306644d237b81060ab1002283060ab100d2dfda
>
8021010200160306644d237b81060ab1002283060ab100d2c0d9
<
8021020200160306644d237b81060ab1002283060ab100d2362a

Local IP: 100.77.35.123
 Peer IP: 10.64.64.64
    DNS1: 10.177.0.34
    DNS2: 10.177.0.210

Send initial IP packet ...
No IP reply for 3 seconds. Just assume we are online
PPP negotiation finished

m4f1050
Posts: 9
Joined: 02 Mar 2016, 03:54

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by m4f1050 » 23 May 2017, 09:03

PPP Widget v1 on 5.1.1 gets stuck here:

Code: Select all

05-23 03:19:10.346 I/PPPWidget(1353): Looking at USB device 12d1:14fe with class 8
05-23 03:19:10.352 I/PPPWidget(1353): trying to switch mode of USB device 12d1:14fe ...
05-23 03:19:11.543 W/PackageManager(560): Failure retrieving resources for de.draisberghof.pppwidget: Resource ID #0x0
05-23 03:19:13.473 I/Timeline(1353): Timeline: Activity_launch_request id:de.draisberghof.pppwidget time:183948
05-23 03:19:13.476 I/ActivityManager(454): START u0 {flg=0x10000000 cmp=de.draisberghof.pppwidget/.UsbHelperActivity (has extras)} from uid 10112 on display 0
05-23 03:19:13.480 V/WindowManager(454): addAppToken: AppWindowToken{313191c7 token=Token{a6d706 ActivityRecord{1d02fde1 u0 de.draisberghof.pppwidget/.UsbHelperActivity t879}}} to stack=1 task=879 at 0

m4f1050
Posts: 9
Joined: 02 Mar 2016, 03:54

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by m4f1050 » 23 May 2017, 09:36

I disabled native usb_modeswitch and PPPWidget v.2 reports no modem found.

Code: Select all

05-23 03:32:41.624 I/PPPWidget2(2484): Trying to mode-switch USB device 12d1:14fe
05-23 03:32:42.521 I/PPPWidget2(2484): Mode switch seems to have succeeded
05-23 03:32:43.165 I/ActivityManager(452): START u0 {act=android.hardware.usb.action.USB_DEVICE_ATTACHED flg=0x10000000 cmp=de.draisberghof.pppwidget2/.PPPHelperActivity (has extras)} from uid 1000 on display 0
05-23 03:32:43.169 V/WindowManager(452): addAppToken: AppWindowToken{37318c4f token=Token{15e786ae ActivityRecord{12f8329 u0 de.draisberghof.pppwidget2/.PPPHelperActivity t908}}} to stack=1 task=908 at 0
PPPWidget 3 finds modem and connects, but no internet, here is logcat:

Code: Select all

05-23 03:43:57.960 I/ActivityManager(452): START u0 {act=android.hardware.usb.action.USB_DEVICE_ATTACHED flg=0x10000000 cmp=de.draisberghof.pppwidget3/.HelperActivity (has extras)} from uid 1000 on display 0
05-23 03:43:57.963 V/WindowManager(452): addAppToken: AppWindowToken{33ef07f8 token=Token{384e735b ActivityRecord{2e12b26a u0 de.draisberghof.pppwidget3/.HelperActivity t926}}} to stack=1 task=926 at 0
05-23 03:43:58.265 I/PPPWidget3(3625): Trying to mode-switch USB device 12d1:14fe
05-23 03:43:58.519 I/PPPWidget3(3625): Mode switch seems to have succeeded
05-23 03:43:59.059 W/PackageManager(569): Failure retrieving resources for de.draisberghof.pppwidget3: Resource ID #0x0
05-23 03:43:59.217 I/ActivityManager(452): START u0 {act=android.hardware.usb.action.USB_DEVICE_ATTACHED flg=0x10000000 cmp=de.draisberghof.pppwidget3/.HelperActivity (has extras)} from uid 1000 on display 0
05-23 03:43:59.222 V/WindowManager(452): addAppToken: AppWindowToken{12d53d0d token=Token{1e08aba4 ActivityRecord{cf6ea37 u0 de.draisberghof.pppwidget3/.HelperActivity t927}}} to stack=1 task=927 at 0
05-23 03:43:59.424 D/PPPWidget3 UsbMgr(3625): Clear halt on endpoint 0x01
05-23 03:43:59.424 D/PPPWidget3 UsbMgr(3625): Clear halt on endpoint 0x82
05-23 03:44:30.021 I/Timeline(3625): Timeline: Activity_launch_request id:de.draisberghof.pppwidget3 time:1091255
05-23 03:44:30.022 I/ActivityManager(452): START u0 {act=StartVpnService flg=0x10000000 cmp=de.draisberghof.pppwidget3/.HelperActivity (has extras)} from uid 10104 on display 0
05-23 03:44:30.027 V/WindowManager(452): addAppToken: AppWindowToken{1b6cde83 token=Token{1558b132 ActivityRecord{1c40753d u0 de.draisberghof.pppwidget3/.HelperActivity t928}}} to stack=1 task=928 at 0
05-23 03:44:30.155 V/WindowManager(452): Adding window Window{2693742c u0 de.draisberghof.pppwidget3/de.draisberghof.pppwidget3.HelperActivity} at 4 of 10 (after Window{881a944 u0 com.nolanlawson.logcat/com.nolanlawson.logcat.LogcatActivity})
05-23 03:44:30.479 W/AppOps  (452): Bad call: specified package de.draisberghof.pppwidget3 under uid 10023 but it is really 10104
05-23 03:44:30.713 V/WindowManager(452): Adding window Window{2b25a571 u0 com.android.vpndialogs/com.android.vpndialogs.ConfirmDialog} at 5 of 11 (after Window{2693742c u0 de.draisberghof.pppwidget3/de.draisberghof.pppwidget3.HelperActivity})
05-23 03:44:32.822 I/Vpn     (452): Switched from [Legacy VPN] to de.draisberghof.pppwidget3
05-23 03:44:32.991 I/PPPWidget3 VPN PPP(3625): Service starting
05-23 03:44:33.368 I/Vpn     (452): Established by de.draisberghof.pppwidget3 on tun0
05-23 03:44:34.140 W/PackageManager(569): Failure retrieving resources for de.draisberghof.pppwidget3: Resource ID #0x0
05-23 03:44:34.311 I/ActivityManager(452): START u0 {act=ActionButtonDetect flg=0x10000000 cmp=de.draisberghof.pppwidget3/.HelperActivity bnds=[578,269][664,355]} from uid 10104 on display 0
05-23 03:44:34.319 V/WindowManager(452): addAppToken: AppWindowToken{1d74788c token=Token{b3fb9bf ActivityRecord{392543de u0 de.draisberghof.pppwidget3/.HelperActivity t929}}} to stack=1 task=929 at 0
05-23 03:44:35.419 I/PPPWidget3(4174): Will try to make OS aware of connect ...
05-23 03:44:37.146 I/PPPWidget3 VPN PPP(3625): LCP packet checksum wrong: 0000e602
05-23 03:44:43.146 I/PPPWidget3 VPN PPP(3625): LCP packet checksum wrong: 0000e602
05-23 03:44:49.146 I/PPPWidget3 VPN PPP(3625): LCP packet checksum wrong: 0000e602
05-23 03:45:30.929 I/Timeline(3625): Timeline: Activity_launch_request id:de.draisberghof.pppwidget3 time:1152164
05-23 03:45:30.931 I/ActivityManager(452): START u0 {act=ActionStopService flg=0x10000000 cmp=de.draisberghof.pppwidget3/.HelperActivity} from uid 10104 on display 0
05-23 03:45:30.945 V/WindowManager(452): addAppToken: AppWindowToken{2c24ae05 token=Token{3a4be57c ActivityRecord{6ae046f u0 de.draisberghof.pppwidget3/.HelperActivity t930}}} to stack=1 task=930 at 0
05-23 03:45:31.318 I/PPPWidget3 VPN PPP(3625): Service stopping
05-23 03:45:31.535 I/PPPWidget3(4174): Will try to make OS aware of disconnect ...
05-23 03:47:12.151 W/PPPWidget3 UsbMgr(3625): Request loop interrupted: Returned request is NULL, abort
05-23 03:47:12.422 W/PPPWidget3(3625): Our USB device was removed

LOM
Posts: 1404
Joined: 11 Jul 2012, 15:14
Location: Koh Samui, TH

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by LOM » 23 May 2017, 10:07

m4f1050 wrote:I disabled native usb_modeswitch and PPPWidget v.2 reports no modem found.
What kind of Android device is this with a native usb_modeswitch?

m4f1050
Posts: 9
Joined: 02 Mar 2016, 03:54

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by m4f1050 » 23 May 2017, 15:12

LOM wrote:
m4f1050 wrote:I disabled native usb_modeswitch and PPPWidget v.2 reports no modem found.
What kind of Android device is this with a native usb_modeswitch?
It's a Rockchips RK3188 but it does not have my modem so I rely on PPP Widget. It works GREAT on KitKat 4.4.4 with PPP Widget ver. 1 disabling the usb_modeswitch. But when I upgraded to L 5.1.1 (it is a port I believe, but regardless I am disabling the usb_modeswitch and on PPP Widget I am disabling the native mobile network) I could no longer use PPP Widget ver. 1 with it. And ver. 2 doesn't find modem, ver. 3 finds the modem and connects, but I guess the modem has old firmware, not sure if it's doing the PPP negotiation... PPP Widget ver. 2 if I let it switch to 12D1:1506 it does the same as what ver. 3 does without the native usb_modeswitch. I am guessing the kernel is missing something?

Also, my USB modem, I can use on Windows 10, it find it's a modem, and I can create a dial-up network and it connects fine and I can browse through T-Mobile's WWAN.

I wonder, maybe if I modify the usb_modeswitch.d 12d1_14de to one of the supported modems it would work?

Also, I noticed PPP Widget ver. 3 has the web option, maybe I can upgrade the firmware to one that has the web interface? I didn't want to use the RNDIS option because it gets a 10gig cap vs. no cap using it as a modem and the dial string ATD*99# vs the ATD*99***1#


EDIT: Here is a logcat using PPP Widget v.3 of entries containing "ppp" and another one of entries containing "tun" maybe someone can pinpoint where the issue is at?

Code: Select all

01-01 07:00:26.233 I/SystemServer(452): PppoeService
01-01 07:00:26.235 I/SystemServiceManager(452): Starting com.android.server.pppoe.PppoeService
01-01 07:00:26.745 D/ConnectivityService(452): Got NetworkFactory Messenger for PPPOE
01-01 00:00:01.669 I/ActivityManager(452): Start proc 1174:com.google.android.partnersetup/u0a11 for content provider com.google.android.partnersetup/.RlzAppProvider
01-01 00:00:06.039 I/ActivityManager(452): Start proc 1312:de.draisberghof.pppwidget3/u0a104 for broadcast de.draisberghof.pppwidget3/.PPPWidget3
01-01 00:00:13.278 I/ActivityManager(452): Killing 1312:de.draisberghof.pppwidget3/u0a104 (adj 15): empty #17
01-01 00:00:23.642 I/ActivityManager(452): Start proc 1991:de.draisberghof.pppwidget3/u0a104 for broadcast de.draisberghof.pppwidget3/.PPPWidget3
01-01 00:00:23.791 I/ActivityManager(452): START u0 {act=ActionReset flg=0x10000000 cmp=de.draisberghof.pppwidget3/.HelperActivity} from uid 10104 on display 0
01-01 00:00:23.823 V/WindowManager(452): addAppToken: AppWindowToken{167821b8 token=Token{ec8dc1b ActivityRecord{1d46462a u0 de.draisberghof.pppwidget3/.HelperActivity t949}}} to stack=1 task=949 at 0
01-01 00:31:00.001 I/ActivityManager(452): Killing 1991:de.draisberghof.pppwidget3/u0a104 (adj 11): empty for 1829s
05-23 09:02:43.752 I/ActivityManager(452): START u0 {act=android.hardware.usb.action.USB_DEVICE_ATTACHED flg=0x10000000 cmp=de.draisberghof.pppwidget3/.HelperActivity (has extras)} from uid 1000 on display 0
05-23 09:02:43.753 V/WindowManager(452): addAppToken: AppWindowToken{1f83ea32 token=Token{3da0f23d ActivityRecord{f6a3794 u0 de.draisberghof.pppwidget3/.HelperActivity t951}}} to stack=1 task=951 at 0
05-23 09:02:43.805 I/ActivityManager(452): Start proc 4030:de.draisberghof.pppwidget3/u0a104 for activity de.draisberghof.pppwidget3/.HelperActivity
05-23 09:03:04.623 I/ActivityManager(452): START u0 {act=ActionButtonDetect flg=0x10000000 cmp=de.draisberghof.pppwidget3/.HelperActivity bnds=[578,269][664,355]} from uid 10104 on display 0
05-23 09:03:04.625 V/WindowManager(452): addAppToken: AppWindowToken{1882777e token=Token{29af7539 ActivityRecord{337d3900 u0 de.draisberghof.pppwidget3/.HelperActivity t952}}} to stack=1 task=952 at 0
05-23 09:03:05.969 I/PPPWidget3(4030): Trying to mode-switch USB device 12d1:14fe
05-23 09:03:06.394 I/PPPWidget3(4030): Mode switch seems to have succeeded
05-23 09:03:06.959 I/ActivityManager(452): START u0 {act=android.hardware.usb.action.USB_DEVICE_ATTACHED flg=0x10000000 cmp=de.draisberghof.pppwidget3/.HelperActivity (has extras)} from uid 1000 on display 0
05-23 09:03:06.961 V/WindowManager(452): addAppToken: AppWindowToken{39e92818 token=Token{269a7ffb ActivityRecord{214a8d8a u0 de.draisberghof.pppwidget3/.HelperActivity t953}}} to stack=1 task=953 at 0
05-23 09:03:07.214 D/PPPWidget3 UsbMgr(4030): Clear halt on endpoint 0x01
05-23 09:03:07.216 D/PPPWidget3 UsbMgr(4030): Clear halt on endpoint 0x82
05-23 09:03:12.512 D/PppoeNetworkFactory(452): stopTrackingInterface
05-23 09:03:12.842 D/PppoeNetworkFactory(452): stopTrackingInterface
05-23 09:03:15.123 I/Timeline(4030): Timeline: Activity_launch_request id:de.draisberghof.pppwidget3 time:6096983
05-23 09:03:15.125 I/ActivityManager(452): START u0 {act=StartVpnService flg=0x10000000 cmp=de.draisberghof.pppwidget3/.HelperActivity (has extras)} from uid 10104 on display 0
05-23 09:03:15.126 V/WindowManager(452): addAppToken: AppWindowToken{293dfc65 token=Token{3399f15c ActivityRecord{1c3f53cf u0 de.draisberghof.pppwidget3/.HelperActivity t954}}} to stack=1 task=954 at 0
05-23 09:03:15.159 I/Vpn     (452): Switched from [Legacy VPN] to de.draisberghof.pppwidget3
05-23 09:03:15.174 I/PPPWidget3 VPN PPP(4030): Service starting
05-23 09:03:15.222 I/Vpn     (452): Established by de.draisberghof.pppwidget3 on tun0
05-23 09:03:16.241 I/PPPWidget3(4181): Will try to make OS aware of connect ...
05-23 09:03:16.249 D/ConnectivityService(452): registerNetworkAgent NetworkAgentInfo{ ni{[type: PPP[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: PPPWIDGET, roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{null}  lp{{InterfaceName: sit0 LinkAddresses: [21.40.194.113/32,]  Routes: [] DnsAddresses: [10.177.0.34,] Domains:  MTU: 1492 TcpBufferSizes: }}  nc{[ Transports: ETHERNET Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=100Kbps LinkDnBandwidth>=512Kbps Specifier: <PPP>]}  Score{100}  everValidated{false}  lastValidated{false}  created{false}  explicitlySelected{true} }
05-23 09:03:16.250 D/ConnectivityService(452): NetworkAgentInfo [PPP () - 101] EVENT_NETWORK_INFO_CHANGED, going from null to CONNECTED
05-23 09:03:16.638 D/ConnectivityService(452): notifyType IP_CHANGED for NetworkAgentInfo [PPP () - 101]
05-23 09:03:16.643 D/ConnectivityService(452): notifyType PRECHECK for NetworkAgentInfo [PPP () - 101]
05-23 09:03:16.643 D/ConnectivityService(452): rematching NetworkAgentInfo [PPP () - 101]
05-23 09:03:16.643 D/NetworkMonitor/NetworkAgentInfo [PPP () - 101](  452): DefaultState{ when=0 what=532481 target=com.android.internal.util.StateMachine$SmHandler }
05-23 09:03:16.643 D/NetworkMonitor/NetworkAgentInfo [PPP () - 101](  452): Connected
05-23 09:03:16.643 D/NetworkMonitor/NetworkAgentInfo [PPP () - 101](  452): EvaluatingState{ when=0 what=532486 arg1=1 target=com.android.internal.util.StateMachine$SmHandler }
05-23 09:03:16.643 D/NetworkMonitor/NetworkAgentInfo [PPP () - 101](  452): Validated
05-23 09:03:16.644 D/ConnectivityService(452): Switching to new default network: NetworkAgentInfo{ ni{[type: PPP[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: PPPWIDGET, roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{101}  lp{{InterfaceName: sit0 LinkAddresses: [21.40.194.113/32,]  Routes: [] DnsAddresses: [10.177.0.34,] Domains:  MTU: 1492 TcpBufferSizes: }}  nc{[ Transports: ETHERNET Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=100Kbps LinkDnBandwidth>=512Kbps Specifier: <PPP>]}  Score{100}  everValidated{false}  lastValidated{false}  created{true}  explicitlySelected{true} }
05-23 09:03:16.653 D/ConnectivityService(452): notifyType AVAILABLE for NetworkAgentInfo [PPP () - 101]
05-23 09:03:16.658 D/CSLegacyTypeTracker(452): Sending connected broadcast for type 1 NetworkAgentInfo [PPP () - 101] isDefaultNetwork=true
05-23 09:03:16.661 D/ConnectivityService(452): Validated NetworkAgentInfo [PPP () - 101]
05-23 09:03:16.661 D/ConnectivityService(452): rematching NetworkAgentInfo [PPP () - 101]
05-23 09:03:16.662 D/ConnectivityService(452): Network NetworkAgentInfo [PPP () - 101] was already satisfying request 1. No change.
05-23 09:03:16.662 D/ConnectivityService(452): notifyType AVAILABLE for NetworkAgentInfo [PPP () - 101]
05-23 09:03:17.854 I/PPPWidget3 VPN PPP(4030): LCP packet checksum wrong: 0000e602
05-23 09:03:17.962 D/ConnectivityService(452): reportBadNetwork(NetworkAgentInfo [PPP () - 101]) by 10008
05-23 09:03:17.962 D/NetworkMonitor/NetworkAgentInfo [PPP () - 101](  452): ValidatedState{ when=0 what=532488 arg1=10008 target=com.android.internal.util.StateMachine$SmHandler }
05-23 09:03:17.963 D/NetworkMonitor/NetworkAgentInfo [PPP () - 101](  452): DefaultState{ when=-1ms what=532488 arg1=10008 target=com.android.internal.util.StateMachine$SmHandler }
05-23 09:03:17.963 D/NetworkMonitor/NetworkAgentInfo [PPP () - 101](  452): Forcing reevaluation
05-23 09:03:17.963 D/NetworkMonitor/NetworkAgentInfo [PPP () - 101](  452): EvaluatingState{ when=0 what=532486 arg1=2 target=com.android.internal.util.StateMachine$SmHandler }
05-23 09:03:17.963 D/NetworkMonitor/NetworkAgentInfo [PPP () - 101](  452): Validated
05-23 09:03:17.964 D/ConnectivityService(452): Validated NetworkAgentInfo [PPP () - 101]
05-23 09:03:23.854 I/PPPWidget3 VPN PPP(4030): LCP packet checksum wrong: 0000e602
05-23 09:03:29.854 I/PPPWidget3 VPN PPP(4030): LCP packet checksum wrong: 0000e602
05-23 09:03:35.854 I/PPPWidget3 VPN PPP(4030): LCP packet checksum wrong: 0000e602

Code: Select all

05-23 09:03:15.221 D/ConnectivityService(452): registerNetworkAgent NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{null}  lp{{InterfaceName: tun0 LinkAddresses: [21.40.194.113/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{false}  lastValidated{false}  created{false}  explicitlySelected{false} }
05-23 09:03:15.225 D/ConnectivityService(452): Adding iface tun0 to network 100
05-23 09:03:15.421 E/ConnectivityService(452): Exception adding interface: java.lang.IllegalStateException: command '40 network interface add 100 tun0' failed with '400 40 addInterfaceToNetwork() failed (Invalid argument)'
05-23 09:03:15.421 E/ConnectivityService(452): Unexpected mtu value: 0, tun0
05-23 09:03:15.422 D/ConnectivityService(452): Adding Route [10.64.64.64/32 -> 0.0.0.0 tun0] to network 100
05-23 09:03:15.424 E/ConnectivityService(452): Exception in addRoute for non-gateway: java.lang.IllegalStateException: command '41 network route add 100 tun0 10.64.64.64/32' failed with '400 41 addRoute() failed (No such device)'
05-23 09:03:15.557 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{100}  lp{{InterfaceName: tun0 LinkAddresses: [21.40.194.113/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }
05-23 09:11:34.863 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{100}  lp{{InterfaceName: tun0 LinkAddresses: [21.40.194.113/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }
05-23 09:11:52.668 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{100}  lp{{InterfaceName: tun0 LinkAddresses: [21.40.194.113/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }
05-23 09:12:16.093 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{100}  lp{{InterfaceName: tun0 LinkAddresses: [21.40.194.113/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }
05-23 09:12:39.533 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{100}  lp{{InterfaceName: tun0 LinkAddresses: [21.40.194.113/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }
05-23 10:30:39.558 D/ConnectivityService(452): registerNetworkAgent NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{null}  lp{{InterfaceName: tun0 LinkAddresses: [26.78.25.150/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{false}  lastValidated{false}  created{false}  explicitlySelected{false} }
05-23 10:30:39.561 D/ConnectivityService(452): Adding iface tun0 to network 107
05-23 10:30:39.586 E/ConnectivityService(452): Exception adding interface: java.lang.IllegalStateException: command '186 network interface add 107 tun0' failed with '400 186 addInterfaceToNetwork() failed (Invalid argument)'
05-23 10:30:39.586 E/ConnectivityService(452): Unexpected mtu value: 0, tun0
05-23 10:30:39.586 D/ConnectivityService(452): Adding Route [10.64.64.64/32 -> 0.0.0.0 tun0] to network 107
05-23 10:30:39.588 E/ConnectivityService(452): Exception in addRoute for non-gateway: java.lang.IllegalStateException: command '187 network route add 107 tun0 10.64.64.64/32' failed with '400 187 addRoute() failed (No such device)'
05-23 10:30:39.642 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{107}  lp{{InterfaceName: tun0 LinkAddresses: [26.78.25.150/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }
05-23 10:31:23.751 D/ConnectivityService(452): registerNetworkAgent NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{null}  lp{{InterfaceName: tun0 LinkAddresses: [26.78.25.150/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{false}  lastValidated{false}  created{false}  explicitlySelected{false} }
05-23 10:31:23.761 D/ConnectivityService(452): Adding iface tun0 to network 109
05-23 10:31:23.803 E/ConnectivityService(452): Exception adding interface: java.lang.IllegalStateException: command '210 network interface add 109 tun0' failed with '400 210 addInterfaceToNetwork() failed (Invalid argument)'
05-23 10:31:23.803 E/ConnectivityService(452): Unexpected mtu value: 0, tun0
05-23 10:31:23.803 D/ConnectivityService(452): Adding Route [10.64.64.64/32 -> 0.0.0.0 tun0] to network 109
05-23 10:31:23.805 E/ConnectivityService(452): Exception in addRoute for non-gateway: java.lang.IllegalStateException: command '211 network route add 109 tun0 10.64.64.64/32' failed with '400 211 addRoute() failed (No such device)'
05-23 10:31:23.857 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{109}  lp{{InterfaceName: tun0 LinkAddresses: [26.78.25.150/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }
05-23 10:32:40.998 D/VpnJni  (452): Address added on tun0: 100.77.35.123/32
05-23 10:32:41.026 D/ConnectivityService(452): registerNetworkAgent NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{null}  lp{{InterfaceName: tun0 LinkAddresses: [100.77.35.123/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{false}  lastValidated{false}  created{false}  explicitlySelected{false} }
05-23 10:32:41.027 I/Vpn     (452): Established by de.draisberghof.pppwidget3 on tun0
05-23 10:32:41.030 D/ConnectivityService(452): Adding iface tun0 to network 111
05-23 10:32:41.063 E/Netd    (131): failed to add interface tun0 to VPN netId 111
05-23 10:32:41.064 E/ConnectivityService(452): Exception adding interface: java.lang.IllegalStateException: command '234 network interface add 111 tun0' failed with '400 234 addInterfaceToNetwork() failed (Invalid argument)'
05-23 10:32:41.064 E/ConnectivityService(452): Unexpected mtu value: 0, tun0
05-23 10:32:41.065 D/ConnectivityService(452): Adding Route [10.64.64.64/32 -> 0.0.0.0 tun0] to network 111
05-23 10:32:41.065 E/Netd    (131): interface tun0 not assigned to any netId
05-23 10:32:41.066 E/ConnectivityService(452): Exception in addRoute for non-gateway: java.lang.IllegalStateException: command '235 network route add 111 tun0 10.64.64.64/32' failed with '400 235 addRoute() failed (No such device)'
05-23 10:32:41.067 E/Netd    (131): interface tun0 not assigned to any netId
05-23 10:32:41.131 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{111}  lp{{InterfaceName: tun0 LinkAddresses: [100.77.35.123/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }
05-23 10:33:37.317 D/VpnJni  (452): Address added on tun0: 100.77.35.123/32
05-23 10:33:37.336 D/ConnectivityService(452): registerNetworkAgent NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{null}  lp{{InterfaceName: tun0 LinkAddresses: [100.77.35.123/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{false}  lastValidated{false}  created{false}  explicitlySelected{false} }
05-23 10:33:37.337 I/Vpn     (452): Established by de.draisberghof.pppwidget3 on tun0
05-23 10:33:37.339 D/ConnectivityService(452): Adding iface tun0 to network 113
05-23 10:33:37.385 E/Netd    (131): failed to add interface tun0 to VPN netId 113
05-23 10:33:37.386 E/ConnectivityService(452): Exception adding interface: java.lang.IllegalStateException: command '258 network interface add 113 tun0' failed with '400 258 addInterfaceToNetwork() failed (Invalid argument)'
05-23 10:33:37.386 E/ConnectivityService(452): Unexpected mtu value: 0, tun0
05-23 10:33:37.386 D/ConnectivityService(452): Adding Route [10.64.64.64/32 -> 0.0.0.0 tun0] to network 113
05-23 10:33:37.387 E/Netd    (131): interface tun0 not assigned to any netId
05-23 10:33:37.388 E/ConnectivityService(452): Exception in addRoute for non-gateway: java.lang.IllegalStateException: command '259 network route add 113 tun0 10.64.64.64/32' failed with '400 259 addRoute() failed (No such device)'
05-23 10:33:37.389 E/Netd    (131): interface tun0 not assigned to any netId
05-23 10:33:37.453 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{113}  lp{{InterfaceName: tun0 LinkAddresses: [100.77.35.123/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }
05-23 10:36:55.785 D/VpnJni  (452): Address added on tun0: 21.37.125.109/32
05-23 10:36:55.814 D/ConnectivityService(452): registerNetworkAgent NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{null}  lp{{InterfaceName: tun0 LinkAddresses: [21.37.125.109/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{false}  lastValidated{false}  created{false}  explicitlySelected{false} }
05-23 10:36:55.815 I/Vpn     (452): Established by de.draisberghof.pppwidget3 on tun0
05-23 10:36:55.818 D/ConnectivityService(452): Adding iface tun0 to network 115
05-23 10:36:55.852 E/Netd    (131): failed to add interface tun0 to VPN netId 115
05-23 10:36:55.853 E/ConnectivityService(452): Exception adding interface: java.lang.IllegalStateException: command '282 network interface add 115 tun0' failed with '400 282 addInterfaceToNetwork() failed (Invalid argument)'
05-23 10:36:55.853 E/ConnectivityService(452): Unexpected mtu value: 0, tun0
05-23 10:36:55.854 D/ConnectivityService(452): Adding Route [10.64.64.64/32 -> 0.0.0.0 tun0] to network 115
05-23 10:36:55.854 E/Netd    (131): interface tun0 not assigned to any netId
05-23 10:36:55.855 E/ConnectivityService(452): Exception in addRoute for non-gateway: java.lang.IllegalStateException: command '283 network route add 115 tun0 10.64.64.64/32' failed with '400 283 addRoute() failed (No such device)'
05-23 10:36:55.856 E/Netd    (131): interface tun0 not assigned to any netId
05-23 10:36:55.922 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{115}  lp{{InterfaceName: tun0 LinkAddresses: [21.37.125.109/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }
05-23 10:38:59.503 D/VpnJni  (452): Address added on tun0: 21.88.123.83/32
05-23 10:38:59.508 D/ConnectivityService(452): registerNetworkAgent NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{null}  lp{{InterfaceName: tun0 LinkAddresses: [21.88.123.83/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{false}  lastValidated{false}  created{false}  explicitlySelected{false} }
05-23 10:38:59.509 I/Vpn     (452): Established by de.draisberghof.pppwidget3 on tun0
05-23 10:38:59.510 D/ConnectivityService(452): Adding iface tun0 to network 117
05-23 10:38:59.544 E/Netd    (131): failed to add interface tun0 to VPN netId 117
05-23 10:38:59.545 E/ConnectivityService(452): Exception adding interface: java.lang.IllegalStateException: command '306 network interface add 117 tun0' failed with '400 306 addInterfaceToNetwork() failed (Invalid argument)'
05-23 10:38:59.545 E/ConnectivityService(452): Unexpected mtu value: 0, tun0
05-23 10:38:59.546 D/ConnectivityService(452): Adding Route [10.64.64.64/32 -> 0.0.0.0 tun0] to network 117
05-23 10:38:59.546 E/Netd    (131): interface tun0 not assigned to any netId
05-23 10:38:59.547 E/ConnectivityService(452): Exception in addRoute for non-gateway: java.lang.IllegalStateException: command '307 network route add 117 tun0 10.64.64.64/32' failed with '400 307 addRoute() failed (No such device)'
05-23 10:38:59.548 E/Netd    (131): interface tun0 not assigned to any netId
05-23 10:38:59.603 E/ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]}  network{117}  lp{{InterfaceName: tun0 LinkAddresses: [21.88.123.83/32,]  Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains:  MTU: 0}}  nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]}  Score{0}  everValidated{true}  lastValidated{true}  created{true}  explicitlySelected{false} }

LOM
Posts: 1404
Joined: 11 Jul 2012, 15:14
Location: Koh Samui, TH

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by LOM » 24 May 2017, 10:59

m4f1050 wrote:
LOM wrote:
m4f1050 wrote:I disabled native usb_modeswitch and PPPWidget v.2 reports no modem found.
What kind of Android device is this with a native usb_modeswitch?
It's a Rockchips RK3188 but it does not have my modem so I rely on PPP Widget.
RK3188 is a CPU/SoC, I asked what kind of Android device you have.
I assume that it is either an Android TV Box or a Car Dashboard Head Unit since your device has built-in 3G support.
pppwidget was made for Android devices without built-in 3G support, devices like some smartphones and many tablets but will usually not work on devices which have native 3G support, there will be a collision between modeswitch programs, kernel drivers, connection managers, and routing methods.
You'd better use the native 3G in your device together with a modem from the mfgrs list of supported dongles.

Josh
Site Admin
Posts: 6570
Joined: 03 Nov 2007, 00:30

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by Josh » 24 May 2017, 22:57

The network notificiation works just fine, according to your logcat.

Your problem is rather here in the PPP log:

Send initial IP packet ...
No IP reply for 3 seconds. Just assume we are online


There is no actual data traffic from the modem. Make sure you have proper network signal and a correct APN.

m4f1050
Posts: 9
Joined: 02 Mar 2016, 03:54

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by m4f1050 » 28 May 2017, 03:00

Josh wrote:The network notificiation works just fine, according to your logcat.

Your problem is rather here in the PPP log:

Send initial IP packet ...
No IP reply for 3 seconds. Just assume we are online


There is no actual data traffic from the modem. Make sure you have proper network signal and a correct APN.
Ok, will check.

Thanks.

m4f1050
Posts: 9
Joined: 02 Mar 2016, 03:54

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by m4f1050 » 28 May 2017, 03:04

LOM wrote:
m4f1050 wrote:I disabled native usb_modeswitch and PPPWidget v.2 reports no modem found.
What kind of Android device is this with a native usb_modeswitch?
I am *well* aware of that LOM, thank you very much. As I mentioned, I manually disabled the modeswitch, but you are correct when you mention drivers. But PPP Widget uses no drivers so shouldn't affect anything if I disable modeswitch. That's how before upgrading to 5.1.1, on 4.4.4 PPP Widget ver 1 worked perfect.

Josh
Site Admin
Posts: 6570
Joined: 03 Nov 2007, 00:30

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by Josh » 29 May 2017, 16:29

m4f1050 wrote:But PPP Widget uses no drivers so shouldn't affect anything if I disable modeswitch.
Well, you don't know that ...

There are devices out there that grab and lock a modem after the modeswitch that PPP Widget initializes. Others have a patched kernel so that the usb-storage driver does the mode switch without any chance for a user-level program to intervene. I encountered the latter case with the Lenovo Tab 2 A7-10 - which doesn't even advertise its modem 'capabilities'.

In short: it's entirely up to the manufacturer how modems are handled.

m4f1050
Posts: 9
Joined: 02 Mar 2016, 03:54

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by m4f1050 » 30 May 2017, 18:21

Josh wrote:
m4f1050 wrote:But PPP Widget uses no drivers so shouldn't affect anything if I disable modeswitch.
Well, you don't know that ...

There are devices out there that grab and lock a modem after the modeswitch that PPP Widget initializes. Others have a patched kernel so that the usb-storage driver does the mode switch without any chance for a user-level program to intervene. I encountered the latter case with the Lenovo Tab 2 A7-10 - which doesn't even advertise its modem 'capabilities'.

In short: it's entirely up to the manufacturer how modems are handled.
That's why my modem is set to only 4G LTE modem (serial port) and the PC Diag port. No CD, no other except the PC Diag port so I can program it and the modem itself.

I can always go back to KitKat 4.4.4 and get it working, but I would rather use PPP Widget on 5.1.1 if possible.

Josh
Site Admin
Posts: 6570
Joined: 03 Nov 2007, 00:30

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with ol

Post by Josh » 31 May 2017, 12:34

What happens if you disable the "Alternative Huawei Mode" option in the configuration of PPP Widget 3?

stedon81
Posts: 1
Joined: 04 Aug 2021, 12:50

Re: PPP Widget 3 / Android 5.1.1 / Huawei E3276-s500 with old fw

Post by stedon81 » 08 Aug 2021, 21:07

Hi,

I have the same symptoms on my car head unit and also see the log line
ConnectivityService(452): Attempting to register duplicate agent for type 17: NetworkAgentInfo{ ni{[type: VPN[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]} network{100} lp{{InterfaceName: tun0 LinkAddresses: [21.40.194.113/32,] Routes: [10.64.64.64/32 -> 0.0.0.0 tun0,::/0 unreachable,] DnsAddresses: [10.177.0.34,] Domains: MTU: 0}} nc{[ Transports: VPN Capabilities: NOT_RESTRICTED&TRUSTED]} Score{0} everValidated{true} lastValidated{true} created{true} explicitlySelected{false} }
I understand that it might be related to any activities on the head unit related to the built-in 3G module.
However, strange thing is that sometimes the app get connection via the 4G dongle and sometimes not.

I wonder if anyone here has found out what is going wrong in that moment when PPP3 says that connection is established but apps won't get data.
It seems that other interfaces are still active, or net routes are not established to the tunnel adapter. Sometimes it helped to tear down the wlan0 interface, but sometimes it didn't.
I'm kind of looking for a command sequence I could run via (rooted) Tasker if connection doesn't work as it should.

One interesting thing also is that on my head unit I cannot access the VPN menu. It seems to be locked by a secret code I don't know.

Anyone any hints how I could track down the problem and find a solution?

Thanks,
Stedon81

Post Reply