[hylafax-users] Infinite loop, defunct faxgetty

[Giulio Orsero <giulioo@xxxxxxxxx>]

linux red hat 6.x
sending: 4.2.0-cvs20041001 rockwell
receiving: 4.2.0-cvs20041001 courier


receiving:
==
Jan 03 16:28:55.11: [ 1594]: SESSION BEGIN 000015196 390110000000
Jan 03 16:28:55.11: [ 1594]: HylaFAX (tm) Version 4.2.0-cvs20041001
Jan 03 16:28:55.11: [ 1594]: <-- [13:AT+FCLASS=1A\r]
Jan 03 16:29:00.50: [ 1594]: --> [7:CONNECT]
Jan 03 16:29:00.50: [ 1594]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Jan 03 16:29:00.50: [ 1594]: sched policy=1, priority=1
Jan 03 16:29:00.50: [ 1594]: RECV FAX: begin
Jan 03 16:29:00.50: [ 1594]: <-- data [44]
Jan 03 16:29:00.50: [ 1594]: <-- data [2]
Jan 03 16:29:02.70: [ 1594]: --> [7:CONNECT]
Jan 03 16:29:02.70: [ 1594]: <-- data [23]
Jan 03 16:29:02.70: [ 1594]: <-- data [2]
Jan 03 16:29:03.85: [ 1594]: --> [7:CONNECT]
Jan 03 16:29:03.85: [ 1594]: <-- data [10]
Jan 03 16:29:03.85: [ 1594]: <-- data [2]
Jan 03 16:29:04.66: [ 1594]: --> [2:OK]
Jan 03 16:29:04.66: [ 1594]: <-- [9:AT+FRH=3\r]
Jan 03 16:29:05.83: [ 1594]: --> [7:CONNECT]
Jan 03 16:29:06.50: [ 1594]: --> [2:OK]
Jan 03 16:29:06.50: [ 1594]: REMOTE TSI "+39 000 000000"
Jan 03 16:29:06.50: [ 1594]: <-- [9:AT+FRH=3\r]
Jan 03 16:29:06.67: [ 1594]: --> [7:CONNECT]
Jan 03 16:29:06.91: [ 1594]: --> [2:OK]
Jan 03 16:29:06.91: [ 1594]: REMOTE wants 14400 bit/s
Jan 03 16:29:06.91: [ 1594]: REMOTE wants A4 page width (215 mm)
Jan 03 16:29:06.91: [ 1594]: REMOTE wants unlimited page length
Jan 03 16:29:06.91: [ 1594]: REMOTE wants 7.7 line/mm
Jan 03 16:29:06.91: [ 1594]: REMOTE wants 2-D MMR
Jan 03 16:29:06.91: [ 1594]: REMOTE wants T.30 Annex A, 256-byte ECM
Jan 03 16:29:06.91: [ 1594]: RECV training at v.17 14400 bit/s
Jan 03 16:29:06.91: [ 1594]: <-- [11:AT+FRM=145\r]
Jan 03 16:29:08.73: [ 1594]: --> [7:CONNECT]
Jan 03 16:29:10.29: [ 1594]: RECV: TCF 2826 bytes, 3% non-zero, 2700
zero-run
Jan 03 16:29:10.32: [ 1594]: --> [10:NO CARRIER]
Jan 03 16:29:10.32: [ 1594]: DELAY 75 ms
Jan 03 16:29:10.40: [ 1594]: TRAINING succeeded
Jan 03 16:29:10.40: [ 1594]: <-- [9:AT+FTH=3\r]
Jan 03 16:29:10.52: [ 1594]: --> [7:CONNECT]
Jan 03 16:29:10.52: [ 1594]: <-- data [3]
Jan 03 16:29:10.52: [ 1594]: <-- data [2]
Jan 03 16:29:11.73: [ 1594]: --> [2:OK]
Jan 03 16:29:11.73: [ 1594]: <-- [11:AT+FRM=146\r]
Jan 03 16:29:12.40: [ 1594]: --> [7:CONNECT]
Jan 03 16:29:12.68: [ 1594]: Bad HDLC terminating flag received.
...
... the same over and over
...
Jan 03 16:34:10.97: [ 1594]: Bad HDLC terminating flag received.
Jan 03 16:34:11.25: [ 1594]: Bad HDLC terminating flag received.
Jan 03 16:34:11.46: [ 1594]: Bad HDLC terminating flag received.
Jan 03 16:34:11.74: [ 1594]: Bad HDLC terminating flag received.
Jan 03 16:34:12.02: [ 1594]: Bad HDLC terminating flag received.
... I Killed it:
Jan 03 16:38:38.80: [ 1594]: CLOSE /dev/ttyS0


If trying without ecm I get the same, it blocks after
Jan 03 16:21:49.88: [ 1414]: RECV: begin page
for more than 5 minutes.

