flexfax: RTN ( retrain negative )

[Tim Ballingall <tim@mazda.com.au>]

Hi all,

I've been trying to get this working for some time with no luck & I was
hoping for a few ideas.. I'm running redhat Linux 5.2 with Netcomm V.34
33600 SuperSecure Rack Modem ( Rockwell based )

I can get things working fine with XON/Xoff flow & speed locked in at 19200.
My problem is I want to use this setup with a terminal server.

The log below shows whats happening but basically the same page gets sent ok
( using 1-D MR only ) but is sent 3 times in total.. 

If anybody has any ideas I'd love to hear them as this has been giving me
trouble for ages. 

Incidently, if anyone is after some altered source code that allows you to
select the originating modem by examining the destination fax area code -
let me know via a post to the mail list only..

Thanks in advance

Tim Ballingall
Network Administrator
Mazda Australia Pty Limited



Jun 24 14:49:40.91: [  385]: SESSION BEGIN 00000047 610396966306
Jun 24 14:49:40.91: [  385]: SEND FAX: JOB 37 DEST 96966306 COMMID 00000047
Jun 24 14:49:40.91: [  385]: MODEM set DTR OFF
Jun 24 14:49:40.91: [  385]: DELAY 2600 ms
Jun 24 14:49:43.52: [  385]: MODEM set DTR ON
Jun 24 14:49:43.52: [  385]: MODEM set baud rate: 19200 baud, input flow
XON/XOFF, output flow XON/XOFF
Jun 24 14:49:43.53: [  385]: MODEM flush i/o
Jun 24 14:49:43.53: [  385]: <-- [15:ATE0V1Q0S0=0H0\r]
Jun 24 14:49:43.75: [  385]: --> [2:OK]
Jun 24 14:49:43.75: [  385]: <-- [12:ATS8=2S7=60\r]
Jun 24 14:49:43.98: [  385]: --> [2:OK]
Jun 24 14:49:43.99: [  385]: <-- [12:AT+FCLASS=2\r]
Jun 24 14:49:44.42: [  385]: --> [2:OK]
Jun 24 14:49:44.42: [  385]: <-- [10:AT+FTBC=0\r]
Jun 24 14:49:44.60: [  385]: --> [2:OK]
Jun 24 14:49:44.60: [  385]: <-- [10:AT+FBOR=0\r]
Jun 24 14:49:44.79: [  385]: --> [2:OK]
Jun 24 14:49:44.79: [  385]: <-- [13:AT+FPHCTO=30\r]
Jun 24 14:49:44.95: [  385]: --> [2:OK]
Jun 24 14:49:44.95: [  385]: <-- [10:AT+FBUG=1\r]
Jun 24 14:49:45.15: [  385]: --> [2:OK]
Jun 24 14:49:45.15: [  385]: <-- [24:AT+FDCC=1,5,0,2,1,0,0,0\r]
Jun 24 14:49:45.32: [  385]: --> [2:OK]
Jun 24 14:49:45.32: [  385]: <-- [5:ATM0\r]
Jun 24 14:49:45.49: [  385]: --> [2:OK]
Jun 24 14:49:45.49: [  385]: STATE CHANGE: RUNNING -> SENDING
Jun 24 14:49:45.49: [  385]: MODEM input buffering enabled
Jun 24 14:49:45.49: [  385]: Apply DialString rules to "96966306"
Jun 24 14:49:45.50: [  385]: --> return result "96966306"
Jun 24 14:49:45.50: [  385]: <-- [12:AT+FCLASS=2\r]
Jun 24 14:49:45.76: [  385]: --> [2:OK]
Jun 24 14:49:45.76: [  385]: <-- [10:AT+FTBC=0\r]
Jun 24 14:49:46.03: [  385]: --> [2:OK]
Jun 24 14:49:46.03: [  385]: <-- [10:AT+FBOR=0\r]
Jun 24 14:49:46.29: [  385]: --> [2:OK]
Jun 24 14:49:46.29: [  385]: <-- [13:AT+FPHCTO=30\r]
Jun 24 14:49:46.54: [  385]: --> [2:OK]
Jun 24 14:49:46.54: [  385]: <-- [10:AT+FBUG=1\r]
Jun 24 14:49:46.84: [  385]: --> [2:OK]
Jun 24 14:49:46.84: [  385]: <-- [24:AT+FDCC=1,5,0,2,1,0,0,0\r]
Jun 24 14:49:47.10: [  385]: --> [2:OK]
Jun 24 14:49:47.10: [  385]: <-- [19:AT+FLID="Mazda S1"\r]
Jun 24 14:49:47.37: [  385]: --> [2:OK]
Jun 24 14:49:47.38: [  385]: DIAL 96966306
Jun 24 14:49:47.38: [  385]: <-- [13:ATDT96966306\r]
Jun 24 14:49:59.79: [  385]: --> [7:RINGING]
Jun 24 14:50:05.97: [  385]: --> [5:+FCON]
Jun 24 14:50:06.13: [  385]: --> [120:+FHR: FF C0 04 00 00 88 01 00 01 92 08
B2 82 5A 22 82 04 82 AA CA 2A 74 04 92 CA 22 04 00 42 00 01 01 03 74 80 80
80 80 ]
Jun 24 14:50:06.13: [  385]: --> [69:FF C0 02 6C 0C CC 6C 04 6C 9C 6C 9C 04
CC 04 8C 6C 04 04 04 04 04 04 ]
Jun 24 14:50:06.13: [  385]: --> [21:FF C8 01 00 73 17 22 ]
Jun 24 14:50:06.20: [  385]: --> [111:+FNSF:00 00 11 80 00 80 49 10 4D 41 5A
44 41 20 41 55 53 54 2E 20 49 53 44 20 00 42 00 80 80 C0 2E 01 01 01 01 ]
Jun 24 14:50:06.20: [  385]: REMOTE NSF "00 00 11 80 00 80 49 10 4D 41 5A 44
41 20 41 55 53 54 2E 20 49 53 44 20 00 42 00 80 80 C0 2E 01 01 01 01"
Jun 24 14:50:06.20: [  385]: --> [28:+FCSI:"      61 3 9696 6306"]
Jun 24 14:50:06.20: [  385]: REMOTE CSI "61 3 9696 6306"
Jun 24 14:50:06.31: [  385]: --> [21:+FDIS:1,3,0,2,1,0,0,2]
Jun 24 14:50:06.31: [  385]: --> [2:OK]
Jun 24 14:50:06.31: [  385]: REMOTE best rate 9600 bit/s
Jun 24 14:50:06.31: [  385]: REMOTE max page width 1728 pixels in 215 mm
Jun 24 14:50:06.31: [  385]: REMOTE max unlimited page length 
Jun 24 14:50:06.32: [  385]: REMOTE best vres 7.7 line/mm
Jun 24 14:50:06.32: [  385]: REMOTE best format 2-D MR
Jun 24 14:50:06.32: [  385]: REMOTE best 10 ms, 5 ms/scanline
Jun 24 14:50:06.32: [  385]: USE 9600 bit/s
Jun 24 14:50:06.32: [  385]: USE 10 ms, 5 ms/scanline
Jun 24 14:50:06.32: [  385]: SEND file "docq/doc37.ps;01"
Jun 24 14:50:06.32: [  385]: USE page width 1728 pixels in 215 mm
Jun 24 14:50:06.32: [  385]: USE unlimited page length 
Jun 24 14:50:06.33: [  385]: USE 7.7 line/mm
Jun 24 14:50:06.33: [  385]: USE 1-D MR
Jun 24 14:50:06.33: [  385]: <-- [24:AT+FDIS=1,3,0,2,0,0,0,2\r]
Jun 24 14:50:06.60: [  385]: --> [2:OK]
Jun 24 14:50:06.60: [  385]: <-- [7:AT+FDT\r]
Jun 24 14:50:07.16: [  385]: --> [75:+FHT: FF 03 43 20 20 20 20 20 20 20 20
20 20 20 20 31 53 20 41 44 5A 41 4D ]
Jun 24 14:50:07.16: [  385]: --> [21:FF 13 83 00 46 A8 00 ]
Jun 24 14:50:12.69: [  385]: --> [15:+FHR: FF C8 21 ]
Jun 24 14:50:12.69: [  385]: --> [21:+FDCS:1,3,0,2,0,0,0,2]
Jun 24 14:50:14.40: [  385]: --> [7:CONNECT]
Jun 24 14:50:14.40: [  385]: SEND begin page
Jun 24 14:50:14.40: [  385]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jun 24 14:50:14.43: [  385]: <-- data [1031]
Jun 24 14:50:14.43: [  385]: <-- data [1025]

