![]() |
On Tue, Mar 21, 2006, Lee Howard wrote: >Bill Campbell wrote: > ...logs deleted... > >Notice the double "FAX" response from the modem. It says "FAX" twice at >15:18:11.56. So misbehavior by the modem is causing HylaFAX to trip >over itself here. > >I've seen Agere-chipsetted modems do this kind of thing before (after >ATZ is my memory). > >This may be fixed in a newer firmware (I don't know), but you're welcome >to try the attached patch, also, which should keep HylaFAX from tripping >up on it. I just built hylafax-4.2.5 with that patch, and still get failures when I enable adaptive answer. This is the answering log: Mar 21 16:47:08.19: [ 5676]: SESSION BEGIN 000005548 12062329186 Mar 21 16:47:08.19: [ 5676]: HylaFAX (tm) Version 4.2.5 Mar 21 16:47:08.19: [ 5676]: <-- [4:ATA\r] Mar 21 16:47:15.74: [ 5676]: --> [3:FAX] Mar 21 16:47:15.74: [ 5676]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyS0' Mar 21 16:47:15.74: [ 5676]: RECV FAX: begin Mar 21 16:47:15.74: [ 5676]: MODEM flush i/o Mar 21 16:47:15.74: [ 5676]: <-- data [32] Mar 21 16:47:15.74: [ 5676]: <-- data [2] Mar 21 16:47:20.02: [ 5676]: --> [6:^H ^H^H ^H] Mar 21 16:47:20.02: [ 5676]: DELAY 1500 ms Mar 21 16:47:21.52: [ 5676]: <-- [9:AT+FTH=3\r] Mar 21 16:47:21.52: [ 5676]: --> [7:CONNECT] Mar 21 16:47:21.52: [ 5676]: <-- data [32] Mar 21 16:47:21.52: [ 5676]: <-- data [2] Mar 21 16:47:22.92: [ 5676]: --> [2:OK] Mar 21 16:47:22.92: [ 5676]: DELAY 1500 ms The other side went away about here. Mar 21 16:47:24.42: [ 5676]: <-- [9:AT+FTH=3\r] Mar 21 16:47:25.38: [ 5676]: --> [7:CONNECT] Mar 21 16:47:25.38: [ 5676]: <-- data [32] Mar 21 16:47:25.38: [ 5676]: <-- data [2] Mar 21 16:47:25.39: [ 5676]: --> [7:CONNECT] Mar 21 16:47:25.39: [ 5676]: <-- data [23] Mar 21 16:47:25.39: [ 5676]: <-- data [2] Mar 21 16:47:25.40: [ 5676]: --> [7:CONNECT] Mar 21 16:47:25.40: [ 5676]: <-- data [13] Mar 21 16:47:25.40: [ 5676]: <-- data [2] Mar 21 16:47:27.68: [ 5676]: --> [2:OK] Mar 21 16:47:27.68: [ 5676]: <-- [9:AT+FRH=3\r] Mar 21 16:47:34.67: [ 5676]: --> [0:] Mar 21 16:47:34.67: [ 5676]: MODEM <Empty line> Mar 21 16:47:34.67: [ 5676]: MODEM TIMEOUT: waiting for v.21 carrier Mar 21 16:47:34.67: [ 5676]: <-- data [1] Mar 21 16:47:34.68: [ 5676]: --> [2:] Mar 21 16:47:34.68: [ 5676]: --> [2:OK] Mar 21 16:47:34.68: [ 5676]: DELAY 1500 ms Mar 21 16:47:36.18: [ 5676]: <-- [9:AT+FTH=3\r] Mar 21 16:47:37.14: [ 5676]: --> [7:CONNECT] Mar 21 16:47:37.14: [ 5676]: <-- data [32] Mar 21 16:47:37.14: [ 5676]: <-- data [2] Mar 21 16:47:37.15: [ 5676]: --> [7:CONNECT] Mar 21 16:47:37.15: [ 5676]: <-- data [23] Mar 21 16:47:37.15: [ 5676]: <-- data [2] Mar 21 16:47:37.16: [ 5676]: --> [7:CONNECT] Mar 21 16:47:37.16: [ 5676]: <-- data [13] Mar 21 16:47:37.16: [ 5676]: <-- data [2] Mar 21 16:47:39.43: [ 5676]: --> [2:OK] Mar 21 16:47:39.43: [ 5676]: <-- [9:AT+FRH=3\r] Mar 21 16:47:46.43: [ 5676]: --> [0:] Mar 21 16:47:46.43: [ 5676]: MODEM <Empty line> Mar 21 16:47:46.43: [ 5676]: MODEM TIMEOUT: waiting for v.21 carrier Mar 21 16:47:46.43: [ 5676]: <-- data [1] Mar 21 16:47:46.44: [ 5676]: --> [2:] Mar 21 16:47:46.44: [ 5676]: --> [2:OK] Mar 21 16:47:46.44: [ 5676]: DELAY 1500 ms Mar 21 16:47:47.94: [ 5676]: <-- [9:AT+FTH=3\r] Mar 21 16:47:48.89: [ 5676]: --> [7:CONNECT] Mar 21 16:47:48.89: [ 5676]: <-- data [32] Mar 21 16:47:48.89: [ 5676]: <-- data [2] Mar 21 16:47:48.90: [ 5676]: --> [7:CONNECT] Mar 21 16:47:48.90: [ 5676]: <-- data [23] Mar 21 16:47:48.90: [ 5676]: <-- data [2] Mar 21 16:47:48.91: [ 5676]: --> [7:CONNECT] Mar 21 16:47:48.91: [ 5676]: <-- data [13] Mar 21 16:47:48.91: [ 5676]: <-- data [2] Mar 21 16:47:51.19: [ 5676]: --> [2:OK] Mar 21 16:47:51.19: [ 5676]: <-- [9:AT+FRH=3\r] Mar 21 16:47:58.19: [ 5676]: --> [0:] Mar 21 16:47:58.19: [ 5676]: MODEM <Empty line> Mar 21 16:47:58.19: [ 5676]: MODEM TIMEOUT: waiting for v.21 carrier Mar 21 16:47:58.19: [ 5676]: <-- data [1] Mar 21 16:47:58.19: [ 5676]: --> [2:] Mar 21 16:47:58.19: [ 5676]: --> [2:OK] Mar 21 16:47:58.19: [ 5676]: RECV FAX: No answer (T.30 T1 timeout) Mar 21 16:47:58.19: [ 5676]: RECV FAX: end Mar 21 16:47:58.19: [ 5676]: SESSION END This is the sending log: Mar 21 16:46:53.26: [29513]: SESSION BEGIN 000000139 12062329186 Mar 21 16:46:53.26: [29513]: HylaFAX (tm) Version 4.2.3 Mar 21 16:46:53.26: [29513]: SEND FAX: JOB 92 DEST 232-9186 COMMID 000000139 DEVICE '/dev/ttyS0' FROM 'Bill Campbell <bill@xxxxxxxxxxxxxxxxxxxxx>' USER bill Mar 21 16:46:53.26: [29513]: <-- [9:AT+FLO=1\r] Mar 21 16:46:53.43: [29513]: --> [2:OK] Mar 21 16:46:53.43: [29513]: <-- [10:AT+FTBC=0\r] Mar 21 16:46:53.59: [29513]: --> [2:OK] Mar 21 16:46:53.59: [29513]: <-- [10:AT+FBOR=0\r] Mar 21 16:46:53.76: [29513]: --> [2:OK] Mar 21 16:46:53.76: [29513]: <-- [13:AT+FPHCTO=30\r] Mar 21 16:46:53.92: [29513]: --> [2:OK] Mar 21 16:46:53.92: [29513]: <-- [24:AT+FDCC=1,5,2,2,2,0,0,0\r] Mar 21 16:46:54.09: [29513]: --> [2:OK] Mar 21 16:46:54.09: [29513]: DIAL 2329186 Mar 21 16:46:54.10: [29513]: <-- [17:ATDTW*70W2329186\r] Mar 21 16:47:18.75: [29513]: --> [5:+FCON] Mar 21 16:47:23.99: [29513]: --> [8:+FHNG:22] Mar 21 16:47:23.99: [29513]: REMOTE HANGUP: COMREC error in transmit Phase B/got DCN (code 22) Mar 21 16:47:23.99: [29513]: SEND FAILED: JOB 92 DEST 232-9186 ERR COMREC error in transmit Phase B/got DCN Mar 21 16:47:23.99: [29513]: <-- [5:ATH0\r] Mar 21 16:47:28.99: [29513]: MODEM <Timeout> Mar 21 16:47:28.99: [29513]: SESSION END Bill -- INTERNET: bill@xxxxxxxxxxxxx Bill Campbell; Celestial Software LLC URL: http://www.celestial.com/ PO Box 820; 6641 E. Mercer Way FAX: (206) 232-9186 Mercer Island, WA 98040-0820; (206) 236-1676 When a place gets crowded enough to require ID's, social collapse is not far away. It is time to go elsewhere. The best thing about space travel is that it made it possible to go elsewhere. -- Robert Heinlein ____________________ 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*