Re: [hylafax-users] Regarding USR5610 Modem

[Philip Walls <pawalls@xxxxxxxxx>]

What would be a sufficient ServerTracing value to use in order to
determine the problem more exactly?

I tried 0x00015 (ServerTracing: 21 if my math/understanding is right..)
and got a little more information, which I've attached. Send transcript
only, this time. What I ended up with looks pretty similar to what you
were describing. 

Feb 22 13:16:12.27: [17917]: SEND send PPS (partial page signal)
Feb 22 13:16:12.27: [17917]: SEND send MPS (more pages, same document)
Feb 22 13:16:12.27: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:16:14.95: [17917]: --> [7:CONNECT]
Feb 22 13:16:16.51: [17917]: --> [5:ERROR]
Feb 22 13:16:16.51: [17917]: MODEM Command error
Feb 22 13:16:16.51: [17917]: FCS error

I've found that we have two spare internal PCI modems in a recently
retired server that I will probably end up replacing these USR modems
with. One of the modems is used for paging via qpage, so I will leave
that one as is.

On Tue, 2005-02-22 at 08:48 -0800, Lee Howard wrote:
>I haven't worked with USR modems long enough to figure out if there is 
>a workaround for that garbage.  However, if my guesses in that 
>illustration are correct, it may be possible to work around the error 
>by storing the faxgetty initialized state as the soft-reset state.  So, 
>you would allow faxgetty to initialize the modem, and then you'd run 
>'minicom -o <devid>' where <devid> is the device name that was used 
>with faxgetty and where 'minicom -s' was used to create a profile for 
>that device.  Once in minicom you would enter "AT&W" or perhaps "AT&W0" 
>or perhaps "AT&W1" (doing all of them probably would not be a problem, 
>either).  This stores the faxgetty-initialized state (including 
>baudrate) in to the soft-reset profile for the modem.  That way, if the 
>modem does reset itself at inopportune times such as this, at least 
>there is the possibility that it would recover.

I tried this method, but whenever I use minicom -o to connect to the
device (and ^A Q to quit without reset) it doesn't seem like it actually
is connecting to the device, as typing does not work. Connecting without
-o does work, however I don't think this is of any use concerning saving
faxgetty's initialized state.

-- 

Philip Walls
Network Administrator
Gleim Internet, Inc.
(352) 375-0772 x 116

