Mgetty won't answer phone after kernel upgrade

Gert Doering (gert@greenie.muc.de)
Thu, 29 May 1997 16:30:17 +0200


Hi,

why on earth are you setting "direct yes" in mgetty.config???

gert

Mark Moran wrote:
> I had a request for a log file of my non answering mgetty (see
> original msg below). Here it is:
> 
> --
> 05/28 22:34:56 yS0 mgetty: official release 1.0.0
> 05/28 22:34:56 yS0 mgetty.c compiled at May 19 1997, 07:10:05
> 05/28 22:34:56 yS0 reading configuration data for port 'ttyS0'
> 05/28 22:34:56 yS0 conf lib: read: 'debug 1'
> 05/28 22:34:56 yS0 conf lib: read: 'fax-id 610 282 1830'
> 05/28 22:34:56 yS0 conf lib: read: 'port-owner uucp'
> 05/28 22:34:56 yS0 conf lib: read: 'port-group uucp'
> 05/28 22:34:56 yS0 conf lib: read: 'port-mode 0664'
> 05/28 22:34:56 yS0 conf lib: read: 'fax-owner root'
> 05/28 22:34:56 yS0 conf lib: read: 'fax-group uucp'
> 05/28 22:34:56 yS0 conf lib: read: 'fax-mode 0640'
> 05/28 22:34:56 yS0 key: 'speed', type=0, flags=2, data=115200
> 05/28 22:34:56 yS0 key: 'switchbd', type=0, flags=1, data=0
> 05/28 22:34:56 yS0 key: 'direct', type=3, flags=2, data=TRUE
> 05/28 22:34:56 yS0 key: 'blocking', type=3, flags=1, data=FALSE
> 05/28 22:34:56 yS0 key: 'port-owner', type=1, flags=3, data=uucp
> 05/28 22:34:56 yS0 key: 'port-group', type=1, flags=3, data=uucp
> 05/28 22:34:56 yS0 key: 'port-mode', type=0, flags=3, data=436
> 05/28 22:34:56 yS0 key: 'toggle-dtr', type=3, flags=1, data=TRUE
> 05/28 22:34:56 yS0 key: 'toggle-dtr-waittime', type=0, flags=1,
> data=500
> 05/28 22:34:56 yS0 key: 'data-only', type=3, flags=1, data=FALSE
> 05/28 22:34:56 yS0 key: 'fax-only', type=3, flags=1, data=FALSE
> 05/28 22:34:56 yS0 key: 'modem-type', type=1, flags=1, data=auto
> 05/28 22:34:56 yS0 key: 'init-chat', type=2, flags=1, data=
> \dATQ0V1H0 OK ATS0=1Q0&D3&C1 OK 
> 05/28 22:34:56 yS0 key: 'force-init-chat', type=2, flags=1, data=
> \d
> \d\d\d+++\d\d\d\dATQ0V1H0 OK 
> 05/28 22:34:56 yS0 key: 'modem-check-time', type=0, flags=1,
> data=3600
> 05/28 22:34:56 yS0 key: 'rings', type=0, flags=1, data=1
> 05/28 22:34:56 yS0 key: 'get-cnd-chat', type=2, flags=0,
> data=(empty)
> 05/28 22:34:56 yS0 key: 'answer-chat', type=2, flags=1, data= ATA
> CONNECT \c 
> 
> 05/28 22:34:56 yS0 key: 'answer-chat-timeout', type=0, flags=1,
> data=80
> 05/28 22:34:56 yS0 key: 'autobauding', type=3, flags=1, data=FALSE
> 05/28 22:34:56 yS0 key: 'ringback', type=3, flags=1, data=FALSE
> 05/28 22:34:56 yS0 key: 'ringback-time', type=0, flags=1, data=30
> 05/28 22:34:56 yS0 key: 'ignore-carrier', type=3, flags=1,
> data=FALSE
> 05/28 22:34:56 yS0 key: 'issue-file', type=1, flags=1,
> data=/etc/issue
> 05/28 22:34:56 yS0 key: 'prompt-waittime', type=0, flags=1, data=500
> 05/28 22:34:56 yS0 key: 'login-prompt', type=1, flags=1,
> data=@!login: 
> 05/28 22:34:56 yS0 key: 'login-time', type=0, flags=1, data=240
> 05/28 22:34:56 yS0 key: 'fido-send-emsi', type=3, flags=1, data=TRUE
> 05/28 22:34:56 yS0 key: 'fax-id', type=1, flags=3, data=610 282 1830
> 05/28 22:34:56 yS0 key: 'fax-server-file', type=1, flags=0,
> data=(empty)
> 05/28 22:34:56 yS0 key: 'diskspace', type=0, flags=1, data=1024
> 05/28 22:34:56 yS0 key: 'notify', type=1, flags=1, data=mmoran
> 05/28 22:34:56 yS0 key: 'fax-owner', type=1, flags=3, data=root
> 05/28 22:34:56 yS0 key: 'fax-group', type=1, flags=3, data=uucp
> 05/28 22:34:56 yS0 key: 'fax-mode', type=0, flags=3, data=416
> 05/28 22:34:56 yS0 key: 'debug', type=0, flags=2, data=5
> 05/28 22:34:56 yS0 key: 'statistics-chat', type=2, flags=0,
> data=(empty)
> 05/28 22:34:56 yS0 key: 'statistics-file', type=1, flags=0,
> data=(empty)
> 05/28 22:34:56 yS0 key: 'gettydefs', type=1, flags=1, data=n
> 05/28 22:34:56 yS0 key: 'term', type=1, flags=0, data=(empty)
> 05/28 22:34:56 yS0 check for lockfiles
> 05/28 22:34:56 yS0 checklock: stat failed, no file
> 05/28 22:34:56 yS0 locking the line
> 05/28 22:34:56 yS0 makelock(ttyS0) called
> 05/28 22:34:56 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
> 05/28 22:34:56 yS0 lock made
> 05/28 22:34:56 yS0 tio_get_rs232_lines: status: RTS CTS DSR DTR
> 05/28 22:34:56 yS0 lowering DTR to reset Modem
> 05/28 22:34:57 yS0 tss: set speed to 115200 (10002)
> 05/28 22:34:57 yS0 tio_set_flow_control( HARD )
> 05/28 22:34:57 yS0 waiting for line to clear (VTIME), read: 
> 05/28 22:34:57 yS0 waiting for line to clear (VTIME), read: 
> 05/28 22:34:57 yS0 removing lock file
> 05/28 22:34:57 yS0 waiting...
> 
> 
> 
> Mark Moran <mmoran@mmoran.com> wrote:
> 
> >I just moved to 2.0.30 of Linux and installed elf binaries (preserving
> >my old a.out too.) Now mgetty won't answer the phone. I've tried
> >recompiling, and every combo of init levels I can think of in inittab,
> >but it won't answer the phone. I'm using an ext USR Courier
> >v.everything. One thing I notice is when mgetty init'd the modem the
> >ARQ/FAX light used to flash, now it doesn't. I've tried -x 5 but it
> >doesn't report any errors? Any ideas?????
> >
> >-- 
> >Mark Moran
> >mmoran@mmoran.com
> >http://mmoran.com/tm.html
> 
> Mark Moran
> mmoran@mmoran.com
> 


