Re: [hylafax-users] DTE to DCE Data Underflow

[Dmitry Bely <dbely@mail.ru>]

"Jay R. Ashworth" <jra@baylink.com> writes:

> >> I set up a HylaFAX Server quite successful some time ago, and it seemed
> >> to work REALLY fine. Then I went in vacation and when I came back, I got
> >> lots of people queueing in front of my office door, all of them telling
> >> me the same thing: Some Faxes just can't be delivered, and in the logs
> >> it says everytime the same thing: "DTE to DCE Data Underflow" etc etc
> >> etc. This does not happen all of the time, most faxes are delivered very
> >> well. For me it seems this is a problem with older Fax machines, I tried
> >> this in our office with one 5-year-old-machine and one 6-months-old-one.
> >> With the old one faxing crashes everytime, with the new one never.
> >> I'm using a SUSE 6.3 Box with a 2.2.14 Kernel running on a
> >> PentiumIII/550 SCSI Machine with 256 Megs of RAM, an Elsa Microlink 56K
> >> Pro Modem in Class 2.0 Mode and HylaFAX 4.1beta2.
> 
> > Strange enough, but your logs was collected in Class 2 mode (not Class
> > 2.0), and there was no "DTE to DCE Data Underflow" ...
> 
> > Jay has already explained that Class1, Class2, Class2.0 have nothing to do
> > with the fax protocol itself (and so fax machines compatibility). I can't
> > do this better anyway :-)
> 
> But, of course, Jay didn't see the logs either.  My mail client
> doesn't appear to know they were there; I wonder what *that's*
> about...

Ahh, you are using that Russian creature TheBat! (Moldavian to be more
precise -- that's ex-Soviet republic, but people who create it, are
Russian-speaking). Nothing unusual -- I never believed that the software,
written in Delphi could work flawlessly :-)))))

Well, I think Andreas has nothing against reposting his logs. Here they
are:

[---cut---]
Jul 25 20:43:58.50: [ 3120]: SESSION BEGIN 00000002 441202684824
Jul 25 20:43:58.50: [ 3120]: SEND FAX: JOB 1 DEST 01202684824 COMMID 00000002
Jul 25 20:43:58.50: [ 3120]: MODEM set DTR OFF
Jul 25 20:43:58.50: [ 3120]: DELAY 2600 ms
Jul 25 20:44:01.10: [ 3120]: MODEM set DTR ON
Jul 25 20:44:01.10: [ 3120]: MODEM set baud rate: 38400 baud, input flow RTS/CTS, output flow RTS/CTS
Jul 25 20:44:01.10: [ 3120]: MODEM flush i/o
Jul 25 20:44:01.10: [ 3120]: <-- [15:ATE0V1Q0S0=0H0\r]
Jul 25 20:44:01.11: [ 3120]: --> [2:OK]
Jul 25 20:44:01.11: [ 3120]: <-- [21:ATS8=2S7=60&K3&D3&C1\r]
Jul 25 20:44:01.13: [ 3120]: --> [2:OK]
Jul 25 20:44:01.13: [ 3120]: <-- [12:AT+FCLASS=2\r]
Jul 25 20:44:01.14: [ 3120]: --> [2:OK]
Jul 25 20:44:01.14: [ 3120]: <-- [10:AT+FTBC=0\r]
Jul 25 20:44:01.15: [ 3120]: --> [2:OK]
Jul 25 20:44:01.15: [ 3120]: <-- [10:AT+FBOR=0\r]
Jul 25 20:44:01.16: [ 3120]: --> [2:OK]
Jul 25 20:44:01.16: [ 3120]: <-- [13:AT+FPHCTO=30\r]
Jul 25 20:44:01.17: [ 3120]: --> [2:OK]
Jul 25 20:44:01.17: [ 3120]: <-- [10:AT+FBUG=1\r]
Jul 25 20:44:01.18: [ 3120]: --> [5:ERROR]
Jul 25 20:44:01.18: [ 3120]: MODEM Command error
Jul 25 20:44:01.18: [ 3120]: <-- [24:AT+FDCC=1,5,2,2,0,0,0,0\r]
Jul 25 20:44:01.19: [ 3120]: --> [2:OK]
Jul 25 20:44:01.19: [ 3120]: <-- [7:ATL1M1\r]
Jul 25 20:44:01.20: [ 3120]: --> [2:OK]
Jul 25 20:44:01.20: [ 3120]: STATE CHANGE: RUNNING -> SENDING
Jul 25 20:44:01.20: [ 3120]: MODEM input buffering enabled
Jul 25 20:44:01.20: [ 3120]: <-- [12:AT+FCLASS=2\r]
Jul 25 20:44:01.31: [ 3120]: --> [2:OK]
Jul 25 20:44:01.31: [ 3120]: <-- [10:AT+FTBC=0\r]
Jul 25 20:44:01.42: [ 3120]: --> [2:OK]
Jul 25 20:44:01.42: [ 3120]: <-- [10:AT+FBOR=0\r]
Jul 25 20:44:01.53: [ 3120]: --> [2:OK]
Jul 25 20:44:01.53: [ 3120]: <-- [13:AT+FPHCTO=30\r]
Jul 25 20:44:01.64: [ 3120]: --> [2:OK]
Jul 25 20:44:01.64: [ 3120]: <-- [10:AT+FBUG=1\r]
Jul 25 20:44:01.75: [ 3120]: --> [5:ERROR]
Jul 25 20:44:01.75: [ 3120]: MODEM Command error
Jul 25 20:44:01.75: [ 3120]: <-- [24:AT+FDCC=1,5,2,2,0,0,0,0\r]
Jul 25 20:44:01.86: [ 3120]: --> [2:OK]
Jul 25 20:44:01.86: [ 3120]: <-- [28:AT+FLID="Rovic Engineering"\r]
Jul 25 20:44:01.98: [ 3120]: --> [2:OK]
Jul 25 20:44:01.99: [ 3120]: DIAL 01202684824
Jul 25 20:44:01.99: [ 3120]: <-- [16:ATDT01202684824\r]
Jul 25 20:44:13.51: [ 3120]: --> [5:+FCON]
Jul 25 20:44:16.23: [ 3120]: --> [29:+FCSI: "       01202 684 824"]
Jul 25 20:44:16.23: [ 3120]: REMOTE CSI "01202 684 824"
Jul 25 20:44:16.68: [ 3120]: --> [22:+FDIS: 1,3,0,2,0,0,0,4]
Jul 25 20:44:16.68: [ 3120]: --> [2:OK]
Jul 25 20:44:16.68: [ 3120]: REMOTE best rate 9600 bit/s
Jul 25 20:44:16.68: [ 3120]: REMOTE max page width 1728 pixels in 215 mm
Jul 25 20:44:16.68: [ 3120]: REMOTE max unlimited page length 
Jul 25 20:44:16.68: [ 3120]: REMOTE best vres 7.7 line/mm
Jul 25 20:44:16.68: [ 3120]: REMOTE best format 1-D MR
Jul 25 20:44:16.68: [ 3120]: REMOTE best 20 ms, 10 ms/scanline
Jul 25 20:44:16.68: [ 3120]: USE 9600 bit/s
Jul 25 20:44:16.68: [ 3120]: USE 20 ms, 10 ms/scanline
Jul 25 20:44:16.68: [ 3120]: <-- [5:ATH0\r]
                             ^^^^^^^^^^^^^^