Feb 22 13:15:09.71: [17917]: SESSION BEGIN 000000197 1##########
Feb 22 13:15:09.71: [17917]: HylaFAX (tm) Version 4.2.1
Feb 22 13:15:09.71: [17917]: SEND FAX: JOB 72 DEST ####### COMMID 000000197 DEVICE '/dev/ttyS5'
Feb 22 13:15:09.71: [17917]: <-- [12:AT+FCLASS=1\r]
Feb 22 13:15:09.82: [17917]: --> [2:OK]
Feb 22 13:15:09.82: [17917]: DIAL #######
Feb 22 13:15:09.82: [17917]: <-- [12:ATDT#######\r]
Feb 22 13:15:24.49: [17917]: --> [7:CONNECT]
Feb 22 13:15:26.15: [17917]: --> [2:OK]
Feb 22 13:15:26.15: [17917]: REMOTE NSF "B5 00 AA 48 79 6C 61 46 41 58 20 28 74 6D 29 20 56 65 72 73 69 6F 6E 20 34 2E 32 2E 31"
Feb 22 13:15:26.15: [17917]: NSF remote fax equipment: HylaFAX
Feb 22 13:15:26.15: [17917]: NSF remote station ID: "HylaFAX (tm) Version 4.2.1"
Feb 22 13:15:26.15: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:15:26.16: [17917]: --> [7:CONNECT]
Feb 22 13:15:26.87: [17917]: --> [2:OK]
Feb 22 13:15:26.87: [17917]: REMOTE CSI "FaxModem1"
Feb 22 13:15:26.87: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:15:26.88: [17917]: --> [7:CONNECT]
Feb 22 13:15:27.27: [17917]: --> [2:OK]
Feb 22 13:15:27.27: [17917]: REMOTE best rate 14400 bit/s
Feb 22 13:15:27.27: [17917]: REMOTE max A3 page width (303 mm)
Feb 22 13:15:27.27: [17917]: REMOTE max unlimited page length
Feb 22 13:15:27.27: [17917]: REMOTE best vres R16 x 15.4 line/mm
Feb 22 13:15:27.27: [17917]: REMOTE best format 2-D MMR
Feb 22 13:15:27.27: [17917]: REMOTE supports T.30 Annex A, 256-byte ECM
Feb 22 13:15:27.27: [17917]: REMOTE best 0 ms/scanline
Feb 22 13:15:27.27: [17917]: USE 14400 bit/s
Feb 22 13:15:27.27: [17917]: USE error correction mode
Feb 22 13:15:27.27: [17917]: USE 0 ms/scanline
Feb 22 13:15:27.27: [17917]: SEND file "docq/doc72.cover;f0"
Feb 22 13:15:27.27: [17917]: USE A4 page width (215 mm)
Feb 22 13:15:27.27: [17917]: USE unlimited page length
Feb 22 13:15:27.27: [17917]: USE 3.85 line/mm
Feb 22 13:15:27.27: [17917]: USE 2-D MMR
Feb 22 13:15:27.27: [17917]: SEND training at v.17 14400 bit/s
Feb 22 13:15:27.27: [17917]: <-- [9:AT+FTH=3\r]
Feb 22 13:15:27.46: [17917]: --> [7:CONNECT]
Feb 22 13:15:27.46: [17917]: <-- data [23]
Feb 22 13:15:27.46: [17917]: <-- data [2]
Feb 22 13:15:27.48: [17917]: --> [7:CONNECT]
Feb 22 13:15:27.48: [17917]: <-- data [7]
Feb 22 13:15:27.48: [17917]: <-- data [2]
Feb 22 13:15:29.46: [17917]: --> [2:OK]
Feb 22 13:15:29.46: [17917]: <-- [9:AT+FTS=7\r]
Feb 22 13:15:29.54: [17917]: --> [2:OK]
Feb 22 13:15:29.54: [17917]: <-- [11:AT+FTM=145\r]
Feb 22 13:15:31.02: [17917]: --> [7:CONNECT]
Feb 22 13:15:31.02: [17917]: <-- data [1024]
Feb 22 13:15:31.33: [17917]: <-- data [1024]
Feb 22 13:15:31.66: [17917]: <-- data [652]
Feb 22 13:15:31.98: [17917]: <-- data [2]
Feb 22 13:15:32.54: [17917]: --> [2:OK]
Feb 22 13:15:32.54: [17917]: <-- [9:AT+FRS=1\r]
Feb 22 13:15:32.56: [17917]: --> [2:OK]
Feb 22 13:15:32.56: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:15:35.66: [17917]: --> [0:]
Feb 22 13:15:35.66: [17917]: MODEM <Empty line>
Feb 22 13:15:35.66: [17917]: <-- data [1]
Feb 22 13:15:35.67: [17917]: --> [2:OK]
Feb 22 13:15:35.67: [17917]: DELAY 1500 ms
Feb 22 13:15:37.17: [17917]: SEND training at v.17 12000 bit/s
Feb 22 13:15:37.17: [17917]: <-- [9:AT+FTH=3\r]
Feb 22 13:15:37.20: [17917]: --> [7:CONNECT]
Feb 22 13:15:37.20: [17917]: <-- data [23]
Feb 22 13:15:37.20: [17917]: <-- data [2]
Feb 22 13:15:37.21: [17917]: --> [7:CONNECT]
Feb 22 13:15:37.21: [17917]: <-- data [7]
Feb 22 13:15:37.21: [17917]: <-- data [2]
Feb 22 13:15:39.20: [17917]: --> [2:OK]
Feb 22 13:15:39.20: [17917]: <-- [9:AT+FTS=7\r]
Feb 22 13:15:39.28: [17917]: --> [2:OK]
Feb 22 13:15:39.28: [17917]: <-- [11:AT+FTM=121\r]
Feb 22 13:15:40.75: [17917]: --> [7:CONNECT]
Feb 22 13:15:40.75: [17917]: <-- data [1024]
Feb 22 13:15:41.07: [17917]: <-- data [1024]
Feb 22 13:15:41.39: [17917]: <-- data [202]
Feb 22 13:15:41.39: [17917]: <-- data [2]
Feb 22 13:15:42.28: [17917]: --> [2:OK]
Feb 22 13:15:42.28: [17917]: <-- [9:AT+FRS=1\r]
Feb 22 13:15:42.30: [17917]: --> [2:OK]
Feb 22 13:15:42.30: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:15:45.40: [17917]: --> [0:]
Feb 22 13:15:45.40: [17917]: MODEM <Empty line>
Feb 22 13:15:45.40: [17917]: <-- data [1]
Feb 22 13:15:45.41: [17917]: --> [2:OK]
Feb 22 13:15:45.41: [17917]: DELAY 1500 ms
Feb 22 13:15:46.91: [17917]: SEND training at v.17 9600 bit/s
Feb 22 13:15:46.91: [17917]: <-- [9:AT+FTH=3\r]
Feb 22 13:15:46.94: [17917]: --> [7:CONNECT]
Feb 22 13:15:46.94: [17917]: <-- data [23]
Feb 22 13:15:46.94: [17917]: <-- data [2]
Feb 22 13:15:46.95: [17917]: --> [7:CONNECT]
Feb 22 13:15:46.95: [17917]: <-- data [7]
Feb 22 13:15:46.95: [17917]: <-- data [2]
Feb 22 13:15:48.94: [17917]: --> [2:OK]
Feb 22 13:15:48.94: [17917]: <-- [9:AT+FTS=7\r]
Feb 22 13:15:49.02: [17917]: --> [2:OK]
Feb 22 13:15:49.02: [17917]: <-- [10:AT+FTM=97\r]
Feb 22 13:15:50.49: [17917]: --> [7:CONNECT]
Feb 22 13:15:50.49: [17917]: <-- data [1024]
Feb 22 13:15:50.81: [17917]: <-- data [776]
Feb 22 13:15:51.13: [17917]: <-- data [2]
Feb 22 13:15:52.03: [17917]: --> [2:OK]
Feb 22 13:15:52.03: [17917]: <-- [9:AT+FRS=1\r]
Feb 22 13:15:52.04: [17917]: --> [2:OK]
Feb 22 13:15:52.04: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:15:54.79: [17917]: --> [7:CONNECT]
Feb 22 13:15:56.30: [17917]: --> [2:OK]
Feb 22 13:15:56.30: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:15:56.31: [17917]: --> [7:CONNECT]
Feb 22 13:15:57.04: [17917]: --> [2:OK]
Feb 22 13:15:57.04: [17917]: REMOTE CSI "FaxModem1"
Feb 22 13:15:57.04: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:15:57.05: [17917]: --> [7:CONNECT]
Feb 22 13:15:57.42: [17917]: --> [2:OK]
Feb 22 13:15:57.42: [17917]: SEND training at v.29 9600 bit/s
Feb 22 13:15:57.42: [17917]: <-- [9:AT+FTH=3\r]
Feb 22 13:15:57.61: [17917]: --> [7:CONNECT]
Feb 22 13:15:57.61: [17917]: <-- data [23]
Feb 22 13:15:57.61: [17917]: <-- data [2]
Feb 22 13:15:57.62: [17917]: --> [7:CONNECT]
Feb 22 13:15:57.62: [17917]: <-- data [7]
Feb 22 13:15:57.62: [17917]: <-- data [2]
Feb 22 13:15:59.61: [17917]: --> [2:OK]
Feb 22 13:15:59.61: [17917]: <-- [9:AT+FTS=7\r]
Feb 22 13:15:59.69: [17917]: --> [2:OK]
Feb 22 13:15:59.69: [17917]: <-- [10:AT+FTM=96\r]
Feb 22 13:16:00.00: [17917]: --> [7:CONNECT]
Feb 22 13:16:00.00: [17917]: <-- data [1024]
Feb 22 13:16:00.32: [17917]: <-- data [776]
Feb 22 13:16:00.64: [17917]: <-- data [2]
Feb 22 13:16:01.54: [17917]: --> [2:OK]
Feb 22 13:16:01.54: [17917]: <-- [9:AT+FRS=1\r]
Feb 22 13:16:01.55: [17917]: --> [2:OK]
Feb 22 13:16:01.55: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:16:02.35: [17917]: --> [7:CONNECT]
Feb 22 13:16:03.16: [17917]: --> [2:OK]
Feb 22 13:16:03.16: [17917]: TRAINING succeeded
Feb 22 13:16:03.16: [17917]: SEND begin page
Feb 22 13:16:03.17: [17917]: SEND EOFB
Feb 22 13:16:03.17: [17917]: SEND send frame number 0
Feb 22 13:16:03.17: [17917]: SEND send frame number 1
Feb 22 13:16:03.17: [17917]: SEND send frame number 2
Feb 22 13:16:03.17: [17917]: SEND send frame number 3
Feb 22 13:16:03.17: [17917]: SEND send frame number 4
Feb 22 13:16:03.17: [17917]: SEND send frame number 5
Feb 22 13:16:03.17: [17917]: SEND send frame number 6
Feb 22 13:16:03.17: [17917]: SEND send frame number 7
Feb 22 13:16:03.17: [17917]: SEND send frame number 8
Feb 22 13:16:03.17: [17917]: SEND send frame number 9
Feb 22 13:16:03.17: [17917]: SEND send frame number 10
Feb 22 13:16:03.17: [17917]: SEND send frame number 11
Feb 22 13:16:03.17: [17917]: SEND send frame number 12
Feb 22 13:16:03.17: [17917]: SEND send frame number 13
Feb 22 13:16:03.17: [17917]: SEND send frame number 14
Feb 22 13:16:03.17: [17917]: SEND send frame number 15
Feb 22 13:16:03.17: [17917]: DELAY 200 ms
Feb 22 13:16:03.37: [17917]: <-- [10:AT+FTM=96\r]
Feb 22 13:16:03.71: [17917]: --> [7:CONNECT]
Feb 22 13:16:03.71: [17917]: <-- data [1037]
Feb 22 13:16:04.03: [17917]: <-- data [1037]
Feb 22 13:16:04.35: [17917]: <-- data [1038]
Feb 22 13:16:04.68: [17917]: <-- data [1036]
Feb 22 13:16:05.32: [17917]: <-- data [488]
Feb 22 13:16:05.32: [17917]: <-- data [2]
Feb 22 13:16:07.56: [17917]: --> [2:OK]
Feb 22 13:16:07.56: [17917]: <-- [9:AT+FTS=7\r]
Feb 22 13:16:07.64: [17917]: --> [2:OK]
Feb 22 13:16:07.64: [17917]: <-- [9:AT+FTH=3\r]
Feb 22 13:16:07.79: [17917]: --> [7:CONNECT]
Feb 22 13:16:07.79: [17917]: <-- data [7]
Feb 22 13:16:07.79: [17917]: <-- data [2]
Feb 22 13:16:09.07: [17917]: --> [2:OK]
Feb 22 13:16:09.07: [17917]: SEND send PPS (partial page signal)
Feb 22 13:16:09.07: [17917]: SEND send MPS (more pages, same document)
Feb 22 13:16:09.07: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:16:09.19: [17917]: --> [10:NO CARRIER]
Feb 22 13:16:09.19: [17917]: MODEM No carrier
Feb 22 13:16:09.19: [17917]: <-- [9:AT+FTH=3\r]
Feb 22 13:16:09.38: [17917]: --> [7:CONNECT]
Feb 22 13:16:09.38: [17917]: <-- data [7]
Feb 22 13:16:09.38: [17917]: <-- data [2]
Feb 22 13:16:10.67: [17917]: --> [2:OK]
Feb 22 13:16:10.67: [17917]: SEND send PPS (partial page signal)
Feb 22 13:16:10.67: [17917]: SEND send MPS (more pages, same document)
Feb 22 13:16:10.67: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:16:10.79: [17917]: --> [10:NO CARRIER]
Feb 22 13:16:10.79: [17917]: MODEM No carrier
Feb 22 13:16:10.79: [17917]: <-- [9:AT+FTH=3\r]
Feb 22 13:16:10.99: [17917]: --> [7:CONNECT]
Feb 22 13:16:10.99: [17917]: <-- data [7]
Feb 22 13:16:10.99: [17917]: <-- data [2]
Feb 22 13:16:12.27: [17917]: --> [2:OK]
Feb 22 13:16:12.27: [17917]: SEND send PPS (partial page signal)
Feb 22 13:16:12.27: [17917]: SEND send MPS (more pages, same document)
Feb 22 13:16:12.27: [17917]: <-- [9:AT+FRH=3\r]
Feb 22 13:16:14.95: [17917]: --> [7:CONNECT]
Feb 22 13:16:16.51: [17917]: --> [5:ERROR]
Feb 22 13:16:16.51: [17917]: MODEM Command error
Feb 22 13:16:16.51: [17917]: FCS error
Feb 22 13:16:16.51: [17917]: No response to PPS repeated 3 times.
Feb 22 13:16:16.51: [17917]: SEND end page
Feb 22 13:16:16.51: [17917]: SEND: No response to PPS repeated 3 times.; Giving up after 3 attempts to send same page "docq/doc72.cover;f0", dirnum 0
Feb 22 13:16:16.51: [17917]: <-- [9:AT+FTH=3\r]
Feb 22 13:16:16.70: [17917]: --> [7:CONNECT]
Feb 22 13:16:16.70: [17917]: <-- data [3]
Feb 22 13:16:16.70: [17917]: <-- data [2]
Feb 22 13:16:17.87: [17917]: --> [2:OK]
Feb 22 13:16:17.87: [17917]: <-- [5:ATH0\r]
Feb 22 13:16:17.98: [17917]: --> [2:OK]
Feb 22 13:16:17.98: [17917]: SESSION END