Re: [hylafax-users] mps,rtn, canon fax machines

[dobos_s@IBCnet.hu]

>No, you're not using the +FTS patch.

Yes, I said I tried... :-))) but reverted, because the result was the same.
(That log attached below)

>At this point it pretty much indicates that the page we just sent never
>got there or we lost synchronicity just now because the remote is a bit
>slow.  In your case, because we repeat the MPS signal three times and hear

>no response, we can assume the prior (that they never got the page).

I tried to lower class1 speed with make some comment-outs in source
(why is there no config options to do this), but at 9600 the result was the
same.

>Using the +FTS patch is a good place to start to diagnose this.  Upgrade
>to 4.1.1 (it has the +FTS patch and more goodies), and if that doesn't
>help you'll have to start adjusting Class1Send*WaitCmd parameters.

That is history :-)) I have done this except upgrading to 4.1.1. After
reading changelog I found nothing interesting
to me but faxrcvd-mail related uncompatibilities for which I dont have time
to modify my setup.

Somebody has idea how to log the commands what win$ faxprograms send to the
modem when they are succesfull?
I will try it out and send the result, maybe it helps!

log with FTS patch:

Feb 22 13:37:50.71: [20752]: SESSION BEGIN 00004206 3613689632
Feb 22 13:37:50.71: [20752]: HylaFAX (tm) Version 4.1
Feb 22 13:37:50.71: [20752]: SEND FAX: JOB 182 DEST 3689632 COMMID 00004206
Feb 22 13:37:50.71: [20752]: DEVICE '/dev/ttyS0'
Feb 22 13:37:50.71: [20752]: DELAY 75 ms
Feb 22 13:37:50.79: [20752]: DELAY 2600 ms
Feb 22 13:37:53.39: [20752]: <-- [4:ATZ\r]
Feb 22 13:37:53.47: [20752]: --> [2:OK]
Feb 22 13:37:53.47: [20752]: <-- [5:AT&F\r]
Feb 22 13:37:53.48: [20752]: --> [4:AT&F]
Feb 22 13:37:53.48: [20752]: --> [2:OK]
Feb 22 13:37:53.48: [20752]: <-- [5:ATE0\r]
Feb 22 13:37:53.49: [20752]: --> [4:ATE0]
Feb 22 13:37:53.49: [20752]: --> [2:OK]
Feb 22 13:37:53.49: [20752]: <-- [5:ATV1\r]
Feb 22 13:37:53.50: [20752]: --> [2:OK]
Feb 22 13:37:53.50: [20752]: <-- [5:ATQ0\r]
Feb 22 13:37:53.51: [20752]: --> [2:OK]
Feb 22 13:37:53.51: [20752]: <-- [7:ATS0=0\r]
Feb 22 13:37:53.52: [20752]: --> [2:OK]
Feb 22 13:37:53.52: [20752]: <-- [7:ATS8=2\r]
Feb 22 13:37:53.53: [20752]: --> [2:OK]
Feb 22 13:37:53.53: [20752]: <-- [8:ATS7=60\r]
Feb 22 13:37:53.54: [20752]: --> [2:OK]
Feb 22 13:37:53.54: [20752]: <-- [6:AT&K3\r]
Feb 22 13:37:53.55: [20752]: --> [2:OK]
Feb 22 13:37:53.55: [20752]: <-- [6:AT&D2\r]
Feb 22 13:37:53.56: [20752]: --> [2:OK]
Feb 22 13:37:53.56: [20752]: <-- [6:AT&C1\r]
Feb 22 13:37:53.57: [20752]: --> [2:OK]
Feb 22 13:37:53.57: [20752]: <-- [12:AT+FCLASS=1\r]
Feb 22 13:37:53.58: [20752]: --> [2:OK]
Feb 22 13:37:53.58: [20752]: <-- [19:AT+FCLASS=0;+FAE=1\r]
Feb 22 13:37:53.59: [20752]: --> [2:OK]
Feb 22 13:37:53.59: [20752]: <-- [5:ATM0\r]
Feb 22 13:37:53.60: [20752]: --> [2:OK]
Feb 22 13:37:53.60: [20752]: <-- [12:AT+FCLASS=1\r]
Feb 22 13:37:53.71: [20752]: --> [2:OK]
Feb 22 13:37:53.72: [20752]: DIAL 3689632
Feb 22 13:37:53.72: [20752]: <-- [14:ATDT0,3689632\r]
Feb 22 13:38:12.30: [20752]: --> [7:CONNECT]
Feb 22 13:38:14.05: [20752]: --> [2:OK]
Feb 22 13:38:14.05: [20752]: REMOTE NSF "00 00 11 80 00 8A 49 10 42 4D 46
20 4E 49 4B 20 20 20 20 20 20 20 20 20 00 59 00 80 80 D8 86 01 01 01 01 00
BB"
Feb 22 13:38:14.05: [20752]: NSF remote fax equipment: Canon
Feb 22 13:38:14.05: [20752]: NSF remote station ID: "BMF NIK         "
Feb 22 13:38:14.05: [20752]: <-- [9:AT+FRH=3\r]
Feb 22 13:38:14.06: [20752]: --> [7:CONNECT]
Feb 22 13:38:14.75: [20752]: --> [2:OK]
Feb 22 13:38:14.75: [20752]: REMOTE CSI "+36 1 368 9632"
Feb 22 13:38:14.75: [20752]: <-- [9:AT+FRH=3\r]
Feb 22 13:38:14.76: [20752]: --> [7:CONNECT]
Feb 22 13:38:15.01: [20752]: --> [2:OK]
Feb 22 13:38:15.01: [20752]: REMOTE best rate 14400 bit/s
Feb 22 13:38:15.01: [20752]: REMOTE max page width 1728 pixels in 215 mm
Feb 22 13:38:15.01: [20752]: REMOTE max unlimited page length
Feb 22 13:38:15.01: [20752]: REMOTE best vres 7.7 line/mm
Feb 22 13:38:15.01: [20752]: REMOTE best format 2-D MMR
Feb 22 13:38:15.01: [20752]: REMOTE supports T.30 Annex A, ECM
Feb 22 13:38:15.01: [20752]: REMOTE best 10 ms/scanline
Feb 22 13:38:15.01: [20752]: USE 14400 bit/s
Feb 22 13:38:15.01: [20752]: USE 10 ms/scanline
Feb 22 13:38:15.01: [20752]: SEND file "docq/doc182.ps;40"
Feb 22 13:38:15.01: [20752]: USE page width 1728 pixels in 215 mm
Feb 22 13:38:15.01: [20752]: USE unlimited page length
Feb 22 13:38:15.01: [20752]: USE 3.85 line/mm
Feb 22 13:38:15.01: [20752]: USE 2-D MR
Feb 22 13:38:15.01: [20752]: SEND training at v.17 14400 bit/s
Feb 22 13:38:15.01: [20752]: <-- [9:AT+FTH=3\r]
Feb 22 13:38:15.04: [20752]: --> [7:CONNECT]
Feb 22 13:38:15.04: [20752]: <-- data [23]
Feb 22 13:38:15.04: [20752]: <-- data [2]
Feb 22 13:38:16.83: [20752]: --> [7:CONNECT]
Feb 22 13:38:16.83: [20752]: <-- data [6]
Feb 22 13:38:16.83: [20752]: <-- data [2]
Feb 22 13:38:17.27: [20752]: --> [2:OK]
Feb 22 13:38:17.27: [20752]: <-- [9:AT+FTS=7\r]
Feb 22 13:38:17.36: [20752]: --> [2:OK]
Feb 22 13:38:17.36: [20752]: <-- [11:AT+FTM=145\r]
Feb 22 13:38:17.39: [20752]: --> [7:CONNECT]
Feb 22 13:38:17.39: [20752]: <-- data [1024]
Feb 22 13:38:17.39: [20752]: <-- data [1024]
Feb 22 13:38:17.39: [20752]: <-- data [652]
Feb 22 13:38:17.39: [20752]: <-- data [2]
Feb 22 13:38:20.51: [20752]: --> [2:OK]
Feb 22 13:38:20.51: [20752]: <-- [9:AT+FRH=3\r]
Feb 22 13:38:20.90: [20752]: --> [7:CONNECT]
Feb 22 13:38:21.79: [20752]: --> [2:OK]
Feb 22 13:38:21.79: [20752]: TRAINING succeeded
Feb 22 13:38:21.79: [20752]: <-- [11:AT+FTM=146\r]
Feb 22 13:38:21.82: [20752]: --> [7:CONNECT]
Feb 22 13:38:21.82: [20752]: SEND begin page
Feb 22 13:38:21.82: [20752]: <-- data [1025]
Feb 22 13:38:21.82: [20752]: <-- data [1024]
Feb 22 13:38:21.82: [20752]: <-- data [1028]
Feb 22 13:38:21.82: [20752]: <-- data [1030]
Feb 22 13:38:21.82: [20752]: <-- data [1032]
Feb 22 13:38:22.38: [20752]: <-- data [1031]
Feb 22 13:38:22.92: [20752]: <-- data [1028]
Feb 22 13:38:23.46: [20752]: <-- data [515]
Feb 22 13:38:23.72: [20752]: SENT 7676 bytes of data
Feb 22 13:38:23.72: [20752]: SEND 2D RTC
Feb 22 13:38:23.72: [20752]: <-- data [30]
Feb 22 13:38:23.72: [20752]: <-- data [2]
Feb 22 13:38:23.72: [20752]: SEND end page
Feb 22 13:38:26.47: [20752]: --> [2:OK]
Feb 22 13:38:26.47: [20752]: <-- [9:AT+FTS=9\r]
Feb 22 13:38:26.58: [20752]: --> [2:OK]
Feb 22 13:38:26.58: [20752]: SEND send EOP (no more pages or documents)
Feb 22 13:38:26.58: [20752]: <-- [9:AT+FTH=3\r]
Feb 22 13:38:26.61: [20752]: --> [7:CONNECT]
Feb 22 13:38:26.61: [20752]: <-- data [3]
Feb 22 13:38:26.61: [20752]: <-- data [2]
Feb 22 13:38:27.93: [20752]: --> [2:OK]
Feb 22 13:38:27.93: [20752]: <-- [9:AT+FRH=3\r]
Feb 22 13:38:28.25: [20752]: --> [7:CONNECT]
Feb 22 13:38:29.06: [20752]: --> [2:OK]
Feb 22 13:38:29.06: [20752]: SEND recv RTN (retrain negative)
Feb 22 13:38:29.06: [20752]: SEND training at v.17 12000 bit/s
Feb 22 13:38:29.06: [20752]: <-- [9:AT+FTH=3\r]
Feb 22 13:38:29.11: [20752]: --> [7:CONNECT]
Feb 22 13:38:29.11: [20752]: <-- data [23]
Feb 22 13:38:29.11: [20752]: <-- data [2]
Feb 22 13:38:30.89: [20752]: --> [7:CONNECT]
Feb 22 13:38:30.89: [20752]: <-- data [6]
Feb 22 13:38:30.89: [20752]: <-- data [2]
Feb 22 13:38:31.33: [20752]: --> [2:OK]
Feb 22 13:38:31.33: [20752]: <-- [9:AT+FTS=7\r]
Feb 22 13:38:31.42: [20752]: --> [2:OK]
Feb 22 13:38:31.42: [20752]: <-- [11:AT+FTM=121\r]
Feb 22 13:38:36.57: [20752]: --> [7:CONNECT]
Feb 22 13:38:36.57: [20752]: <-- data [1024]
Feb 22 13:38:36.57: [20752]: <-- data [1024]
Feb 22 13:38:36.57: [20752]: <-- data [202]
Feb 22 13:38:36.57: [20752]: <-- data [2]
Feb 22 13:38:39.68: [20752]: --> [2:OK]
Feb 22 13:38:39.68: [20752]: <-- [9:AT+FRH=3\r]
Feb 22 13:38:42.78: [20752]: --> [0:]
Feb 22 13:38:42.78: [20752]: MODEM <Empty line>
Feb 22 13:38:42.78: [20752]: <-- data [1]
Feb 22 13:38:42.83: [20752]: --> [2:OK]
Feb 22 13:38:42.83: [20752]: DELAY 1500 ms
Feb 22 13:38:44.33: [20752]: SEND training at v.17 9600 bit/s
Feb 22 13:38:44.33: [20752]: <-- [9:AT+FTH=3\r]
Feb 22 13:38:44.36: [20752]: --> [7:CONNECT]
Feb 22 13:38:44.36: [20752]: <-- data [23]
Feb 22 13:38:44.36: [20752]: <-- data [2]
Feb 22 13:38:46.15: [20752]: --> [7:CONNECT]
Feb 22 13:38:46.15: [20752]: <-- data [6]
Feb 22 13:38:46.15: [20752]: <-- data [2]
Feb 22 13:38:46.59: [20752]: --> [2:OK]
Feb 22 13:38:46.59: [20752]: <-- [9:AT+FTS=7\r]
Feb 22 13:38:46.67: [20752]: --> [2:OK]
Feb 22 13:38:46.67: [20752]: <-- [10:AT+FTM=97\r]
Feb 22 13:38:46.70: [20752]: --> [7:CONNECT]
Feb 22 13:38:46.70: [20752]: <-- data [1024]
Feb 22 13:38:46.70: [20752]: <-- data [776]
Feb 22 13:38:46.70: [20752]: <-- data [2]
Feb 22 13:38:49.82: [20752]: --> [2:OK]
Feb 22 13:38:49.82: [20752]: <-- [9:AT+FRH=3\r]
Feb 22 13:38:52.92: [20752]: --> [0:]
Feb 22 13:38:52.92: [20752]: MODEM <Empty line>
Feb 22 13:38:52.92: [20752]: <-- data [1]
Feb 22 13:38:52.97: [20752]: --> [2:OK]
Feb 22 13:38:52.97: [20752]: DELAY 1500 ms
Feb 22 13:38:54.47: [20752]: SEND training at v.17 7200 bit/s
Feb 22 13:38:54.47: [20752]: <-- [9:AT+FTH=3\r]
Feb 22 13:38:54.50: [20752]: --> [7:CONNECT]
Feb 22 13:38:54.50: [20752]: <-- data [23]
Feb 22 13:38:54.50: [20752]: <-- data [2]
Feb 22 13:38:56.29: [20752]: --> [7:CONNECT]
Feb 22 13:38:56.29: [20752]: <-- data [6]
Feb 22 13:38:56.29: [20752]: <-- data [2]
Feb 22 13:38:56.73: [20752]: --> [2:OK]
Feb 22 13:38:56.73: [20752]: <-- [9:AT+FTS=7\r]
Feb 22 13:38:56.81: [20752]: --> [2:OK]
Feb 22 13:38:56.81: [20752]: <-- [10:AT+FTM=73\r]
Feb 22 13:38:56.84: [20752]: --> [7:CONNECT]
Feb 22 13:38:56.84: [20752]: <-- data [1024]
Feb 22 13:38:56.84: [20752]: <-- data [326]
Feb 22 13:38:56.84: [20752]: <-- data [2]
Feb 22 13:38:59.96: [20752]: --> [2:OK]
Feb 22 13:38:59.96: [20752]: <-- [9:AT+FRH=3\r]
Feb 22 13:39:03.06: [20752]: --> [0:]
Feb 22 13:39:03.06: [20752]: MODEM <Empty line>
Feb 22 13:39:03.06: [20752]: <-- data [1]
Feb 22 13:39:03.11: [20752]: --> [2:OK]
Feb 22 13:39:03.11: [20752]: DELAY 1500 ms
Feb 22 13:39:04.61: [20752]: SEND training at v.27ter 4800 bit/s
Feb 22 13:39:04.61: [20752]: <-- [9:AT+FTH=3\r]
Feb 22 13:39:04.64: [20752]: --> [7:CONNECT]
Feb 22 13:39:04.64: [20752]: <-- data [23]
Feb 22 13:39:04.64: [20752]: <-- data [2]
Feb 22 13:39:06.43: [20752]: --> [7:CONNECT]
Feb 22 13:39:06.43: [20752]: <-- data [6]
Feb 22 13:39:06.43: [20752]: <-- data [2]
Feb 22 13:39:06.87: [20752]: --> [2:OK]
Feb 22 13:39:06.87: [20752]: <-- [9:AT+FTS=7\r]
Feb 22 13:39:06.95: [20752]: --> [2:OK]
Feb 22 13:39:06.95: [20752]: <-- [10:AT+FTM=48\r]
Feb 22 13:39:06.99: [20752]: --> [7:CONNECT]
Feb 22 13:39:06.99: [20752]: <-- data [900]
Feb 22 13:39:06.99: [20752]: <-- data [2]
Feb 22 13:39:09.41: [20752]: --> [2:OK]
Feb 22 13:39:09.41: [20752]: <-- [9:AT+FRH=3\r]
Feb 22 13:39:12.51: [20752]: --> [0:]
Feb 22 13:39:12.51: [20752]: MODEM <Empty line>
Feb 22 13:39:12.51: [20752]: <-- data [1]
Feb 22 13:39:12.56: [20752]: --> [2:OK]
Feb 22 13:39:12.56: [20752]: DELAY 1500 ms
Feb 22 13:39:14.06: [20752]: SEND training at v.27ter fallback mode 2400
bit/s
Feb 22 13:39:14.06: [20752]: <-- [9:AT+FTH=3\r]
Feb 22 13:39:14.09: [20752]: --> [7:CONNECT]
Feb 22 13:39:14.09: [20752]: <-- data [23]
Feb 22 13:39:14.09: [20752]: <-- data [2]
Feb 22 13:39:15.88: [20752]: --> [7:CONNECT]
Feb 22 13:39:15.88: [20752]: <-- data [6]
Feb 22 13:39:15.88: [20752]: <-- data [2]
Feb 22 13:39:16.31: [20752]: --> [2:OK]
Feb 22 13:39:16.31: [20752]: <-- [9:AT+FTS=7\r]
Feb 22 13:39:16.39: [20752]: --> [2:OK]
Feb 22 13:39:16.39: [20752]: <-- [10:AT+FTM=24\r]
Feb 22 13:39:16.43: [20752]: --> [7:CONNECT]
Feb 22 13:39:16.43: [20752]: <-- data [450]
Feb 22 13:39:16.43: [20752]: <-- data [2]
Feb 22 13:39:19.10: [20752]: --> [2:OK]
Feb 22 13:39:19.10: [20752]: <-- [9:AT+FRH=3\r]
Feb 22 13:39:22.20: [20752]: --> [0:]
Feb 22 13:39:22.20: [20752]: MODEM <Empty line>
Feb 22 13:39:22.20: [20752]: <-- data [1]
Feb 22 13:39:22.25: [20752]: --> [2:OK]
Feb 22 13:39:22.25: [20752]: DELAY 1500 ms
Feb 22 13:39:23.75: [20752]: TRAINING failed
Feb 22 13:39:23.75: [20752]: <-- [9:AT+FTH=3\r]
Feb 22 13:39:23.78: [20752]: --> [7:CONNECT]
Feb 22 13:39:23.78: [20752]: <-- data [3]
Feb 22 13:39:23.78: [20752]: <-- data [2]
Feb 22 13:39:25.10: [20752]: --> [2:OK]
Feb 22 13:39:25.10: [20752]: <-- [5:ATH0\r]
Feb 22 13:39:25.97: [20752]: --> [2:OK]
Feb 22 13:39:25.97: [20752]: SESSION END

Dobos Sandor
IBCnet Hungary Ltd.





____________________ HylaFAX(tm) Users Mailing List _______________________
 To unsub: mail -s unsubscribe hylafax-users-request@hylafax.org < /dev/null