[hylafax-users] Help debugging modem session log

["George H" <george.dma@xxxxxxxxx>]

Hi,

I'm giving my self a try at this and there are a few things I need help with.
First off the background info.

HylaFAX+ v 5.1.10
Modem: USR Model 5631
Fax Line: Connected directly to telco (not passing through a PBX nor a gateway)
Sender has the same error 6 times.


Nov 15 16:14:37.45: [27171]: SESSION BEGIN 000014681 +961xxxxxxxx
Nov 15 16:14:37.45: [27171]: HylaFAX (tm) Version 5.1.10
Nov 15 16:14:37.45: [27171]: MODEM set XON/XOFF/FLUSH: input ignored,
output disabled
Nov 15 16:14:37.45: [27171]: <-- [4:ATA\r]
Nov 15 16:14:43.42: [27171]: --> [7:CONNECT]
Nov 15 16:14:43.42: [27171]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS5'
Nov 15 16:14:43.42: [27171]: STATE CHANGE: ANSWERING -> RECEIVING
Nov 15 16:14:43.42: [27171]: RECV FAX: begin
Nov 15 16:14:43.42: [27171]: <-- HDLC<33: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 8C 74 8C 0C>
Nov 15 16:14:43.42: [27171]: <-- data [33]
Nov 15 16:14:43.42: [27171]: <-- data [2]
Nov 15 16:14:45.47: [27171]: --> [7:CONNECT]
Nov 15 16:14:45.47: [27171]: <-- HDLC<23:FF C0 02 4E A6 6E 4E A6 CA 04
1E 86 62 04 B2 F2 32 42 04 04 04 04 04>
Nov 15 16:14:45.47: [27171]: <-- data [23]
Nov 15 16:14:45.47: [27171]: <-- data [2]
Nov 15 16:14:46.30: [27171]: --> [7:CONNECT]
Nov 15 16:14:46.30: [27171]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 1E>
Nov 15 16:14:46.30: [27171]: <-- data [13]
Nov 15 16:14:46.30: [27171]: <-- data [2]
Nov 15 16:14:46.93: [27171]: --> [2:OK]
Nov 15 16:14:46.93: [27171]: <-- [9:AT+FRH=3\r]
Nov 15 16:14:47.69: [27171]: --> [7:CONNECT]
Nov 15 16:14:49.09: [27171]: --> HDLC<25:FF C0 C2 6C 2C CC 0C 1C 2C 04
8C 04 8C 6C 9C 04 04 04 04 04 04 04 04 1C 8F>
Nov 15 16:14:49.09: [27171]: --> [2:OK]

So far so good, we received the first HDLC control frames and the
result codes indicate everything is going as they should.

Nov 15 16:14:49.09: [27171]: RECV recv TSI (sender id)
Nov 15 16:14:49.09: [27171]: REMOTE TSI "961 x xxxxxx"
Nov 15 16:14:49.09: [27171]: <-- [9:AT+FRH=3\r]
Nov 15 16:14:49.14: [27171]: --> [7:CONNECT]
Nov 15 16:14:49.35: [27171]: --> HDLC<9:FF C8 C1 00 60 15 00 E6 36>
Nov 15 16:14:49.36: [27171]: --> [2:OK]
Nov 15 16:14:49.36: [27171]: RECV recv DCS (command signal)
Nov 15 16:14:49.36: [27171]: REMOTE wants 9600 bit/s
Nov 15 16:14:49.36: [27171]: REMOTE wants A4 page width (215 mm)
Nov 15 16:14:49.36: [27171]: REMOTE wants unlimited page length
Nov 15 16:14:49.36: [27171]: REMOTE wants 3.85 line/mm
Nov 15 16:14:49.36: [27171]: REMOTE wants 1-D MH
Nov 15 16:14:49.36: [27171]: RECV training at v.29 9600 bit/s
Nov 15 16:14:49.36: [27171]: MODEM set XON/XOFF/DRAIN: input ignored,
output generated
Nov 15 16:14:49.36: [27171]: <-- [10:AT+FRM=96\r]
Nov 15 16:14:49.86: [27171]: --> [7:CONNECT]