-- 
USENET is *not* the non-clickable part of WWW!
           //www.muc.de/~gert/
Gert Doering - Munich, Germany      gert@greenie.muc.de
fax: +49-89-3545980     gert.doering@physik.tu-muenchen.de
*From MAILER-DAEMON@eli.wariat.org Thu May 29 22:56:39 1997
Received: from ns.crynwr.com (ns.crynwr.com [192.203.178.14]) by eli.wariat.org (8.7.5/8.7.3) with SMTP id WAA29038 for <mgarc@eli.wariat.org>; Thu, 29 May 1997 22:56:30 -0500
Received: (qmail 22054 invoked by alias); 30 May 1997 03:57:50 -0000
Delivered-To: mgetty@crynwr.com
Received: (qmail 22044 invoked from network); 30 May 1997 03:57:46 -0000
Received: from vogon.muc.de (root@193.174.4.4)
 by ns.crynwr.com with SMTP; 30 May 1997 03:57:46 -0000
Received: from blast.sheep.com ([204.134.97.46]) by vogon.muc.de with ESMTP id <48885-8570>; Fri, 30 May 1997 05:57:38 +0100
Received: (from honglu@localhost) by blast.sheep.com (8.7.4/8.7.3) id VAA04171; Thu, 29 May 1997 21:56:00 -0600
Date: Thu, 29 May 1997 21:56:00 -0600 (MDT)
From: Henry H Lu <honglu@rt66.com>
X-Sender: honglu@blast
To: mgetty@muc.de
Subject: my faxlog
Message-ID: <Pine.LNX.3.91.970529215256.4098B-100000@blast>
MIME-Version: 1.0
Content-Type: TEXT/PLAIN; charset=US-ASCII

sorry in last letter I did not include an error log. Here is my entire 
FaxLog. The last error message was under sendfax/mgetty compiled with 
USR modem flag on, the previous error messages was under sendfax/mgetty 
compiled with USR modem flag off in policy.h

/var/spool/fax/Faxlog
------------------------------------------

