Faxpoll server with USR Courier V34+

Gerhard Ahuis (gerhard@ats.xs4all.nl)
Sun, 28 Jan 1996 14:05:52 +0100




On Wed, 24 Jan 1996, Gert Doering wrote:

> > > Could you send me a log file of a transmission where the modem starts
> > > echoing the page data back to the host? In the meantime, I have a goo=
d
> > > contact at USR support Germany, maybe he can try to get it fixed.
> > Oeps, I think it's the way I dropped the line. I pushed the button in=
=20
> > front of the courier which is not the normal way.
>=20
> No, it is not :)
>=20
> > But I did notice a difference in behavior..
> >=20
> > With #define FAX_SEND_IGNORE_CARRIER the file is echoed by the modem an=
d=20
> > sent into the logfile.
> >=20
> > Without #define FAX_SEND_IGNORE_CARRIER there are only I/O error messag=
es=20
> > logged.
>=20
> Sure. The carrier drops, and all further activity on the modem fails
> (regardless of what the modem is actually doing). With the #define,=20
> the modem's misbehaviour can be seen, because the carrier drop won't
> prevent further access to the line.
>=20
> I don't really complain about the carrier drop here (this is another
> matter), but the modem should really *never* drop back to command
> (=3D=3Decho :) ) mode without sending mgetty an error message.
>=20
> Please, again, send me a log file so I can see what's going on!
>=20

Ok, here it is:

Sending a fax with sendfax:

Without #define FAX_SEND_IGNORE_CARRIER

