Re: [hylafax-users] Transmission problems: info and questions

[Ross Boylan <RossBoylan@xxxxxxxxxxxxxxxxxx>]

Thanks  for the quick reply--and on Memorial Day too!

On Mon, May 29, 2006 at 01:20:53PM -0700, Lee Howard wrote:
> Ross Boylan wrote:
> 
> >Since it takes many minutes to process my inputs into tiff, this
> >resubmission was a big win for me.
> > 
> >
> 
> I've added a resubmit feature to faxalter in hylafax+ 
> (http://hylafax.sourceforge.net) for this very reason.

Great.  I hope that percolates into the mainline version soon.

....

> >2) faxalter -a doesn't seem to work.  Not only did running it not
> >reset the time to that desired, it caused a job to attempt
> >transmission immediately.
> >
> 
> I wonder if the Debian maintainer applied a timezone patch to 4.2.5 that 
> was developed in the 4.3.0 development process.  Ultimately the initial 
> changes there were incomplete or faulty, and these were eventually 
> remedied in 4.3.0.

I don't see signs of it in the Debian.changelog, but that's not
definitive.

> 
> >There seem to be a number of conditions that cause the job to fail
> >before the indicated number of retries.
> > 
> >
> 
> Be careful not to confuse the faxalter -t meaning of "tries" with the 
> sendfax -t meaning of "tries".  

Oh, my!  The terminology and option names seem to invite confusion.

> In faxalter -t "tries" refers to the 
> number of calls that will be placed.  In sendfax -t "tries" refers to 
> the number of attempts that will be made to send the entire fax job 
> after a connection is made to the remote receiver.  In sendfax -T 
> "maxdials" refers to faxalter -t "tries".  So you're running into the 
> max number of protocol attempts (meaning as sendfax gives -t) as set 
> forth by the client program and not the max number of dials (meaning as 
> set by faxalter -t).

Is the rule  that whenever either limit is exceeded the job is done?
Is there a way to reset the parameter set by sendfax -t after
submission?

Even after reading the preceding description and the sendfax man page,
I'm not clear about what each sense of "try" means.  There are 3 kinds
of activities I can think of:
1) a single phone call;
2) attempts to do something within a single phone call if the first
attempt fails;
3) attempts to send a complete fax.  For example, in my attempted
transmission of 47 pages, the first call managed the first 10 pages,
the next did the next 5, and the third didn't get anywhere before it
failed, killing not only that attempt but the whole hylafax job (in
the jobid sense).

Well, I'm thinking of more possibilities (e.g., calls with successful
initial handshake), but the basic point is I don't know.

I think I understand what 1) is, and that faxalter -t or sendfax -T
determines this.

But as for what you call "protocol attempts" and the man page refers
to  as "attempts to send a particular page", I'm uncertain.  The man's
reference to sending a particular page suggest perhaps another page is
then tried (though admittedly such behavior seems unlikely to be
helpful, and so this meaning is probably not intended).

> 
> >May 28 21:57:02.05: [21888]: SEND send frame number 35
> >May 28 21:57:02.05: [21888]: SEND send frame number 36
> >May 28 21:57:02.05: [21888]: SEND send frame number 37
> >May 28 21:57:02.05: [21888]: DELAY 200 ms
> >May 28 21:57:02.24: [21888]: <-- [11:AT+FTM=146\r]
> >May 28 21:57:32.24: [21888]: MODEM TIMEOUT: reading line from modem
> >May 28 21:57:32.24: [21888]: MODEM <Timeout>
> >May 28 21:57:32.24: [21888]: SEND end page
> >May 28 21:57:32.24: [21888]: Unspecified Transmit Phase C error
> >May 28 21:57:32.24: [21888]: <-- [9:AT+FTH=3\r]
> >May 28 21:57:32.37: [21888]: --> [8:AT+FTH=3]
> >
> 
> USR modems stink this way.  Using AT+FRS seems to give them grief.
> The 
> new (4.3.0) prototypes for USR modems have this in them:
> 
> #
> # When using AT+FRS=n we see USR modems reset themselves in the middle 
> of sessions
> # this is not good.  So, we seem to work-around that problem by not 
> using the
> # command.  Unfortunately, this isn't an ideal thing.
> #
> Class1SwitchingCmd:     "<delay\0727>"
> 
> 
> Lee.

I put the previous line in config.ttyS0.  Now I get

Your facsimile job to 1-866-643-2219 was not sent because:
    Unknown problem

The job will be retried at 14:07.

    ---- Transcript of session follows ----