--
05/29 03:19:11 sendfax: official release 1.0.0
05/29 03:19:11 sendfax.c compiled at May 29 1997, 02:28:59
05/29 03:19:11 sending fax to 6653493
05/29 03:19:11 checking f1.g3
05/29 03:19:11 checking f2.g3
05/29 03:19:11 makelock(ttyS1) called
05/29 03:19:11 do_makelock: lock='/var/lock/LCK..ttyS1'
05/29 03:19:11 lock made
05/29 03:19:11 tss: set speed to 38400 (017)
05/29 03:19:11 tio_set_flow_control( )
05/29 03:19:11 yS1 fax_open_device succeeded, ttyS1 -> 4
05/29 03:19:11 yS1 reading specific data for port 'ttyS1'
05/29 03:19:11 yS1 conf lib: read: 'verbose y'
05/29 03:19:11 yS1 conf lib: read: 'debug 5'
05/29 03:19:11 yS1 conf lib: read: 'fax-devices ttyS1'
05/29 03:19:11 yS1 conf lib: read: 'fax-id +1-505-661-2634'
05/29 03:19:11 yS1 conf lib: read: 'max-tries 3'
05/29 03:19:11 yS1 conf lib: read: 'max-tries-continue y'
05/29 03:19:11 yS1 key: 'fax-devices', type=1, flags=4, data=(ignored)
05/29 03:19:11 yS1 key: 'modem-init', type=1, flags=0, data=(empty)
05/29 03:19:11 yS1 key: 'modem-handshake', type=1, flags=0, data=(empty)
05/29 03:19:11 yS1 key: 'modem-type', type=1, flags=1, data=auto
05/29 03:19:11 yS1 key: 'max-tries', type=0, flags=3, data=3
05/29 03:19:11 yS1 key: 'max-tries-continue', type=3, flags=3, data=TRUE
05/29 03:19:11 yS1 key: 'speed', type=0, flags=1, data=38400
05/29 03:19:11 yS1 key: 'switchbd', type=0, flags=1, data=0
05/29 03:19:11 yS1 key: 'ignore-carrier', type=3, flags=1, data=FALSE
05/29 03:19:11 yS1 key: 'dial-prefix', type=1, flags=1, data=ATD
05/29 03:19:11 yS1 key: 'fax-id', type=1, flags=3, data=+1-505-661-2634
05/29 03:19:11 yS1 key: 'poll-dir', type=1, flags=1, data=.
05/29 03:19:11 yS1 key: 'normal-res', type=3, flags=1, data=FALSE
05/29 03:19:11 yS1 key: 'debug', type=0, flags=3, data=5
05/29 03:19:11 yS1 key: 'verbose', type=3, flags=2, data=TRUE
05/29 03:19:11 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:19:11 yS1 key: 'page-header', type=1, flags=0, data=(empty)
05/29 03:19:11 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:19:11 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:19:11 yS1 key: '', type=1, flags=1, data=
05/29 03:19:11 yS1 tss: set speed to 38400 (017)
05/29 03:19:11 yS1 fax_send: 'ATV1Q0'
05/29 03:19:11 yS1 fax_wait_for(OK)
05/29 03:19:12 yS1 fax_wait_for: string 'ATV1Q0'
05/29 03:21:11 yS1 Warning: got alarm signal!
05/29 03:21:11 yS1 mdm_read_byte: read returned -1: Interrupted system call
05/29 03:21:11 yS1 mdm_get_line: cannot read byte, return: Interrupted system call
05/29 03:21:11 yS1 modem doesn't talk to me: Interrupted system call
05/29 03:21:11 yS1 removing lock file
--
05/29 03:35:50 sendfax: official release 1.0.0
05/29 03:35:50 sendfax.c compiled at May 29 1997, 02:28:59
05/29 03:35:50 sending fax to 6653493
05/29 03:35:50 checking f1.g3
05/29 03:35:50 checking f2.g3
05/29 03:35:50 makelock(ttyS1) called
05/29 03:35:50 do_makelock: lock='/var/lock/LCK..ttyS1'
05/29 03:35:50 lock made
05/29 03:35:50 tss: set speed to 38400 (017)
05/29 03:35:50 tio_set_flow_control( )
05/29 03:35:50 yS1 fax_open_device succeeded, ttyS1 -> 4
05/29 03:35:50 yS1 reading specific data for port 'ttyS1'
05/29 03:35:50 yS1 conf lib: read: 'verbose y'
05/29 03:35:50 yS1 conf lib: read: 'debug 5'
05/29 03:35:50 yS1 conf lib: read: 'fax-devices ttyS1'
05/29 03:35:50 yS1 conf lib: read: 'fax-id +1-505-661-2634'
05/29 03:35:50 yS1 conf lib: read: 'max-tries 3'
05/29 03:35:50 yS1 conf lib: read: 'max-tries-continue y'
05/29 03:35:50 yS1 key: 'fax-devices', type=1, flags=4, data=(ignored)
05/29 03:35:50 yS1 key: 'modem-init', type=1, flags=0, data=(empty)
05/29 03:35:50 yS1 key: 'modem-handshake', type=1, flags=0, data=(empty)
05/29 03:35:50 yS1 key: 'modem-type', type=1, flags=1, data=auto
05/29 03:35:50 yS1 key: 'max-tries', type=0, flags=3, data=3
05/29 03:35:50 yS1 key: 'max-tries-continue', type=3, flags=3, data=TRUE
05/29 03:35:50 yS1 key: 'speed', type=0, flags=1, data=38400
05/29 03:35:50 yS1 key: 'switchbd', type=0, flags=1, data=0
05/29 03:35:50 yS1 key: 'ignore-carrier', type=3, flags=1, data=FALSE
05/29 03:35:50 yS1 key: 'dial-prefix', type=1, flags=1, data=ATD
05/29 03:35:50 yS1 key: 'fax-id', type=1, flags=3, data=+1-505-661-2634
05/29 03:35:50 yS1 key: 'poll-dir', type=1, flags=1, data=.
05/29 03:35:50 yS1 key: 'normal-res', type=3, flags=1, data=FALSE
05/29 03:35:50 yS1 key: 'debug', type=0, flags=3, data=5
05/29 03:35:50 yS1 key: 'verbose', type=3, flags=2, data=TRUE
05/29 03:35:50 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:35:50 yS1 key: 'page-header', type=1, flags=0, data=(empty)
05/29 03:35:50 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:35:50 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:35:50 yS1 key: '', type=1, flags=1, data=
05/29 03:35:50 yS1 tss: set speed to 38400 (017)
05/29 03:35:50 yS1 fax_send: 'ATV1Q0'
05/29 03:35:50 yS1 fax_wait_for(OK)
05/29 03:35:50 yS1 fax_wait_for: string 'ATV1Q0'
05/29 03:36:45 ##### failed: got signal 2, pid=832, dev=ttyS1, time=0s, acct=""

