RTN: Zyxel firmware bug?

To: "Harald Pollack" <Harald.Pollack@DATAnews.at>
Subject: RTN: Zyxel firmware bug?
From: Dmitry Bely <dbely@mail.ru>
Date: Wed, 22 Mar 2000 20:06:19 +0300
Hello Harald,

Excuse me, but I am completely confounded, and your help is my last hope
:-) There is a fax machine, which always responds with RTN to my
Hylafax+Zyxel l496e firmware 6.20. I've send there the very simple page (no
tagline, no any black pixel -- just a white space), which is proven to
conform to T.4 specs (I checked that) and still have the 100% reproductable
RTN error (other destinations work fine). What do you think, can it be the
Zyxel's firmware bug?

Here is the log:

[---cut---]
Mar 22 13:55:10.86: [30588]: SESSION BEGIN 00004526 710496211780969
Mar 22 13:55:10.86: [30588]: SEND FAX: JOB 1033 DEST 810496211780969 COMMID 00004526
Mar 22 13:55:10.86: [30588]: MODEM set DTR OFF
Mar 22 13:55:10.86: [30588]: DELAY 2600 ms
Mar 22 13:55:13.47: [30588]: MODEM set DTR ON
Mar 22 13:55:13.47: [30588]: MODEM set baud rate: 38400 baud, input flow RTS/CTS, output flow RTS/CTS
Mar 22 13:55:13.47: [30588]: MODEM flush i/o
Mar 22 13:55:13.47: [30588]: <-- [26:ATZ*P7S38.3=1E0V1Q0S0=0H0\r]

*P for russian local settings (country code 230) _affects_ TX level in the
fax mode. *P9 is the AT&F default.

Mar 22 13:55:13.77: [30588]: --> [2:OK]
Mar 22 13:55:13.77: [30588]: <-- [21:ATS8=2S7=60&H3&D2&C1\r]
Mar 22 13:55:13.79: [30588]: --> [2:OK]
Mar 22 13:55:13.79: [30588]: <-- [14:AT+FCLASS=2.0\r]
Mar 22 13:55:13.89: [30588]: --> [2:OK]
Mar 22 13:55:13.89: [30588]: <-- [9:AT+FLO=2\r]
Mar 22 13:55:13.90: [30588]: --> [2:OK]
Mar 22 13:55:13.90: [30588]: <-- [9:AT+FPP=0\r]
Mar 22 13:55:13.91: [30588]: --> [2:OK]
Mar 22 13:55:13.91: [30588]: <-- [9:AT+FBO=0\r]
Mar 22 13:55:13.92: [30588]: --> [2:OK]
Mar 22 13:55:13.92: [30588]: <-- [10:AT+FCT=30\r]
Mar 22 13:55:13.93: [30588]: --> [2:OK]
Mar 22 13:55:13.93: [30588]: <-- [21:AT+FCQ=2,1;+FRQ=4F,A\r]
Mar 22 13:55:13.95: [30588]: --> [2:OK]
Mar 22 13:55:13.95: [30588]: <-- [15:AT+FNR=1,1,1,1\r]
Mar 22 13:55:13.96: [30588]: --> [2:OK]
Mar 22 13:55:13.96: [30588]: <-- [9:AT+FIE=0\r]
Mar 22 13:55:13.97: [30588]: --> [2:OK]
Mar 22 13:55:13.97: [30588]: <-- [9:AT+FBU=1\r]
Mar 22 13:55:13.98: [30588]: --> [2:OK]
Mar 22 13:55:13.98: [30588]: <-- [23:AT+FCC=1,5,2,2,1,0,0,0\r]
Mar 22 13:55:14.00: [30588]: --> [2:OK]
Mar 22 13:55:14.00: [30588]: <-- [7:ATM1L5\r]
Mar 22 13:55:14.01: [30588]: --> [2:OK]
Mar 22 13:55:14.01: [30588]: STATE CHANGE: RUNNING -> SENDING
Mar 22 13:55:14.01: [30588]: MODEM input buffering enabled
Mar 22 13:55:14.01: [30588]: <-- [14:AT+FCLASS=2.0\r]
Mar 22 13:55:14.21: [30588]: --> [2:OK]
Mar 22 13:55:14.21: [30588]: <-- [9:AT+FLO=2\r]
Mar 22 13:55:14.32: [30588]: --> [2:OK]
Mar 22 13:55:14.32: [30588]: <-- [9:AT+FPP=0\r]
Mar 22 13:55:14.43: [30588]: --> [2:OK]
Mar 22 13:55:14.43: [30588]: <-- [9:AT+FBO=0\r]
Mar 22 13:55:14.54: [30588]: --> [2:OK]
Mar 22 13:55:14.54: [30588]: <-- [10:AT+FCT=30\r]
Mar 22 13:55:14.65: [30588]: --> [2:OK]
Mar 22 13:55:14.65: [30588]: <-- [21:AT+FCQ=2,1;+FRQ=4F,A\r]
Mar 22 13:55:14.77: [30588]: --> [2:OK]
Mar 22 13:55:14.77: [30588]: <-- [15:AT+FNR=1,1,1,1\r]
Mar 22 13:55:14.88: [30588]: --> [2:OK]
Mar 22 13:55:14.88: [30588]: <-- [9:AT+FIE=0\r]
Mar 22 13:55:14.99: [30588]: --> [2:OK]
Mar 22 13:55:14.99: [30588]: <-- [9:AT+FBU=1\r]
Mar 22 13:55:15.10: [30588]: --> [2:OK]
Mar 22 13:55:15.10: [30588]: <-- [23:AT+FCC=1,5,2,2,1,0,0,0\r]
Mar 22 13:55:15.22: [30588]: --> [2:OK]
Mar 22 13:55:15.22: [30588]: <-- [11:AT+FLI=" "\r]
Mar 22 13:55:15.33: [30588]: --> [2:OK]
Mar 22 13:55:15.35: [30588]: DIAL S7=60S8=15DT84w64496211780969,
Mar 22 13:55:15.35: [30588]: <-- [33:ATS7=60S8=15DT84w64496211780969,\r]