The AT+FRH=3 command  is successful twice (though in the T.31 doc they
mention it occuring 3 times instead of 2 before AT+FRM=96

Nov 15 16:14:52.09: [27171]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Nov 15 16:14:52.09: [27171]: RECV: TCF 1781 bytes, 1% non-zero, 1756 zero-run
Nov 15 16:14:52.09: [27171]: --> [10:NO CARRIER]
Nov 15 16:14:52.09: [27171]: DELAY 70 ms
Nov 15 16:14:52.16: [27171]: TRAINING succeeded

I don't know if this is a problem with my modem config or not but the
T.31 docs say it should delay 75ms not 70ms. But then again you guys
are the experts so I don't know if this is ok.

Nov 15 16:14:52.17: [27171]: <-- [9:AT+FTH=3\r]
Nov 15 16:14:59.72: [27171]: --> [0:]
Nov 15 16:14:59.72: [27171]: MODEM TIMEOUT: sending HDLC frame

I don't understand this part. The result code for AT+FTH=3  is empty.
The T.31 doc (8.3.5) says it should return another frame if the DTE
sends more data, or return OK if a null frame is sent and then the
transmit carrier is turned off, or ERROR returned if 5 seconds elapses
after a CONNECT is reported.

Nov 15 16:14:59.72: [27171]: <-- [10:AT+FRM=96\r]

To me looks like it's trying to retrain

Nov 15 16:15:06.73: [27171]: MODEM TIMEOUT: reading line from modem
Nov 15 16:15:06.73: [27171]: <-- data [1]
Nov 15 16:15:06.93: [27171]: MODEM TIMEOUT: reading line from modem
Nov 15 16:15:06.93: [27171]: MODEM <Timeout>

Got no clue what's going on here.

Nov 15 16:15:06.93: [27171]: <-- [9:AT+FRH=3\r]
Nov 15 16:15:10.35: [27171]: --> [10:NO CARRIER]
Nov 15 16:15:10.35: [27171]: MODEM No carrier

Detects loss of carrier, but I Don't know what "MODEM No carrier"
should indicate.

Nov 15 16:15:10.35: [27171]: RECV FAX (000014681):
recvq/fax000014638.tif from 961 x xxxxxx, subaddress <unspecified>, 0
pages in 0:27
Nov 15 16:15:10.36: [27171]: RECV FAX: T.30 T2 timeout, expected page
not received {E111}
Nov 15 16:15:10.36: [27171]: DELAY 70 ms
Nov 15 16:15:10.43: [27171]: <-- [9:AT+FTH=3\r]
Nov 15 16:15:10.48: [27171]: --> [5:ERROR]

Here's the ERROR result code that I mentioned above. The last CONNECT
was at 16:14:49.86, and ERROR was returned at 16:15:10.48 .. that's
about a minute. Was this error due to that because in between no
additional data was transmitted?

Nov 15 16:15:10.48: [27171]: MODEM input buffering enabled
Nov 15 16:15:10.48: [27171]: RECV FAX (000014681): session with 961 x
xxxxxx terminated abnormally: T.30 T2 timeout, expected page not
received {E111}
Nov 15 16:15:10.48: [27171]: RECV FAX: bin/faxrcvd
'recvq/fax000014638.tif' 'ttyS5' '000014681' 'T.30 T2 timeout,
expected page not received {E111}'
Nov 15 16:15:10.48: [27171]: RECV FAX: end
Nov 15 16:15:10.48: [27171]: SESSION END

End of the session log. This one sender trying to send me a fax has 6
different session logs just like this one. What kind of conclusions
could I draw from this?

Thanks.

-- 
"Nothing is impossible for the person that doesn't have to do it"
"The probability of anything happening is in inverse ratio to its desirability"
--
George H
george.dma@xxxxxxxxx


____________________ 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*