05/29 03:36:45 yS1 removing lock file
--
05/29 03:40:03 sendfax: official release 1.0.0
05/29 03:40:03 sendfax.c compiled at May 29 1997, 02:28:59
05/29 03:40:03 sending fax to 6653493
05/29 03:40:03 checking f1.g3
05/29 03:40:03 checking f2.g3
05/29 03:40:03 makelock(ttyS1) called
05/29 03:40:03 do_makelock: lock='/var/lock/LCK..ttyS1'
05/29 03:40:03 lock made
05/29 03:40:03 tss: set speed to 38400 (017)
05/29 03:40:03 tio_set_flow_control( )
05/29 03:40:03 yS1 fax_open_device succeeded, ttyS1 -> 4
05/29 03:40:03 yS1 reading specific data for port 'ttyS1'
05/29 03:40:03 yS1 conf lib: read: 'verbose y'
05/29 03:40:03 yS1 conf lib: read: 'debug 5'
05/29 03:40:03 yS1 conf lib: read: 'fax-devices ttyS1'
05/29 03:40:03 yS1 conf lib: read: 'fax-id +1-505-661-2634'
05/29 03:40:03 yS1 conf lib: read: 'max-tries 3'
05/29 03:40:03 yS1 conf lib: read: 'max-tries-continue y'
05/29 03:40:03 yS1 key: 'fax-devices', type=1, flags=4, data=(ignored)
05/29 03:40:03 yS1 key: 'modem-init', type=1, flags=0, data=(empty)
05/29 03:40:03 yS1 key: 'modem-handshake', type=1, flags=0, data=(empty)
05/29 03:40:03 yS1 key: 'modem-type', type=1, flags=1, data=auto
05/29 03:40:03 yS1 key: 'max-tries', type=0, flags=3, data=3
05/29 03:40:03 yS1 key: 'max-tries-continue', type=3, flags=3, data=TRUE
05/29 03:40:03 yS1 key: 'speed', type=0, flags=1, data=38400
05/29 03:40:03 yS1 key: 'switchbd', type=0, flags=1, data=0
05/29 03:40:03 yS1 key: 'ignore-carrier', type=3, flags=1, data=FALSE
05/29 03:40:03 yS1 key: 'dial-prefix', type=1, flags=1, data=ATD
05/29 03:40:03 yS1 key: 'fax-id', type=1, flags=3, data=+1-505-661-2634
05/29 03:40:03 yS1 key: 'poll-dir', type=1, flags=1, data=.
05/29 03:40:03 yS1 key: 'normal-res', type=3, flags=1, data=FALSE
05/29 03:40:03 yS1 key: 'debug', type=0, flags=3, data=5
05/29 03:40:03 yS1 key: 'verbose', type=3, flags=2, data=TRUE
05/29 03:40:03 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:40:03 yS1 key: 'page-header', type=1, flags=0, data=(empty)
05/29 03:40:03 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:40:03 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:40:03 yS1 key: '', type=1, flags=1, data=
05/29 03:40:03 yS1 tss: set speed to 38400 (017)
05/29 03:40:03 yS1 fax_send: 'ATV1Q0'
05/29 03:40:03 yS1 fax_wait_for(OK)
05/29 03:40:03 yS1 fax_wait_for: string 'ATV1Q0'
05/29 03:40:03 yS1 fax_wait_for: string 'OK'** found **
05/29 03:40:03 yS1 mdm_send: 'AT+FCLASS=2.0'
05/29 03:40:03 yS1 mdm_command: string 'AT+FCLASS=2.0'
05/29 03:40:03 yS1 mdm_command: string 'OK' -> OK
05/29 03:40:03 yS1 mdm_send: 'AT+FLI="+1-505-661-2634"'
05/29 03:40:03 yS1 mdm_command: string 'AT+FLI="+1-505-661-2634"'
05/29 03:40:03 yS1 mdm_command: string 'OK' -> OK
05/29 03:40:03 yS1 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
05/29 03:40:03 yS1 mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
05/29 03:40:04 yS1 mdm_command: string 'OK' -> OK
05/29 03:40:04 yS1 mdm_send: 'AT+FBO=0'
05/29 03:40:04 yS1 mdm_command: string 'AT+FBO=0'
05/29 03:40:04 yS1 mdm_command: string 'OK' -> OK
05/29 03:40:04 yS1 mdm_send: 'AT+FNR=1,1,1,0'
05/29 03:40:04 yS1 mdm_command: string 'AT+FNR=1,1,1,0'
05/29 03:40:04 yS1 mdm_command: string 'OK' -> OK
05/29 03:40:04 yS1 mdm_send: 'AT+FLO=1'
05/29 03:40:04 yS1 mdm_command: string 'AT+FLO=1'
05/29 03:40:04 yS1 mdm_command: string 'OK' -> OK
05/29 03:40:04 yS1 fax_send: 'ATD6653493'
05/29 03:40:04 yS1 fax_wait_for(OK)
05/29 03:40:04 yS1 fax_wait_for: string 'ATD6653493'
05/29 03:40:16 yS1 fax_wait_for: string '+FCO'
05/29 03:40:19 yS1 fax_wait_for: string '+FCI:"  5056653493"'
05/29 03:40:19 yS1 fax_id: '+FCI:"  5056653493"'
05/29 03:40:19 yS1 fax_wait_for: string '+FIS:1,3,0,2,1,0,1,2'
05/29 03:40:19 yS1 fax_wait_for: string 'OK'** found **
05/29 03:40:19 yS1 sendfax: honouring DCD (carrier) drops now
05/29 03:40:19 yS1 fax_send_page("f1.g3") started...
05/29 03:40:19 yS1 tio_set_flow_control( )
05/29 03:40:19 yS1 fax_send: 'AT+FDT'
05/29 03:40:19 yS1 fax_wait_for(CONNECT)
05/29 03:40:19 yS1 fax_wait_for: string 'AT+FDT'
05/29 03:40:19 yS1 fax_wait_for: string '+FCS:1,3,0,2,0,0,0,0'
05/29 03:40:19 yS1 transmission par.: '+FCS:1,3,0,2,0,0,0,0'
05/29 03:40:26 yS1 fax_wait_for: string '+FCS:1,3,0,2,0,0,0,0'
05/29 03:40:26 yS1 transmission par.: '+FCS:1,3,0,2,0,0,0,0'
05/29 03:40:32 yS1 fax_wait_for: string '+FCS:1,3,0,2,0,0,0,0'
05/29 03:40:32 yS1 transmission par.: '+FCS:1,3,0,2,0,0,0,0'
05/29 03:40:40 yS1 fax_wait_for: string '+FHS:25'
05/29 03:40:40 yS1 connection hangup: '+FHS:25'
05/29 03:40:40 yS1 (DCS sent three times without response)** found **
05/29 03:40:40 yS1 AT+FDT -> some error (25), abort fax send!
05/29 03:40:40 ##### failed transmitting f1.g3: phone="6653493", +FHS:25, dev=ttyS1, time=36s, acct=""

