Re: [hylafax-users] MT modem hangs OH and TR stays on forever

[GianLuca Sarto <glsarto@xxxxxxxxxx>]

Lee Howard wrote:
> GianLuca Sarto wrote:
> > hello All,
> >
> > I had though I was over with this problem adding
> > > ModemSoftResetCmd: AT
> > > ModemOnHookCmd: AT
> > but here I am again asking for help.
> >
> > Situation is the following:
> >
> > MultiTech 5634ZBA connected to Elmeg ICT88 pabx.
> > Fax hangs with 56/OH/TR/FX leds on.
>
> Does it hang in the middle of a session?  If so, let's see the session 
> logs.  Tell us more of what happens to cause the "crash".
Lee,

here is a session log.

Thanks for your support.

-Gian
Jan 15 13:35:26.94: [ 7739]: SESSION BEGIN 000000872 390248953807
Jan 15 13:35:26.94: [ 7739]: HylaFAX (tm) Version 4.4.0
Jan 15 13:35:26.94: [ 7739]: <-- [4:ATA\r]
Jan 15 13:35:37.32: [ 7739]: --> [7:CONNECT]
Jan 15 13:35:37.32: [ 7739]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Jan 15 13:35:37.32: [ 7739]: RECV FAX: begin
Jan 15 13:35:37.32: [ 7739]: <-- data [32]
Jan 15 13:35:37.32: [ 7739]: <-- data [2]
Jan 15 13:35:37.35: [ 7739]: --> [7:CONNECT]
Jan 15 13:35:37.35: [ 7739]: <-- data [23]
Jan 15 13:35:37.35: [ 7739]: <-- data [2]
Jan 15 13:35:37.38: [ 7739]: --> [7:CONNECT]
Jan 15 13:35:37.38: [ 7739]: <-- data [13]
Jan 15 13:35:37.38: [ 7739]: <-- data [2]
Jan 15 13:35:39.64: [ 7739]: --> [2:OK]
Jan 15 13:35:39.64: [ 7739]: <-- [9:AT+FRH=3\r]
Jan 15 13:35:40.31: [ 7739]: --> [7:CONNECT]
Jan 15 13:35:42.02: [ 7739]: --> [2:OK]
Jan 15 13:35:42.02: [ 7739]: REMOTE TSI ""
Jan 15 13:35:42.02: [ 7739]: RECV recv TSI (sender id)
Jan 15 13:35:42.02: [ 7739]: <-- [9:AT+FRH=3\r]
Jan 15 13:35:42.04: [ 7739]: --> [7:CONNECT]
Jan 15 13:35:42.34: [ 7739]: --> [2:OK]
Jan 15 13:35:42.34: [ 7739]: REMOTE wants 9600 bit/s
Jan 15 13:35:42.34: [ 7739]: REMOTE wants A4 page width (215 mm)
Jan 15 13:35:42.34: [ 7739]: REMOTE wants unlimited page length
Jan 15 13:35:42.34: [ 7739]: REMOTE wants 3.85 line/mm
Jan 15 13:35:42.34: [ 7739]: REMOTE wants 1-D MH
Jan 15 13:35:42.34: [ 7739]: REMOTE wants T.30 Annex A, 256-byte ECM
Jan 15 13:35:42.34: [ 7739]: RECV recv DCS (command signal)
Jan 15 13:35:42.34: [ 7739]: RECV training at v.29 9600 bit/s
Jan 15 13:35:42.34: [ 7739]: <-- [10:AT+FRM=96\r]
Jan 15 13:35:42.70: [ 7739]: --> [7:CONNECT]
Jan 15 13:35:44.28: [ 7739]: RECV: TCF 1872 bytes, 0% non-zero, 1854 zero-run
Jan 15 13:35:44.28: [ 7739]: --> [10:NO CARRIER]
Jan 15 13:35:44.28: [ 7739]: DELAY 70 ms
Jan 15 13:35:44.34: [ 7739]: TRAINING succeeded
Jan 15 13:35:44.34: [ 7739]: <-- [9:AT+FTH=3\r]
Jan 15 13:35:45.31: [ 7739]: --> [7:CONNECT]
Jan 15 13:35:45.31: [ 7739]: <-- data [3]
Jan 15 13:35:45.31: [ 7739]: <-- data [2]
Jan 15 13:35:45.70: [ 7739]: --> [2:OK]
Jan 15 13:35:45.70: [ 7739]: <-- [10:AT+FRM=96\r]
Jan 15 13:35:46.77: [ 7739]: --> [7:CONNECT]
Jan 15 13:35:47.30: [ 7739]: RECV received frame number 0
Jan 15 13:35:47.52: [ 7739]: RECV received frame number 1
Jan 15 13:35:47.75: [ 7739]: RECV received frame number 2
Jan 15 13:35:47.97: [ 7739]: RECV received frame number 3
Jan 15 13:35:48.20: [ 7739]: RECV received frame number 4
Jan 15 13:35:48.42: [ 7739]: RECV received frame number 5
Jan 15 13:35:48.65: [ 7739]: RECV received frame number 6
Jan 15 13:35:48.87: [ 7739]: RECV received frame number 7
Jan 15 13:35:49.11: [ 7739]: RECV received frame number 8
Jan 15 13:35:49.32: [ 7739]: RECV received frame number 9
Jan 15 13:35:49.54: [ 7739]: RECV received frame number 10
Jan 15 13:35:49.76: [ 7739]: RECV received frame number 11
Jan 15 13:35:49.97: [ 7739]: RECV received frame number 12
Jan 15 13:35:50.19: [ 7739]: RECV received frame number 13
Jan 15 13:35:50.42: [ 7739]: RECV received frame number 14
Jan 15 13:35:50.64: [ 7739]: RECV received frame number 15
Jan 15 13:35:50.85: [ 7739]: RECV received frame number 16
Jan 15 13:35:51.07: [ 7739]: RECV received frame number 17
Jan 15 13:35:51.30: [ 7739]: RECV received frame number 18
Jan 15 13:35:51.54: [ 7739]: RECV received frame number 19
Jan 15 13:35:51.78: [ 7739]: RECV received frame number 20
Jan 15 13:35:52.02: [ 7739]: RECV received frame number 21
Jan 15 13:35:52.26: [ 7739]: RECV received frame number 22
Jan 15 13:35:52.38: [ 7739]: RECV received frame number 23
Jan 15 13:35:52.62: [ 7739]: RECV received frame number 24
Jan 15 13:35:52.86: [ 7739]: RECV received frame number 25
Jan 15 13:35:53.10: [ 7739]: RECV received frame number 26
Jan 15 13:35:53.34: [ 7739]: RECV received frame number 27
Jan 15 13:35:53.58: [ 7739]: RECV received frame number 28
Jan 15 13:35:53.82: [ 7739]: RECV received frame number 29
Jan 15 13:35:54.06: [ 7739]: RECV received frame number 30
Jan 15 13:35:54.18: [ 7739]: RECV received frame number 31
Jan 15 13:35:54.42: [ 7739]: RECV received frame number 32
Jan 15 13:35:54.66: [ 7739]: RECV received frame number 33
Jan 15 13:35:54.89: [ 7739]: RECV received frame number 34
Jan 15 13:35:55.11: [ 7739]: RECV received frame number 35
Jan 15 13:35:55.34: [ 7739]: RECV received frame number 36
Jan 15 13:35:55.56: [ 7739]: RECV received frame number 37
Jan 15 13:35:55.77: [ 7739]: RECV received frame number 38
Jan 15 13:35:56.00: [ 7739]: RECV received frame number 39
Jan 15 13:35:56.21: [ 7739]: RECV received frame number 40
Jan 15 13:35:56.45: [ 7739]: RECV received frame number 41
Jan 15 13:35:56.68: [ 7739]: RECV received frame number 42
Jan 15 13:35:56.89: [ 7739]: RECV received frame number 43
Jan 15 13:35:57.11: [ 7739]: RECV received frame number 44
Jan 15 13:35:57.33: [ 7739]: RECV received frame number 45
Jan 15 13:35:57.55: [ 7739]: RECV received frame number 46
Jan 15 13:35:57.78: [ 7739]: RECV received frame number 47
Jan 15 13:35:58.02: [ 7739]: RECV received frame number 48
Jan 15 13:35:58.24: [ 7739]: RECV received frame number 49
Jan 15 13:35:58.46: [ 7739]: RECV received frame number 50
Jan 15 13:35:58.68: [ 7739]: RECV received frame number 51
Jan 15 13:35:58.89: [ 7739]: RECV received frame number 52
Jan 15 13:35:59.10: [ 7739]: RECV received frame number 53
Jan 15 13:35:59.33: [ 7739]: RECV received frame number 54
Jan 15 13:35:59.55: [ 7739]: RECV received frame number 55
Jan 15 13:35:59.87: [ 7739]: RECV received frame number 56
Jan 15 13:36:00.09: [ 7739]: RECV received frame number 57
Jan 15 13:36:00.30: [ 7739]: RECV received frame number 58
Jan 15 13:36:00.53: [ 7739]: RECV received frame number 59
Jan 15 13:36:00.75: [ 7739]: RECV received frame number 60
Jan 15 13:36:00.95: [ 7739]: RECV received frame number 61
Jan 15 13:36:01.18: [ 7739]: RECV received frame number 62
Jan 15 13:36:01.40: [ 7739]: RECV received frame number 63
Jan 15 13:36:01.61: [ 7739]: RECV received frame number 64
Jan 15 13:36:01.82: [ 7739]: RECV received frame number 65
Jan 15 13:36:02.06: [ 7739]: RECV received frame number 66
Jan 15 13:36:02.27: [ 7739]: RECV received frame number 67
Jan 15 13:36:02.50: [ 7739]: RECV received frame number 68
Jan 15 13:36:02.72: [ 7739]: RECV received frame number 69
Jan 15 13:36:02.93: [ 7739]: RECV received frame number 70
Jan 15 13:36:03.15: [ 7739]: RECV received frame number 71
Jan 15 13:36:03.35: [ 7739]: RECV received frame number 72
Jan 15 13:36:03.57: [ 7739]: RECV received frame number 73
Jan 15 13:36:03.79: [ 7739]: RECV received frame number 74
Jan 15 13:36:04.01: [ 7739]: RECV received frame number 75
Jan 15 13:36:04.23: [ 7739]: RECV received frame number 76
Jan 15 13:36:04.45: [ 7739]: RECV received frame number 77
Jan 15 13:36:04.77: [ 7739]: RECV received frame number 78
Jan 15 13:36:04.99: [ 7739]: RECV received frame number 79
Jan 15 13:36:05.21: [ 7739]: RECV received frame number 80
Jan 15 13:36:05.43: [ 7739]: RECV received frame number 81
Jan 15 13:36:05.65: [ 7739]: RECV received frame number 82
Jan 15 13:36:05.88: [ 7739]: RECV received frame number 83
Jan 15 13:36:06.09: [ 7739]: RECV received frame number 84
Jan 15 13:36:06.31: [ 7739]: RECV received frame number 85
Jan 15 13:36:06.53: [ 7739]: RECV received frame number 86
Jan 15 13:36:06.76: [ 7739]: RECV received frame number 87
Jan 15 13:36:06.98: [ 7739]: RECV received frame number 88
Jan 15 13:36:07.19: [ 7739]: RECV received frame number 89
Jan 15 13:36:07.41: [ 7739]: RECV received frame number 90
Jan 15 13:36:07.64: [ 7739]: RECV received frame number 91
Jan 15 13:36:07.86: [ 7739]: RECV received frame number 92
Jan 15 13:36:08.08: [ 7739]: RECV received frame number 93
Jan 15 13:36:08.31: [ 7739]: RECV received frame number 94
Jan 15 13:36:08.53: [ 7739]: RECV received frame number 95
Jan 15 13:37:08.79: [ 7739]: Bad HDLC terminating flag received.
Jan 15 13:37:38.00: [ 7739]: Timeout awaiting synchronization sequence
Jan 15 13:37:38.00: [ 7739]: Bad HDLC terminating flag received.
Jan 15 13:38:08.00: [ 7739]: Timeout awaiting synchronization sequence
Jan 15 13:38:08.00: [ 7739]: Bad HDLC terminating flag received.
Jan 15 13:38:38.00: [ 7739]: Timeout awaiting synchronization sequence
Jan 15 13:38:38.00: [ 7739]: Bad HDLC terminating flag received.
Jan 15 13:39:08.00: [ 7739]: Timeout awaiting synchronization sequence
Jan 15 13:39:08.00: [ 7739]: Bad HDLC terminating flag received.
Jan 15 13:39:38.00: [ 7739]: Timeout awaiting synchronization sequence
Jan 15 13:39:38.00: [ 7739]: Bad HDLC terminating flag received.
Jan 15 13:40:08.00: [ 7739]: Timeout awaiting synchronization sequence
Jan 15 13:40:08.00: [ 7739]: Bad HDLC terminating flag received.
Jan 15 13:40:38.00: [ 7739]: Timeout awaiting synchronization sequence
Jan 15 13:40:38.00: [ 7739]: Bad HDLC terminating flag received.
Jan 15 13:41:08.00: [ 7739]: Timeout awaiting synchronization sequence
Jan 15 13:41:40.02: [ 7739]: MODEM <Empty line>
Jan 15 13:41:42.03: [ 7739]: MODEM <Empty line>
Jan 15 13:41:44.04: [ 7739]: MODEM <Empty line>
Jan 15 13:41:44.04: [ 7739]: <-- [9:AT+FRH=3\r]
Jan 15 13:42:52.32: [ 7739]: --> [0:]
Jan 15 13:42:52.32: [ 7739]: MODEM <Empty line>
Jan 15 13:42:52.32: [ 7739]: <-- data [1]
Jan 15 13:42:52.54: [ 7739]: MODEM <Timeout>
Jan 15 13:42:52.65: [ 7739]: RECV: 24576 bytes of data, 452 total lines
Jan 15 13:42:52.65: [ 7739]: RECV keeping unconfirmed page
Jan 15 13:42:52.65: [ 7739]: RECV FAX (000000872): from , page 1 in 7:15, INF, 3.85 line/mm, 1-D MH, 9600 bit/s
Jan 15 13:42:52.65: [ 7739]: RECV FAX (000000872): recvq/fax000008955.tif from , route to <unspecified>, 1 pages in 7:15
Jan 15 13:42:52.65: [ 7739]: RECV FAX: T.30 T2 timeout, expected signal not received
Jan 15 13:42:52.65: [ 7739]: <-- [9:AT+FTH=3\r]
Jan 15 13:43:00.22: [ 7739]: --> [0:]
Jan 15 13:43:00.22: [ 7739]: RECV FAX (000000872): session with  terminated abnormally: T.30 T2 timeout, expected signal not received
Jan 15 13:43:00.22: [ 7739]: RECV FAX: bin/faxrcvd "recvq/fax000008955.tif" "ttyS0" "000000872" "T.30 T2 timeout, expected signal not received"
Jan 15 13:43:00.22: [ 7739]: RECV FAX: end
Jan 15 13:43:00.22: [ 7739]: T.30 T2 timeout, expected signal not received
Jan 15 13:43:00.22: [ 7739]: SESSION END