--
01/28 12:45:32  sending fax to 4
01/28 12:45:32  checking f1.g3
01/28 12:45:32  checking f2.g3
01/28 12:45:32  makelock(ttyS1) called
01/28 12:45:32  do_makelock: lock=3D'/var/spool/uucp/LCK..ttyS1'
01/28 12:45:32  lock made
01/28 12:45:32  tss: set speed to 38400 (017)
01/28 12:45:32  tio_set_flow_control( )
01/28 12:45:32 yS1  fax_open_device succeeded, ttyS1 -> 4
01/28 12:45:32 yS1  reading specific data for port 'ttyS1'
01/28 12:45:32 yS1  conf lib: read: 'verbose y'
01/28 12:45:32 yS1  conf lib: read: 'debug 5'
01/28 12:45:32 yS1  conf lib: read: 'fax-devices ttyS1'
01/28 12:45:32 yS1  conf lib: read: 'fax-id +XX XXX XXXXXX'
01/28 12:45:32 yS1  conf lib: read: 'dial-prefix ATDT'
01/28 12:45:32 yS1  conf lib: read: 'max-tries 3'
01/28 12:45:32 yS1  conf lib: read: 'max-tries-continue y'
01/28 12:45:32 yS1  key: 'fax-devices', type=3D1, flags=3D4, data=3D(ignore=
d)
01/28 12:45:32 yS1  key: 'modem-init', type=3D1, flags=3D0, data=3D(empty)
01/28 12:45:32 yS1  key: 'modem-handshake', type=3D1, flags=3D0, data=3D(em=
pty)
01/28 12:45:32 yS1  key: 'modem-type', type=3D1, flags=3D1, data=3Dc2.0
01/28 12:45:32 yS1  key: 'max-tries', type=3D0, flags=3D3, data=3D3
01/28 12:45:32 yS1  key: 'max-tries-continue', type=3D3, flags=3D3, data=3D=
TRUE
01/28 12:45:32 yS1  key: 'speed', type=3D0, flags=3D1, data=3D38400
01/28 12:45:32 yS1  key: 'switchbd', type=3D0, flags=3D1, data=3D0
01/28 12:45:32 yS1  key: 'dial-prefix', type=3D1, flags=3D3, data=3DATDT
01/28 12:45:32 yS1  key: 'fax-id', type=3D1, flags=3D3, data=3D+XX XXX XXXX=
XX
01/28 12:45:32 yS1  key: 'poll-dir', type=3D1, flags=3D1, data=3D.
01/28 12:45:32 yS1  key: 'normal-res', type=3D3, flags=3D1, data=3DFALSE
01/28 12:45:32 yS1  key: 'debug', type=3D0, flags=3D3, data=3D5
01/28 12:45:32 yS1  key: 'verbose', type=3D3, flags=3D2, data=3DTRUE
01/28 12:45:32 yS1  key: '', type=3D3, flags=3D1, data=3DFALSE
01/28 12:45:32 yS1  key: 'page-header', type=3D1, flags=3D0, data=3D(empty)
01/28 12:45:32 yS1  key: '', type=3D3, flags=3D1, data=3DFALSE
01/28 12:45:32 yS1  key: '', type=3D3, flags=3D1, data=3DFALSE
01/28 12:45:32 yS1  fax_send: 'ATV1Q0'
01/28 12:45:32 yS1  fax_wait_for(OK)
01/28 12:45:32 yS1  fax_wait_for: string 'ATV1Q0'
01/28 12:45:32 yS1  fax_wait_for: string 'OK'** found **
01/28 12:45:32 yS1  fax_send: 'AT+FCLASS=3D2.0'
01/28 12:45:32 yS1  mdm_command: string 'AT+FCLASS=3D2.0'
01/28 12:45:32 yS1  mdm_command: string 'OK' -> OK
01/28 12:45:32 yS1  fax_send: 'AT+FLI=3D"+XX XXX XXXXXX"'
01/28 12:45:32 yS1  mdm_command: string 'AT+FLI=3D"+XX XXX XXXXXX"'
01/28 12:45:32 yS1  mdm_command: string 'OK' -> OK
01/28 12:45:32 yS1  fax_send: 'AT+FCC=3D1,5,0,2,0,0,0,0'
01/28 12:45:32 yS1  mdm_command: string 'AT+FCC=3D1,5,0,2,0,0,0,0'
01/28 12:45:32 yS1  mdm_command: string 'OK' -> OK
01/28 12:45:33 yS1  fax_send: 'AT+FBO=3D0'
01/28 12:45:33 yS1  mdm_command: string 'AT+FBO=3D0'
01/28 12:45:33 yS1  mdm_command: string 'OK' -> OK
01/28 12:45:33 yS1  fax_send: 'AT+FNR=3D1,1,1,0'
01/28 12:45:33 yS1  mdm_command: string 'AT+FNR=3D1,1,1,0'
01/28 12:45:33 yS1  mdm_command: string 'OK' -> OK
01/28 12:45:33 yS1  fax_send: 'AT+FLO=3D1'
01/28 12:45:33 yS1  mdm_command: string 'AT+FLO=3D1'
01/28 12:45:33 yS1  mdm_command: string 'OK' -> OK
01/28 12:45:33 yS1  fax_send: 'ATDT4'
01/28 12:45:33 yS1  fax_wait_for(OK)
01/28 12:45:33 yS1  fax_wait_for: string 'ATDT4'
01/28 12:45:43 yS1  fax_wait_for: string 'RINGING'
01/28 12:45:57 yS1  fax_wait_for: string '+FCO'
01/28 12:45:59 yS1  fax_wait_for: string '+FCI:"      +XX XXX XXXXXX"'
01/28 12:45:59 yS1  fax_id: '+FCI:"      +XX XXX XXXXXX"'
01/28 12:46:00 yS1  fax_wait_for: string '+FIS:1,3,0,2,0,0,0,4'
01/28 12:46:00 yS1  fax_wait_for: string 'OK'** found **
01/28 12:46:00 yS1  fax_send_page("f1.g3") started...
01/28 12:46:00 yS1  tio_set_flow_control( )
01/28 12:46:00 yS1  fax_send: 'AT+FDT'
01/28 12:46:00 yS1  fax_wait_for(CONNECT)
01/28 12:46:00 yS1  fax_wait_for: string 'AT+FDT'
01/28 12:46:00 yS1  fax_wait_for: string '+FCS:1,3,0,2,0,0,0,5'
01/28 12:46:00 yS1  transmission par.: '+FCS:1,3,0,2,0,0,0,5'
01/28 12:46:05 yS1  fax_wait_for: string 'CONNECT'** found **
01/28 12:46:05 yS1  tio_set_flow_control( XON_OUT )
01/28 12:46:05 yS1  sending f1.g3...
01/28 12:46:55 yS1  could not write all 64 bytes: No such file or directory
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: No such file or directory
01/28 12:46:55 yS1  could not write all 64 bytes: I/O error
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: I/O error
01/28 12:46:55 yS1  could not write all 64 bytes: I/O error
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: I/O error
01/28 12:46:55 yS1  could not write all 64 bytes: I/O error
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: I/O error
01/28 12:46:55 yS1  could not write all 64 bytes: I/O error
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: I/O error
01/28 12:46:55 yS1  could not write all 64 bytes: I/O error
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: I/O error
01/28 12:46:55 yS1  could not write all 64 bytes: I/O error
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: I/O error
01/28 12:46:55 yS1  could not write all 64 bytes: I/O error
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: I/O error
01/28 12:46:55 yS1  could not write all 64 bytes: I/O error
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: I/O error
01/28 12:46:55 yS1  could not write all 64 bytes: I/O error
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: I/O error
01/28 12:46:55 yS1  could not write all 42 bytes: I/O error
01/28 12:46:55 yS1  input: got=20
01/28 12:46:55 yS1  read failed: I/O error
01/28 12:46:55 yS1  page complete, 49996 bytes sent
01/28 12:46:55 yS1  sending DLE ','
01/28 12:46:55 yS1  cannot write PPM: I/O error
01/28 12:46:55 ##### failed transmitting f1.g3: +FHS:-5, time=3D82s

