HylaFAX The world's most advanced open source fax server

[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]

[hylafax-users] Problems with receiving fax - T.30 T2 timeout, expected signal not received




Hi,


I have a problem with receiving a fax from all destinations. The communication looks fine, according to the log. Every received fax is readable, but the sender and the receiver (root@xxxxxxxxxxxx) gets error.

I'm using external U.S. Robotics 56K FAX EXT Rev. 11.16.39 modem and hylfax-versions hylafax-4.3.3-1rhel4.

I have no idea what to do, to make the receiving from that destination work. As far as I understand the logs, hylafax is receiving data normaly until end. I think that the ending of the fax communicaton is in question. Looks like that my modem is not sending "RECEIVED" message to the sender. Can somebody help me?

thanks,
tejlor

----------

I removed the fax numbers of sender and receiver in following log.


Sep 22 13:40:29.87: [ 2956]: SESSION BEGIN 000000007 XXXYY5192ZZ
Sep 22 13:40:29.87: [ 2956]: HylaFAX (tm) Version 4.3.3
Sep 22 13:40:29.87: [ 2956]: <-- [13:AT+FCLASS=1A\r]
Sep 22 13:40:35.06: [ 2956]: --> [7:CONNECT]
Sep 22 13:40:35.06: [ 2956]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyS3'
Sep 22 13:40:35.06: [ 2956]: RECV FAX: begin
Sep 22 13:40:35.07: [ 2956]: <-- data [32]
Sep 22 13:40:35.07: [ 2956]: <-- data [2]
Sep 22 13:40:35.22: [ 2956]: --> [7:CONNECT]
Sep 22 13:40:35.22: [ 2956]: <-- data [23]
Sep 22 13:40:35.22: [ 2956]: <-- data [2]
Sep 22 13:40:35.94: [ 2956]: --> [7:CONNECT]
Sep 22 13:40:35.94: [ 2956]: <-- data [13]
Sep 22 13:40:35.94: [ 2956]: <-- data [2]
Sep 22 13:40:38.21: [ 2956]: --> [2:OK]
Sep 22 13:40:38.21: [ 2956]: <-- [9:AT+FRH=3\r]
Sep 22 13:40:38.66: [ 2956]: --> [7:CONNECT]
Sep 22 13:40:40.20: [ 2956]: --> [2:OK]
Sep 22 13:40:40.20: [ 2956]: REMOTE TSI "+XXXYY3007ZZZ"
Sep 22 13:40:40.20: [ 2956]: <-- [9:AT+FRH=3\r]
Sep 22 13:40:40.22: [ 2956]: --> [7:CONNECT]
Sep 22 13:40:40.50: [ 2956]: --> [2:OK]
Sep 22 13:40:40.50: [ 2956]: REMOTE wants 14400 bit/s
Sep 22 13:40:40.50: [ 2956]: REMOTE wants A4 page width (215 mm)
Sep 22 13:40:40.50: [ 2956]: REMOTE wants unlimited page length
Sep 22 13:40:40.50: [ 2956]: REMOTE wants 7.7 line/mm
Sep 22 13:40:40.50: [ 2956]: REMOTE wants 2-D MMR
Sep 22 13:40:40.50: [ 2956]: REMOTE wants T.30 Annex A, 256-byte ECM
Sep 22 13:40:40.50: [ 2956]: RECV training at v.17 14400 bit/s
Sep 22 13:40:40.50: [ 2956]: <-- [11:AT+FRM=145\r]
Sep 22 13:40:42.00: [ 2956]: --> [7:CONNECT]
Sep 22 13:40:43.41: [ 2956]: RECV: TCF 2541 bytes, 2% non-zero, 2474 zero-run
Sep 22 13:40:43.41: [ 2956]: --> [10:NO CARRIER]
Sep 22 13:40:43.41: [ 2956]: DELAY 70 ms
Sep 22 13:40:43.48: [ 2956]: TRAINING succeeded
Sep 22 13:40:43.48: [ 2956]: <-- [9:AT+FTH=3\r]
Sep 22 13:40:43.68: [ 2956]: --> [7:CONNECT]
Sep 22 13:40:43.68: [ 2956]: <-- data [3]
Sep 22 13:40:43.68: [ 2956]: <-- data [2]
Sep 22 13:40:44.86: [ 2956]: --> [2:OK]
Sep 22 13:40:44.86: [ 2956]: <-- [11:AT+FRM=146\r]
Sep 22 13:40:45.39: [ 2956]: --> [7:CONNECT]
Sep 22 13:40:45.74: [ 2956]: RECV received frame number 0
Sep 22 13:40:45.88: [ 2956]: RECV received frame number 1
Sep 22 13:40:46.08: [ 2956]: RECV received frame number 2
Sep 22 13:40:46.22: [ 2956]: RECV received frame number 3
Sep 22 13:40:46.36: [ 2956]: RECV received frame number 4
Sep 22 13:40:46.49: [ 2956]: RECV received frame number 5
Sep 22 13:40:46.63: [ 2956]: RECV received frame number 6
Sep 22 13:40:46.76: [ 2956]: RECV received frame number 7
Sep 22 13:40:46.97: [ 2956]: RECV received frame number 8
Sep 22 13:40:47.11: [ 2956]: RECV received frame number 9
Sep 22 13:40:47.25: [ 2956]: RECV received frame number 10
Sep 22 13:40:47.38: [ 2956]: RECV received frame number 11
Sep 22 13:40:47.52: [ 2956]: RECV received frame number 12
Sep 22 13:40:47.66: [ 2956]: RECV received frame number 13
Sep 22 13:40:47.80: [ 2956]: RECV received frame number 14
Sep 22 13:40:47.94: [ 2956]: RECV received frame number 15
Sep 22 13:40:48.14: [ 2956]: RECV received frame number 16
Sep 22 13:40:48.28: [ 2956]: RECV received frame number 17
Sep 22 13:40:48.41: [ 2956]: RECV received frame number 18
Sep 22 13:40:48.55: [ 2956]: RECV received frame number 19
Sep 22 13:40:48.69: [ 2956]: RECV received frame number 20
Sep 22 13:40:48.82: [ 2956]: RECV received frame number 21
Sep 22 13:40:48.96: [ 2956]: RECV received frame number 22
Sep 22 13:40:49.17: [ 2956]: RECV received frame number 23
Sep 22 13:40:49.30: [ 2956]: RECV received frame number 24
Sep 22 13:40:49.44: [ 2956]: RECV received frame number 25
Sep 22 13:40:49.58: [ 2956]: RECV received frame number 26
Sep 22 13:40:49.72: [ 2956]: RECV received frame number 27
Sep 22 13:40:49.85: [ 2956]: RECV received frame number 28
Sep 22 13:40:49.99: [ 2956]: RECV received frame number 29
Sep 22 13:40:50.20: [ 2956]: RECV received frame number 30
Sep 22 13:40:50.34: [ 2956]: RECV received frame number 31
Sep 22 13:40:50.48: [ 2956]: RECV received frame number 32
Sep 22 13:40:50.61: [ 2956]: RECV received frame number 33
Sep 22 13:40:50.75: [ 2956]: RECV received frame number 34
Sep 22 13:40:50.88: [ 2956]: RECV received frame number 35
Sep 22 13:40:51.02: [ 2956]: RECV received frame number 36
Sep 22 13:40:51.22: [ 2956]: RECV received frame number 37
Sep 22 13:40:51.36: [ 2956]: RECV received frame number 38
Sep 22 13:40:51.50: [ 2956]: RECV received frame number 39
Sep 22 13:40:51.63: [ 2956]: RECV received frame number 40
Sep 22 13:40:51.77: [ 2956]: RECV received frame number 41
Sep 22 13:40:51.91: [ 2956]: RECV received frame number 42
Sep 22 13:40:52.05: [ 2956]: RECV received frame number 43
Sep 22 13:40:52.19: [ 2956]: RECV received frame number 44
Sep 22 13:40:52.39: [ 2956]: RECV received frame number 45
Sep 22 13:40:52.53: [ 2956]: RECV received frame number 46
Sep 22 13:40:52.67: [ 2956]: RECV received frame number 47
Sep 22 13:40:52.80: [ 2956]: RECV received frame number 48
Sep 22 13:40:52.94: [ 2956]: RECV received frame number 49
Sep 22 13:40:53.08: [ 2956]: RECV received frame number 50
Sep 22 13:40:53.22: [ 2956]: RECV received frame number 51
Sep 22 13:40:53.43: [ 2956]: RECV received frame number 52
Sep 22 13:40:53.56: [ 2956]: RECV received frame number 53
Sep 22 13:40:53.70: [ 2956]: RECV received frame number 54
Sep 22 13:40:53.84: [ 2956]: RECV received frame number 55
Sep 22 13:40:53.97: [ 2956]: RECV received frame number 56
Sep 22 13:40:54.11: [ 2956]: RECV received frame number 57
Sep 22 13:40:54.24: [ 2956]: RECV received frame number 58
Sep 22 13:40:54.45: [ 2956]: RECV received frame number 59
Sep 22 13:40:54.58: [ 2956]: RECV received frame number 60
Sep 22 13:40:54.72: [ 2956]: RECV received frame number 61
Sep 22 13:40:54.86: [ 2956]: RECV received frame number 62
Sep 22 13:40:55.00: [ 2956]: RECV received frame number 63
Sep 22 13:40:55.14: [ 2956]: RECV received frame number 64
Sep 22 13:40:55.28: [ 2956]: RECV received frame number 65
Sep 22 13:40:55.42: [ 2956]: RECV received frame number 66
Sep 22 13:40:55.63: [ 2956]: RECV received frame number 67
Sep 22 13:40:55.77: [ 2956]: RECV received frame number 68
Sep 22 13:40:55.91: [ 2956]: RECV received frame number 69
Sep 22 13:40:56.06: [ 2956]: RECV received frame number 70
Sep 22 13:40:56.20: [ 2956]: RECV received frame number 71
Sep 22 13:40:56.34: [ 2956]: RECV received frame number 72
Sep 22 13:40:56.48: [ 2956]: RECV received frame number 73
Sep 22 13:40:56.69: [ 2956]: RECV received frame number 74
Sep 22 13:40:56.83: [ 2956]: RECV received frame number 75
Sep 22 13:40:56.97: [ 2956]: RECV received frame number 76
Sep 22 13:40:57.11: [ 2956]: RECV received frame number 77
Sep 22 13:40:57.25: [ 2956]: RECV received frame number 78
Sep 22 13:40:57.39: [ 2956]: RECV received frame number 79
Sep 22 13:40:57.61: [ 2956]: RECV assumed RCP frame with block end
Sep 22 13:40:57.61: [ 2956]: --> [10:NO CARRIER]
Sep 22 13:40:57.61: [ 2956]: <-- [9:AT+FRH=3\r]
Sep 22 13:41:03.87: [ 2956]: --> [7:CONNECT]
Sep 22 13:41:13.87: [ 2956]: <-- data [1]
Sep 22 13:41:13.88: [ 2956]: --> [2:OK]
Sep 22 13:41:13.88: [ 2956]: RECV keeping unconfirmed page
Sep 22 13:41:14.03: [ 2956]: RECV: 20480 bytes of data, 1738 total lines
Sep 22 13:41:14.03: [ 2956]: RECV FAX (000000007): from +XXXYY3007ZZZ, page 1 in 0:39, INF, 7.7 line/mm, 2-D MMR, 14400 bit/s
Sep 22 13:41:14.03: [ 2956]: RECV FAX (000000007): recvq/fax000000007.tif from +XXXYY3007ZZZ, route to <unspecified>, 1 pages in 0:39
Sep 22 13:41:14.03: [ 2956]: RECV FAX: T.30 T2 timeout, expected signal not received
Sep 22 13:41:14.03: [ 2956]: <-- [9:AT+FTH=3\r]
Sep 22 13:41:14.07: [ 2956]: --> [7:CONNECT]
Sep 22 13:41:14.07: [ 2956]: <-- data [3]
Sep 22 13:41:14.07: [ 2956]: <-- data [2]
Sep 22 13:41:15.24: [ 2956]: --> [2:OK]
Sep 22 13:41:15.24: [ 2956]: RECV FAX (000000007): session with +XXXYY3007ZZZ terminated abnormally: T.30 T2 timeout, expected signal not received
Sep 22 13:41:15.24: [ 2956]: RECV FAX: bin/faxrcvd "recvq/fax000000007.tif" "ttyS3" "000000007" "T.30 T2 timeout, expected signal not received"
Sep 22 13:41:15.25: [ 2956]: RECV FAX: end
Sep 22 13:41:15.25: [ 2956]: 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*




Project hosted by iFAX Solutions