flexfax: Remote machine hangs up on MPS

[Raj Mathur <raju@sgi.com>]

hylafax-v4.0pl1 on an SGI O2 IRIX 6.3 GCC 2.6.3

Motorola UDS Fastalk II

Sendfax sends the first page of the fax fine, but the connection
closes once the server sends the MPS (more pages same document)
command.

I haven't been able to subscribe to the list yet (vix.com has some
problem) so please send a CC of any suggestions to me at raju@sgi.com
too. Thanks.

-- Raju
--
       Raj Mathur / Web Technical Support / Silicon Graphics / New Delhi
         +91-11-6216324/25, 6211354/55   /    raju@sgi.com  / 7228
            http://reality.sgi.com/raju / It is the Mind that Moves
           PGP: F2 D4 4A 21 27 B0 63 FF | Not necessarily speaking
                15 97 9D AE 9D 40 BC B8 | for Silicon Graphics

Session trace follows:

Feb 06 12:05:40.24: [ 2317]: SESSION BEGIN 00000720 91116473167
Feb 06 12:05:40.25: [ 2317]: SEND FAX: JOB 57 DEST 6473167 COMMID 00000720
Feb 06 12:05:40.25: [ 2317]: MODEM set DTR OFF
Feb 06 12:05:40.25: [ 2317]: MODEM set baud rate: 0 baud (flow control unchanged)
Feb 06 12:05:40.25: [ 2317]: DELAY 2600 ms
Feb 06 12:05:42.85: [ 2317]: MODEM set DTR ON
Feb 06 12:05:42.85: [ 2317]: MODEM set baud rate: 38400 baud, input flow RTS/CTS, output flow RTS/CTS
Feb 06 12:05:42.85: [ 2317]: MODEM flush i/o
Feb 06 12:05:42.85: [ 2317]: <-- [21:ATS11=35E0V1Q0S0=0H0\r]
Feb 06 12:05:43.66: [ 2317]: --> [20:ATS11=35E0V1Q0S0=0H0]
Feb 06 12:05:43.66: [ 2317]: --> [2:OK]
Feb 06 12:05:43.66: [ 2317]: <-- [21:ATS8=2S7=60\Q3&D3&C1\r]
Feb 06 12:05:43.77: [ 2317]: --> [2:OK]
Feb 06 12:05:43.77: [ 2317]: <-- [12:AT+FCLASS=2\r]
Feb 06 12:05:43.88: [ 2317]: --> [2:OK]
Feb 06 12:05:43.88: [ 2317]: <-- [10:AT+FTBC=0\r]
Feb 06 12:05:43.89: [ 2317]: --> [5:ERROR]
Feb 06 12:05:43.89: [ 2317]: MODEM Command error
Feb 06 12:05:43.89: [ 2317]: <-- [10:AT+FBOR=0\r]
Feb 06 12:05:43.90: [ 2317]: --> [2:OK]
Feb 06 12:05:43.90: [ 2317]: <-- [13:AT+FPHCTO=30\r]
Feb 06 12:05:43.91: [ 2317]: --> [5:ERROR]
Feb 06 12:05:43.91: [ 2317]: MODEM Command error
Feb 06 12:05:43.91: [ 2317]: <-- [10:AT+FBUG=1\r]
Feb 06 12:05:43.92: [ 2317]: --> [2:OK]
Feb 06 12:05:43.92: [ 2317]: <-- [24:AT+FDCC=1,5,2,2,1,0,0,0\r]
Feb 06 12:05:43.93: [ 2317]: --> [2:OK]
Feb 06 12:05:43.93: [ 2317]: <-- [5:ATM0\r]
Feb 06 12:05:43.94: [ 2317]: --> [2:OK]
Feb 06 12:05:43.94: [ 2317]: STATE CHANGE: RUNNING -> SENDING
Feb 06 12:05:43.94: [ 2317]: MODEM input buffering enabled
Feb 06 12:05:43.94: [ 2317]: <-- [12:AT+FCLASS=2\r]
Feb 06 12:05:44.05: [ 2317]: --> [2:OK]
Feb 06 12:05:44.05: [ 2317]: <-- [10:AT+FTBC=0\r]
Feb 06 12:05:44.16: [ 2317]: --> [5:ERROR]
Feb 06 12:05:44.16: [ 2317]: MODEM Command error
Feb 06 12:05:44.16: [ 2317]: <-- [10:AT+FBOR=0\r]
Feb 06 12:05:44.26: [ 2317]: --> [2:OK]
Feb 06 12:05:44.26: [ 2317]: <-- [13:AT+FPHCTO=30\r]
Feb 06 12:05:44.37: [ 2317]: --> [5:ERROR]
Feb 06 12:05:44.37: [ 2317]: MODEM Command error
Feb 06 12:05:44.37: [ 2317]: <-- [10:AT+FBUG=1\r]
Feb 06 12:05:44.47: [ 2317]: --> [2:OK]
Feb 06 12:05:44.47: [ 2317]: <-- [24:AT+FDCC=1,5,2,2,1,0,0,0\r]
Feb 06 12:05:44.58: [ 2317]: --> [2:OK]
Feb 06 12:05:44.58: [ 2317]: <-- [18:AT+FLID="SGIndia"\r]
Feb 06 12:05:44.69: [ 2317]: --> [2:OK]
Feb 06 12:05:44.69: [ 2317]: DIAL 6473167
Feb 06 12:05:44.69: [ 2317]: <-- [13:ATDT6473167@\r]
Feb 06 12:05:57.30: [ 2317]: --> [5:+FCON]
Feb 06 12:05:59.84: [ 2317]: --> [33:+FNSF: 0000001B7B6001A880403600AD]
Feb 06 12:05:59.84: [ 2317]: REMOTE NSF " 0000001B7B6001A880403600AD"
Feb 06 12:05:59.84: [ 2317]: --> [29:+FCSI: "                    "]
Feb 06 12:05:59.84: [ 2317]: REMOTE CSI ""
Feb 06 12:05:59.84: [ 2317]: --> [22:+FDIS: 1,3,0,2,1,0,0,4]
Feb 06 12:05:59.84: [ 2317]: --> [2:OK]
Feb 06 12:05:59.84: [ 2317]: REMOTE best rate 9600 bit/s
Feb 06 12:05:59.84: [ 2317]: REMOTE max page width 1728 pixels in 215 mm
Feb 06 12:05:59.84: [ 2317]: REMOTE max unlimited page length 
Feb 06 12:05:59.84: [ 2317]: REMOTE best vres 7.7 line/mm
Feb 06 12:05:59.84: [ 2317]: REMOTE best format 2-D MR
Feb 06 12:05:59.84: [ 2317]: REMOTE best 20 ms, 10 ms/scanline
Feb 06 12:05:59.84: [ 2317]: USE 9600 bit/s
Feb 06 12:05:59.84: [ 2317]: USE 20 ms, 10 ms/scanline
Feb 06 12:05:59.84: [ 2317]: SEND file "docq/doc57.cover;41"
Feb 06 12:05:59.84: [ 2317]: USE page width 1728 pixels in 215 mm
Feb 06 12:05:59.84: [ 2317]: USE unlimited page length 
Feb 06 12:05:59.84: [ 2317]: USE 7.7 line/mm
Feb 06 12:05:59.84: [ 2317]: USE 2-D MR
Feb 06 12:05:59.84: [ 2317]: <-- [24:AT+FDIS=1,3,0,2,1,0,0,4\r]
Feb 06 12:05:59.96: [ 2317]: --> [2:OK]
Feb 06 12:05:59.96: [ 2317]: <-- [7:AT+FDT\r]
Feb 06 12:06:05.78: [ 2317]: --> [22:+FDCS: 1,3,0,2,1,0,0,3]
Feb 06 12:06:06.37: [ 2317]: --> [7:CONNECT]
Feb 06 12:06:06.37: [ 2317]: SEND wait for XON
Feb 06 12:06:06.37: [ 2317]: --> [1:^Q]
Feb 06 12:06:06.37: [ 2317]: SEND begin page
Feb 06 12:06:06.37: [ 2317]: <-- data [1028]
Feb 06 12:06:06.37: [ 2317]: <-- data [1031]
Feb 06 12:06:06.37: [ 2317]: <-- data [1031]
Feb 06 12:06:06.37: [ 2317]: <-- data [1030]
Feb 06 12:06:06.37: [ 2317]: <-- data [1034]
Feb 06 12:06:06.38: [ 2317]: <-- data [1034]
Feb 06 12:06:08.61: [ 2317]: <-- data [1036]
Feb 06 12:06:08.61: [ 2317]: <-- data [1045]
Feb 06 12:06:10.84: [ 2317]: <-- data [1044]
Feb 06 12:06:10.84: [ 2317]: <-- data [1039]
Feb 06 12:06:15.66: [ 2317]: <-- data [1038]
Feb 06 12:06:17.87: [ 2317]: <-- data [1037]
Feb 06 12:06:17.87: [ 2317]: <-- data [1043]
Feb 06 12:06:20.95: [ 2317]: <-- data [1037]
Feb 06 12:06:20.95: [ 2317]: <-- data [1042]
Feb 06 12:06:23.18: [ 2317]: <-- data [1024]
Feb 06 12:06:23.19: [ 2317]: <-- data [504]
Feb 06 12:06:25.39: [ 2317]: SENT 16888 bytes of data
Feb 06 12:06:25.39: [ 2317]: <-- data [2]
Feb 06 12:06:25.39: [ 2317]: SEND end page
Feb 06 12:06:34.64: [ 2317]: --> [54:^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q^S^Q]
Feb 06 12:06:34.64: [ 2317]: --> [2:OK]
Feb 06 12:06:34.64: [ 2317]: SEND send MPS (more pages, same document)
Feb 06 12:06:34.64: [ 2317]: <-- [9:AT+FET=0\r]
Feb 06 12:06:42.85: [ 2317]: --> [9:+FHNG: 53]
Feb 06 12:06:42.85: [ 2317]: REMOTE HANGUP: Invalid response to MPS (code 53)
Feb 06 12:06:42.85: [ 2317]: <-- [5:ATH0\r]
Feb 06 12:06:42.85: [ 2317]: --> [2:OK]
Feb 06 12:06:42.85: [ 2317]: MODEM set DTR OFF
Feb 06 12:06:42.85: [ 2317]: MODEM set baud rate: 0 baud (flow control unchanged)
Feb 06 12:06:42.87: [ 2317]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Feb 06 12:06:42.87: [ 2317]: SESSION END