new serial hw - new problem

Devin Redlich (devin@pctc.com)
Wed, 16 Dec 1998 09:10:20 -0800
Messages sorted by:[ date ][ thread ][ subject ][ author ]
I've had mgetty-1.1.18 running quite nicely for a while now with a USR
Courier (with latest flash) on SCO OpenServer 5 through the onboard serial
port in my fax server.  Since I want to add more modems, I added a Digi 8Xr
multiport serial board with the latest drivers from Digi's FTP site.  I
used to use this board with mgetty for a dial-in server (data only), but
now I want to use it for my fax server.

Anyhow, receiving faxes still works fine, but I'm unable to send (to the
same machine I can receive from).  Below is a sendfax log file (with the
data part trimmed out to save bw).

Any suggestions appreciated.  Thx.

--
12/16 08:34:27  sendfax: experimental test release 1.1.18-Sep11
12/16 08:34:27   sendfax.c compiled at Mar  6 2000, 11:39:57
12/16 08:34:27  sending fax to 6898144
12/16 08:34:27  checking f1.g3
12/16 08:34:27   makelock(ttyr1A) called
12/16 08:34:27   do_makelock: lock='/usr/spool/uucp/LCK..ttyr1A'
12/16 08:34:27   lock made
12/16 08:34:27   tss: set speed to 38400 (017)
12/16 08:34:27   tio_set_flow_control( HARD )
12/16 08:34:27 r1A   fax_open_device succeeded, ttyr1A -> 2
12/16 08:34:27 r1A   reading specific data for port 'ttyr1A'
12/16 08:34:27 r1A   reading /usr/local/etc/mgetty+sendfax/sendfax.config...
12/16 08:34:27 r1A   conf lib: read: 'verbose n'
12/16 08:34:27 r1A   conf lib: read: 'debug 9'
12/16 08:34:27 r1A   conf lib: read: 'fax-devices ttyr1A'
12/16 08:34:27 r1A   conf lib: read: 'fax-id 6046898144'
12/16 08:34:27 r1A   conf lib: read: 'dial-prefix ATDT'
12/16 08:34:27 r1A   conf lib: read: 'max-tries 3'
12/16 08:34:27 r1A   conf lib: read: 'max-tries-continue y'
12/16 08:34:27 r1A   conf lib: read: 'ignore-carrier true'
12/16 08:34:27 r1A   key: 'fax-devices', type=1, flags=4, data=(ignored)
12/16 08:34:27 r1A   key: 'modem-init', type=1, flags=0, data=(empty)
12/16 08:34:27 r1A   key: 'modem-handshake', type=1, flags=1, data=AT&H1
12/16 08:34:27 r1A   key: 'modem-type', type=1, flags=1, data=auto
12/16 08:34:27 r1A   key: 'modem-quirks', type=0, flags=0, data=(empty)
12/16 08:34:27 r1A   key: 'max-tries', type=0, flags=3, data=3
12/16 08:34:27 r1A   key: 'max-tries-continue', type=3, flags=3, data=TRUE
12/16 08:34:27 r1A   key: 'speed', type=0, flags=1, data=38400
12/16 08:34:27 r1A   key: 'switchbd', type=0, flags=1, data=0
12/16 08:34:27 r1A   key: 'open-delay', type=0, flags=0, data=(empty)
12/16 08:34:27 r1A   key: 'ignore-carrier', type=3, flags=3, data=TRUE
12/16 08:34:27 r1A   key: 'dial-prefix', type=1, flags=3, data=ATDT
12/16 08:34:27 r1A   key: 'fax-id', type=1, flags=3, data=6046898144
12/16 08:34:27 r1A   key: 'poll-dir', type=1, flags=1, data=.
12/16 08:34:27 r1A   key: 'normal-res', type=3, flags=1, data=FALSE
12/16 08:34:27 r1A   key: 'fax-min-speed', type=0, flags=1, data=0
12/16 08:34:27 r1A   key: 'fax-max-speed', type=0, flags=1, data=14400
12/16 08:34:27 r1A   key: 'debug', type=0, flags=3, data=9
12/16 08:34:27 r1A   key: 'verbose', type=3, flags=3, data=FALSE
12/16 08:34:27 r1A   key: '', type=3, flags=1, data=FALSE
12/16 08:34:27 r1A   key: 'page-header', type=1, flags=0, data=(empty)
12/16 08:34:27 r1A   key: '', type=3, flags=1, data=FALSE
12/16 08:34:27 r1A   key: '', type=3, flags=2, data=TRUE
12/16 08:34:27 r1A   key: '', type=1, flags=1, data=
12/16 08:34:27 r1A   tss: set speed to 38400 (017)
12/16 08:34:27 r1A  mdm_send: 'ATV1Q0'
12/16 08:34:27 r1A    got:ATV1Q0[0d]
12/16 08:34:27 r1A   mdm_command: string 'ATV1Q0'
12/16 08:34:27 r1A    got:[0d][0a]OK[0d]
12/16 08:34:27 r1A   mdm_command: string 'OK' -> OK
12/16 08:34:27 r1A  mdm_send: 'ATI'
12/16 08:34:27 r1A    got:[0a]ATI[0d]
12/16 08:34:27 r1A    got:[0d][0a]3367[0d]
12/16 08:34:27 r1A   mdm_gis: string 1: '3367'
12/16 08:34:27 r1A    got:[0a][0d][0a]OK[0d]
12/16 08:34:28 r1A   mdm_identify: string '3367'
12/16 08:34:28 r1A  USR Courier/Sportster V.34(+) detected
12/16 08:34:28 r1A  mdm_send: 'ATI3'
12/16 08:34:28 r1A    got:[0a]ATI3[0d]
12/16 08:34:28 r1A    got:[0d][0a]USRobotics Courier V.Everything[0d]
12/16 08:34:28 r1A   mdm_gis: string 1: 'USRobotics Courier V.Everything'
12/16 08:34:28 r1A    got:[0a][0d][0a]OK[0d]
12/16 08:34:28 r1A  additional info: 'USRobotics Courier V.Everything'
12/16 08:34:28 r1A  mdm_send: 'AT+FCLASS=2.0'
12/16 08:34:28 r1A    got:[0a]AT+FCLASS=2.0[0d]
12/16 08:34:28 r1A   mdm_command: string 'AT+FCLASS=2.0'
12/16 08:34:28 r1A    got:[0d][0a]OK[0d]
12/16 08:34:28 r1A   mdm_command: string 'OK' -> OK
12/16 08:34:29 r1A  mdm_send: 'AT+FLI="6046898144"'
12/16 08:34:29 r1A    got:[0a]AT+FLI="6046898144"[0d]
12/16 08:34:29 r1A   mdm_command: string 'AT+FLI="6046898144"'
12/16 08:34:29 r1A    got:[0d][0a]OK[0d]
12/16 08:34:29 r1A   mdm_command: string 'OK' -> OK
12/16 08:34:29 r1A  mdm_send: 'AT+FCC=1'
12/16 08:34:29 r1A    got:[0a]AT+FCC=1[0d]
12/16 08:34:29 r1A   mdm_command: string 'AT+FCC=1'
12/16 08:34:29 r1A    got:[0d][0a]OK[0d]
12/16 08:34:29 r1A   mdm_command: string 'OK' -> OK
12/16 08:34:29 r1A  mdm_send: 'AT+FBO=0'
12/16 08:34:29 r1A    got:[0a]AT+FBO=0[0d]
12/16 08:34:29 r1A   mdm_command: string 'AT+FBO=0'
12/16 08:34:29 r1A    got:[0d][0a]OK[0d]
12/16 08:34:29 r1A   mdm_command: string 'OK' -> OK
12/16 08:34:30 r1A  mdm_send: 'AT+FNR=1,1,1,0'
12/16 08:34:30 r1A    got:[0a]AT+FNR=1,1,1,0[0d]
12/16 08:34:30 r1A   mdm_command: string 'AT+FNR=1,1,1,0'
12/16 08:34:30 r1A    got:[0d][0a]OK[0d]
12/16 08:34:30 r1A   mdm_command: string 'OK' -> OK
12/16 08:34:30 r1A  mdm_send: 'AT+FLO=2'
12/16 08:34:30 r1A    got:[0a]AT+FLO=2[0d]
12/16 08:34:30 r1A   mdm_command: string 'AT+FLO=2'
12/16 08:34:30 r1A    got:[0d][0a]OK[0d]
12/16 08:34:30 r1A   mdm_command: string 'OK' -> OK
12/16 08:34:30 r1A  mdm_send: 'AT&H1'
12/16 08:34:30 r1A    got:[0a]AT&H1[0d]
12/16 08:34:30 r1A   mdm_command: string 'AT&H1'
12/16 08:34:30 r1A    got:[0d][0a]OK[0d]
12/16 08:34:30 r1A   mdm_command: string 'OK' -> OK
12/16 08:34:31 r1A  fax_send: 'ATDT6898144'
12/16 08:34:31 r1A  fax_wait_for(OK)
12/16 08:34:31 r1A    got:[0a]ATDT6898144[0d]
12/16 08:34:31 r1A   fax_wait_for: string 'ATDT6898144'
12/16 08:34:31 r1A    got:[0d][0a]+FCO[0d]
12/16 08:34:42 r1A   fax_wait_for: string '+FCO'
12/16 08:34:42 r1A    got:[0a][0d][0a]+FCI:"      1 604 689 8144"[0d]
12/16 08:34:43 r1A   fax_wait_for: string '+FCI:"      1 604 689 8144"'
12/16 08:34:43 r1A  fax_id: '+FCI:"      1 604 689 8144"'
12/16 08:34:43 r1A    got:[0a][0d][0a]+FIS:1,3,0,2,0,0,0,0[0d]
12/16 08:34:44 r1A   fax_wait_for: string '+FIS:1,3,0,2,0,0,0,0'
12/16 08:34:44 r1A    got:[0a][0d][0a]OK[0d]
12/16 08:34:44 r1A   fax_wait_for: string 'OK'** found **
12/16 08:34:44 r1A  sendfax: IGNORE DCD (carrier) status
12/16 08:34:44 r1A   fax_send_page("f1.g3") started...
12/16 08:34:44 r1A   tio_set_flow_control( HARD )
12/16 08:34:44 r1A  fax_send: 'AT+FDT'
12/16 08:34:44 r1A  fax_wait_for(CONNECT)
12/16 08:34:44 r1A    got:[0a]AT+FDT[0d]
12/16 08:34:44 r1A   fax_wait_for: string 'AT+FDT'
12/16 08:34:44 r1A    got:[0d][0a]+FCS:1,3,0,2,0,0,0,0[0d]
12/16 08:34:44 r1A   fax_wait_for: string '+FCS:1,3,0,2,0,0,0,0'
12/16 08:34:44 r1A  transmission par.: '+FCS:1,3,0,2,0,0,0,0'
12/16 08:34:44 r1A    got:[0a][0d][0a]CONNECT[0d]
12/16 08:34:50 r1A   fax_wait_for: string 'CONNECT'** found **
12/16 08:34:50 r1A   tio_set_flow_control( HARD XON_OUT )
12/16 08:34:50 r1A  sending f1.g3...
12/16 08:34:50 r1A    read 64, write 64
[whole bunch of lines snipped]
12/16 08:34:52 r1A    read 23, write 23
12/16 08:34:52 r1A  page complete, 9687 bytes sent
12/16 08:34:52 r1A  sending DLE '.'
12/16 08:34:52 r1A    got:[0a][0d][0a]ERROR[0d]
12/16 08:35:05 r1A  got response: 'ERROR'
12/16 08:35:05 r1A   fax_send_page("f1.g3") started...
12/16 08:35:05 r1A   tio_set_flow_control( HARD )
12/16 08:35:06 r1A  fax_send: 'AT+FDT'
12/16 08:35:06 r1A  fax_wait_for(CONNECT)
12/16 08:35:06 r1A    got:[0a]ERROR: RTN: page bad - retrain requested[0a]
12/16 08:35:06 r1A   fax_wait_for: string 'ERROR: RTN: page bad - retrain
requested'
12/16 08:35:06 r1A    got:AT+FDT[0d]
12/16 08:35:06 r1A   fax_wait_for: string 'AT+FDT'
12/16 08:35:06 r1A    got:[0d][0a]+FHS:00[0d]
12/16 08:35:07 r1A   fax_wait_for: string '+FHS:00'
12/16 08:35:07 r1A  connection hangup: '+FHS:00'
12/16 08:35:07 r1A   (Normal and proper end of connection)** found **
12/16 08:35:07 r1A  AT+FDT -> some error (0), abort fax send!
12/16 08:35:07 ##### failed transmitting f1.g3: phone="6898144", +FHS:00,
dev=ttyr1A, time=37s, acct=""

12/16 08:35:07 r1A  fax_send: 'AT+FCLASS=0'
12/16 08:35:08 r1A   removing lock file


__
Devin Redlich
devin@pctc.com