snip snip


Jun 24 14:51:06.87: [  385]: <-- data [1024]
Jun 24 14:51:06.87: [  385]: <-- data [765]
Jun 24 14:51:07.61: [  385]: SENT 68349 bytes of data
Jun 24 14:51:07.61: [  385]: <-- data [2]
Jun 24 14:51:07.61: [  385]: MODEM set XON/XOFF/DRAIN: input interpreted,
output generated
Jun 24 14:51:10.17: [  385]: SEND end page
Jun 24 14:51:20.90: [  385]: --> [2:OK]
Jun 24 14:51:20.90: [  385]: SEND send EOP (no more pages or documents)
Jun 24 14:51:20.90: [  385]: <-- [9:AT+FET=2\r]
Jun 24 14:51:21.39: [  385]: --> [15:+FHT: FF 13 2F ]
Jun 24 14:51:23.98: [  385]: --> [15:+FHR: FF C8 32 ]
Jun 24 14:51:23.98: [  385]: --> [7:+FPTS:2]
Jun 24 14:51:23.98: [  385]: --> [2:OK]
Jun 24 14:51:23.98: [  385]: SEND recv RTN (retrain negative)
Jun 24 14:51:23.99: [  385]: <-- [24:AT+FDIS=1,2,0,2,0,0,0,2\r]
Jun 24 14:51:24.23: [  385]: --> [2:OK]
Jun 24 14:51:24.23: [  385]: <-- [7:AT+FDT\r]
Jun 24 14:51:24.78: [  385]: --> [75:+FHT: FF 03 43 20 20 20 20 20 20 20 20
20 20 20 20 31 53 20 41 44 5A 41 4D ]
Jun 24 14:51:24.78: [  385]: --> [21:FF 13 83 00 4E A8 00 ]
Jun 24 14:51:30.30: [  385]: --> [15:+FHR: FF C8 21 ]
Jun 24 14:51:30.30: [  385]: --> [21:+FDCS:1,2,0,2,0,0,0,2]
Jun 24 14:51:31.99: [  385]: --> [7:CONNECT]
Jun 24 14:51:31.99: [  385]: SEND begin page
Jun 24 14:51:31.99: [  385]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jun 24 14:51:32.02: [  385]: <-- data [1031]