Sending (system clock is about 2min30sec out of sync):
Jan 03 16:31:41.65: [11057]: TRAINING succeeded
Jan 03 16:31:41.66: [11057]: SEND begin page
Jan 03 16:31:41.66: [11057]: Reading MR-compressed image file
Jan 03 16:31:41.66: [11057]: SEND EOFB
Jan 03 16:31:41.66: [11057]: SEND send frame number 0
Jan 03 16:31:41.66: [11057]: SEND send frame number 1
Jan 03 16:31:41.66: [11057]: SEND send frame number 2
Jan 03 16:31:41.66: [11057]: SEND send frame number 3
Jan 03 16:31:41.66: [11057]: SEND send frame number 4
Jan 03 16:31:41.66: [11057]: SEND send frame number 5
Jan 03 16:31:41.66: [11057]: DELAY 200 ms
Jan 03 16:31:41.85: [11057]: <-- [11:AT+FTM=146\r]
Jan 03 16:31:41.89: [11057]: --> [7:CONNECT]
Jan 03 16:31:41.89: [11057]: <-- data [1031]
Jan 03 16:31:41.90: [11057]: <-- data [1028]
Jan 03 16:31:41.90: [11057]: <-- data [2]
Jan 03 16:31:43.41: [11057]: --> [2:OK]
Jan 03 16:31:43.41: [11057]: <-- [9:AT+FTS=9\r]
Jan 03 16:31:43.53: [11057]: --> [2:OK]
Jan 03 16:31:43.53: [11057]: <-- [9:AT+FTH=3\r]
Jan 03 16:31:43.58: [11057]: --> [7:CONNECT]
Jan 03 16:31:43.58: [11057]: <-- data [7]
Jan 03 16:31:43.58: [11057]: <-- data [2]
Jan 03 16:31:44.98: [11057]: --> [2:OK]
Jan 03 16:31:44.98: [11057]: SEND send PPS (partial page signal)
Jan 03 16:31:44.98: [11057]: SEND send EOP (no more pages or documents)
Jan 03 16:31:44.98: [11057]: <-- [9:AT+FRH=3\r]
Jan 03 16:31:48.08: [11057]: --> [0:]
Jan 03 16:31:48.08: [11057]: MODEM <Empty line>
Jan 03 16:31:48.08: [11057]: <-- data [1]
Jan 03 16:31:48.14: [11057]: --> [2:OK]
Jan 03 16:31:48.14: [11057]: <-- [9:AT+FTH=3\r]
Jan 03 16:31:48.18: [11057]: --> [7:CONNECT]
Jan 03 16:31:48.18: [11057]: <-- data [7]
Jan 03 16:31:48.18: [11057]: <-- data [2]
Jan 03 16:31:49.59: [11057]: --> [2:OK]
Jan 03 16:31:49.59: [11057]: SEND send PPS (partial page signal)
Jan 03 16:31:49.59: [11057]: SEND send EOP (no more pages or documents)
Jan 03 16:31:49.59: [11057]: <-- [9:AT+FRH=3\r]
Jan 03 16:31:52.69: [11057]: --> [0:]
Jan 03 16:31:52.69: [11057]: MODEM <Empty line>
Jan 03 16:31:52.69: [11057]: <-- data [1]
Jan 03 16:31:52.75: [11057]: --> [2:OK]
Jan 03 16:31:52.75: [11057]: <-- [9:AT+FTH=3\r]
Jan 03 16:31:52.79: [11057]: --> [7:CONNECT]
Jan 03 16:31:52.79: [11057]: <-- data [7]
Jan 03 16:31:52.79: [11057]: <-- data [2]
Jan 03 16:31:54.20: [11057]: --> [2:OK]
Jan 03 16:31:54.20: [11057]: SEND send PPS (partial page signal)
Jan 03 16:31:54.20: [11057]: SEND send EOP (no more pages or documents)
Jan 03 16:31:54.20: [11057]: <-- [9:AT+FRH=3\r]
Jan 03 16:31:57.30: [11057]: --> [0:]
Jan 03 16:31:57.30: [11057]: MODEM <Empty line>
Jan 03 16:31:57.30: [11057]: <-- data [1]
Jan 03 16:31:57.36: [11057]: --> [2:OK]
Jan 03 16:31:57.36: [11057]: No response to PPS repeated 3 times.
Jan 03 16:31:57.36: [11057]: SEND end page
Jan 03 16:31:57.36: [11057]: <-- [9:AT+FTH=3\r]
Jan 03 16:31:57.40: [11057]: --> [7:CONNECT]
Jan 03 16:31:57.40: [11057]: <-- data [3]
Jan 03 16:31:57.40: [11057]: <-- data [2]
Jan 03 16:31:58.70: [11057]: --> [2:OK]
Jan 03 16:31:58.70: [11057]: <-- [5:ATH0\r]
Jan 03 16:31:59.62: [11057]: --> [2:OK]
Jan 03 16:31:59.62: [11057]: sched policy=0, priority=0
Jan 03 16:31:59.62: [11057]: SESSION END

At the end on the receiving side I get a stuck defunct faxgetty process.
The receiving keeps waiting even if sending is already gone.

I understand there may be line/noise issue, but is this normal behavior? Is
it possible bad line conditions triggered some kind of unexpected behavior?

Thanks

-- 
giulioo@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*