Actual number is +49 6211780969 (Germany)

Mar 22 13:55:56.75: [30588]: --> [4:+FCO]
Mar 22 13:55:56.89: [30588]: --> [132:+FNF:00 00 11 80 00 80 48 00 5B 00 80 80 C0 07 02 01 00 01 01 01 01 00 00 11 80 00 80 48 00 5B 00 80 80 C0 07 02 01 00 01 01 01 01  ]
Mar 22 13:55:56.89: [30588]: REMOTE NSF "00 00 11 80 00 80 48 00 5B 00 80 80 C0 07 02 01 00 01 01 01 01 00 00 11 80 00 80 48 00 5B 00 80 80 C0 07 02 01 00 01 01 01 01"

Seems to be an inkjet Canon machine (country/provider code 00 00 11). Not
present in your NSF list yet.

Mar 22 13:55:56.89: [30588]: --> [26:+FCI:         06211667249 ]
Mar 22 13:55:56.89: [30588]: REMOTE CSI "06211667249"
Mar 22 13:55:56.89: [30588]: --> [20:+FIS:1,3,0,2,1,1,0,5]
Mar 22 13:55:56.89: [30588]: --> [2:OK]
Mar 22 13:55:56.92: [30588]: REMOTE best rate 9600 bit/s
Mar 22 13:55:56.92: [30588]: REMOTE max page width 1728 pixels in 215 mm
Mar 22 13:55:56.92: [30588]: REMOTE max unlimited page length 
Mar 22 13:55:56.92: [30588]: REMOTE best vres 7.7 line/mm
Mar 22 13:55:56.92: [30588]: REMOTE best format 2-D MR
Mar 22 13:55:56.92: [30588]: REMOTE supports T.30 Annex A, ECM
Mar 22 13:55:56.92: [30588]: REMOTE best 20 ms/scanline
Mar 22 13:55:56.92: [30588]: USE 9600 bit/s
Mar 22 13:55:56.92: [30588]: USE 20 ms/scanline
Mar 22 13:55:56.92: [30588]: SEND file "docq/doc619.ps;70"
Mar 22 13:55:56.93: [30588]: USE page width 1728 pixels in 215 mm
Mar 22 13:55:56.93: [30588]: USE unlimited page length 
Mar 22 13:55:56.93: [30588]: USE 3.85 line/mm
Mar 22 13:55:56.93: [30588]: USE 2-D MR
Mar 22 13:55:56.93: [30588]: <-- [23:AT+FIS=0,3,0,2,1,0,0,5\r]
Mar 22 13:55:57.04: [30588]: --> [2:OK]
Mar 22 13:55:57.04: [30588]: <-- [7:AT+FDT\r]
Mar 22 13:56:03.22: [30588]: --> [20:+FCS:0,3,0,2,1,0,0,5]
Mar 22 13:56:03.22: [30588]: --> [7:CONNECT]
Mar 22 13:56:03.22: [30588]: SEND begin page
Mar 22 13:56:03.34: [30588]: <-- data [299]

There is no RTC in the end. Other data is also valid (leading EOL etc.) --
I have checked the generated TIFF (as you know, Hylafax is just a TIFF ->
/dev/modem copier :-))