Hylafax terminates the session here (BTW, wrong way -- AT+FK is necessary)

Jul 25 20:44:16.94: [ 3120]: --> [9:+FHNG: 20]

the modem does not expects this an returns phase B error

Jul 25 20:44:16.94: [ 3120]: REMOTE HANGUP: Unspecified Transmit Phase B error (code 20)
Jul 25 20:44:18.36: [ 3120]: --> [2:OK]
Jul 25 20:44:18.36: [ 3120]: MODEM set DTR OFF
Jul 25 20:44:18.36: [ 3120]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jul 25 20:44:18.36: [ 3120]: SESSION END
[---cut---]

[---cut---]
Jul 26 13:29:19.03: [ 5312]: SESSION BEGIN 00000004 441202684824
Jul 26 13:29:19.03: [ 5312]: SEND FAX: JOB 4 DEST 01202684824 COMMID 00000004
Jul 26 13:29:19.03: [ 5312]: MODEM set DTR OFF
Jul 26 13:29:19.03: [ 5312]: DELAY 2600 ms
Jul 26 13:29:21.63: [ 5312]: MODEM set DTR ON
Jul 26 13:29:21.63: [ 5312]: MODEM set baud rate: 38400 baud, input flow RTS/CTS, output flow RTS/CTS
Jul 26 13:29:21.63: [ 5312]: MODEM flush i/o
Jul 26 13:29:21.63: [ 5312]: <-- [15:ATE0V1Q0S0=0H0\r]
Jul 26 13:29:21.64: [ 5312]: --> [2:OK]
Jul 26 13:29:21.64: [ 5312]: <-- [21:ATS8=2S7=60&K3&D3&C1\r]
Jul 26 13:29:21.66: [ 5312]: --> [2:OK]
Jul 26 13:29:21.66: [ 5312]: <-- [12:AT+FCLASS=2\r]
Jul 26 13:29:21.67: [ 5312]: --> [2:OK]
Jul 26 13:29:21.67: [ 5312]: <-- [10:AT+FTBC=0\r]
Jul 26 13:29:21.68: [ 5312]: --> [2:OK]
Jul 26 13:29:21.68: [ 5312]: <-- [10:AT+FBOR=0\r]
Jul 26 13:29:21.69: [ 5312]: --> [2:OK]
Jul 26 13:29:21.69: [ 5312]: <-- [13:AT+FPHCTO=30\r]
Jul 26 13:29:21.70: [ 5312]: --> [2:OK]
Jul 26 13:29:21.70: [ 5312]: <-- [10:AT+FBUG=1\r]
Jul 26 13:29:21.71: [ 5312]: --> [5:ERROR]
Jul 26 13:29:21.71: [ 5312]: MODEM Command error
Jul 26 13:29:21.71: [ 5312]: <-- [24:AT+FDCC=1,5,2,2,0,0,0,0\r]
Jul 26 13:29:21.72: [ 5312]: --> [2:OK]
Jul 26 13:29:21.72: [ 5312]: <-- [7:ATL1M1\r]
Jul 26 13:29:21.73: [ 5312]: --> [2:OK]
Jul 26 13:29:21.73: [ 5312]: STATE CHANGE: RUNNING -> SENDING
Jul 26 13:29:21.73: [ 5312]: MODEM input buffering enabled
Jul 26 13:29:21.73: [ 5312]: <-- [12:AT+FCLASS=2\r]
Jul 26 13:29:21.84: [ 5312]: --> [2:OK]
Jul 26 13:29:21.84: [ 5312]: <-- [10:AT+FTBC=0\r]
Jul 26 13:29:21.95: [ 5312]: --> [2:OK]
Jul 26 13:29:21.95: [ 5312]: <-- [10:AT+FBOR=0\r]
Jul 26 13:29:22.06: [ 5312]: --> [2:OK]
Jul 26 13:29:22.06: [ 5312]: <-- [13:AT+FPHCTO=30\r]
Jul 26 13:29:22.17: [ 5312]: --> [2:OK]
Jul 26 13:29:22.17: [ 5312]: <-- [10:AT+FBUG=1\r]
Jul 26 13:29:22.28: [ 5312]: --> [5:ERROR]
Jul 26 13:29:22.28: [ 5312]: MODEM Command error
Jul 26 13:29:22.28: [ 5312]: <-- [24:AT+FDCC=1,5,2,2,0,0,0,0\r]
Jul 26 13:29:22.39: [ 5312]: --> [2:OK]
Jul 26 13:29:22.39: [ 5312]: <-- [28:AT+FLID="Rovic Engineering"\r]
Jul 26 13:29:22.51: [ 5312]: --> [2:OK]
Jul 26 13:29:22.51: [ 5312]: DIAL 01202684824
Jul 26 13:29:22.51: [ 5312]: <-- [16:ATDT01202684824\r]
Jul 26 13:29:34.09: [ 5312]: --> [5:+FCON]
Jul 26 13:29:36.81: [ 5312]: --> [29:+FCSI: "       01202 684 824"]
Jul 26 13:29:36.81: [ 5312]: REMOTE CSI "01202 684 824"
Jul 26 13:29:37.15: [ 5312]: --> [22:+FDIS: 1,3,0,2,0,0,0,4]
Jul 26 13:29:37.26: [ 5312]: --> [2:OK]
Jul 26 13:29:37.26: [ 5312]: REMOTE best rate 9600 bit/s
Jul 26 13:29:37.26: [ 5312]: REMOTE max page width 1728 pixels in 215 mm
Jul 26 13:29:37.26: [ 5312]: REMOTE max unlimited page length 
Jul 26 13:29:37.26: [ 5312]: REMOTE best vres 7.7 line/mm
Jul 26 13:29:37.26: [ 5312]: REMOTE best format 1-D MR
Jul 26 13:29:37.26: [ 5312]: REMOTE best 20 ms, 10 ms/scanline
Jul 26 13:29:37.26: [ 5312]: USE 9600 bit/s
Jul 26 13:29:37.26: [ 5312]: USE 20 ms, 10 ms/scanline
Jul 26 13:29:37.26: [ 5312]: <-- [5:ATH0\r]
Jul 26 13:29:37.51: [ 5312]: --> [9:+FHNG: 20]
Jul 26 13:29:37.51: [ 5312]: REMOTE HANGUP: Unspecified Transmit Phase B error (code 20)
Jul 26 13:29:38.93: [ 5312]: --> [2:OK]
Jul 26 13:29:38.93: [ 5312]: MODEM set DTR OFF
Jul 26 13:29:38.93: [ 5312]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jul 26 13:29:38.93: [ 5312]: SESSION END
[---cut---]

Hope to hear from you soon,
Dmitry




____________________ HylaFAX(tm) Users Mailing List _______________________
 To unsub: mail -s unsubscribe hylafax-users-request@hylafax.org < /dev/null