Forcing modem to dial in absence of dialtone
I am currently staying with my parents and am having
severe problems trying to connect via dialup.
The problem is that they have a telco-based 'answering
machine' which signals the presence of waiting messages
with an intermittent dialtone. The modem thinks this is an
invalid dialtone, reports NO DIAL TONE and I cannot dial out.
I have tried deleting all the ABORT strings in
/etc/chatscripts/provider, but this does not help.
I have tried replacing the expected CONNECT string with
'NO DIAL TONE' but this does not help either.
In desperation I have tried lifting the telephone receiver
and making dialtone noises with my vocal chords to try and fool
the modem; this doesn't work either (not entirely to my
surprise).
This problem can easily be circumvented in Windoze by
unchecking the "Wait for dial tone before dialling" option
in Dial-up Networking, which suggests to me that there
should be an equally simple way to circumvent it in Linux.
The modem is an external serial modem, ADDTEL AD-56K-EX, which
I think uses a Rockwell chipset.
I append the entries in /var/log/syslog for a failed session,
and also those for a successful session.
Many thanks,
Pigeon
=============================================
/var/log/syslog for a failed session:
Apr 25 22:42:03 debian pppd[583]: pppd 2.4.1 started by pigeon, uid 1000
Apr 25 22:42:04 debian chat[584]: send (ATZ^M)
Apr 25 22:42:04 debian chat[584]: expect (OK)
Apr 25 22:42:04 debian chat[584]: ATZ^M^M
Apr 25 22:42:04 debian chat[584]: OK
Apr 25 22:42:04 debian chat[584]: -- got it
Apr 25 22:42:04 debian chat[584]: send (ATDT08453331121^M)
Apr 25 22:42:05 debian chat[584]: expect (NO DIAL TONE)
Apr 25 22:42:05 debian chat[584]: ^M
Apr 25 22:42:07 debian chat[584]: ATDT08453331121^M^M
Apr 25 22:42:07 debian chat[584]: NO DIAL TONE
Apr 25 22:42:07 debian chat[584]: -- got it
Apr 25 22:42:07 debian chat[584]: send (\d)
Apr 25 22:42:08 debian pppd[583]: Serial connection established.
Apr 25 22:42:08 debian pppd[583]: using channel 9
Apr 25 22:42:08 debian pppd[583]: Using interface ppp0
Apr 25 22:42:08 debian pppd[583]: Connect: ppp0 <--> /dev/ttyS0
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x74ffc5fc> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x74ffc5fc> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfNak id=0x1 <magic 0x84a68821>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfNak id=0x1 <magic 0x84a68821>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfReq id=0x2 <asyncmap 0x0> <magic 0x7d97b835> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfReq id=0x2 <asyncmap 0x0> <magic 0x7d97b835> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfNak id=0x2 <magic 0x875564c2>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfNak id=0x2 <magic 0x875564c2>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfReq id=0x3 <asyncmap 0x0> <magic 0xbb9d9ec1> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfReq id=0x3 <asyncmap 0x0> <magic 0xbb9d9ec1> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfNak id=0x3 <magic 0x72edc285>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfNak id=0x3 <magic 0x72edc285>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfReq id=0x4 <asyncmap 0x0> <magic 0xb581ecf3> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfReq id=0x4 <asyncmap 0x0> <magic 0xb581ecf3> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfNak id=0x4 <magic 0x9fd5c6fd>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfNak id=0x4 <magic 0x9fd5c6fd>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfReq id=0x5 <asyncmap 0x0> <magic 0xfeb9d495> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfReq id=0x5 <asyncmap 0x0> <magic 0xfeb9d495> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfNak id=0x5 <magic 0x40197608>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfNak id=0x5 <magic 0x40197608>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfReq id=0x6 <asyncmap 0x0> <magic 0x6664dcb0> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfReq id=0x6 <asyncmap 0x0> <magic 0x6664dcb0> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfNak id=0x6 <magic 0xb1ed9817>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfNak id=0x6 <magic 0xb1ed9817>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfReq id=0x7 <asyncmap 0x0> <magic 0x1276137> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfReq id=0x7 <asyncmap 0x0> <magic 0x1276137> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfNak id=0x7 <magic 0xf17abb58>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfNak id=0x7 <magic 0xf17abb58>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfReq id=0x8 <asyncmap 0x0> <magic 0x29b1ddf4> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfReq id=0x8 <asyncmap 0x0> <magic 0x29b1ddf4> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfNak id=0x8 <magic 0x9ba8586c>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfNak id=0x8 <magic 0x9ba8586c>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfReq id=0x9 <asyncmap 0x0> <magic 0xe5f7a427> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfReq id=0x9 <asyncmap 0x0> <magic 0xe5f7a427> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfNak id=0x9 <magic 0xb5ba37a2>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfNak id=0x9 <magic 0xb5ba37a2>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfReq id=0xa <asyncmap 0x0> <magic 0x5d63b9b1> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfReq id=0xa <asyncmap 0x0> <magic 0x5d63b9b1> <pcomp> <accomp>]
Apr 25 22:42:09 debian pppd[583]: sent [LCP ConfNak id=0xa <magic 0xa70f01ca>]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP ConfNak id=0xa <magic 0xa70f01ca>]
Apr 25 22:42:09 debian pppd[583]: Serial line is looped back.
Apr 25 22:42:09 debian pppd[583]: sent [LCP TermReq id=0xb "Loopback detected"]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP TermReq id=0xb "Loopback detected"]
Apr 25 22:42:09 debian pppd[583]: sent [LCP TermAck id=0xb]
Apr 25 22:42:09 debian pppd[583]: rcvd [LCP TermAck id=0xb]
Apr 25 22:42:09 debian pppd[583]: Connection terminated.
Apr 25 22:42:10 debian pppd[583]: Exit.
================================================
/var/log/syslog for a successful session:
Apr 24 19:11:35 debian pppd[689]: pppd 2.4.1 started by pigeon, uid 1000
Apr 24 19:11:36 debian chat[690]: abort on (BUSY)
Apr 24 19:11:36 debian chat[690]: abort on (NO CARRIER)
Apr 24 19:11:36 debian chat[690]: abort on (VOICE)
Apr 24 19:11:36 debian chat[690]: abort on (NO DIALTONE)
Apr 24 19:11:36 debian chat[690]: abort on (NO DIAL TONE)
Apr 24 19:11:36 debian chat[690]: abort on (NO ANSWER)
Apr 24 19:11:36 debian chat[690]: abort on (DELAYED)
Apr 24 19:11:36 debian chat[690]: send (ATZ^M)
Apr 24 19:11:36 debian chat[690]: expect (OK)
Apr 24 19:11:37 debian chat[690]: ATZ^M^M
Apr 24 19:11:37 debian chat[690]: OK
Apr 24 19:11:37 debian chat[690]: -- got it
Apr 24 19:11:37 debian chat[690]: send (ATDT08453331121^M)
Apr 24 19:11:37 debian chat[690]: expect (CONNECT)
Apr 24 19:11:37 debian chat[690]: ^M
Apr 24 19:12:05 debian chat[690]: ATDT08453331121^M^M
Apr 24 19:12:05 debian chat[690]: CONNECT
Apr 24 19:12:05 debian chat[690]: -- got it
Apr 24 19:12:05 debian chat[690]: send (\d)
Apr 24 19:12:06 debian pppd[689]: Serial connection established.
Apr 24 19:12:06 debian pppd[689]: using channel 1
Apr 24 19:12:06 debian pppd[689]: Using interface ppp0
Apr 24 19:12:06 debian pppd[689]: Connect: ppp0 <--> /dev/ttyS0
Apr 24 19:12:07 debian pppd[689]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x4d71e459> <pcomp> <accomp>]
Apr 24 19:12:08 debian pppd[689]: rcvd [LCP ConfReq id=0x1 < 00 04 00 00> <mru 1524> <asyncmap 0x0> <auth pap> <pcomp> <accomp> <mrru 1524> <endpoint [MAC:00:c0:7b:90:26:d1]> < 17 04 47 07>]
Apr 24 19:12:08 debian pppd[689]: sent [LCP ConfRej id=0x1 < 00 04 00 00> <mrru 1524> < 17 04 47 07>]
Apr 24 19:12:08 debian pppd[689]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x4d71e459> <pcomp> <accomp>]
Apr 24 19:12:08 debian pppd[689]: rcvd [LCP ConfReq id=0x2 <mru 1524> <asyncmap 0x0> <auth pap> <pcomp> <accomp> <endpoint [MAC:00:c0:7b:90:26:d1]>]
Apr 24 19:12:08 debian pppd[689]: sent [LCP ConfAck id=0x2 <mru 1524> <asyncmap 0x0> <auth pap> <pcomp> <accomp> <endpoint [MAC:00:c0:7b:90:26:d1]>]
Apr 24 19:12:08 debian pppd[689]: sent [LCP EchoReq id=0x0 magic=0x4d71e459]
Apr 24 19:12:08 debian pppd[689]: sent [PAP AuthReq id=0x1 user="jah.pigeon" password=<hidden>]
Apr 24 19:12:08 debian pppd[689]: rcvd [LCP EchoRep id=0x0 magic=0x0]
Apr 24 19:12:08 debian pppd[689]: rcvd [PAP AuthAck id=0x1 "EASYSTART"]
Apr 24 19:12:08 debian pppd[689]: Remote message: EASYSTART
Apr 24 19:12:08 debian pppd[689]: kernel does not support PPP filtering
Apr 24 19:12:08 debian pppd[689]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Apr 24 19:12:08 debian kernel: PPP BSD Compression module registered
Apr 24 19:12:08 debian pppd[689]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 24 19:12:08 debian pppd[689]: rcvd [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 195.40.4.201>]
Apr 24 19:12:08 debian pppd[689]: sent [IPCP ConfAck id=0x1 <compress VJ 0f 01> <addr 195.40.4.201>]
Apr 24 19:12:08 debian pppd[689]: rcvd [CCP ConfReq id=0x1 < 11 05 00 01 03>]
Apr 24 19:12:08 debian pppd[689]: sent [CCP ConfRej id=0x1 < 11 05 00 01 03>]
Apr 24 19:12:08 debian pppd[689]: rcvd [IPCP ConfNak id=0x1 <addr 212.134.224.252> <ms-dns1 195.40.1.36> <ms-dns3 193.131.248.36>]
Apr 24 19:12:08 debian pppd[689]: sent [IPCP ConfReq id=0x2 <addr 212.134.224.252> <compress VJ 0f 01> <ms-dns1 195.40.1.36> <ms-dns3 193.131.248.36>]
Apr 24 19:12:08 debian pppd[689]: rcvd [CCP ConfRej id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 24 19:12:08 debian pppd[689]: sent [CCP ConfReq id=0x2]
Apr 24 19:12:08 debian pppd[689]: rcvd [CCP ConfReq id=0x2 < 11 06 00 01 01 03>]
Apr 24 19:12:08 debian pppd[689]: sent [CCP ConfRej id=0x2 < 11 06 00 01 01 03>]
Apr 24 19:12:08 debian pppd[689]: rcvd [IPCP ConfAck id=0x2 <addr 212.134.224.252> <compress VJ 0f 01> <ms-dns1 195.40.1.36> <ms-dns3 193.131.248.36>]
Apr 24 19:12:08 debian pppd[689]: Cannot determine ethernet address for proxy ARP
Apr 24 19:12:08 debian pppd[689]: local IP address 212.134.224.252
Apr 24 19:12:08 debian pppd[689]: remote IP address 195.40.4.201
Apr 24 19:12:08 debian pppd[689]: primary DNS address 195.40.1.36
Apr 24 19:12:08 debian pppd[689]: secondary DNS address 193.131.248.36
Apr 24 19:12:08 debian pppd[689]: Script /etc/ppp/ip-up started (pid 695)
Apr 24 19:12:08 debian pppd[689]: rcvd [CCP ConfRej id=0x2]
Apr 24 19:12:33 debian pppd[689]: Script /etc/ppp/ip-up finished (pid 695), status = 0x0
Apr 24 19:12:38 debian pppd[689]: sent [LCP EchoReq id=0x1 magic=0x4d71e459]
Apr 24 19:12:38 debian pppd[689]: rcvd [LCP EchoRep id=0x1 magic=0x0]
Apr 24 19:12:38 debian identd[728]: started
Apr 24 19:13:08 debian pppd[689]: sent [LCP EchoReq id=0x2 magic=0x4d71e459]
Apr 24 19:13:08 debian pppd[689]: rcvd [LCP EchoRep id=0x2 magic=0x0]
Apr 24 19:13:38 debian pppd[689]: sent [LCP EchoReq id=0x3 magic=0x4d71e459]
Apr 24 19:13:38 debian pppd[689]: rcvd [LCP EchoRep id=0x3 magic=0x0]
Apr 24 19:14:08 debian pppd[689]: sent [LCP EchoReq id=0x4 magic=0x4d71e459]
Apr 24 19:14:08 debian pppd[689]: rcvd [LCP EchoRep id=0x4 magic=0x0]
Apr 24 19:14:38 debian pppd[689]: sent [LCP EchoReq id=0x5 magic=0x4d71e459]
Apr 24 19:14:38 debian pppd[689]: rcvd [LCP EchoRep id=0x5 magic=0x0]
Apr 24 19:15:08 debian pppd[689]: sent [LCP EchoReq id=0x6 magic=0x4d71e459]
Apr 24 19:15:08 debian pppd[689]: rcvd [LCP EchoRep id=0x6 magic=0x0]
Apr 24 19:15:34 debian jed: /dev/gpmctl: No such file or directory
Apr 24 19:15:34 debian jed: /dev/gpmctl: No such file or directory
Apr 24 19:15:38 debian pppd[689]: sent [LCP EchoReq id=0x7 magic=0x4d71e459]
Apr 24 19:15:38 debian pppd[689]: rcvd [LCP EchoRep id=0x7 magic=0x0]
Apr 24 19:15:48 debian pppd[689]: Terminating on signal 15.
Apr 24 19:15:48 debian pppd[689]: Script /etc/ppp/ip-down started (pid 1046)
Apr 24 19:15:48 debian pppd[689]: sent [LCP TermReq id=0x2 "User request"]
Apr 24 19:15:49 debian pppd[689]: Script /etc/ppp/ip-down finished (pid 1046), status = 0x0
Apr 24 19:15:49 debian pppd[689]: rcvd [LCP TermAck id=0x2]
Apr 24 19:15:49 debian pppd[689]: Connection terminated.
Apr 24 19:15:49 debian pppd[689]: Connect time 3.7 minutes.
Apr 24 19:15:49 debian pppd[689]: Sent 97201 bytes, received 711827 bytes.
Apr 24 19:15:49 debian pppd[689]: Hangup (SIGHUP)
Apr 24 19:15:49 debian pppd[689]: Exit.
Reply to: