Re: [hylafax-users] one fax machine always causes error on hylafax

["Bazooka Joe" <fastfish@xxxxxxxxx>]

On Thu, Apr 24, 2008 at 9:37 PM, Lee Howard <faxguy@xxxxxxxxxxxxxxxx> wrote:
> Bazooka Joe wrote:
>>
>> Every time one of our offices tries to send a fax to our main office's
>> hylafax server it errors out.
>>
>
>> Apr 23 15:14:53 fax FaxGetty[2267]: RECV FAX (000000274): session with
>> 530 865 5570 terminated abnormally: T.30 T2 timeout, expected signal
>> not received {E118}
>>
>
>> Faxes from other numbers come through fine.  I have tried 3 different
>> fax machines at the remote site all w/ the same result.  Hylafax will
>> receive a partial page or no page every time.
>>
>> I am running hylafax-5.2.3-1.fc8.i386.rpm
>
> Please send the associated session log found in /var/spool/hylafax/log/...
>
> Thanks,
>
> Lee.
>

[root@fax log]# cat c000000373
May 08 14:21:02.61: [ 9494]: SESSION BEGIN 000000373 +15308914946
May 08 14:21:02.61: [ 9494]: HylaFAX (tm) Version 5.2.3
May 08 14:21:02.61: [ 9494]: MODEM set XON/XOFF/FLUSH: input ignored,
output disabled
May 08 14:21:02.61: [ 9494]: <-- [13:AT+FCLASS=1A\r]
May 08 14:21:08.05: [ 9494]: --> [7:CONNECT]
May 08 14:21:08.05: [ 9494]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS1'
May 08 14:21:08.05: [ 9494]: STATE CHANGE: ANSWERING -> RECEIVING
May 08 14:21:08.05: [ 9494]: RECV FAX: begin
May 08 14:21:08.05: [ 9494]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86
62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 AC 74 4C 74 CC>
May 08 14:21:08.05: [ 9494]: <-- data [32]
May 08 14:21:08.05: [ 9494]: <-- data [2]
May 08 14:21:08.22: [ 9494]: --> [7:CONNECT]
May 08 14:21:08.22: [ 9494]: <-- HDLC<23:FF C0 02 0E AE 2E A6 CA E6 76
96 16 2E F6 72 04 04 04 04 04 04 04 04>
May 08 14:21:08.22: [ 9494]: <-- data [23]
May 08 14:21:08.22: [ 9494]: <-- data [2]
May 08 14:21:08.94: [ 9494]: --> [7:CONNECT]
May 08 14:21:08.94: [ 9494]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
May 08 14:21:08.94: [ 9494]: <-- data [13]
May 08 14:21:08.94: [ 9494]: <-- data [2]
May 08 14:21:11.20: [ 9494]: --> [2:OK]
May 08 14:21:11.20: [ 9494]: <-- [9:AT+FRH=3\r]
May 08 14:21:11.90: [ 9494]: --> [7:CONNECT]
May 08 14:21:13.47: [ 9494]: --> HDLC<25:FF C0 C2 0C EC AC AC 04 AC 6C
1C 04 0C CC AC 04 04 04 04 04 04 04 04 DE B0>
May 08 14:21:13.47: [ 9494]: --> [2:OK]
May 08 14:21:13.47: [ 9494]: RECV recv TSI (sender id)
May 08 14:21:13.47: [ 9494]: REMOTE TSI "530 865 5570"
May 08 14:21:13.47: [ 9494]: <-- [9:AT+FRH=3\r]
May 08 14:21:13.48: [ 9494]: --> [7:CONNECT]
May 08 14:21:13.92: [ 9494]: --> HDLC<15:FF C8 C1 00 46 1F 23 01 01 01
01 01 00 71 65>
May 08 14:21:13.92: [ 9494]: --> [2:OK]
May 08 14:21:13.92: [ 9494]: RECV recv DCS (command signal)
May 08 14:21:13.92: [ 9494]: REMOTE wants 14400 bit/s
May 08 14:21:13.92: [ 9494]: REMOTE wants A4 page width (215 mm)
May 08 14:21:13.92: [ 9494]: REMOTE wants unlimited page length
May 08 14:21:13.92: [ 9494]: REMOTE wants 7.7 line/mm
May 08 14:21:13.92: [ 9494]: REMOTE wants 2-D MMR
May 08 14:21:13.92: [ 9494]: REMOTE wants T.30 Annex A, 256-byte ECM
May 08 14:21:13.92: [ 9494]: RECV training at v.17 14400 bit/s
May 08 14:21:13.92: [ 9494]: MODEM set XON/XOFF/DRAIN: input ignored,
output generated
May 08 14:21:13.92: [ 9494]: <-- [11:AT+FRM=145\r]
May 08 14:21:15.70: [ 9494]: --> [7:CONNECT]
May 08 14:21:16.46: [ 9494]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
May 08 14:21:16.46: [ 9494]: RECV: TCF 1372 bytes, 0% non-zero, 1354 zero-run
May 08 14:21:16.46: [ 9494]: --> [10:NO CARRIER]
May 08 14:21:16.46: [ 9494]: DELAY 70 ms
May 08 14:21:16.53: [ 9494]: TRAINING succeeded
May 08 14:21:16.54: [ 9494]: <-- [9:AT+FTH=3\r]
May 08 14:21:16.73: [ 9494]: --> [7:CONNECT]
May 08 14:21:16.73: [ 9494]: <-- HDLC<3:FF C8 21>
May 08 14:21:16.73: [ 9494]: <-- data [3]
May 08 14:21:16.73: [ 9494]: <-- data [2]
May 08 14:21:17.91: [ 9494]: --> [2:OK]
May 08 14:21:17.91: [ 9494]: <-- [11:AT+FRM=146\r]
May 08 14:21:18.90: [ 9494]: --> [7:CONNECT]
May 08 14:21:18.90: [ 9494]: MODEM input buffering enabled
May 08 14:21:18.90: [ 9494]: MODEM set XON/XOFF/FLUSH: input ignored,
output generated
May 08 14:21:19.88: [ 9494]: RECV received frame number 0
May 08 14:21:20.01: [ 9494]: RECV assumed RCP frame with block end
May 08 14:21:20.01: [ 9494]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
May 08 14:21:20.01: [ 9494]: MODEM input buffering disabled
May 08 14:21:20.01: [ 9494]: --> [10:NO CARRIER]
May 08 14:21:20.01: [ 9494]: <-- [9:AT+FRH=3\r]
May 08 14:21:20.93: [ 9494]: --> [7:CONNECT]
May 08 14:21:21.77: [ 9494]: --> [20:13 0E 60 47 72 F2 8D D0 15 EF 3C
70 1A 3A CD 26 C3 80 C2 B2]
May 08 14:21:21.77: [ 9494]: --> [13:ED 00 5F E9 8D A3 6B 8D D0 6E EF 10 03]
May 08 14:21:21.78: [ 9494]: --> [5:ERROR]
May 08 14:21:21.78: [ 9494]: MODEM Command error
May 08 14:21:21.78: [ 9494]: FCS error
May 08 14:21:21.78: [ 9494]: DELAY 70 ms
May 08 14:21:21.85: [ 9494]: <-- [9:AT+FTH=3\r]
May 08 14:21:22.04: [ 9494]: --> [7:CONNECT]
May 08 14:21:22.04: [ 9494]: <-- HDLC<3:FF C8 58>
May 08 14:21:22.04: [ 9494]: <-- data [3]
May 08 14:21:22.04: [ 9494]: <-- data [2]
May 08 14:21:23.22: [ 9494]: --> [2:OK]
May 08 14:21:23.22: [ 9494]: RECV send CRP (command repeat)
May 08 14:21:23.22: [ 9494]: <-- [9:AT+FRH=3\r]
May 08 14:21:24.43: [ 9494]: --> [7:CONNECT]
May 08 14:21:25.46: [ 9494]: --> [20:BA F9 C6 DF C9 6C E3 60 A7 58 5E
4F 92 7B 64 BD 6A 4C A7 2A]
May 08 14:21:25.46: [ 9494]: --> [20:F2 E1 15 68 80 04 11 68 63 62 70
8B 06 C1 76 BE B1 80 10 03]
May 08 14:21:25.46: [ 9494]: --> [5:ERROR]
May 08 14:21:25.46: [ 9494]: MODEM Command error
May 08 14:21:25.46: [ 9494]: FCS error
May 08 14:21:25.46: [ 9494]: DELAY 70 ms
May 08 14:21:25.53: [ 9494]: <-- [9:AT+FTH=3\r]
May 08 14:21:25.73: [ 9494]: --> [7:CONNECT]
May 08 14:21:25.73: [ 9494]: <-- HDLC<3:FF C8 58>
May 08 14:21:25.73: [ 9494]: <-- data [3]
May 08 14:21:25.73: [ 9494]: <-- data [2]
May 08 14:21:26.91: [ 9494]: --> [2:OK]
May 08 14:21:26.91: [ 9494]: RECV send CRP (command repeat)
May 08 14:21:26.91: [ 9494]: <-- [9:AT+FRH=3\r]
May 08 14:21:27.10: [ 9494]: --> [7:CONNECT]
May 08 14:21:27.37: [ 9494]: --> HDLC<9:FF C8 FD F4 00 00 08 D2 AA>
May 08 14:21:27.37: [ 9494]: --> [2:OK]
May 08 14:21:27.37: [ 9494]: RECV recv PPS (partial page signal)
May 08 14:21:27.37: [ 9494]: RECV recv EOP (no more pages or documents)
May 08 14:21:27.37: [ 9494]: RECV received 17 frames of block 1 of page 1
May 08 14:21:27.37: [ 9494]: DELAY 70 ms
May 08 14:21:27.44: [ 9494]: <-- [9:AT+FTH=3\r]
May 08 14:21:27.64: [ 9494]: --> [7:CONNECT]
May 08 14:21:27.64: [ 9494]: <-- HDLC<35:FF C8 3D 7F FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF
FF FF>
May 08 14:21:27.64: [ 9494]: <-- data [35]
May 08 14:21:27.64: [ 9494]: <-- data [2]
May 08 14:21:29.83: [ 9494]: --> [2:OK]
May 08 14:21:29.83: [ 9494]: RECV send PPR (partial page request)
May 08 14:21:29.83: [ 9494]: <-- [11:AT+FRM=146\r]
May 08 14:21:30.81: [ 9494]: --> [7:CONNECT]
May 08 14:21:30.81: [ 9494]: MODEM input buffering enabled
May 08 14:21:30.81: [ 9494]: MODEM set XON/XOFF/FLUSH: input ignored,
output generated
May 08 14:21:31.23: [ 9494]: RECV received frame number 1
May 08 14:21:31.37: [ 9494]: RECV received frame number 2
May 08 14:21:31.51: [ 9494]: RECV received frame number 3
May 08 14:21:31.72: [ 9494]: RECV received frame number 4
May 08 14:21:31.92: [ 9494]: RECV received frame number 5
May 08 14:21:31.92: [ 9494]: RECV assumed RCP frame with block end
May 08 14:21:31.92: [ 9494]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
May 08 14:21:31.92: [ 9494]: MODEM input buffering disabled
May 08 14:21:31.92: [ 9494]: --> [10:NO CARRIER]
May 08 14:21:31.92: [ 9494]: <-- [9:AT+FRH=3\r]
May 08 14:21:33.85: [ 9494]: --> [10:NO CARRIER]
May 08 14:21:33.85: [ 9494]: MODEM No carrier
May 08 14:21:33.87: [ 9494]: RECV/CQ: Adjusting for EOFB at row 788
May 08 14:21:33.87: [ 9494]: RECV: 4352 bytes of data, 788 total lines
May 08 14:21:33.87: [ 9494]: RECV keeping unconfirmed page
May 08 14:21:33.87: [ 9494]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
May 08 14:21:33.87: [ 9494]: RECV FAX (000000373): from 530 865 5570,
page 1 in 0:00:25, INF, 7.7 line/mm, 2-D MMR, 14400 bit/s
May 08 14:21:34.88: [ 9494]: RECV FAX: T.30 T2 timeout, expected
signal not received {E118}
May 08 14:21:34.88: [ 9494]: DELAY 70 ms
May 08 14:21:34.88: [ 9494]: RECV FAX (000000373):
recvq/fax000000371.tif from 530 865 5570, subaddress <unspecified>, 1
pages in 0:00:25
May 08 14:21:34.95: [ 9494]: <-- [9:AT+FTH=3\r]
May 08 14:21:35.14: [ 9494]: --> [7:CONNECT]
May 08 14:21:35.14: [ 9494]: <-- HDLC<3:FF C8 5F>
May 08 14:21:35.14: [ 9494]: <-- data [3]
May 08 14:21:35.14: [ 9494]: <-- data [2]
May 08 14:21:36.32: [ 9494]: --> [2:OK]
May 08 14:21:36.32: [ 9494]: MODEM input buffering enabled
May 08 14:21:36.32: [ 9494]: RECV FAX (000000373): session with 530
865 5570 terminated abnormally: T.30 T2 timeout, expected signal not
received {E118}
May 08 14:21:36.32: [ 9494]: RECV FAX: bin/faxrcvd
'recvq/fax000000371.tif' 'ttyS1' '000000373' 'T.30 T2 timeout,
expected signal not received {E118}'
May 08 14:21:36.32: [ 9494]: RECV FAX: end
May 08 14:21:36.32: [ 9494]: SESSION END


____________________ HylaFAX(tm) Users Mailing List _______________________
  To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi
 On UNIX: mail -s unsubscribe hylafax-users-request@xxxxxxxxxxx < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@xxxxxxxxx*