Hello all,
I'm getting stuck on a (maybe simple) problem.
I configured in 2017 a TP-LINK MR3220 with USB modem for WAN internet connection.
SW: OpenWrt Chaos Calmer 15.05.1
All worked fine until this June and since it I cannot get connection.
I've tried various configurations edit but none of them is risolutive.
This is my configuration:
And This is the logread (all day in the same loop).
Thu Jan 11 02:15:10 2018 daemon.notice netifd: Interface 'wan' is setting up now
Thu Jan 11 02:15:12 2018 daemon.notice pppd[15624]: pppd 2.4.7 started by root, uid 0
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: abort on (BUSY)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: abort on (NO CARRIER)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: abort on (ERROR)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: report (CONNECT)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: timeout set to 10 seconds
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: send (AT&F^M)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: expect (OK)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: AT&F^M^M
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: OK
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: -- got it
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: send (ATE1^M)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: expect (OK)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: ^M
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: ATE1^M^M
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: OK
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: -- got it
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: send (AT+CGDCONT=1,"IP","iliad"^M)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: timeout set to 30 seconds
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: expect (OK)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: ^M
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: AT+CGDCONT=1,"IP","iliad"^M^M
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: OK
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: -- got it
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: send (ATD*99#^M)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: expect (CONNECT)
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: ^M
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: ATD*99#^M^M
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: CONNECT
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: -- got it
Thu Jan 11 02:15:13 2018 local2.info chat[15630]: send ( ^M)
Thu Jan 11 02:15:13 2018 daemon.debug pppd[15624]: Script USE_APN=iliad DIALNUMBER=*99***1# /usr/sbin/chat -t5 -v -E -f /etc/chatscripts/3g.chat finished (pid 15629), status = 0x0
Thu Jan 11 02:15:13 2018 daemon.info pppd[15624]: Serial connection established.
Thu Jan 11 02:15:13 2018 daemon.debug pppd[15624]: using channel 1228
Thu Jan 11 02:15:13 2018 daemon.info pppd[15624]: Using interface 3g-wan
Thu Jan 11 02:15:13 2018 daemon.notice pppd[15624]: Connect: 3g-wan <--> /dev/ttyUSB4
Thu Jan 11 02:15:13 2018 kern.info kernel: [10346.920000] 3g-wan: renamed from ppp0
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x7f612ca9>]
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: rcvd [LCP ConfReq id=0x49 <asyncmap 0x0> <auth chap MD5> <magic 0x185443e> <pcomp> <accomp>]
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: No auth is possible
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: sent [LCP ConfRej id=0x49 <auth chap MD5> <pcomp> <accomp>]
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x7f612ca9>]
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: rcvd [LCP ConfReq id=0x4a <asyncmap 0x0> <magic 0x185443e>]
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: sent [LCP ConfAck id=0x4a <asyncmap 0x0> <magic 0x185443e>]
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: sent [LCP EchoReq id=0x0 magic=0x7f612ca9]
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: sent [IPV6CP ConfReq id=0x1 <addr fe80::892f:461c:0831:c644>]
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: rcvd [LCP DiscReq id=0x4b magic=0x185443e]
Thu Jan 11 02:15:14 2018 daemon.debug pppd[15624]: rcvd [LCP EchoRep id=0x0 magic=0x185443e 7f 61 2c a9]
Thu Jan 11 02:15:15 2018 daemon.debug pppd[15624]: sent [LCP EchoReq id=0x1 magic=0x7f612ca9]
Thu Jan 11 02:15:15 2018 daemon.debug pppd[15624]: rcvd [LCP EchoRep id=0x1 magic=0x185443e 7f 61 2c a9]
Thu Jan 11 02:15:15 2018 daemon.debug pppd[15624]: rcvd [IPCP ConfNak id=0x1 <ms-dns1 10.11.12.13> <ms-dns2 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Thu Jan 11 02:15:15 2018 daemon.debug pppd[15624]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns2 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Thu Jan 11 02:15:16 2018 daemon.debug pppd[15624]: sent [LCP EchoReq id=0x2 magic=0x7f612ca9]
Thu Jan 11 02:15:16 2018 daemon.debug pppd[15624]: rcvd [LCP EchoRep id=0x2 magic=0x185443e 7f 61 2c a9]
Thu Jan 11 02:15:16 2018 daemon.debug pppd[15624]: rcvd [IPCP ConfNak id=0x2 <ms-dns1 10.11.12.13> <ms-dns2 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Thu Jan 11 02:15:16 2018 daemon.debug pppd[15624]: sent [IPCP ConfReq id=0x3 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns2 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Thu Jan 11 02:15:17 2018 daemon.debug pppd[15624]: sent [IPV6CP ConfReq id=0x1 <addr fe80::892f:461c:0831:c644>]
Thu Jan 11 02:15:17 2018 daemon.debug pppd[15624]: sent [LCP EchoReq id=0x3 magic=0x7f612ca9]
Thu Jan 11 02:15:17 2018 daemon.debug pppd[15624]: rcvd [LCP EchoRep id=0x3 magic=0x185443e 7f 61 2c a9]
Thu Jan 11 02:15:17 2018 daemon.debug pppd[15624]: rcvd [IPCP ConfNak id=0x3 <ms-dns1 10.11.12.13> <ms-dns2 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Thu Jan 11 02:15:17 2018 daemon.debug pppd[15624]: sent [IPCP ConfReq id=0x4 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns2 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Thu Jan 11 02:15:18 2018 daemon.debug pppd[15624]: sent [LCP EchoReq id=0x4 magic=0x7f612ca9]
Thu Jan 11 02:15:18 2018 daemon.debug pppd[15624]: rcvd [LCP EchoRep id=0x4 magic=0x185443e 7f 61 2c a9]
Thu Jan 11 02:15:18 2018 daemon.debug pppd[15624]: rcvd [IPCP ConfNak id=0x4 <ms-dns1 10.11.12.13> <ms-dns2 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Thu Jan 11 02:15:18 2018 daemon.debug pppd[15624]: sent [IPCP ConfReq id=0x5 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns2 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Thu Jan 11 02:15:19 2018 daemon.debug pppd[15624]: sent [LCP EchoReq id=0x5 magic=0x7f612ca9]
Thu Jan 11 02:15:19 2018 daemon.debug pppd[15624]: rcvd [LCP EchoRep id=0x5 magic=0x185443e 7f 61 2c a9]
Thu Jan 11 02:15:19 2018 daemon.debug pppd[15624]: rcvd [IPCP ConfNak id=0x5 <ms-dns1 10.11.12.13> <ms-dns2 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Thu Jan 11 02:15:19 2018 daemon.debug pppd[15624]: sent [IPCP ConfReq id=0x6 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns2 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Thu Jan 11 02:15:20 2018 daemon.notice pppd[15624]: Modem hangup
Thu Jan 11 02:15:20 2018 daemon.notice pppd[15624]: Connection terminated.
Thu Jan 11 02:15:21 2018 daemon.info pppd[15624]: Exit.
Anyone know why "modem hangup" ?
I'm not so expert to analyse the chat and understand what is missing.
For me the realistic reasons are two: 1) modem hardware is broken due to its age. 2) my internet provider has changed something since june (anyway APN is correct).
Currently this is the lone 3G dongle to test. I've teste the SIM card on a phone and internet connection is OK. I cannot test the USB modem on PC because its windows SW dosen't support this provider (I cannot change the manually the APN).
Thanks to all.