May 29 13:59:20.66: [26205]: SESSION BEGIN 000000312 18666432219
May 29 13:59:20.66: [26205]: HylaFAX (tm) Version 4.2.5
May 29 13:59:20.66: [26205]: SEND FAX: JOB 195 DEST 1-866-643-2219 COMMID 000000312 DEVICE '/dev/ttyS0' FROM 'Ross Boylan <ross@localhost>'
+USER boylan
May 29 13:59:20.66: [26205]: <-- [12:AT+FCLASS=1\r]
May 29 13:59:20.79: [26205]: --> [2:OK]
May 29 13:59:20.81: [26205]: DIAL 18666432219
May 29 13:59:20.81: [26205]: <-- [16:ATDT18666432219\r]
May 29 14:02:20.80: [26205]: MODEM TIMEOUT: reading line from modem
May 29 14:02:20.82: [26205]: SEND FAILED: JOB 195 DEST 1-866-643-2219 ERR Unknown problem
May 29 14:02:20.82: [26205]: <-- [5:ATH0\r]
May 29 14:02:25.82: [26205]: MODEM TIMEOUT: reading line from modem
May 29 14:02:25.82: [26205]: MODEM <Timeout>
May 29 14:02:25.82: [26205]: MODEM set DTR OFF
May 29 14:02:25.82: [26205]: SESSION END


The second try seems to fail about the same way.

The  third appears to have sent a few pages
May 29 14:15:47.20: [28747]: SESSION BEGIN 000000314 18666432219
May 29 14:15:47.20: [28747]: HylaFAX (tm) Version 4.2.5
May 29 14:15:47.20: [28747]: SEND FAX: JOB 195 DEST 1-866-643-2219 COMMID 000000314 DEVICE '/dev/ttyS0' FROM 'Ross Boylan <ross@localhost>'
+USER boylan
May 29 14:15:47.20: [28747]: <-- [12:AT+FCLASS=1\r]
May 29 14:15:47.33: [28747]: --> [2:OK]
May 29 14:15:47.36: [28747]: DIAL 18666432219
May 29 14:15:47.36: [28747]: <-- [16:ATDT18666432219\r]
May 29 14:15:58.29: [28747]: --> [7:CONNECT]
May 29 14:15:59.89: [28747]: --> HDLC<25:FF C0 02 CA 0A 12 CA 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 98 03>
May 29 14:15:59.89: [28747]: --> [2:OK]
May 29 14:15:59.89: [28747]: REMOTE CSI "SHPS"
May 29 14:15:59.89: [28747]: <-- [9:AT+FRH=3\r]
May 29 14:15:59.90: [28747]: --> [7:CONNECT]
May 29 14:16:00.21: [28747]: --> HDLC<11:FF C8 01 04 77 1F 23 01 08 18 4F>
May 29 14:16:00.21: [28747]: --> [2:OK]
May 29 14:16:00.21: [28747]: REMOTE best rate 33600 bit/s
May 29 14:16:00.21: [28747]: REMOTE max A4 page width (215 mm)
May 29 14:16:00.21: [28747]: REMOTE max unlimited page length
May 29 14:16:00.21: [28747]: REMOTE best vres 7.7 line/mm
May 29 14:16:00.21: [28747]: REMOTE format support: MH, MR, MMR
May 29 14:16:00.21: [28747]: REMOTE supports T.30 Annex A, 256-byte ECM
May 29 14:16:00.21: [28747]: REMOTE best 0 ms/scanline
May 29 14:16:00.21: [28747]: USE 14400 bit/s
May 29 14:16:00.21: [28747]: USE error correction mode
May 29 14:16:00.21: [28747]: SEND file "docq/doc601.ps;f1"
May 29 14:16:00.23: [28747]: USE A4 page width (215 mm)
May 29 14:16:00.23: [28747]: USE unlimited page length
May 29 14:16:00.23: [28747]: USE 7.7 line/mm
May 29 14:16:00.23: [28747]: USE 2-D MMR
May 29 14:16:00.23: [28747]: USE 0 ms/scanline
May 29 14:16:00.23: [28747]: SEND training at v.17 14400 bit/s
May 29 14:16:00.23: [28747]: DELAY 70 ms
May 29 14:16:00.29: [28747]: <-- [9:AT+FTH=3\r]
May 29 14:16:00.48: [28747]: --> [7:CONNECT]
May 29 14:16:00.48: [28747]: <-- HDLC<23:FF C0 C2 CE 76 86 36 9E F6 42 04 A6 16 2A 04 04 04 04 04 04 04 04 04>
May 29 14:16:00.49: [28747]: --> [7:CONNECT]


 and then
May 29 14:17:28.63: [28747]: SEND send frame number 34
May 29 14:17:28.63: [28747]: DELAY 200 ms
May 29 14:17:28.82: [28747]: <-- [11:AT+FTM=146\r]
May 29 14:17:58.82: [28747]: MODEM TIMEOUT: reading line from modem
May 29 14:17:58.82: [28747]: MODEM <Timeout>
May 29 14:17:58.82: [28747]: SEND end page
May 29 14:17:58.82: [28747]: Unspecified Transmit Phase C error
May 29 14:17:58.82: [28747]: <-- [9:AT+FTH=3\r]
May 29 14:17:58.95: [28747]: --> [8:AT+FTH=3]
May 29 14:17:58.95: [28747]: MODEM input buffering enabled
May 29 14:17:58.95: [28747]: <-- [5:ATH0\r]
May 29 14:17:59.06: [28747]: --> [9:ERATH0ROR]
May 29 14:18:04.06: [28747]: MODEM TIMEOUT: reading line from modem
May 29 14:18:04.06: [28747]: MODEM <Timeout>
May 29 14:18:04.06: [28747]: MODEM set DTR OFF
May 29 14:18:04.06: [28747]: SESSION END


