Mgetty problem & question
"Edmond Cheng" (echeng@orbonline.net)
Thu, 10 Dec 1998 02:07:45 GMT
My modem (Acer K56Flex) does not response to any subsequent dialin request
after it successfully serves the first one. I use the Hyperterminal of
Windows 98 to connect to my Redhat 5.1 Linux. The mgetty allows me to login
to the Linux machine if it is the first dailin request it serves after the
Linux machine boots up. After I disconnected and try to connect again, I
never succeed unless I reboot the Linux machine. The connection can be
established for a while and then the line drops due to timeout and there is
nothing showing up in the screen of Hyperterminal at all.
And I notice there are some errors logged in the mgetty log file.
1. Error occurs when mgetty sends commands 'AT+FCLASS=2' and 'AT+FCLASS=2.0
・. The log indicates there is no faxmodem found. My modem has built in
fax capability and I can fax out from this modem when my Linux machine
boots to Windows 98 using WinFax. My question: Does mgetty+sendfax support
my Acer modem?
2. Error occurs when mgetty sends commands 'AT+FCLASS=2.0' and subsequent
．AT+F・ commands. After these errors occurs, I cannot login to my Linux
machine.
Thanks for any help.
Edmond
Attached is the mgetty log:
12/07 19:43:52 ##### failed dev=ttyS2, pid=402, got signal 15, exiting
--
12/07 19:45:18 yS2 mgetty: experimental test release 1.1.14-Apr02
12/07 19:45:18 yS2 mgetty.c compiled at Apr 24 1998, 18:10:42
12/07 19:45:18 yS2 user id: 0, parent pid: 1
12/07 19:45:18 yS2 reading configuration data for port 'ttyS2'
12/07 19:45:18 yS2 reading /etc/mgetty+sendfax/mgetty.config...
12/07 19:45:18 yS2 conf lib: read: 'debug 4'
12/07 19:45:18 yS2 conf lib: read: 'fax-id 49 89 xxxxxxxx'
12/07 19:45:18 yS2 conf lib: read: 'speed 38400'
12/07 19:45:18 yS2 key: 'speed', type=0, flags=3, data=38400
12/07 19:45:18 yS2 key: 'switchbd', type=0, flags=1, data=0
12/07 19:45:18 yS2 key: 'direct', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'blocking', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'port-owner', type=1, flags=1, data=uucp
12/07 19:45:18 yS2 key: 'port-group', type=1, flags=1, data=uucp
12/07 19:45:18 yS2 key: 'port-mode', type=0, flags=1, data=432
12/07 19:45:18 yS2 key: 'toggle-dtr', type=3, flags=1, data=TRUE
12/07 19:45:18 yS2 key: 'toggle-dtr-waittime', type=0, flags=1, data=500
12/07 19:45:18 yS2 key: 'data-only', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'fax-only', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'modem-type', type=1, flags=1, data=auto
12/07 19:45:18 yS2 key: 'modem-quirks', type=0, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'init-chat', type=2, flags=1, data= \dATQ0V1H0 OK
ATS0=0Q0&D3&C1 OK
12/07 19:45:18 yS2 key: 'force-init-chat', type=2, flags=1, data= \d
\d\d\d+++\d\d\d
\dATQ0V1H0 OK
12/07 19:45:18 yS2 key: 'modem-check-time', type=0, flags=1, data=3600
12/07 19:45:18 yS2 key: 'rings', type=0, flags=1, data=1
12/07 19:45:18 yS2 key: 'get-cnd-chat', type=2, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'answer-chat', type=2, flags=1, data= ATA CONNECT
\c
12/07 19:45:18 yS2 key: 'answer-chat-timeout', type=0, flags=1, data=80
12/07 19:45:18 yS2 key: 'autobauding', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'ringback', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'ringback-time', type=0, flags=1, data=30
12/07 19:45:18 yS2 key: 'ignore-carrier', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'issue-file', type=1, flags=1, data=/etc/issue
12/07 19:45:18 yS2 key: 'prompt-waittime', type=0, flags=1, data=500
12/07 19:45:18 yS2 key: 'login-prompt', type=1, flags=1, data=login:
12/07 19:45:18 yS2 key: 'login-time', type=0, flags=1, data=240
12/07 19:45:18 yS2 key: 'fido-send-emsi', type=3, flags=1, data=TRUE
12/07 19:45:18 yS2 key: 'fax-id', type=1, flags=3, data=49 89 xxxxxxxx
12/07 19:45:18 yS2 key: 'fax-server-file', type=1, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'diskspace', type=0, flags=1, data=1024
12/07 19:45:18 yS2 key: 'notify', type=1, flags=1, data=faxadmin
12/07 19:45:18 yS2 key: 'fax-owner', type=1, flags=1, data=uucp
12/07 19:45:18 yS2 key: 'fax-group', type=1, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'fax-mode', type=0, flags=1, data=432
12/07 19:45:18 yS2 key: 'debug', type=0, flags=2, data=6
12/07 19:45:18 yS2 key: 'statistics-chat', type=2, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'statistics-file', type=1, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'gettydefs', type=1, flags=1, data=n
12/07 19:45:18 yS2 key: 'term', type=1, flags=0, data=(empty)
12/07 19:45:18 yS2 check for lockfiles
12/07 19:45:18 yS2 checklock: stat failed, no file
12/07 19:45:18 yS2 locking the line
12/07 19:45:18 yS2 makelock(ttyS2) called
12/07 19:45:18 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
12/07 19:45:18 yS2 lock made
12/07 19:45:19 yS2 tio_get_rs232_lines: status: RTS CTS DSR DTR
12/07 19:45:19 yS2 lowering DTR to reset Modem
12/07 19:45:19 yS2 tss: set speed to 38400 (017)
12/07 19:45:19 yS2 tio_set_flow_control( HARD )
12/07 19:45:19 yS2 waiting for line to clear (VTIME), read:
12/07 19:45:20 yS2 send: \dATQ0V1H0[0d]
12/07 19:45:20 yS2 waiting for ``OK''
12/07 19:45:20 yS2 got: ATQ0V1H0[0d]
12/07 19:45:20 yS2 CND: ATQ0V1H0[0d][0a]OK ** found **
12/07 19:45:20 yS2 send: ATS0=0Q0&D3&C1[0d]
12/07 19:45:20 yS2 waiting for ``OK''
12/07 19:45:20 yS2 got: [0d]
12/07 19:45:20 yS2 CND: OK[0a]ATS0=0Q0&D3&C1[0d]
12/07 19:45:20 yS2 CND: ATS0=0Q0&D3&C1[0d][0a]OK ** found **
12/07 19:45:20 yS2 mdm_send: 'ATI'
12/07 19:45:20 yS2 got:[0d][0a]ATI[0d]
12/07 19:45:20 yS2 got:[0d][0a]56000[0d]
12/07 19:45:20 yS2 mdm_gis: string 1: '56000'
12/07 19:45:20 yS2 got:[0a][0d][0a]OK[0d]
12/07 19:45:20 yS2 mdm_identify: string '56000'
12/07 19:45:20 yS2 Generic Rockwell modem (56000)
12/07 19:45:20 yS2 mdm_send: 'ATI3'
12/07 19:45:20 yS2 got:[0a]ATI3[0d]
12/07 19:45:20 yS2 got:[0d][0a]V0.520C-K56_ACF_DP2[0d]
12/07 19:45:20 yS2 mdm_gis: string 1: 'V0.520C-K56_ACF_DP2'
12/07 19:45:20 yS2 got:[0a][0d][0a]OK[0d]
12/07 19:45:20 yS2 mdm_send: 'ATI4'
12/07 19:45:20 yS2 got:[0a]ATI4[0d]
12/07 19:45:20 yS2 got:[0d][0a]a007880284C6002F[0d]
12/07 19:45:20 yS2 mdm_gis: string 1: 'a007880284C6002F'
12/07 19:45:20 yS2 got:[0a][0d][0a]bC60000000[0d]
12/07 19:45:20 yS2 mdm_gis: string 2: 'bC60000000'
12/07 19:45:20 yS2 got:[0a][0d][0a]r1005111151012004[0d]
12/07 19:45:20 yS2 mdm_gis: string 3: 'r1005111151012004'
12/07 19:45:20 yS2 got:[0a][0d][0a]r3000111170000000[0d]
12/07 19:45:20 yS2 mdm_gis: string 4: 'r3000111170000000'
12/07 19:45:20 yS2 got:[0a][0d][0a]OK[0d]
12/07 19:45:20 yS2 additional info: 'a007880284C6002F'
12/07 19:45:20 yS2 modem quirks: 0004
12/07 19:45:20 yS2 mdm_send: 'AT+FCLASS=2'
12/07 19:45:20 yS2 got:[0a]AT+FCLASS=2[0d]
12/07 19:45:20 yS2 mdm_command: string 'AT+FCLASS=2'
12/07 19:45:20 yS2 got:[0d][0a]ERROR[0d]
12/07 19:45:20 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:45:21 yS2 mdm_send: 'AT+FCLASS=2.0'
12/07 19:45:21 yS2 got:[0a]AT+FCLASS=2.0[0d]
12/07 19:45:21 yS2 mdm_command: string 'AT+FCLASS=2.0'
12/07 19:45:21 yS2 got:[0d][0a]ERROR[0d]
12/07 19:45:21 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:45:21 yS2 mdm_send: 'AT+FCLASS=2'
12/07 19:45:21 yS2 got:[0a]AT+FCLASS=2[0d]
12/07 19:45:21 yS2 mdm_command: string 'AT+FCLASS=2'
12/07 19:45:21 yS2 got:[0d][0a]ERROR[0d]
12/07 19:45:21 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:45:21 yS2 no class 2/2.0 faxmodem, no faxing available
12/07 19:45:21 yS2 waiting for line to clear (VTIME), read:
12/07 19:45:21 yS2 removing lock file
12/07 19:45:21 yS2 waiting...
12/07 19:45:53 yS2 select returned 1
12/07 19:45:53 yS2 checking lockfiles, locking the line
12/07 19:45:53 yS2 makelock(ttyS2) called
12/07 19:45:53 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
12/07 19:45:53 yS2 lock made
12/07 19:45:53 yS2 waiting for ``RING''
12/07 19:45:53 yS2 got: [0d]
12/07 19:45:53 yS2 CND: OK[0a]RING ** found **
12/07 19:45:53 yS2 send: ATA[0d]
12/07 19:45:53 yS2 waiting for ``CONNECT''
12/07 19:45:53 yS2 got: [0d]
12/07 19:45:53 yS2 CND: RING[0a]ATA[0d]
12/07 19:45:53 yS2 CND: ATA[0d][0a]CONNECT ** found **
12/07 19:46:06 yS2 send:
12/07 19:46:06 yS2 waiting for ``_''
12/07 19:46:06 yS2 got: 38400[0d]
12/07 19:46:06 yS2 CND: CONNECT 38400
12/07 19:46:06 yS2 CND: found: 38400[0a] ** found **
12/07 19:46:06 yS2 waiting for line to clear (VTIME), read:
12/07 19:46:06 yS2 looking for utmp entry... (my PID: 402)
12/07 19:46:06 yS2 utmp + wtmp entry made
12/07 19:46:07 yS2 tio_set_flow_control( HARD )
12/07 19:46:07 yS2 print welcome banner (/etc/issue)
12/07 19:46:07 yS2 getlogname (AUTO_PPP), read:edmond[0d]
12/07 19:46:24 yS2 input finished with '\r', setting ICRNL ONLCR
12/07 19:46:24 yS2 tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
12/07 19:46:24 yS2 login: use login config file
/etc/mgetty+sendfax/login.config
12/07 19:46:24 yS2 match: user='edmond', key=''
12/07 19:46:24 yS2 match: user='edmond', key='/FIDO/'
12/07 19:46:24 yS2 match: user='edmond', key=''
12/07 19:46:24 yS2 match: user='edmond', key='/AutoPPP/'
12/07 19:46:24 yS2 match: user='edmond', key=''
12/07 19:46:24 yS2 match: user='edmond', key='*'*** hit!
12/07 19:46:24 yS2 calling login: cmd='/bin/login', argv[]='login edmond'
12/07 19:46:24 ##### data dev=ttyS2, pid=402, caller='none', conn='38400',
name='', cmd='/bin/login', user='edmond'
12/07 19:46:24 yS2 setenv: 'CALLER_ID=none'
12/07 19:46:24 yS2 setenv: 'CONNECT=38400'
--
12/07 19:46:40 yS2 mgetty: experimental test release 1.1.14-Apr02
12/07 19:46:40 yS2 mgetty.c compiled at Apr 24 1998, 18:10:42
12/07 19:46:40 yS2 user id: 0, parent pid: 1
12/07 19:46:40 yS2 reading configuration data for port 'ttyS2'
12/07 19:46:40 yS2 reading /etc/mgetty+sendfax/mgetty.config...
12/07 19:46:40 yS2 conf lib: read: 'debug 4'
12/07 19:46:40 yS2 conf lib: read: 'fax-id 49 89 xxxxxxxx'
12/07 19:46:40 yS2 conf lib: read: 'speed 38400'
12/07 19:46:40 yS2 key: 'speed', type=0, flags=3, data=38400
12/07 19:46:40 yS2 key: 'switchbd', type=0, flags=1, data=0
12/07 19:46:40 yS2 key: 'direct', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'blocking', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'port-owner', type=1, flags=1, data=uucp
12/07 19:46:40 yS2 key: 'port-group', type=1, flags=1, data=uucp
12/07 19:46:40 yS2 key: 'port-mode', type=0, flags=1, data=432
12/07 19:46:40 yS2 key: 'toggle-dtr', type=3, flags=1, data=TRUE
12/07 19:46:40 yS2 key: 'toggle-dtr-waittime', type=0, flags=1, data=500
12/07 19:46:40 yS2 key: 'data-only', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'fax-only', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'modem-type', type=1, flags=1, data=auto
12/07 19:46:40 yS2 key: 'modem-quirks', type=0, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'init-chat', type=2, flags=1, data= \dATQ0V1H0 OK
ATS0=0Q0&D3&C1 OK
12/07 19:46:40 yS2 key: 'force-init-chat', type=2, flags=1, data= \d
\d\d\d+++\d\d\d
\dATQ0V1H0 OK
12/07 19:46:40 yS2 key: 'modem-check-time', type=0, flags=1, data=3600
12/07 19:46:40 yS2 key: 'rings', type=0, flags=1, data=1
12/07 19:46:40 yS2 key: 'get-cnd-chat', type=2, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'answer-chat', type=2, flags=1, data= ATA CONNECT
\c
12/07 19:46:40 yS2 key: 'answer-chat-timeout', type=0, flags=1, data=80
12/07 19:46:40 yS2 key: 'autobauding', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'ringback', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'ringback-time', type=0, flags=1, data=30
12/07 19:46:40 yS2 key: 'ignore-carrier', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'issue-file', type=1, flags=1, data=/etc/issue
12/07 19:46:40 yS2 key: 'prompt-waittime', type=0, flags=1, data=500
12/07 19:46:40 yS2 key: 'login-prompt', type=1, flags=1, data=login:
12/07 19:46:40 yS2 key: 'login-time', type=0, flags=1, data=240
12/07 19:46:40 yS2 key: 'fido-send-emsi', type=3, flags=1, data=TRUE
12/07 19:46:40 yS2 key: 'fax-id', type=1, flags=3, data=49 89 xxxxxxxx
12/07 19:46:40 yS2 key: 'fax-server-file', type=1, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'diskspace', type=0, flags=1, data=1024
12/07 19:46:40 yS2 key: 'notify', type=1, flags=1, data=faxadmin
12/07 19:46:40 yS2 key: 'fax-owner', type=1, flags=1, data=uucp
12/07 19:46:40 yS2 key: 'fax-group', type=1, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'fax-mode', type=0, flags=1, data=432
12/07 19:46:40 yS2 key: 'debug', type=0, flags=2, data=6
12/07 19:46:40 yS2 key: 'statistics-chat', type=2, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'statistics-file', type=1, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'gettydefs', type=1, flags=1, data=n
12/07 19:46:40 yS2 key: 'term', type=1, flags=0, data=(empty)
12/07 19:46:40 yS2 check for lockfiles
12/07 19:46:40 yS2 checklock: no active process has lock, will remove
12/07 19:46:40 yS2 locking the line
12/07 19:46:40 yS2 makelock(ttyS2) called
12/07 19:46:40 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
12/07 19:46:40 yS2 lock made
12/07 19:46:40 yS2 tio_get_rs232_lines: status: RTS CTS DSR DTR
12/07 19:46:40 yS2 lowering DTR to reset Modem
12/07 19:46:41 yS2 tss: set speed to 38400 (017)
12/07 19:46:41 yS2 tio_set_flow_control( HARD )
12/07 19:46:41 yS2 waiting for line to clear (VTIME), read:
12/07 19:46:41 yS2 send: \dATQ0V1H0[0d]
12/07 19:46:42 yS2 waiting for ``OK''
12/07 19:46:42 yS2 got: [0d][0a]OK ** found **
12/07 19:46:42 yS2 send: ATS0=0Q0&D3&C1[0d]
12/07 19:46:42 yS2 waiting for ``OK''
12/07 19:46:42 yS2 got: [0d]
12/07 19:46:42 yS2 CND: OK[0a]ATQ0V1H0[0d]
12/07 19:46:42 yS2 CND: ATQ0V1H0[0d][0a]OK ** found **
12/07 19:46:42 yS2 mdm_send: 'ATI'
12/07 19:46:42 yS2 got:[0d][0a]ATS0=0Q0&D3&C1[0d]
12/07 19:46:42 yS2 mdm_gis: string 1: 'ATS0=0Q0&D3&C1'
12/07 19:46:42 yS2 got:[0d][0a]OK[0d]
12/07 19:46:42 yS2 mdm_identify: string 'ATS0=0Q0&D3&C1'
12/07 19:46:42 yS2 non-numeric ID string: 'ATS0=0Q0&D3&C1'
12/07 19:46:42 yS2 mdm_send: 'AT+FCLASS=2.0'
12/07 19:46:42 yS2 got:[0a]ATI[0d]
12/07 19:46:42 yS2 mdm_command: string 'ATI'
12/07 19:46:42 yS2 got:[0d][0a]56000[0d]
12/07 19:46:42 yS2 mdm_command: string '56000'
12/07 19:46:42 yS2 got:[0a][0d][0a]OK[0d]
12/07 19:46:42 yS2 mdm_command: string 'OK' -> OK
12/07 19:46:42 yS2 mdm_send: 'AT+FAA=1;+FCR=1'
12/07 19:46:42 yS2 got:[0a]AT+FCLASS=2.0[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FCLASS=2.0'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 cannot set answer/reception flags
12/07 19:46:42 yS2 mdm_send: 'AT+FBO=1'
12/07 19:46:42 yS2 got:[0a]AT+FAA=1;+FCR=1[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FAA=1;+FCR=1'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 cannot set bit order, trying +BOR=0
12/07 19:46:42 yS2 mdm_send: 'AT+FBO=0'
12/07 19:46:42 yS2 got:[0a]AT+FBO=1[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FBO=1'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 mdm_send: 'AT+FNR=1,1,1,0'
12/07 19:46:42 yS2 got:[0a]AT+FBO=0[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FBO=0'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 mdm_send: 'AT+FLI="49 89 xxxxxxxx"'
12/07 19:46:42 yS2 got:[0a]AT+FNR=1,1,1,0[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FNR=1,1,1,0'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 cannot set local fax id. Huh?
12/07 19:46:42 yS2 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
12/07 19:46:42 yS2 got:[0a]AT+FLI="49 89 xxxxxxxx"[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FLI="49 89 xxxxxxxx"'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 mdm_send: 'AT+FCC=1,3,0,2,0,0,0,0'
12/07 19:46:42 yS2 got:[0a]AT+FCC=1,5,0,2,0,0,0,0[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 huh? Cannot set +FDCC parameters
12/07 19:46:42 yS2 waiting for line to clear (VTIME), read:
AT+FCC=1,3,0,2,0,0,0,0[0d][0d][0a]ERROR[0d][0a]
12/07 19:46:42 yS2 removing lock file
12/07 19:46:42 yS2 waiting...
12/07 19:47:35 yS2 select returned 1
12/07 19:47:35 yS2 checking lockfiles, locking the line
12/07 19:47:35 yS2 makelock(ttyS2) called
12/07 19:47:35 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
12/07 19:47:35 yS2 lock made
12/07 19:47:35 yS2 waiting for ``RING''
12/07 19:47:35 yS2 got: [0d]
12/07 19:47:35 yS2 CND: OK[0a]RING ** found **
12/07 19:47:35 yS2 send: ATA[0d]
12/07 19:47:35 yS2 waiting for ``CONNECT''
12/07 19:47:35 yS2 got: [0d]
12/07 19:47:35 yS2 CND: RING[0a]ATA[0d]
12/07 19:47:35 yS2 CND: ATA[0d][0a]DATA[0d]
12/07 19:47:49 yS2 CND: DATA[0a]
12/07 19:48:55 yS2 timeout in chat script, waiting for `CONNECT'
12/07 19:48:55 ##### failed timeout dev=ttyS2, pid=438, caller='none',
conn='', name=''
12/07 19:48:55 yS2 removing lock file
--
12/07 19:48:55 yS2 mgetty: experimental test release 1.1.14-Apr02
12/07 19:48:55 yS2 mgetty.c compiled at Apr 24 1998, 18:10:42
12/07 19:48:55 yS2 user id: 0, parent pid: 1
12/07 19:48:55 yS2 reading configuration data for port 'ttyS2'
12/07 19:48:55 yS2 reading /etc/mgetty+sendfax/mgetty.config...
12/07 19:48:55 yS2 conf lib: read: 'debug 4'
12/07 19:48:55 yS2 conf lib: read: 'fax-id 49 89 xxxxxxxx'
12/07 19:48:55 yS2 conf lib: read: 'speed 38400'
12/07 19:48:55 yS2 key: 'speed', type=0, flags=3, data=38400
12/07 19:48:55 yS2 key: 'switchbd', type=0, flags=1, data=0
12/07 19:48:55 yS2 key: 'direct', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'blocking', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'port-owner', type=1, flags=1, data=uucp
12/07 19:48:55 yS2 key: 'port-group', type=1, flags=1, data=uucp
12/07 19:48:55 yS2 key: 'port-mode', type=0, flags=1, data=432
12/07 19:48:55 yS2 key: 'toggle-dtr', type=3, flags=1, data=TRUE
12/07 19:48:55 yS2 key: 'toggle-dtr-waittime', type=0, flags=1, data=500
12/07 19:48:55 yS2 key: 'data-only', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'fax-only', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'modem-type', type=1, flags=1, data=auto
12/07 19:48:55 yS2 key: 'modem-quirks', type=0, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'init-chat', type=2, flags=1, data= \dATQ0V1H0 OK
ATS0=0Q0&D3&C1 OK
12/07 19:48:55 yS2 key: 'force-init-chat', type=2, flags=1, data= \d
\d\d\d+++\d\d\d
\dATQ0V1H0 OK
12/07 19:48:55 yS2 key: 'modem-check-time', type=0, flags=1, data=3600
12/07 19:48:55 yS2 key: 'rings', type=0, flags=1, data=1
12/07 19:48:55 yS2 key: 'get-cnd-chat', type=2, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'answer-chat', type=2, flags=1, data= ATA CONNECT
\c
12/07 19:48:55 yS2 key: 'answer-chat-timeout', type=0, flags=1, data=80
12/07 19:48:55 yS2 key: 'autobauding', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'ringback', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'ringback-time', type=0, flags=1, data=30
12/07 19:48:55 yS2 key: 'ignore-carrier', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'issue-file', type=1, flags=1, data=/etc/issue
12/07 19:48:55 yS2 key: 'prompt-waittime', type=0, flags=1, data=500
12/07 19:48:55 yS2 key: 'login-prompt', type=1, flags=1, data=login:
12/07 19:48:55 yS2 key: 'login-time', type=0, flags=1, data=240
12/07 19:48:55 yS2 key: 'fido-send-emsi', type=3, flags=1, data=TRUE
12/07 19:48:55 yS2 key: 'fax-id', type=1, flags=3, data=49 89 xxxxxxxx
12/07 19:48:55 yS2 key: 'fax-server-file', type=1, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'diskspace', type=0, flags=1, data=1024
12/07 19:48:55 yS2 key: 'notify', type=1, flags=1, data=faxadmin
12/07 19:48:55 yS2 key: 'fax-owner', type=1, flags=1, data=uucp
12/07 19:48:55 yS2 key: 'fax-group', type=1, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'fax-mode', type=0, flags=1, data=432
12/07 19:48:55 yS2 key: 'debug', type=0, flags=2, data=6
12/07 19:48:55 yS2 key: 'statistics-chat', type=2, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'statistics-file', type=1, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'gettydefs', type=1, flags=1, data=n
12/07 19:48:55 yS2 key: 'term', type=1, flags=0, data=(empty)
12/07 19:48:55 yS2 check for lockfiles
12/07 19:48:55 yS2 checklock: stat failed, no file
12/07 19:48:55 yS2 locking the line
12/07 19:48:55 yS2 makelock(ttyS2) called
12/07 19:48:55 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
12/07 19:48:55 yS2 lock made
12/07 19:48:55 yS2 tio_get_rs232_lines: status: RTS CTS DSR DTR
12/07 19:48:55 yS2 lowering DTR to reset Modem
12/07 19:48:56 yS2 tss: set speed to 38400 (017)
12/07 19:48:56 yS2 tio_set_flow_control( HARD )
12/07 19:48:56 yS2 waiting for line to clear (VTIME), read:
12/07 19:48:56 yS2 send: \dATQ0V1H0[0d]
12/07 19:48:57 yS2 waiting for ``OK''
12/07 19:48:57 yS2 got: [0d][0a]OK ** found **
12/07 19:48:57 yS2 send: ATS0=0Q0&D3&C1[0d]
12/07 19:48:57 yS2 waiting for ``OK''
12/07 19:48:57 yS2 got: [0d]
12/07 19:48:57 yS2 CND: OK[0a]ATQ0V1H0[0d]
12/07 19:48:57 yS2 CND: ATQ0V1H0[0d][0a]OK ** found **
12/07 19:48:57 yS2 mdm_send: 'ATI'
12/07 19:48:57 yS2 got:[0d][0a]ATS0=0Q0&D3&C1[0d]
12/07 19:48:57 yS2 mdm_gis: string 1: 'ATS0=0Q0&D3&C1'
12/07 19:48:57 yS2 got:[0d][0a]OK[0d]
12/07 19:48:57 yS2 mdm_identify: string 'ATS0=0Q0&D3&C1'
12/07 19:48:57 yS2 non-numeric ID string: 'ATS0=0Q0&D3&C1'
12/07 19:48:57 yS2 mdm_send: 'AT+FCLASS=2.0'
12/07 19:48:57 yS2 got:[0a]ATI[0d]
12/07 19:48:57 yS2 mdm_command: string 'ATI'
12/07 19:48:57 yS2 got:[0d][0a]56000[0d]
12/07 19:48:57 yS2 mdm_command: string '56000'
12/07 19:48:57 yS2 got:[0a][0d][0a]OK[0d]
12/07 19:48:57 yS2 mdm_command: string 'OK' -> OK
12/07 19:48:57 yS2 mdm_send: 'AT+FAA=1;+FCR=1'
12/07 19:48:57 yS2 got:[0a]AT+FCLASS=2.0[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FCLASS=2.0'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 cannot set answer/reception flags
12/07 19:48:57 yS2 mdm_send: 'AT+FBO=1'
12/07 19:48:57 yS2 got:[0a]AT+FAA=1;+FCR=1[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FAA=1;+FCR=1'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 cannot set bit order, trying +BOR=0
12/07 19:48:57 yS2 mdm_send: 'AT+FBO=0'
12/07 19:48:57 yS2 got:[0a]AT+FBO=1[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FBO=1'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 mdm_send: 'AT+FNR=1,1,1,0'
12/07 19:48:57 yS2 got:[0a]AT+FBO=0[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FBO=0'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 mdm_send: 'AT+FLI="49 89 xxxxxxxx"'
12/07 19:48:57 yS2 got:[0a]AT+FNR=1,1,1,0[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FNR=1,1,1,0'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 cannot set local fax id. Huh?
12/07 19:48:57 yS2 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
12/07 19:48:57 yS2 got:[0a]AT+FLI="49 89 xxxxxxxx"[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FLI="49 89 xxxxxxxx"'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 mdm_send: 'AT+FCC=1,3,0,2,0,0,0,0'
12/07 19:48:57 yS2 got:[0a]AT+FCC=1,5,0,2,0,0,0,0[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 huh? Cannot set +FDCC parameters
12/07 19:48:57 yS2 waiting for line to clear (VTIME), read:
AT+FCC=1,3,0,2,0,0,0,0[0d][0d][0a]ERROR[0d][0a]
12/07 19:48:57 yS2 removing lock file
12/07 19:48:57 yS2 waiting...
12/07 19:53:35 ##### failed dev=ttyS2, pid=455, got signal 15, My modem
(Acer K56Flex) does not response to any subsequent dialin request after it
successfully serves the first one. I use the Hyperterminal of Windows 98
to connect to my Redhat 5.1 Linux. The mgetty allows me to login to the
Linux machine if it is the first dailin request it serves after the Linux
machine boots up. After I disconnected and try to connect again, I never
succeed unless I reboot the Linux machine. The connection can be
established for a while and then the line drops due to timeout and there is
nothing showing up in the screen of Hyperterminal at all.
And I notice there are some errors logged in the mgetty log file.
1. Error occurs when mgetty sends commands 'AT+FCLASS=2' and 'AT+FCLASS=2.0
・. The log indicates there is no faxmodem found. My modem has built in
fax capability and I can fax out from this modem when my Linux machine
boots to Windows 98 using WinFax. My question: Does mgetty+sendfax support
my Acer modem?
2. Error occurs when mgetty sends commands 'AT+FCLASS=2.0' and subsequent
．AT+F・ commands. After these errors occurs, I cannot login to my Linux
machine.
Thanks for any help.
Edmond
Attached is the mgetty log:
12/07 19:43:52 ##### failed dev=ttyS2, pid=402, got signal 15, exiting
--
12/07 19:45:18 yS2 mgetty: experimental test release 1.1.14-Apr02
12/07 19:45:18 yS2 mgetty.c compiled at Apr 24 1998, 18:10:42
12/07 19:45:18 yS2 user id: 0, parent pid: 1
12/07 19:45:18 yS2 reading configuration data for port 'ttyS2'
12/07 19:45:18 yS2 reading /etc/mgetty+sendfax/mgetty.config...
12/07 19:45:18 yS2 conf lib: read: 'debug 4'
12/07 19:45:18 yS2 conf lib: read: 'fax-id 49 89 xxxxxxxx'
12/07 19:45:18 yS2 conf lib: read: 'speed 38400'
12/07 19:45:18 yS2 key: 'speed', type=0, flags=3, data=38400
12/07 19:45:18 yS2 key: 'switchbd', type=0, flags=1, data=0
12/07 19:45:18 yS2 key: 'direct', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'blocking', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'port-owner', type=1, flags=1, data=uucp
12/07 19:45:18 yS2 key: 'port-group', type=1, flags=1, data=uucp
12/07 19:45:18 yS2 key: 'port-mode', type=0, flags=1, data=432
12/07 19:45:18 yS2 key: 'toggle-dtr', type=3, flags=1, data=TRUE
12/07 19:45:18 yS2 key: 'toggle-dtr-waittime', type=0, flags=1, data=500
12/07 19:45:18 yS2 key: 'data-only', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'fax-only', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'modem-type', type=1, flags=1, data=auto
12/07 19:45:18 yS2 key: 'modem-quirks', type=0, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'init-chat', type=2, flags=1, data= \dATQ0V1H0 OK
ATS0=0Q0&D3&C1 OK
12/07 19:45:18 yS2 key: 'force-init-chat', type=2, flags=1, data= \d
\d\d\d+++\d\d\d
\dATQ0V1H0 OK
12/07 19:45:18 yS2 key: 'modem-check-time', type=0, flags=1, data=3600
12/07 19:45:18 yS2 key: 'rings', type=0, flags=1, data=1
12/07 19:45:18 yS2 key: 'get-cnd-chat', type=2, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'answer-chat', type=2, flags=1, data= ATA CONNECT
\c
12/07 19:45:18 yS2 key: 'answer-chat-timeout', type=0, flags=1, data=80
12/07 19:45:18 yS2 key: 'autobauding', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'ringback', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'ringback-time', type=0, flags=1, data=30
12/07 19:45:18 yS2 key: 'ignore-carrier', type=3, flags=1, data=FALSE
12/07 19:45:18 yS2 key: 'issue-file', type=1, flags=1, data=/etc/issue
12/07 19:45:18 yS2 key: 'prompt-waittime', type=0, flags=1, data=500
12/07 19:45:18 yS2 key: 'login-prompt', type=1, flags=1, data=login:
12/07 19:45:18 yS2 key: 'login-time', type=0, flags=1, data=240
12/07 19:45:18 yS2 key: 'fido-send-emsi', type=3, flags=1, data=TRUE
12/07 19:45:18 yS2 key: 'fax-id', type=1, flags=3, data=49 89 xxxxxxxx
12/07 19:45:18 yS2 key: 'fax-server-file', type=1, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'diskspace', type=0, flags=1, data=1024
12/07 19:45:18 yS2 key: 'notify', type=1, flags=1, data=faxadmin
12/07 19:45:18 yS2 key: 'fax-owner', type=1, flags=1, data=uucp
12/07 19:45:18 yS2 key: 'fax-group', type=1, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'fax-mode', type=0, flags=1, data=432
12/07 19:45:18 yS2 key: 'debug', type=0, flags=2, data=6
12/07 19:45:18 yS2 key: 'statistics-chat', type=2, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'statistics-file', type=1, flags=0, data=(empty)
12/07 19:45:18 yS2 key: 'gettydefs', type=1, flags=1, data=n
12/07 19:45:18 yS2 key: 'term', type=1, flags=0, data=(empty)
12/07 19:45:18 yS2 check for lockfiles
12/07 19:45:18 yS2 checklock: stat failed, no file
12/07 19:45:18 yS2 locking the line
12/07 19:45:18 yS2 makelock(ttyS2) called
12/07 19:45:18 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
12/07 19:45:18 yS2 lock made
12/07 19:45:19 yS2 tio_get_rs232_lines: status: RTS CTS DSR DTR
12/07 19:45:19 yS2 lowering DTR to reset Modem
12/07 19:45:19 yS2 tss: set speed to 38400 (017)
12/07 19:45:19 yS2 tio_set_flow_control( HARD )
12/07 19:45:19 yS2 waiting for line to clear (VTIME), read:
12/07 19:45:20 yS2 send: \dATQ0V1H0[0d]
12/07 19:45:20 yS2 waiting for ``OK''
12/07 19:45:20 yS2 got: ATQ0V1H0[0d]
12/07 19:45:20 yS2 CND: ATQ0V1H0[0d][0a]OK ** found **
12/07 19:45:20 yS2 send: ATS0=0Q0&D3&C1[0d]
12/07 19:45:20 yS2 waiting for ``OK''
12/07 19:45:20 yS2 got: [0d]
12/07 19:45:20 yS2 CND: OK[0a]ATS0=0Q0&D3&C1[0d]
12/07 19:45:20 yS2 CND: ATS0=0Q0&D3&C1[0d][0a]OK ** found **
12/07 19:45:20 yS2 mdm_send: 'ATI'
12/07 19:45:20 yS2 got:[0d][0a]ATI[0d]
12/07 19:45:20 yS2 got:[0d][0a]56000[0d]
12/07 19:45:20 yS2 mdm_gis: string 1: '56000'
12/07 19:45:20 yS2 got:[0a][0d][0a]OK[0d]
12/07 19:45:20 yS2 mdm_identify: string '56000'
12/07 19:45:20 yS2 Generic Rockwell modem (56000)
12/07 19:45:20 yS2 mdm_send: 'ATI3'
12/07 19:45:20 yS2 got:[0a]ATI3[0d]
12/07 19:45:20 yS2 got:[0d][0a]V0.520C-K56_ACF_DP2[0d]
12/07 19:45:20 yS2 mdm_gis: string 1: 'V0.520C-K56_ACF_DP2'
12/07 19:45:20 yS2 got:[0a][0d][0a]OK[0d]
12/07 19:45:20 yS2 mdm_send: 'ATI4'
12/07 19:45:20 yS2 got:[0a]ATI4[0d]
12/07 19:45:20 yS2 got:[0d][0a]a007880284C6002F[0d]
12/07 19:45:20 yS2 mdm_gis: string 1: 'a007880284C6002F'
12/07 19:45:20 yS2 got:[0a][0d][0a]bC60000000[0d]
12/07 19:45:20 yS2 mdm_gis: string 2: 'bC60000000'
12/07 19:45:20 yS2 got:[0a][0d][0a]r1005111151012004[0d]
12/07 19:45:20 yS2 mdm_gis: string 3: 'r1005111151012004'
12/07 19:45:20 yS2 got:[0a][0d][0a]r3000111170000000[0d]
12/07 19:45:20 yS2 mdm_gis: string 4: 'r3000111170000000'
12/07 19:45:20 yS2 got:[0a][0d][0a]OK[0d]
12/07 19:45:20 yS2 additional info: 'a007880284C6002F'
12/07 19:45:20 yS2 modem quirks: 0004
12/07 19:45:20 yS2 mdm_send: 'AT+FCLASS=2'
12/07 19:45:20 yS2 got:[0a]AT+FCLASS=2[0d]
12/07 19:45:20 yS2 mdm_command: string 'AT+FCLASS=2'
12/07 19:45:20 yS2 got:[0d][0a]ERROR[0d]
12/07 19:45:20 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:45:21 yS2 mdm_send: 'AT+FCLASS=2.0'
12/07 19:45:21 yS2 got:[0a]AT+FCLASS=2.0[0d]
12/07 19:45:21 yS2 mdm_command: string 'AT+FCLASS=2.0'
12/07 19:45:21 yS2 got:[0d][0a]ERROR[0d]
12/07 19:45:21 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:45:21 yS2 mdm_send: 'AT+FCLASS=2'
12/07 19:45:21 yS2 got:[0a]AT+FCLASS=2[0d]
12/07 19:45:21 yS2 mdm_command: string 'AT+FCLASS=2'
12/07 19:45:21 yS2 got:[0d][0a]ERROR[0d]
12/07 19:45:21 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:45:21 yS2 no class 2/2.0 faxmodem, no faxing available
12/07 19:45:21 yS2 waiting for line to clear (VTIME), read:
12/07 19:45:21 yS2 removing lock file
12/07 19:45:21 yS2 waiting...
12/07 19:45:53 yS2 select returned 1
12/07 19:45:53 yS2 checking lockfiles, locking the line
12/07 19:45:53 yS2 makelock(ttyS2) called
12/07 19:45:53 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
12/07 19:45:53 yS2 lock made
12/07 19:45:53 yS2 waiting for ``RING''
12/07 19:45:53 yS2 got: [0d]
12/07 19:45:53 yS2 CND: OK[0a]RING ** found **
12/07 19:45:53 yS2 send: ATA[0d]
12/07 19:45:53 yS2 waiting for ``CONNECT''
12/07 19:45:53 yS2 got: [0d]
12/07 19:45:53 yS2 CND: RING[0a]ATA[0d]
12/07 19:45:53 yS2 CND: ATA[0d][0a]CONNECT ** found **
12/07 19:46:06 yS2 send:
12/07 19:46:06 yS2 waiting for ``_''
12/07 19:46:06 yS2 got: 38400[0d]
12/07 19:46:06 yS2 CND: CONNECT 38400
12/07 19:46:06 yS2 CND: found: 38400[0a] ** found **
12/07 19:46:06 yS2 waiting for line to clear (VTIME), read:
12/07 19:46:06 yS2 looking for utmp entry... (my PID: 402)
12/07 19:46:06 yS2 utmp + wtmp entry made
12/07 19:46:07 yS2 tio_set_flow_control( HARD )
12/07 19:46:07 yS2 print welcome banner (/etc/issue)
12/07 19:46:07 yS2 getlogname (AUTO_PPP), read:edmond[0d]
12/07 19:46:24 yS2 input finished with '\r', setting ICRNL ONLCR
12/07 19:46:24 yS2 tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
12/07 19:46:24 yS2 login: use login config file
/etc/mgetty+sendfax/login.config
12/07 19:46:24 yS2 match: user='edmond', key=''
12/07 19:46:24 yS2 match: user='edmond', key='/FIDO/'
12/07 19:46:24 yS2 match: user='edmond', key=''
12/07 19:46:24 yS2 match: user='edmond', key='/AutoPPP/'
12/07 19:46:24 yS2 match: user='edmond', key=''
12/07 19:46:24 yS2 match: user='edmond', key='*'*** hit!
12/07 19:46:24 yS2 calling login: cmd='/bin/login', argv[]='login edmond'
12/07 19:46:24 ##### data dev=ttyS2, pid=402, caller='none', conn='38400',
name='', cmd='/bin/login', user='edmond'
12/07 19:46:24 yS2 setenv: 'CALLER_ID=none'
12/07 19:46:24 yS2 setenv: 'CONNECT=38400'
--
12/07 19:46:40 yS2 mgetty: experimental test release 1.1.14-Apr02
12/07 19:46:40 yS2 mgetty.c compiled at Apr 24 1998, 18:10:42
12/07 19:46:40 yS2 user id: 0, parent pid: 1
12/07 19:46:40 yS2 reading configuration data for port 'ttyS2'
12/07 19:46:40 yS2 reading /etc/mgetty+sendfax/mgetty.config...
12/07 19:46:40 yS2 conf lib: read: 'debug 4'
12/07 19:46:40 yS2 conf lib: read: 'fax-id 49 89 xxxxxxxx'
12/07 19:46:40 yS2 conf lib: read: 'speed 38400'
12/07 19:46:40 yS2 key: 'speed', type=0, flags=3, data=38400
12/07 19:46:40 yS2 key: 'switchbd', type=0, flags=1, data=0
12/07 19:46:40 yS2 key: 'direct', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'blocking', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'port-owner', type=1, flags=1, data=uucp
12/07 19:46:40 yS2 key: 'port-group', type=1, flags=1, data=uucp
12/07 19:46:40 yS2 key: 'port-mode', type=0, flags=1, data=432
12/07 19:46:40 yS2 key: 'toggle-dtr', type=3, flags=1, data=TRUE
12/07 19:46:40 yS2 key: 'toggle-dtr-waittime', type=0, flags=1, data=500
12/07 19:46:40 yS2 key: 'data-only', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'fax-only', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'modem-type', type=1, flags=1, data=auto
12/07 19:46:40 yS2 key: 'modem-quirks', type=0, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'init-chat', type=2, flags=1, data= \dATQ0V1H0 OK
ATS0=0Q0&D3&C1 OK
12/07 19:46:40 yS2 key: 'force-init-chat', type=2, flags=1, data= \d
\d\d\d+++\d\d\d
\dATQ0V1H0 OK
12/07 19:46:40 yS2 key: 'modem-check-time', type=0, flags=1, data=3600
12/07 19:46:40 yS2 key: 'rings', type=0, flags=1, data=1
12/07 19:46:40 yS2 key: 'get-cnd-chat', type=2, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'answer-chat', type=2, flags=1, data= ATA CONNECT
\c
12/07 19:46:40 yS2 key: 'answer-chat-timeout', type=0, flags=1, data=80
12/07 19:46:40 yS2 key: 'autobauding', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'ringback', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'ringback-time', type=0, flags=1, data=30
12/07 19:46:40 yS2 key: 'ignore-carrier', type=3, flags=1, data=FALSE
12/07 19:46:40 yS2 key: 'issue-file', type=1, flags=1, data=/etc/issue
12/07 19:46:40 yS2 key: 'prompt-waittime', type=0, flags=1, data=500
12/07 19:46:40 yS2 key: 'login-prompt', type=1, flags=1, data=login:
12/07 19:46:40 yS2 key: 'login-time', type=0, flags=1, data=240
12/07 19:46:40 yS2 key: 'fido-send-emsi', type=3, flags=1, data=TRUE
12/07 19:46:40 yS2 key: 'fax-id', type=1, flags=3, data=49 89 xxxxxxxx
12/07 19:46:40 yS2 key: 'fax-server-file', type=1, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'diskspace', type=0, flags=1, data=1024
12/07 19:46:40 yS2 key: 'notify', type=1, flags=1, data=faxadmin
12/07 19:46:40 yS2 key: 'fax-owner', type=1, flags=1, data=uucp
12/07 19:46:40 yS2 key: 'fax-group', type=1, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'fax-mode', type=0, flags=1, data=432
12/07 19:46:40 yS2 key: 'debug', type=0, flags=2, data=6
12/07 19:46:40 yS2 key: 'statistics-chat', type=2, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'statistics-file', type=1, flags=0, data=(empty)
12/07 19:46:40 yS2 key: 'gettydefs', type=1, flags=1, data=n
12/07 19:46:40 yS2 key: 'term', type=1, flags=0, data=(empty)
12/07 19:46:40 yS2 check for lockfiles
12/07 19:46:40 yS2 checklock: no active process has lock, will remove
12/07 19:46:40 yS2 locking the line
12/07 19:46:40 yS2 makelock(ttyS2) called
12/07 19:46:40 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
12/07 19:46:40 yS2 lock made
12/07 19:46:40 yS2 tio_get_rs232_lines: status: RTS CTS DSR DTR
12/07 19:46:40 yS2 lowering DTR to reset Modem
12/07 19:46:41 yS2 tss: set speed to 38400 (017)
12/07 19:46:41 yS2 tio_set_flow_control( HARD )
12/07 19:46:41 yS2 waiting for line to clear (VTIME), read:
12/07 19:46:41 yS2 send: \dATQ0V1H0[0d]
12/07 19:46:42 yS2 waiting for ``OK''
12/07 19:46:42 yS2 got: [0d][0a]OK ** found **
12/07 19:46:42 yS2 send: ATS0=0Q0&D3&C1[0d]
12/07 19:46:42 yS2 waiting for ``OK''
12/07 19:46:42 yS2 got: [0d]
12/07 19:46:42 yS2 CND: OK[0a]ATQ0V1H0[0d]
12/07 19:46:42 yS2 CND: ATQ0V1H0[0d][0a]OK ** found **
12/07 19:46:42 yS2 mdm_send: 'ATI'
12/07 19:46:42 yS2 got:[0d][0a]ATS0=0Q0&D3&C1[0d]
12/07 19:46:42 yS2 mdm_gis: string 1: 'ATS0=0Q0&D3&C1'
12/07 19:46:42 yS2 got:[0d][0a]OK[0d]
12/07 19:46:42 yS2 mdm_identify: string 'ATS0=0Q0&D3&C1'
12/07 19:46:42 yS2 non-numeric ID string: 'ATS0=0Q0&D3&C1'
12/07 19:46:42 yS2 mdm_send: 'AT+FCLASS=2.0'
12/07 19:46:42 yS2 got:[0a]ATI[0d]
12/07 19:46:42 yS2 mdm_command: string 'ATI'
12/07 19:46:42 yS2 got:[0d][0a]56000[0d]
12/07 19:46:42 yS2 mdm_command: string '56000'
12/07 19:46:42 yS2 got:[0a][0d][0a]OK[0d]
12/07 19:46:42 yS2 mdm_command: string 'OK' -> OK
12/07 19:46:42 yS2 mdm_send: 'AT+FAA=1;+FCR=1'
12/07 19:46:42 yS2 got:[0a]AT+FCLASS=2.0[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FCLASS=2.0'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 cannot set answer/reception flags
12/07 19:46:42 yS2 mdm_send: 'AT+FBO=1'
12/07 19:46:42 yS2 got:[0a]AT+FAA=1;+FCR=1[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FAA=1;+FCR=1'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 cannot set bit order, trying +BOR=0
12/07 19:46:42 yS2 mdm_send: 'AT+FBO=0'
12/07 19:46:42 yS2 got:[0a]AT+FBO=1[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FBO=1'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 mdm_send: 'AT+FNR=1,1,1,0'
12/07 19:46:42 yS2 got:[0a]AT+FBO=0[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FBO=0'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 mdm_send: 'AT+FLI="49 89 xxxxxxxx"'
12/07 19:46:42 yS2 got:[0a]AT+FNR=1,1,1,0[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FNR=1,1,1,0'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 cannot set local fax id. Huh?
12/07 19:46:42 yS2 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
12/07 19:46:42 yS2 got:[0a]AT+FLI="49 89 xxxxxxxx"[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FLI="49 89 xxxxxxxx"'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 mdm_send: 'AT+FCC=1,3,0,2,0,0,0,0'
12/07 19:46:42 yS2 got:[0a]AT+FCC=1,5,0,2,0,0,0,0[0d]
12/07 19:46:42 yS2 mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
12/07 19:46:42 yS2 got:[0d][0a]ERROR[0d]
12/07 19:46:42 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:46:42 yS2 huh? Cannot set +FDCC parameters
12/07 19:46:42 yS2 waiting for line to clear (VTIME), read:
AT+FCC=1,3,0,2,0,0,0,0[0d][0d][0a]ERROR[0d][0a]
12/07 19:46:42 yS2 removing lock file
12/07 19:46:42 yS2 waiting...
12/07 19:47:35 yS2 select returned 1
12/07 19:47:35 yS2 checking lockfiles, locking the line
12/07 19:47:35 yS2 makelock(ttyS2) called
12/07 19:47:35 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
12/07 19:47:35 yS2 lock made
12/07 19:47:35 yS2 waiting for ``RING''
12/07 19:47:35 yS2 got: [0d]
12/07 19:47:35 yS2 CND: OK[0a]RING ** found **
12/07 19:47:35 yS2 send: ATA[0d]
12/07 19:47:35 yS2 waiting for ``CONNECT''
12/07 19:47:35 yS2 got: [0d]
12/07 19:47:35 yS2 CND: RING[0a]ATA[0d]
12/07 19:47:35 yS2 CND: ATA[0d][0a]DATA[0d]
12/07 19:47:49 yS2 CND: DATA[0a]
12/07 19:48:55 yS2 timeout in chat script, waiting for `CONNECT'
12/07 19:48:55 ##### failed timeout dev=ttyS2, pid=438, caller='none',
conn='', name=''
12/07 19:48:55 yS2 removing lock file
--
12/07 19:48:55 yS2 mgetty: experimental test release 1.1.14-Apr02
12/07 19:48:55 yS2 mgetty.c compiled at Apr 24 1998, 18:10:42
12/07 19:48:55 yS2 user id: 0, parent pid: 1
12/07 19:48:55 yS2 reading configuration data for port 'ttyS2'
12/07 19:48:55 yS2 reading /etc/mgetty+sendfax/mgetty.config...
12/07 19:48:55 yS2 conf lib: read: 'debug 4'
12/07 19:48:55 yS2 conf lib: read: 'fax-id 49 89 xxxxxxxx'
12/07 19:48:55 yS2 conf lib: read: 'speed 38400'
12/07 19:48:55 yS2 key: 'speed', type=0, flags=3, data=38400
12/07 19:48:55 yS2 key: 'switchbd', type=0, flags=1, data=0
12/07 19:48:55 yS2 key: 'direct', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'blocking', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'port-owner', type=1, flags=1, data=uucp
12/07 19:48:55 yS2 key: 'port-group', type=1, flags=1, data=uucp
12/07 19:48:55 yS2 key: 'port-mode', type=0, flags=1, data=432
12/07 19:48:55 yS2 key: 'toggle-dtr', type=3, flags=1, data=TRUE
12/07 19:48:55 yS2 key: 'toggle-dtr-waittime', type=0, flags=1, data=500
12/07 19:48:55 yS2 key: 'data-only', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'fax-only', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'modem-type', type=1, flags=1, data=auto
12/07 19:48:55 yS2 key: 'modem-quirks', type=0, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'init-chat', type=2, flags=1, data= \dATQ0V1H0 OK
ATS0=0Q0&D3&C1 OK
12/07 19:48:55 yS2 key: 'force-init-chat', type=2, flags=1, data= \d
\d\d\d+++\d\d\d
\dATQ0V1H0 OK
12/07 19:48:55 yS2 key: 'modem-check-time', type=0, flags=1, data=3600
12/07 19:48:55 yS2 key: 'rings', type=0, flags=1, data=1
12/07 19:48:55 yS2 key: 'get-cnd-chat', type=2, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'answer-chat', type=2, flags=1, data= ATA CONNECT
\c
12/07 19:48:55 yS2 key: 'answer-chat-timeout', type=0, flags=1, data=80
12/07 19:48:55 yS2 key: 'autobauding', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'ringback', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'ringback-time', type=0, flags=1, data=30
12/07 19:48:55 yS2 key: 'ignore-carrier', type=3, flags=1, data=FALSE
12/07 19:48:55 yS2 key: 'issue-file', type=1, flags=1, data=/etc/issue
12/07 19:48:55 yS2 key: 'prompt-waittime', type=0, flags=1, data=500
12/07 19:48:55 yS2 key: 'login-prompt', type=1, flags=1, data=login:
12/07 19:48:55 yS2 key: 'login-time', type=0, flags=1, data=240
12/07 19:48:55 yS2 key: 'fido-send-emsi', type=3, flags=1, data=TRUE
12/07 19:48:55 yS2 key: 'fax-id', type=1, flags=3, data=49 89 xxxxxxxx
12/07 19:48:55 yS2 key: 'fax-server-file', type=1, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'diskspace', type=0, flags=1, data=1024
12/07 19:48:55 yS2 key: 'notify', type=1, flags=1, data=faxadmin
12/07 19:48:55 yS2 key: 'fax-owner', type=1, flags=1, data=uucp
12/07 19:48:55 yS2 key: 'fax-group', type=1, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'fax-mode', type=0, flags=1, data=432
12/07 19:48:55 yS2 key: 'debug', type=0, flags=2, data=6
12/07 19:48:55 yS2 key: 'statistics-chat', type=2, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'statistics-file', type=1, flags=0, data=(empty)
12/07 19:48:55 yS2 key: 'gettydefs', type=1, flags=1, data=n
12/07 19:48:55 yS2 key: 'term', type=1, flags=0, data=(empty)
12/07 19:48:55 yS2 check for lockfiles
12/07 19:48:55 yS2 checklock: stat failed, no file
12/07 19:48:55 yS2 locking the line
12/07 19:48:55 yS2 makelock(ttyS2) called
12/07 19:48:55 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
12/07 19:48:55 yS2 lock made
12/07 19:48:55 yS2 tio_get_rs232_lines: status: RTS CTS DSR DTR
12/07 19:48:55 yS2 lowering DTR to reset Modem
12/07 19:48:56 yS2 tss: set speed to 38400 (017)
12/07 19:48:56 yS2 tio_set_flow_control( HARD )
12/07 19:48:56 yS2 waiting for line to clear (VTIME), read:
12/07 19:48:56 yS2 send: \dATQ0V1H0[0d]
12/07 19:48:57 yS2 waiting for ``OK''
12/07 19:48:57 yS2 got: [0d][0a]OK ** found **
12/07 19:48:57 yS2 send: ATS0=0Q0&D3&C1[0d]
12/07 19:48:57 yS2 waiting for ``OK''
12/07 19:48:57 yS2 got: [0d]
12/07 19:48:57 yS2 CND: OK[0a]ATQ0V1H0[0d]
12/07 19:48:57 yS2 CND: ATQ0V1H0[0d][0a]OK ** found **
12/07 19:48:57 yS2 mdm_send: 'ATI'
12/07 19:48:57 yS2 got:[0d][0a]ATS0=0Q0&D3&C1[0d]
12/07 19:48:57 yS2 mdm_gis: string 1: 'ATS0=0Q0&D3&C1'
12/07 19:48:57 yS2 got:[0d][0a]OK[0d]
12/07 19:48:57 yS2 mdm_identify: string 'ATS0=0Q0&D3&C1'
12/07 19:48:57 yS2 non-numeric ID string: 'ATS0=0Q0&D3&C1'
12/07 19:48:57 yS2 mdm_send: 'AT+FCLASS=2.0'
12/07 19:48:57 yS2 got:[0a]ATI[0d]
12/07 19:48:57 yS2 mdm_command: string 'ATI'
12/07 19:48:57 yS2 got:[0d][0a]56000[0d]
12/07 19:48:57 yS2 mdm_command: string '56000'
12/07 19:48:57 yS2 got:[0a][0d][0a]OK[0d]
12/07 19:48:57 yS2 mdm_command: string 'OK' -> OK
12/07 19:48:57 yS2 mdm_send: 'AT+FAA=1;+FCR=1'
12/07 19:48:57 yS2 got:[0a]AT+FCLASS=2.0[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FCLASS=2.0'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 cannot set answer/reception flags
12/07 19:48:57 yS2 mdm_send: 'AT+FBO=1'
12/07 19:48:57 yS2 got:[0a]AT+FAA=1;+FCR=1[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FAA=1;+FCR=1'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 cannot set bit order, trying +BOR=0
12/07 19:48:57 yS2 mdm_send: 'AT+FBO=0'
12/07 19:48:57 yS2 got:[0a]AT+FBO=1[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FBO=1'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 mdm_send: 'AT+FNR=1,1,1,0'
12/07 19:48:57 yS2 got:[0a]AT+FBO=0[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FBO=0'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 mdm_send: 'AT+FLI="49 89 xxxxxxxx"'
12/07 19:48:57 yS2 got:[0a]AT+FNR=1,1,1,0[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FNR=1,1,1,0'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 cannot set local fax id. Huh?
12/07 19:48:57 yS2 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
12/07 19:48:57 yS2 got:[0a]AT+FLI="49 89 xxxxxxxx"[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FLI="49 89 xxxxxxxx"'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 mdm_send: 'AT+FCC=1,3,0,2,0,0,0,0'
12/07 19:48:57 yS2 got:[0a]AT+FCC=1,5,0,2,0,0,0,0[0d]
12/07 19:48:57 yS2 mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
12/07 19:48:57 yS2 got:[0d][0a]ERROR[0d]
12/07 19:48:57 yS2 mdm_command: string 'ERROR' -> ERROR
12/07 19:48:57 yS2 huh? Cannot set +FDCC parameters
12/07 19:48:57 yS2 waiting for line to clear (VTIME), read:
AT+FCC=1,3,0,2,0,0,0,0[0d][0d][0a]ERROR[0d][0a]
12/07 19:48:57 yS2 removing lock file
12/07 19:48:57 yS2 waiting...
12/07 19:53:35 ##### failed dev=ttyS2, pid=455, got signal 15, exiting
exiting