OpenWrt Forum Archive

Topic: Nokia 21M USB modem disconnect [TP-Link TL-WR1043-ND]

The content of this topic has been archived on 13 Apr 2018. There are no obvious gaps in this topic, but there may still be some posts missing at the end.

Hello,

I bought USB modem Nokia 21M (usbid: 0421:0638) and I have a problem
running it on OpenWRT on TP-Link TL-WR1043-ND. Firstly modem shows up
as CDROM drive. I run "eject" on this drive and then cdc-acm interfaces
appears. I can initiate ppp connection on the first interface.
Interface ppp0 shows up for few seconds, and then USB disconnects.
Sometimes when ppp0 is alive, device is not disconnecting for a long
period, but on first use of ppp0 (i.e. ping 8.8.8.8) its immediately
dying.

Three seconds after USB-disconnect, device shows up once again, but now
it shows up as SINGLE cdc-acm interface, and this interface is not
fully functional. I can chat with it, but for most AT-commands
it responds "ERROR". It responds "OK" on "AT", but "ERROR" on "ATZ",
"ATI",...

I tried connecting modem with powered or unpowered USB HUB, both
"full speed" and "high speed" - no effect - modem disconnects
(modem alone, not USB HUB).

I tried with two SIM cards, from different providers.

But this pattern does not repeat on my Linux notebook (I achieved
this effect only once in many attempts; Slackware 13.37, tried with kernels
2.6.37, 3.1, 3.2), regardless through HUB or not.

I measured current consumed by modem, when working OK with notebook:
about 260mA. Then I measured current consumed by Huawei E220 which work
OK also on my router: 330mA (with other SIM-card; but further i placed
that SIM-card in Nokia Modem - obviously with no effect).
So, maybe its not current shortage nor SIM-card problem...

I looked at voltage with oscilloscope - when modem connected to router without
powered HUB, voltage can drop a little below 4.70V, but with powered
HUB voltage stays in a range 4.90-5.30V - the same range as when
modem is connected to my notebook. Unfortunately, I have no lab power supply.

I tried with OpenWRT trunk r30543 with my bloated configuration (kernel 3.2),
another trunk version with bloated configuration and 2.6.39 kernel,
and OpenWRT 10.03.1, precompiled image from OpenWRT downloads +
installed only kmod-usb-acm and chat.


pppd commandline (I know, some options are useless here, but this works on notebook):
/usr/sbin/pppd /dev/ttyACM0 921600 connect "chat -V -f /tmp/chat.aero2" crtscts -detach defaultroute usepeerdns noauth debug persist noipdefault

/tmp/chat.aero2:
'' ATZ
OK AT+CGDCONT=1,"IP","darmowy"
OK "ATD*99#"
CONNECT '\d\c'

What else can I try to solve this problem?

--
Wojciech Jaczewski,
Poland

execute "cat /proc/bus/usb/devices"

xopal wrote:

execute "cat /proc/bus/usb/devices"

Before disconnect:

T:  Bus=01 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=480  MxCh= 1
B:  Alloc=  0/800 us ( 0%), #Int=  0, #Iso=  0
D:  Ver= 2.00 Cls=09(hub  ) Sub=00 Prot=01 MxPS=64 #Cfgs=  1
P:  Vendor=1d6b ProdID=0002 Rev= 3.02
S:  Manufacturer=Linux 3.2.5 ehci_hcd
S:  Product=Atheros built-in EHCI controller
S:  SerialNumber=ar913x-ehci
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=  0mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   4 Ivl=256ms