The 4th breaks into gibberish in the logs and ends
May 29 14:20:05.49: [28797]: -->
+[265:?^Yht???1?Y?b??^]&?r^VN;^]?0^W???^A^F??T0w^V????????,^^?3?5s^V???2oP^^???Y8r_?(?9>?c^A?G|Z???(:??c?<|,??+???,?v??y;?^B????b^V?p:^P^P^Rsg
+?P^V? {9?me??[4?^Tn? Y
+z??????X?h?a/CY?k?,f?,v????^Rl??%?*$??v?^E}??^O!(??}?^K^R?^N??pA????m]K?Y^X?2^Z^Dc??h?6?S^[^R??v???-?n??Q?^WT^P^P???m??t??^K??jA????^D??^]^V?
+'?]
May 29 14:20:05.59: [28797]: --> [50:t?q?Z^>?%?j)^K?0z^_B??$???????}^_`????}?a????^A?i?~~^P^C]
May 29 14:20:05.59: [28797]: --> [2:OK]
May 29 14:20:05.59: [28797]: <-- [9:AT+FTS=9\r]
May 29 14:20:05.72: [28797]: --> [8:AT+FTS=9]
May 29 14:20:05.73: [28797]: --> [5:ERROR]
May 29 14:20:05.73: [28797]: MODEM Command error
May 29 14:20:05.73: [28797]: Stop and wait failure (modem on hook)
May 29 14:20:05.73: [28797]: SEND end page
May 29 14:20:05.73: [28797]: <-- [9:AT+FTH=3\r]
May 29 14:20:05.86: [28797]: --> [8:AT+FTH=3]
May 29 14:20:05.86: [28797]: MODEM input buffering enabled
May 29 14:20:05.86: [28797]: <-- [5:ATH0\r]
May 29 14:20:05.97: [28797]: --> [9:ERROATH0R]
May 29 14:20:10.97: [28797]: MODEM TIMEOUT: reading line from modem
May 29 14:20:10.97: [28797]: MODEM <Timeout>
May 29 14:20:10.97: [28797]: MODEM set DTR OFF
May 29 14:20:10.97: [28797]: SESSION END


And the last ends
ay 29 14:22:30.88: [28841]: SEND send frame number 193
May 29 14:22:30.88: [28841]: DELAY 200 ms
May 29 14:22:31.08: [28841]: <-- [11:AT+FTM=146\r]
May 29 14:23:01.06: [28841]: MODEM TIMEOUT: reading line from modem
May 29 14:23:01.06: [28841]: MODEM <Timeout>
May 29 14:23:01.06: [28841]: SEND end page
May 29 14:23:01.06: [28841]: Unspecified Transmit Phase C error
May 29 14:23:01.06: [28841]: <-- [9:AT+FTH=3\r]
May 29 14:23:01.19: [28841]: --> [8:AT+FTH=3]
May 29 14:23:01.19: [28841]: MODEM input buffering enabled
May 29 14:23:01.19: [28841]: SEND FAILED: JOB 195 DEST 1-866-643-2219 ERR Unspecified Transmit Phase C error; too many attempts to send
May 29 14:23:01.19: [28841]: <-- [5:ATH0\r]
May 29 14:23:01.30: [28841]: --> [5:ERROR]
May 29 14:23:01.30: [28841]: MODEM Command error
May 29 14:23:01.30: [28841]: MODEM set DTR OFF
May 29 14:23:01.30: [28841]: SESSION END

    ---- Unsent job status ----

     Destination: 1-866-643-2219
           JobID: 195
         GroupID: 192
          Sender: Ross Boylan
        Mailaddr: ross@localhost
          CommID: 000000316
  Submitted From: localhost
      Page Width: 215 (mm)
     Page Length: 279 (mm)
      Resolution: 196 (lpi)
          Status: Unspecified Transmit Phase C error; too many attempts to send
         Dialogs: 3 (exchanges with remote device)
           Dials: 0 (consecutive failed calls to destination)
           Calls: 5 (total phone calls placed)
           Pages: 4 (pages transmitted)
        TotPages: 47 (total pages to transmit)
        Attempts: 1 (attempts to send current page)
          Dirnum: 0 (directory of next page to send)

Argh!

Is config.ttyS0 the right place (my only modem)?  Do I need to restart
or bounce faxgetty as well (it's running from inittab)?  The spot
where FRS appeared in the logs (right after the RETURN identifier)
does now show something else, kind of suggesting I did get the change in.

Did the characters get mangled in transmission (e.g., did \0727 start
out as something else on your 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*