05/29 03:40:40 yS1 fax_send: 'AT+FCLASS=0'
05/29 03:40:40 yS1 removing lock file
--
05/29 03:44:15 sendfax: official release 1.0.0
05/29 03:44:15 sendfax.c compiled at May 29 1997, 02:28:59
05/29 03:44:15 sending fax to 6653493
05/29 03:44:15 checking f1.g3
05/29 03:44:15 checking f2.g3
05/29 03:44:15 makelock(ttyS1) called
05/29 03:44:15 do_makelock: lock='/var/lock/LCK..ttyS1'
05/29 03:44:15 lock made
05/29 03:44:15 tss: set speed to 38400 (017)
05/29 03:44:15 tio_set_flow_control( )
05/29 03:44:15 yS1 fax_open_device succeeded, ttyS1 -> 4
05/29 03:44:15 yS1 reading specific data for port 'ttyS1'
05/29 03:44:15 yS1 conf lib: read: 'verbose y'
05/29 03:44:15 yS1 conf lib: read: 'debug 5'
05/29 03:44:15 yS1 conf lib: read: 'fax-devices ttyS1'
05/29 03:44:15 yS1 conf lib: read: 'fax-id +1-505-661-2634'
05/29 03:44:15 yS1 conf lib: read: 'max-tries 3'
05/29 03:44:15 yS1 conf lib: read: 'max-tries-continue y'
05/29 03:44:15 yS1 key: 'fax-devices', type=1, flags=4, data=(ignored)
05/29 03:44:15 yS1 key: 'modem-init', type=1, flags=0, data=(empty)
05/29 03:44:15 yS1 key: 'modem-handshake', type=1, flags=0, data=(empty)
05/29 03:44:15 yS1 key: 'modem-type', type=1, flags=1, data=auto
05/29 03:44:15 yS1 key: 'max-tries', type=0, flags=3, data=3
05/29 03:44:15 yS1 key: 'max-tries-continue', type=3, flags=3, data=TRUE
05/29 03:44:15 yS1 key: 'speed', type=0, flags=1, data=38400
05/29 03:44:15 yS1 key: 'switchbd', type=0, flags=1, data=0
05/29 03:44:15 yS1 key: 'ignore-carrier', type=3, flags=1, data=FALSE
05/29 03:44:15 yS1 key: 'dial-prefix', type=1, flags=1, data=ATD
05/29 03:44:15 yS1 key: 'fax-id', type=1, flags=3, data=+1-505-661-2634
05/29 03:44:15 yS1 key: 'poll-dir', type=1, flags=1, data=.
05/29 03:44:15 yS1 key: 'normal-res', type=3, flags=1, data=FALSE
05/29 03:44:15 yS1 key: 'debug', type=0, flags=3, data=5
05/29 03:44:15 yS1 key: 'verbose', type=3, flags=2, data=TRUE
05/29 03:44:15 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:44:15 yS1 key: 'page-header', type=1, flags=0, data=(empty)
05/29 03:44:15 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:44:15 yS1 key: '', type=3, flags=1, data=FALSE
05/29 03:44:15 yS1 key: '', type=1, flags=1, data=
05/29 03:44:15 yS1 tss: set speed to 38400 (017)
05/29 03:44:15 yS1 fax_send: 'ATV1Q0'
05/29 03:44:15 yS1 fax_wait_for(OK)
05/29 03:44:15 yS1 fax_wait_for: string 'ATV1Q0'
05/29 03:44:15 yS1 fax_wait_for: string 'OK'** found **
05/29 03:44:15 yS1 mdm_send: 'AT+FCLASS=2.0'
05/29 03:44:15 yS1 mdm_command: string 'AT+FCLASS=2.0'
05/29 03:44:15 yS1 mdm_command: string 'OK' -> OK
05/29 03:44:15 yS1 mdm_send: 'AT+FLI="+1-505-661-2634"'
05/29 03:44:15 yS1 mdm_command: string 'AT+FLI="+1-505-661-2634"'
05/29 03:44:15 yS1 mdm_command: string 'OK' -> OK
05/29 03:44:16 yS1 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
05/29 03:44:16 yS1 mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
05/29 03:44:16 yS1 mdm_command: string 'OK' -> OK
05/29 03:44:16 yS1 mdm_send: 'AT+FBO=0'
05/29 03:44:16 yS1 mdm_command: string 'AT+FBO=0'
05/29 03:44:16 yS1 mdm_command: string 'OK' -> OK
05/29 03:44:16 yS1 mdm_send: 'AT+FNR=1,1,1,0'
05/29 03:44:16 yS1 mdm_command: string 'AT+FNR=1,1,1,0'
05/29 03:44:16 yS1 mdm_command: string 'OK' -> OK
05/29 03:44:16 yS1 mdm_send: 'AT+FLO=1'
05/29 03:44:16 yS1 mdm_command: string 'AT+FLO=1'
05/29 03:44:16 yS1 mdm_command: string 'OK' -> OK
05/29 03:44:16 yS1 fax_send: 'ATD6653493'
05/29 03:44:16 yS1 fax_wait_for(OK)
05/29 03:44:16 yS1 fax_wait_for: string 'ATD6653493'
05/29 03:44:29 yS1 fax_wait_for: string '+FCO'
05/29 03:44:32 yS1 fax_wait_for: string '+FCI:"  5056653493"'
05/29 03:44:32 yS1 fax_id: '+FCI:"  5056653493"'
05/29 03:44:32 yS1 fax_wait_for: string '+FIS:1,3,0,2,1,0,1,2'
05/29 03:44:32 yS1 fax_wait_for: string 'OK'** found **
05/29 03:44:32 yS1 sendfax: honouring DCD (carrier) drops now
05/29 03:44:32 yS1 fax_send_page("f1.g3") started...
05/29 03:44:32 yS1 tio_set_flow_control( )
05/29 03:44:32 yS1 fax_send: 'AT+FDT'
05/29 03:44:32 yS1 fax_wait_for(CONNECT)
05/29 03:44:32 yS1 fax_wait_for: string 'AT+FDT'
05/29 03:44:32 yS1 fax_wait_for: string '+FCS:1,3,0,2,0,0,0,0'
05/29 03:44:32 yS1 transmission par.: '+FCS:1,3,0,2,0,0,0,0'
05/29 03:44:38 yS1 fax_wait_for: string '+FCS:1,3,0,2,0,0,0,0'
05/29 03:44:38 yS1 transmission par.: '+FCS:1,3,0,2,0,0,0,0'
05/29 03:44:45 yS1 fax_wait_for: string '+FCS:1,3,0,2,0,0,0,0'
05/29 03:44:45 yS1 transmission par.: '+FCS:1,3,0,2,0,0,0,0'
05/29 03:44:51 yS1 fax_wait_for: string 'ERROR'
05/29 03:44:51 yS1 ERROR response
05/29 03:44:51 yS1 tio_set_flow_control( XON_OUT )
05/29 03:44:51 yS1 sending f1.g3...
05/29 03:45:21 yS1 fax_send: timeout
05/29 03:45:21 yS1 could not write all 64 bytes: No such file or directory
05/29 03:45:21 yS1 PANIC: timeout sending fax page data, trying force modem reset
: No such file or directory
--
05/29 21:23:10 sendfax: official release 1.0.0
05/29 21:23:10 sendfax.c compiled at May 29 1997, 19:16:39
05/29 21:23:10 sending fax to 6653493
05/29 21:23:10 checking f1.g3
05/29 21:23:10 checking f2.g3
05/29 21:23:10 makelock(ttyS1) called
05/29 21:23:10 do_makelock: lock='/var/lock/LCK..ttyS1'
05/29 21:23:10 lock made
05/29 21:23:10 tss: set speed to 38400 (017)
05/29 21:23:10 tio_set_flow_control( )
05/29 21:23:10 yS1 fax_open_device succeeded, ttyS1 -> 4
05/29 21:23:10 yS1 reading specific data for port 'ttyS1'
05/29 21:23:10 yS1 conf lib: read: 'verbose y'
05/29 21:23:10 yS1 conf lib: read: 'debug 5'
05/29 21:23:10 yS1 conf lib: read: 'fax-devices ttyS1'
05/29 21:23:10 yS1 conf lib: read: 'fax-id +1-505-661-2634'
05/29 21:23:10 yS1 conf lib: read: 'max-tries 3'
05/29 21:23:10 yS1 conf lib: read: 'max-tries-continue y'
05/29 21:23:10 yS1 key: 'fax-devices', type=1, flags=4, data=(ignored)
05/29 21:23:10 yS1 key: 'modem-init', type=1, flags=0, data=(empty)
05/29 21:23:10 yS1 key: 'modem-handshake', type=1, flags=0, data=(empty)
05/29 21:23:10 yS1 key: 'modem-type', type=1, flags=1, data=auto
05/29 21:23:10 yS1 key: 'max-tries', type=0, flags=3, data=3
05/29 21:23:10 yS1 key: 'max-tries-continue', type=3, flags=3, data=TRUE
05/29 21:23:10 yS1 key: 'speed', type=0, flags=1, data=38400
05/29 21:23:10 yS1 key: 'switchbd', type=0, flags=1, data=0
05/29 21:23:10 yS1 key: 'ignore-carrier', type=3, flags=1, data=FALSE
05/29 21:23:10 yS1 key: 'dial-prefix', type=1, flags=1, data=ATD
05/29 21:23:10 yS1 key: 'fax-id', type=1, flags=3, data=+1-505-661-2634
05/29 21:23:10 yS1 key: 'poll-dir', type=1, flags=1, data=.
05/29 21:23:10 yS1 key: 'normal-res', type=3, flags=1, data=FALSE
05/29 21:23:10 yS1 key: 'debug', type=0, flags=3, data=5
05/29 21:23:10 yS1 key: 'verbose', type=3, flags=2, data=TRUE
05/29 21:23:10 yS1 key: '', type=3, flags=1, data=FALSE
05/29 21:23:10 yS1 key: 'page-header', type=1, flags=0, data=(empty)
05/29 21:23:10 yS1 key: '', type=3, flags=1, data=FALSE
05/29 21:23:10 yS1 key: '', type=3, flags=1, data=FALSE
05/29 21:23:10 yS1 key: '', type=1, flags=1, data=
05/29 21:23:10 yS1 tss: set speed to 38400 (017)
05/29 21:23:10 yS1 fax_send: 'ATV1Q0'
05/29 21:23:10 yS1 fax_wait_for(OK)
05/29 21:23:10 yS1 fax_wait_for: string 'ATV1Q0'
05/29 21:23:10 yS1 fax_wait_for: string 'OK'** found **
05/29 21:23:10 yS1 mdm_send: 'AT+FCLASS=2.0'
05/29 21:23:10 yS1 mdm_command: string 'AT+FCLASS=2.0'
05/29 21:23:11 yS1 mdm_command: string 'OK' -> OK
05/29 21:23:11 yS1 mdm_send: 'AT+FLI="+1-505-661-2634"'
05/29 21:23:11 yS1 mdm_command: string 'AT+FLI="+1-505-661-2634"'
05/29 21:23:11 yS1 mdm_command: string 'OK' -> OK
05/29 21:23:11 yS1 mdm_send: 'AT+FCC=1'
05/29 21:23:11 yS1 mdm_command: string 'AT+FCC=1'
05/29 21:23:11 yS1 mdm_command: string 'OK' -> OK
05/29 21:23:11 yS1 mdm_send: 'AT+FBO=0'
05/29 21:23:11 yS1 mdm_command: string 'AT+FBO=0'
05/29 21:23:11 yS1 mdm_command: string 'OK' -> OK
05/29 21:23:11 yS1 mdm_send: 'AT+FNR=1,1,1,0'
05/29 21:23:11 yS1 mdm_command: string 'AT+FNR=1,1,1,0'
05/29 21:23:11 yS1 mdm_command: string 'OK' -> OK
05/29 21:23:11 yS1 mdm_send: 'AT+FLO=1'
05/29 21:23:11 yS1 mdm_command: string 'AT+FLO=1'
05/29 21:23:11 yS1 mdm_command: string 'OK' -> OK
05/29 21:23:12 yS1 fax_send: 'ATD6653493'
05/29 21:23:12 yS1 fax_wait_for(OK)
05/29 21:23:12 yS1 fax_wait_for: string 'ATD6653493'
05/29 21:23:24 yS1 fax_wait_for: string '+FCO'
05/29 21:23:27 yS1 fax_wait_for: string '+FCI:"  5056653493"'
05/29 21:23:27 yS1 fax_id: '+FCI:"  5056653493"'
05/29 21:23:27 yS1 fax_wait_for: string '+FIS:1,3,0,2,1,0,1,2'
05/29 21:23:27 yS1 fax_wait_for: string 'OK'** found **
05/29 21:23:27 yS1 sendfax: honouring DCD (carrier) drops now
05/29 21:23:27 yS1 fax_send_page("f1.g3") started...
05/29 21:23:27 yS1 tio_set_flow_control( )
05/29 21:23:27 yS1 fax_send: 'AT+FDT'
05/29 21:23:27 yS1 fax_wait_for(CONNECT)
05/29 21:23:27 yS1 fax_wait_for: string 'AT+FDT'
05/29 21:23:27 yS1 fax_wait_for: string '+FCS:1,3,0,2,0,0,0,2'
05/29 21:23:27 yS1 transmission par.: '+FCS:1,3,0,2,0,0,0,2'
05/29 21:23:33 yS1 fax_wait_for: string 'CONNECT'** found **
05/29 21:23:33 yS1 tio_set_flow_control( XON_OUT )
05/29 21:23:33 yS1 sending f1.g3...
05/29 21:23:36 yS1 page complete, 9339 bytes sent
05/29 21:23:36 yS1 sending DLE ','
05/29 21:23:47 yS1 mdm_read_byte: read returned 0: Unknown error
05/29 21:23:47 yS1 mdm_get_line: cannot read byte, return: Unknown error
05/29 21:23:47 ##### failed transmitting f1.g3: phone="6653493", +FHS:-5, dev=ttyS1, time=36s, acct=""

05/29 21:23:47 yS1 fax_send: 'AT+FCLASS=0'
05/29 21:23:47 yS1 fax_send: cannot write: I/O error
05/29 21:23:47 yS1 removing lock file
.