snip snip

Jun 24 14:52:39.22: [  385]: <-- data [1024]
Jun 24 14:52:39.97: [  385]: <-- data [765]
Jun 24 14:52:40.73: [  385]: SENT 68349 bytes of data
Jun 24 14:52:40.73: [  385]: <-- data [2]
Jun 24 14:52:40.73: [  385]: MODEM set XON/XOFF/DRAIN: input interpreted,
output generated
Jun 24 14:52:42.72: [  385]: SEND end page
Jun 24 14:52:56.70: [  385]: --> [2:OK]
Jun 24 14:52:56.70: [  385]: SEND send EOP (no more pages or documents)
Jun 24 14:52:56.70: [  385]: <-- [9:AT+FET=2\r]
Jun 24 14:52:57.19: [  385]: --> [15:+FHT: FF 13 2F ]
Jun 24 14:52:59.80: [  385]: --> [15:+FHR: FF C8 32 ]
Jun 24 14:52:59.80: [  385]: --> [7:+FPTS:2]
Jun 24 14:52:59.80: [  385]: --> [2:OK]
Jun 24 14:52:59.80: [  385]: SEND recv RTN (retrain negative)
Jun 24 14:52:59.81: [  385]: <-- [24:AT+FDIS=1,1,0,2,0,0,0,2\r]
Jun 24 14:53:00.03: [  385]: --> [2:OK]
Jun 24 14:53:00.03: [  385]: <-- [7:AT+FDT\r]
Jun 24 14:53:00.56: [  385]: --> [75:+FHT: FF 03 43 20 20 20 20 20 20 20 20
20 20 20 20 31 53 20 41 44 5A 41 4D ]
Jun 24 14:53:00.56: [  385]: --> [21:FF 13 83 00 4A A8 00 ]
Jun 24 14:53:06.61: [  385]: --> [15:+FHR: FF C8 21 ]
Jun 24 14:53:06.61: [  385]: --> [21:+FDCS:1,1,0,2,0,0,0,2]
Jun 24 14:53:08.38: [  385]: --> [7:CONNECT]
Jun 24 14:53:08.38: [  385]: SEND begin page
Jun 24 14:53:08.38: [  385]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jun 24 14:53:08.41: [  385]: <-- data [1031]

snip snip

Jun 24 14:54:42.63: [  385]: <-- data [1024]
Jun 24 14:54:43.37: [  385]: <-- data [765]
Jun 24 14:54:44.12: [  385]: SENT 68349 bytes of data
Jun 24 14:54:44.12: [  385]: <-- data [2]
Jun 24 14:54:44.12: [  385]: MODEM set XON/XOFF/DRAIN: input interpreted,
output generated
Jun 24 14:54:46.11: [  385]: SEND end page
Jun 24 14:55:09.72: [  385]: --> [2:OK]
Jun 24 14:55:09.72: [  385]: SEND send EOP (no more pages or documents)
Jun 24 14:55:09.72: [  385]: <-- [9:AT+FET=2\r]
Jun 24 14:55:10.51: [  385]: --> [15:+FHT: FF 13 2F ]
Jun 24 14:55:13.12: [  385]: --> [15:+FHR: FF C8 32 ]
Jun 24 14:55:13.12: [  385]: --> [7:+FPTS:2]
Jun 24 14:55:13.12: [  385]: --> [2:OK]
Jun 24 14:55:13.12: [  385]: SEND recv RTN (retrain negative)
Jun 24 14:55:13.12: [  385]: <-- [6:AT+FK\r]
Jun 24 14:55:13.53: [  385]: --> [15:+FHT: FF 13 FB ]
Jun 24 14:55:14.81: [  385]: --> [9:+FHNG:100]
Jun 24 14:55:14.81: [  385]: REMOTE HANGUP: Unspecified Phase D error (code
100)
Jun 24 14:55:16.70: [  385]: --> [2:OK]
Jun 24 14:55:16.70: [  385]: <-- [5:ATH0\r]
Jun 24 14:55:16.94: [  385]: --> [2:OK]
Jun 24 14:55:16.94: [  385]: MODEM set DTR OFF
Jun 24 14:55:16.94: [  385]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jun 24 14:55:16.94: [  385]: SESSION END
Mazda Australia takes every precaution to ensure email messages are virus free. For complete protection, you should virus test this message.