T:  Bus=01 Lev=01 Prnt=01 Port=00 Cnt=01 Dev#=  7 Spd=480  MxCh= 0
D:  Ver= 2.00 Cls=02(comm.) Sub=00 Prot=00 MxPS=64 #Cfgs=  1
P:  Vendor=0421 ProdID=0638 Rev= 0.21
S:  Manufacturer=Nokia
S:  Product=Nokia USB Modem
S:  SerialNumber=73423930009B27B46CC304EEF6977DC2E295AD49
C:* #Ifs=10 Cfg#= 1 Atr=80 MxPwr=500mA
I:* If#= 0 Alt= 0 #EPs= 0 Cls=02(comm.) Sub=08 Prot=00 Driver=(none)
I:  If#= 0 Alt= 1 #EPs= 0 Cls=02(comm.) Sub=08 Prot=00 Driver=(none)
I:* If#= 1 Alt= 0 #EPs= 1 Cls=02(comm.) Sub=02 Prot=01 Driver=cdc_acm
E:  Ad=81(I) Atr=03(Int.) MxPS=  64 Ivl=2ms
I:* If#= 2 Alt= 0 #EPs= 2 Cls=0a(data ) Sub=00 Prot=00 Driver=cdc_acm
E:  Ad=82(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E:  Ad=01(O) Atr=02(Bulk) MxPS= 512 Ivl=4ms
I:* If#= 3 Alt= 0 #EPs= 1 Cls=02(comm.) Sub=02 Prot=01 Driver=cdc_acm
E:  Ad=83(I) Atr=03(Int.) MxPS=  64 Ivl=2ms
I:* If#= 4 Alt= 0 #EPs= 2 Cls=0a(data ) Sub=00 Prot=00 Driver=cdc_acm
E:  Ad=84(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E:  Ad=02(O) Atr=02(Bulk) MxPS= 512 Ivl=4ms
I:* If#= 5 Alt= 0 #EPs= 1 Cls=02(comm.) Sub=02 Prot=01 Driver=cdc_acm
E:  Ad=85(I) Atr=03(Int.) MxPS=  64 Ivl=2ms
I:* If#= 6 Alt= 0 #EPs= 2 Cls=0a(data ) Sub=00 Prot=00 Driver=cdc_acm
E:  Ad=86(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E:  Ad=03(O) Atr=02(Bulk) MxPS= 512 Ivl=4ms
I:* If#= 7 Alt= 0 #EPs= 1 Cls=02(comm.) Sub=06 Prot=00 Driver=(none)
E:  Ad=87(I) Atr=03(Int.) MxPS=  64 Ivl=2ms
I:* If#= 8 Alt= 0 #EPs= 0 Cls=0a(data ) Sub=00 Prot=00 Driver=(none)
I:  If#= 8 Alt= 1 #EPs= 2 Cls=0a(data ) Sub=00 Prot=00 Driver=(none)
E:  Ad=88(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E:  Ad=04(O) Atr=02(Bulk) MxPS= 512 Ivl=4ms
I:* If#= 9 Alt= 0 #EPs= 2 Cls=08(stor.) Sub=06 Prot=50 Driver=usb-storage
E:  Ad=05(O) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E:  Ad=89(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms

After ppp connection and therefore USB disconnect/connect again:

T:  Bus=01 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=480  MxCh= 1
B:  Alloc=  0/800 us ( 0%), #Int=  0, #Iso=  0
D:  Ver= 2.00 Cls=09(hub  ) Sub=00 Prot=01 MxPS=64 #Cfgs=  1
P:  Vendor=1d6b ProdID=0002 Rev= 3.02
S:  Manufacturer=Linux 3.2.5 ehci_hcd
S:  Product=Atheros built-in EHCI controller
S:  SerialNumber=ar913x-ehci
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=  0mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   4 Ivl=256ms

T:  Bus=01 Lev=01 Prnt=01 Port=00 Cnt=01 Dev#=  8 Spd=480  MxCh= 0
D:  Ver= 2.00 Cls=02(comm.) Sub=00 Prot=00 MxPS=64 #Cfgs=  1
P:  Vendor=0421 ProdID=0638 Rev= 0.21
S:  Manufacturer=Nokia
S:  Product=Nokia USB Modem
S:  SerialNumber=73423930009B27B46CC304EEF6977DC2E295AD49
C:* #Ifs= 3 Cfg#= 1 Atr=80 MxPwr=500mA
I:* If#= 0 Alt= 0 #EPs= 0 Cls=02(comm.) Sub=08 Prot=00 Driver=(none)
I:  If#= 0 Alt= 1 #EPs= 0 Cls=02(comm.) Sub=08 Prot=00 Driver=(none)
I:* If#= 1 Alt= 0 #EPs= 1 Cls=02(comm.) Sub=02 Prot=01 Driver=cdc_acm
E:  Ad=81(I) Atr=03(Int.) MxPS=  64 Ivl=2ms
I:* If#= 2 Alt= 0 #EPs= 2 Cls=0a(data ) Sub=00 Prot=00 Driver=cdc_acm
E:  Ad=82(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E:  Ad=01(O) Atr=02(Bulk) MxPS= 512 Ivl=4ms

Some news, but no solution at the moment.

I run OpenWRT 10.03.1 x86 image in the Qemu emulator, and then Slackware image also in the Qemu. My problem shows up ONLY on OpenWRT image, not Slackware. So, I must find a cause in software. Any suggestions, what should I test first?

Now I plan to run emulated OpenWRT and emulated Slackware on the same kernel image. I will report on progress...

Problem solved.

When I commented "noaccomp" or "nopccomp" (one or another, or both) line in /etc/ppp/options file, connections work OK.
Previously I missed the fact, that /etc/ppp/options on my notebook and on my router differs. And this made huge difference with my modem.

I'm facing exactly the same problem. The only difference is that I try to run this modem on TP-LINK TL-MR3220.

Unfortunately your solution doesn't work for me.

Can you show your /etc/ppp/options file? What OpenWRT version do you use?

I described my problem in details here: http://eko.one.pl/forum/viewtopic.php?id=3032

Maybe you can help? Thanks.

My /etc/ppp/options:

#debug
logfile /dev/null
#noaccomp
#nopcomp
nocrtscts
lock
maxfail 0
lcp-echo-failure 5
lcp-echo-interval 1

OpenWRT trunk, r30543, but probably its not version-specific.

Try with my pppd commandline, which sets another options (I dont know if any of these can be important):
pppd /dev/ttyACM0 921600 connect 'chat -V "" ATZ OK AT+CGDCONT=1,\"IP\",\"darmowy\" OK ATD*99# CONNECT "\d\c"' crtscts -detach defaultroute usepeerdns noauth debug persist noipdefault

I could send You my Qemu image (and description, how to run it), which works OK, and fail when You uncomment "noaccomp" and "nopcomp" in /etc/ppp/options.

[some polish language - not the main topic]
Co prawda du?o wygodniej mi pisa? po polsku, ale nie chce mi si? zak?ada? kolejnego konta - tym razem na eko.one.pl. Mo?esz spróbowa? ?cign?? mnie na gg 2168963 - zagl?dam cz??ciej ni? raz dziennie. Tylko spróbuj najpierw ten sposób wystartowania pppd.

Your /etc/ppp/options configuration doesn't work for me. When I have copied it to my option file the connection doesn't want to start at all (the IP and DNS are not being acquired). Previously the connection could be at least established for a short moment.

The pppd commandline doesn't make any difference. Modem tries to connect but after few seconds red light starts to blink...   

It seams that you are the first person that managed to run this modem on openwrt. Can you show your logread?

maar wrote:

Can you show your logread?

Sep  8 17:56:18 OpenWrt user.notice usb-modeswitch: 1-1:1.0: Manufacturer=Nokia Product=Nokia_USB_Modem Serial=73423930009B27B46CC304EEF6977DC2E295AD49
Sep  8 17:56:18 OpenWrt user.notice usb-modeswitch: 1-1:1.1: Manufacturer=Nokia Product=Nokia_USB_Modem Serial=73423930009B27B46CC304EEF6977DC2E295AD49
Sep  8 17:56:18 OpenWrt user.notice usb-modeswitch: 1-1:1.2: Manufacturer=Nokia Product=Nokia_USB_Modem Serial=73423930009B27B46CC304EEF6977DC2E295AD49
Sep  8 17:56:18 OpenWrt user.info sysinit: Loading zones
Sep  8 17:56:18 OpenWrt user.notice usb-modeswitch: 1-1:1.3: Manufacturer=Nokia Product=Nokia_USB_Modem Serial=73423930009B27B46CC304EEF6977DC2E295AD49
Sep  8 17:56:19 OpenWrt user.notice usb-modeswitch: 1-1:1.4: Manufacturer=Nokia Product=Nokia_USB_Modem Serial=73423930009B27B46CC304EEF6977DC2E295AD49
Sep  8 17:56:19 OpenWrt user.notice usb-modeswitch: 1-1:1.5: Manufacturer=Nokia Product=Nokia_USB_Modem Serial=73423930009B27B46CC304EEF6977DC2E295AD49
Sep  8 17:56:19 OpenWrt user.notice usb-modeswitch: 1-1:1.6: Manufacturer=Nokia Product=Nokia_USB_Modem Serial=73423930009B27B46CC304EEF6977DC2E295AD49
Sep  8 17:56:19 OpenWrt user.info sysinit: Loading forwardings
Sep  8 17:56:19 OpenWrt user.info sysinit: Loading redirects
Sep  8 17:56:19 OpenWrt user.info sysinit: Loading rules
Sep  8 17:56:19 OpenWrt user.notice usb-modeswitch: 1-1:1.7: Manufacturer=Nokia Product=Nokia_USB_Modem Serial=73423930009B27B46CC304EEF6977DC2E295AD49
Sep  8 17:56:20 OpenWrt user.notice usb-modeswitch: 1-1:1.8: Manufacturer=Nokia Product=Nokia_USB_Modem Serial=73423930009B27B46CC304EEF6977DC2E295AD49
Sep  8 17:56:20 OpenWrt user.info sysinit: Loading includes
Sep  8 17:56:20 OpenWrt user.info sysinit: Optimizing conntrack
Sep  8 17:56:20 OpenWrt user.info sysinit: Loading interfaces
Sep  8 17:56:20 OpenWrt user.notice usb-modeswitch: 1-1:1.9: Manufacturer=Nokia Product=Nokia_USB_Modem Serial=73423930009B27B46CC304EEF6977DC2E295AD49
Sep  8 17:56:20 OpenWrt user.info firewall: adding lan (br-lan) to zone lan
Sep  8 17:56:20 OpenWrt kern.info kernel: [   31.210000] cfg80211: Calling CRDA to update world regulatory domain
Sep  8 17:56:20 OpenWrt kern.info kernel: [   31.390000] cfg80211: World regulatory domain updated:
Sep  8 17:56:20 OpenWrt kern.info kernel: [   31.390000] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep  8 17:56:20 OpenWrt kern.info kernel: [   31.400000] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep  8 17:56:20 OpenWrt kern.info kernel: [   31.410000] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep  8 17:56:20 OpenWrt kern.info kernel: [   31.420000] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep  8 17:56:20 OpenWrt kern.info kernel: [   31.420000] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep  8 17:56:20 OpenWrt kern.info kernel: [   31.430000] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep  8 17:56:20 OpenWrt kern.info kernel: [   31.440000] cfg80211: Calling CRDA for country: PL
Sep  8 17:56:21 OpenWrt kern.info kernel: [   31.630000] cfg80211: Regulatory domain changed to country: PL
Sep  8 17:56:21 OpenWrt kern.info kernel: [   31.640000] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep  8 17:56:21 OpenWrt kern.info kernel: [   31.650000] cfg80211:   (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Sep  8 17:56:21 OpenWrt kern.info kernel: [   31.660000] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Sep  8 17:56:21 OpenWrt kern.info kernel: [   31.660000] cfg80211:   (5250000 KHz - 5330000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Sep  8 17:56:21 OpenWrt kern.info kernel: [   31.670000] cfg80211:   (5490000 KHz - 5710000 KHz @ 40000 KHz), (N/A, 2700 mBm)
Sep  8 17:56:21 OpenWrt authpriv.info dropbear[1745]: Running in background
Sep  8 17:56:22 OpenWrt user.notice dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses!
Sep  8 17:56:22 OpenWrt user.notice dnsmasq: Allowing 127.0.0.0/8 responses
Sep  8 17:56:24 OpenWrt authpriv.info dropbear[1777]: Child connection from 192.168.1.2:39058
Sep  8 17:56:25 OpenWrt daemon.info dnsmasq[1791]: started, version 2.59 cachesize 150
Sep  8 17:56:25 OpenWrt daemon.info dnsmasq[1791]: compile time options: IPv6 GNU-getopt no-DBus no-i18n DHCP TFTP no-conntrack no-IDN
Sep  8 17:56:25 OpenWrt daemon.info dnsmasq-dhcp[1791]: DHCP, IP range 192.168.1.100 -- 192.168.1.249, lease time 12h
Sep  8 17:56:25 OpenWrt daemon.info dnsmasq[1791]: using local addresses only for domain lan
Sep  8 17:56:25 OpenWrt daemon.warn dnsmasq[1791]: no servers found in /tmp/resolv.conf.auto, will retry
Sep  8 17:56:25 OpenWrt daemon.info dnsmasq[1791]: read /etc/hosts - 1 addresses
Sep  8 17:56:25 OpenWrt daemon.info dnsmasq-dhcp[1791]: read /etc/ethers - 0 addresses
Sep  8 17:56:26 OpenWrt user.info sysinit: setting up led USB
Sep  8 17:56:26 OpenWrt user.info sysinit: sh: write error: Invalid argument
Sep  8 17:56:26 OpenWrt user.info sysinit: /etc/rc.common: eval: line 1: can't create /sys/class/leds/tp-link:green:usb/device_name: nonexistent directory
Sep  8 17:56:26 OpenWrt user.info sysinit: /etc/rc.common: eval: line 1: can't create /sys/class/leds/tp-link:green:usb/activity_interval: nonexistent directory
Sep  8 17:56:26 OpenWrt user.info sysinit: setting up led WLAN
Sep  8 17:56:26 OpenWrt daemon.notice pppd[1809]: pppd 2.4.5 started by root, uid 0
Sep  8 17:56:30 OpenWrt authpriv.notice dropbear[1777]: Password auth succeeded for 'root' from 192.168.1.2:39058
Sep  8 17:56:30 OpenWrt kern.info kernel: [   40.760000] br-lan: port 1(wlan0) entering forwarding state
Sep  8 17:56:30 OpenWrt kern.info kernel: [   40.760000] br-lan: port 2(eth0.1) entering forwarding state
Sep  8 17:56:35 OpenWrt daemon.info dnsmasq-dhcp[1791]: DHCPDISCOVER(br-lan) 192.168.1.105 00:16:cf:3f:50:6f
Sep  8 17:56:35 OpenWrt daemon.info dnsmasq-dhcp[1791]: DHCPOFFER(br-lan) 192.168.1.105 00:16:cf:3f:50:6f
Sep  8 17:56:35 OpenWrt daemon.info dnsmasq-dhcp[1791]: DHCPREQUEST(br-lan) 192.168.1.105 00:16:cf:3f:50:6f
Sep  8 17:56:35 OpenWrt daemon.info dnsmasq-dhcp[1791]: DHCPACK(br-lan) 192.168.1.105 00:16:cf:3f:50:6f bonifacy
Sep  8 17:56:43 OpenWrt daemon.debug pppd[1809]: Script chat -V "" ATZ OK AT+CGDCONT=1,\"IP\",\"darmowy\" OK ATD*99# CONNECT "\d\c" finished (pid 1819), status = 0x0
Sep  8 17:56:43 OpenWrt daemon.info pppd[1809]: Serial connection established.
Sep  8 17:56:43 OpenWrt daemon.debug pppd[1809]: using channel 1
Sep  8 17:56:43 OpenWrt daemon.info pppd[1809]: Using interface ppp0
Sep  8 17:56:43 OpenWrt daemon.notice pppd[1809]: Connect: ppp0 <--> /dev/ttyACM0
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xe4cf86f> <pcomp> <accomp>]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: rcvd [LCP ConfReq id=0x1 <mru 1600> <auth pap> <magic 0xe3565da5> <asyncmap 0x0> <pcomp> <accomp>]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: No auth is possible
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: sent [LCP ConfRej id=0x1 <auth pap>]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xe4cf86f> <pcomp> <accomp>]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: rcvd [LCP ConfReq id=0x2 <mru 1600> <magic 0xe3565da5> <asyncmap 0x0> <pcomp> <accomp>]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: sent [LCP ConfAck id=0x2 <mru 1600> <magic 0xe3565da5> <asyncmap 0x0> <pcomp> <accomp>]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: sent [LCP EchoReq id=0x0 magic=0xe4cf86f]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: rcvd [LCP EchoRep id=0x0 magic=0xe3565da5]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: rcvd [IPCP ConfReq id=0x1 <addr 10.0.0.1>]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: sent [IPCP ConfAck id=0x1 <addr 10.0.0.1>]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: rcvd [IPV6CP ConfReq id=0x1 <addr fe80::ffa7:68d8:78b4:0429>]
Sep  8 17:56:44 OpenWrt daemon.warn pppd[1809]: Unsupported protocol 'IPv6 Control Protocol' (0x8057) received
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: sent [LCP ProtRej id=0x2 80 57 01 01 00 0e 01 0a ff a7 68 d8 78 b4 04 29]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
Sep  8 17:56:44 OpenWrt daemon.debug pppd[1809]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Sep  8 17:56:45 OpenWrt daemon.debug pppd[1809]: sent [LCP EchoReq id=0x1 magic=0xe4cf86f]
Sep  8 17:56:45 OpenWrt daemon.debug pppd[1809]: rcvd [LCP EchoRep id=0x1 magic=0xe3565da5]
Sep  8 17:56:46 OpenWrt daemon.debug pppd[1809]: sent [LCP EchoReq id=0x2 magic=0xe4cf86f]
Sep  8 17:56:46 OpenWrt daemon.debug pppd[1809]: rcvd [LCP EchoRep id=0x2 magic=0xe3565da5]
Sep  8 17:56:47 OpenWrt daemon.debug pppd[1809]: sent [LCP EchoReq id=0x3 magic=0xe4cf86f]
Sep  8 17:56:47 OpenWrt daemon.debug pppd[1809]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Sep  8 17:56:47 OpenWrt daemon.debug pppd[1809]: rcvd [LCP EchoRep id=0x3 magic=0xe3565da5]
Sep  8 17:56:47 OpenWrt daemon.debug pppd[1809]: rcvd [IPCP ConfNak id=0x2 <addr 78.30.103.234> <ms-dns1 193.41.112.14> <ms-dns2 193.41.112.18>]
Sep  8 17:56:47 OpenWrt daemon.debug pppd[1809]: sent [IPCP ConfReq id=0x3 <addr 78.30.103.234> <ms-dns1 193.41.112.14> <ms-dns2 193.41.112.18>]
Sep  8 17:56:47 OpenWrt daemon.debug pppd[1809]: rcvd [IPCP ConfAck id=0x3 <addr 78.30.103.234> <ms-dns1 193.41.112.14> <ms-dns2 193.41.112.18>]
Sep  8 17:56:47 OpenWrt daemon.notice pppd[1809]: local  IP address 78.30.103.234
Sep  8 17:56:47 OpenWrt daemon.notice pppd[1809]: remote IP address 10.0.0.1
Sep  8 17:56:47 OpenWrt daemon.notice pppd[1809]: primary   DNS address 193.41.112.14
Sep  8 17:56:47 OpenWrt daemon.notice pppd[1809]: secondary DNS address 193.41.112.18
Sep  8 17:56:47 OpenWrt daemon.debug pppd[1809]: Script /etc/ppp/ip-up started (pid 1851)
Sep  8 17:56:47 OpenWrt daemon.debug pppd[1809]: Script /etc/ppp/ip-up finished (pid 1851), status = 0x1

The discussion might have continued from here.