01/28 12:46:55 yS1  fax_send: 'AT+FCLASS=3D0'
01/28 12:46:55 yS1  fax_send: cannot write: I/O error
01/28 12:46:55 yS1  removing lock file


With #define FAX_SEND_IGNORE_CARRIER

--
01/28 12:52:40  sending fax to 4
01/28 12:52:40  checking f1.g3
01/28 12:52:40  checking f2.g3
01/28 12:52:40  makelock(ttyS1) called
01/28 12:52:40  do_makelock: lock=3D'/var/spool/uucp/LCK..ttyS1'
01/28 12:52:40  lock made
01/28 12:52:40  tss: set speed to 38400 (017)
01/28 12:52:40  tio_set_flow_control( )
01/28 12:52:40 yS1  fax_open_device succeeded, ttyS1 -> 4
01/28 12:52:40 yS1  reading specific data for port 'ttyS1'
01/28 12:52:40 yS1  conf lib: read: 'verbose y'
01/28 12:52:40 yS1  conf lib: read: 'debug 5'
01/28 12:52:40 yS1  conf lib: read: 'fax-devices ttyS1'
01/28 12:52:40 yS1  conf lib: read: 'fax-id +XX XXX XXXXXX'
01/28 12:52:40 yS1  conf lib: read: 'dial-prefix ATDT'
01/28 12:52:40 yS1  conf lib: read: 'max-tries 3'
01/28 12:52:40 yS1  conf lib: read: 'max-tries-continue y'
01/28 12:52:40 yS1  key: 'fax-devices', type=3D1, flags=3D4, data=3D(ignore=
d)
01/28 12:52:40 yS1  key: 'modem-init', type=3D1, flags=3D0, data=3D(empty)
01/28 12:52:40 yS1  key: 'modem-handshake', type=3D1, flags=3D0, data=3D(em=
pty)
01/28 12:52:40 yS1  key: 'modem-type', type=3D1, flags=3D1, data=3Dc2.0
01/28 12:52:40 yS1  key: 'max-tries', type=3D0, flags=3D3, data=3D3
01/28 12:52:40 yS1  key: 'max-tries-continue', type=3D3, flags=3D3, data=3D=
TRUE
01/28 12:52:40 yS1  key: 'speed', type=3D0, flags=3D1, data=3D38400
01/28 12:52:40 yS1  key: 'switchbd', type=3D0, flags=3D1, data=3D0
01/28 12:52:40 yS1  key: 'dial-prefix', type=3D1, flags=3D3, data=3DATDT
01/28 12:52:40 yS1  key: 'fax-id', type=3D1, flags=3D3, data=3D+XX XXX XXXX=
XX
01/28 12:52:40 yS1  key: 'poll-dir', type=3D1, flags=3D1, data=3D.
01/28 12:52:40 yS1  key: 'normal-res', type=3D3, flags=3D1, data=3DFALSE
01/28 12:52:40 yS1  key: 'debug', type=3D0, flags=3D3, data=3D5
01/28 12:52:40 yS1  key: 'verbose', type=3D3, flags=3D2, data=3DTRUE
01/28 12:52:40 yS1  key: '', type=3D3, flags=3D1, data=3DFALSE
01/28 12:52:40 yS1  key: 'page-header', type=3D1, flags=3D0, data=3D(empty)
01/28 12:52:41 yS1  key: '', type=3D3, flags=3D1, data=3DFALSE
01/28 12:52:41 yS1  key: '', type=3D3, flags=3D1, data=3DFALSE
01/28 12:52:41 yS1  fax_send: 'ATV1Q0'
01/28 12:52:41 yS1  fax_wait_for(OK)
01/28 12:52:41 yS1  fax_wait_for: string 'ATV1Q0'
01/28 12:52:41 yS1  fax_wait_for: string 'OK'** found **
01/28 12:52:41 yS1  fax_send: 'AT+FCLASS=3D2.0'
01/28 12:52:41 yS1  mdm_command: string 'AT+FCLASS=3D2.0'
01/28 12:52:41 yS1  mdm_command: string 'OK' -> OK
01/28 12:52:41 yS1  fax_send: 'AT+FLI=3D"+XX XXX XXXXXX"'
01/28 12:52:41 yS1  mdm_command: string 'AT+FLI=3D"+XX XXX XXXXXX"'
01/28 12:52:41 yS1  mdm_command: string 'OK' -> OK
01/28 12:52:41 yS1  fax_send: 'AT+FCC=3D1,5,0,2,0,0,0,0'
01/28 12:52:41 yS1  mdm_command: string 'AT+FCC=3D1,5,0,2,0,0,0,0'
01/28 12:52:41 yS1  mdm_command: string 'OK' -> OK
01/28 12:52:41 yS1  fax_send: 'AT+FBO=3D0'
01/28 12:52:41 yS1  mdm_command: string 'AT+FBO=3D0'
01/28 12:52:41 yS1  mdm_command: string 'OK' -> OK
01/28 12:52:41 yS1  fax_send: 'AT+FNR=3D1,1,1,0'
01/28 12:52:41 yS1  mdm_command: string 'AT+FNR=3D1,1,1,0'
01/28 12:52:41 yS1  mdm_command: string 'OK' -> OK
01/28 12:52:41 yS1  fax_send: 'AT+FLO=3D1'
01/28 12:52:41 yS1  mdm_command: string 'AT+FLO=3D1'
01/28 12:52:41 yS1  mdm_command: string 'OK' -> OK
01/28 12:52:41 yS1  fax_send: 'ATDT4'
01/28 12:52:41 yS1  fax_wait_for(OK)
01/28 12:52:41 yS1  fax_wait_for: string 'ATDT4'
01/28 12:52:47 yS1  fax_wait_for: string 'RINGING'
01/28 12:52:53 yS1  fax_wait_for: string 'RINGING'
01/28 12:53:05 yS1  fax_wait_for: string '+FCO'
01/28 12:53:08 yS1  fax_wait_for: string '+FCI:"      +XX XXX XXXXXX"'
01/28 12:53:08 yS1  fax_id: '+FCI:"      +XX XXX XXXXXX"'
01/28 12:53:08 yS1  fax_wait_for: string '+FIS:1,3,0,2,0,0,0,4'
01/28 12:53:08 yS1  fax_wait_for: string 'OK'** found **
01/28 12:53:08 yS1  fax_send_page("f1.g3") started...
01/28 12:53:08 yS1  tio_set_flow_control( )
01/28 12:53:08 yS1  fax_send: 'AT+FDT'
01/28 12:53:08 yS1  fax_wait_for(CONNECT)
01/28 12:53:08 yS1  fax_wait_for: string 'AT+FDT'
01/28 12:53:08 yS1  fax_wait_for: string '+FCS:1,3,0,2,0,0,0,5'
01/28 12:53:08 yS1  transmission par.: '+FCS:1,3,0,2,0,0,0,5'
01/28 12:53:14 yS1  fax_wait_for: string 'CONNECT'** found **
01/28 12:53:14 yS1  tio_set_flow_control( XON_OUT )
01/28 12:53:14 yS1  sending f1.g3...
01/28 12:53:55 yS1  input: got [0a]+FHS:02[0d][0a][0d][0a]OK[0d][0a]Fqy[94]
<lots of mess>
d]F=AE=D7=A9=E8[94][0f]=9DF=C0=82=85P[d6]+[eb][b5]=8A[10][10].=A1=ACS=D1=A2=
=AC2,B=AE-[17]!=8C\[d6]X[01]
01/28 12:54:30 yS1  fax_send: timeout
01/28 12:54:30 yS1  could not write all 64 bytes: No such file or directory
01/28 12:54:30 yS1  PANIC: timeout sending fax page data, trying force
modem reset
: No such file or directory