Mar 22 13:56:03.34: [30588]: SENT 299 bytes of data
Mar 22 13:56:03.34: [30588]: SEND 2D RTC
Mar 22 13:56:03.34: [30588]: <-- data [10]
Mar 22 13:56:03.34: [30588]: SEND end page
Mar 22 13:56:03.34: [30588]: SEND send EOP (no more pages or documents)
Mar 22 13:56:03.35: [30588]: <-- data [2]
Mar 22 13:56:09.46: [30588]: --> [5:ERROR]
Mar 22 13:56:09.46: [30588]: <-- [8:AT+FPS?\r]
Mar 22 13:56:09.57: [30588]: --> [1:2]
Mar 22 13:56:09.57: [30588]: --> [2:OK]
Mar 22 13:56:09.57: [30588]: SEND recv RTN (retrain negative)
Mar 22 13:56:09.57: [30588]: <-- [23:AT+FIS=0,1,0,2,1,0,0,5\r]
Mar 22 13:56:09.69: [30588]: --> [2:OK]

Limit max speed at 4800 and try again.

Mar 22 13:56:09.69: [30588]: <-- [7:AT+FDT\r]
Mar 22 13:56:16.38: [30588]: --> [20:+FCS:0,1,0,2,1,0,0,5]
Mar 22 13:56:16.38: [30588]: --> [7:CONNECT]
Mar 22 13:56:16.38: [30588]: SEND begin page
Mar 22 13:56:16.38: [30588]: <-- data [299]
Mar 22 13:56:16.38: [30588]: SENT 299 bytes of data
Mar 22 13:56:16.38: [30588]: SEND 2D RTC
Mar 22 13:56:16.38: [30588]: <-- data [10]
Mar 22 13:56:16.38: [30588]: SEND end page
Mar 22 13:56:16.38: [30588]: SEND send EOP (no more pages or documents)
Mar 22 13:56:16.38: [30588]: <-- data [2]
Mar 22 13:56:23.12: [30588]: --> [5:ERROR]
Mar 22 13:56:23.12: [30588]: <-- [8:AT+FPS?\r]
Mar 22 13:56:23.23: [30588]: --> [1:2]
Mar 22 13:56:23.23: [30588]: --> [2:OK]
Mar 22 13:56:23.23: [30588]: SEND recv RTN (retrain negative)
Mar 22 13:56:23.23: [30588]: <-- [23:AT+FIS=0,0,0,2,1,0,0,5\r]
Mar 22 13:56:23.35: [30588]: --> [2:OK]
Mar 22 13:56:23.35: [30588]: <-- [7:AT+FDT\r]
Mar 22 13:56:30.29: [30588]: --> [20:+FCS:0,0,0,2,1,0,0,5]
Mar 22 13:56:30.29: [30588]: --> [7:CONNECT]
Mar 22 13:56:30.29: [30588]: SEND begin page
Mar 22 13:56:30.29: [30588]: <-- data [299]
Mar 22 13:56:30.29: [30588]: SENT 299 bytes of data
Mar 22 13:56:30.29: [30588]: SEND 2D RTC
Mar 22 13:56:30.29: [30588]: <-- data [10]
Mar 22 13:56:30.29: [30588]: SEND end page
Mar 22 13:56:30.29: [30588]: SEND send EOP (no more pages or documents)
Mar 22 13:56:30.29: [30588]: <-- data [2]
Mar 22 13:56:37.33: [30588]: --> [5:ERROR]
Mar 22 13:56:37.33: [30588]: <-- [8:AT+FPS?\r]
Mar 22 13:56:37.44: [30588]: --> [1:2]
Mar 22 13:56:37.44: [30588]: --> [2:OK]
Mar 22 13:56:37.44: [30588]: SEND recv RTN (retrain negative)

Still get the error even at the minimum speed (2400); give up.

Mar 22 13:56:37.44: [30588]: <-- [7:AT+FKS\r]
Mar 22 13:56:38.91: [30588]: --> [7:+FHS:02]
Mar 22 13:56:38.91: [30588]: REMOTE HANGUP: Call aborted,  from +FK or <CAN> (code 2)
Mar 22 13:56:38.91: [30588]: --> [5:ERROR]
Mar 22 13:56:38.91: [30588]: MODEM Command error
Mar 22 13:56:38.91: [30588]: <-- [5:ATH0\r]
Mar 22 13:56:39.26: [30588]: --> [10:NO CARRIER]
Mar 22 13:56:39.26: [30588]: MODEM No carrier
Mar 22 13:56:39.26: [30588]: MODEM set DTR OFF
Mar 22 13:56:39.26: [30588]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Mar 22 13:56:39.26: [30588]: SESSION END
[---cut---]

Hope to hear from you soon,
Dmitry
Prev by Date: [hylafax-devel] Re: faxq maxing out CPU - let's crack this!!!!!
Next by Date: [hylafax-devel] Re: faxq maxing out CPU - let's crack this!!!!!
Prev by thread: [hylafax-devel] "sendfax missing" when trying to start faxsetup
Next by thread: Re: RTN: Zyxel firmware bug?
Index(es):
- Main
- Thread
Report any problems to webmaster@hylafax.org

HylaFAX is a trademark of Silicon Graphics Corporation.
Internet connectivity for hylafax.org is provided by:
VirtuALL Private Host Services