Re: Failure to transmit clean ECM image data

Ross Boylan <[email protected]> Mon, 10 Apr 2017 15:22:24 -0700
Newsgroups gmane.comp.telephony.fax.hylafax.user
Message-ID <CAK3NTRA-8O0S0+FSQ08hY5tbLy-OB4hcOMLJ1Q9SrBNyc4NNLA@mail.gmail.com>
Also, while attempting to send the fax I got notices that
kernel:[241905.020862] Disabling IRQ #16

and the kernel log shows stack traces such as
Apr 10 13:28:49 tempserver kernel: [239069.304554] irq 16: nobody
cared (try booting with the "irqpoll" option)
Apr 10 13:28:49 tempserver kernel: [239069.304559] CPU: 6 PID: 0 Comm:
swapper/6 Tainted: P           O  3.16.0-0.bpo.4-amd64 #1 Debian
3.16.39-1+deb8u1~bpo70+1
Apr 10 13:28:49 tempserver kernel: [239069.304560] Hardware name:
System manufacturer System Product Name/P8C WS, BIOS 3202 01/05/2013
Apr 10 13:28:49 tempserver kernel: [239069.304562]  0000000000000086
0000000000000000 ffffffff8154f97f 0000000000000000
Apr 10 13:28:49 tempserver kernel: [239069.304564]  ffff88040d0e0400
ffff88040d0e04c4 ffffffff810c968b ffff88040d0e0400
Apr 10 13:28:49 tempserver kernel: [239069.304565]  0000000000000010
0000000000000010 ffffffff810c9c06 ffff88040d0e04c4
Apr 10 13:28:49 tempserver kernel: [239069.304567] Call Trace:
Apr 10 13:28:49 tempserver kernel: [239069.304568]  <IRQ>
[<ffffffff8154f97f>] ? dump_stack+0x5e/0x7a
Apr 10 13:28:49 tempserver kernel: [239069.304577]
[<ffffffff810c968b>] ? __report_bad_irq+0x3b/0xe0
Apr 10 13:28:49 tempserver kernel: [239069.304579]
[<ffffffff810c9c06>] ? note_interrupt+0x266/0x2c0
Apr 10 13:28:49 tempserver kernel: [239069.304582]
[<ffffffff810c6fac>] ? handle_irq_event_percpu+0xbc/0x220
Apr 10 13:28:49 tempserver kernel: [239069.304584]
[<ffffffff810c7156>] ? handle_irq_event+0x46/0x70
Apr 10 13:28:49 tempserver kernel: [239069.304585]
[<ffffffff810ca837>] ? handle_fasteoi_irq+0x87/0x140
Apr 10 13:28:49 tempserver kernel: [239069.304589]
[<ffffffff8101866d>] ? handle_irq+0x1d/0x30
Apr 10 13:28:49 tempserver kernel: [239069.304591]
[<ffffffff81558ded>] ? do_IRQ+0x5d/0x110
Apr 10 13:28:49 tempserver kernel: [239069.304593]
[<ffffffff81556c2d>] ? common_interrupt+0x6d/0x6d
Apr 10 13:28:49 tempserver kernel: [239069.304594]  <EOI>
[<ffffffff814124d3>] ? cpuidle_enter_state+0x63/0xf0
Apr 10 13:28:49 tempserver kernel: [239069.304598]
[<ffffffff814124c9>] ? cpuidle_enter_state+0x59/0xf0
Apr 10 13:28:49 tempserver kernel: [239069.304600]
[<ffffffff810b361e>] ? cpu_startup_entry+0x36e/0x510
Apr 10 13:28:49 tempserver kernel: [239069.304603]
[<ffffffff810db9c4>] ? tick_setup_periodic+0x64/0x90
Apr 10 13:28:49 tempserver kernel: [239069.304604]
[<ffffffff810dbd41>] ? tick_check_new_device+0x111/0x150
Apr 10 13:28:49 tempserver kernel: [239069.304607]
[<ffffffff81048c18>] ? start_secondary+0x218/0x2d0
Apr 10 13:28:49 tempserver kernel: [239069.304608] handlers:
Apr 10 13:28:49 tempserver kernel: [239069.304627]
[<ffffffffa0010940>] usb_hcd_irq [usbcore]
Apr 10 13:28:49 tempserver kernel: [239069.304628] Disabling IRQ #16

I don't ever remember receiving such messages (disabling IRQ) before.

Fortunately, I was able to send the fax using a different number.  I
would still like to know what was going on with the failure.  The logs
show that the successful fax actually went to Hylafax on the other
end, if I read the logs right.  Also, it seems to have picked a
slightly lower transmission rate; I wonder if that has anything to do
with the success.
Apr 10 14:11:56.37: [31988]: SESSION BEGIN 000000113 18885484395
Apr 10 14:11:56.37: [31988]: HylaFAX (tm) Version 6.0.6
Apr 10 14:11:56.37: [31988]: SEND FAX: JOB 18 DEST 1  888 548-4395
COMMID 000000113 DEVICE '/dev/ttyS1' FROM 'Ross Boylan
<ross@localhost>' USER ross
Apr 10 14:11:56.37: [31988]: <-- [14:AT+FCLASS=1.0\r]
Apr 10 14:11:56.39: [31988]: --> [2:OK]
Apr 10 14:11:56.39: [31988]: DIAL 18885484395
Apr 10 14:11:56.39: [31988]: <-- [16:ATDT18885484395\r]
Apr 10 14:12:28.66: [31988]: --> [9:+F34:10,1]
Apr 10 14:12:28.66: [31988]: V.8 handshaking succeeded, V.34-Fax
(SuperG3) capability enabled.
Apr 10 14:12:28.66: [31988]: Primary channel rate: 24000 bit/s,
Control channel rate: 1200 bit/s.
Apr 10 14:12:28.66: [31988]: --> [7:CONNECT]
Apr 10 14:12:28.66: [31988]: Control channel selected
Apr 10 14:12:28.95: [31988]: REMOTE NSF "AD 00 55 48 79 6C 61 46 41 58
20 28 74 6D 29 20 56 65 72 73 69 6F 6E 20 35 2E 35 2E 31"
Apr 10 14:12:28.95: [31988]: NSF remote fax equipment: HylaFAX
Apr 10 14:12:28.95: [31988]: NSF remote station ID: "HylaFAX (tm) Version 5.5.1"
Apr 10 14:12:29.12: [31988]: REMOTE CSI "+1.888.548.4395"
Apr 10 14:12:29.23: [31988]: REMOTE best rate 33600 bit/s
Apr 10 14:12:29.23: [31988]: REMOTE max A3 page width (303 mm)
Apr 10 14:12:29.23: [31988]: REMOTE max unlimited page length
Apr 10 14:12:29.23: [31988]: REMOTE best vres R16 x 15.4 line/mm
Apr 10 14:12:29.23: [31988]: REMOTE format support: MH, MR, MMR
Apr 10 14:12:29.23: [31988]: REMOTE supports T.30 Annex A, 256-byte ECM
Apr 10 14:12:29.23: [31988]: REMOTE best 0 ms/scanline
Apr 10 14:12:29.23: [31988]: USE 24000 bit/s
Apr 10 14:12:29.23: [31988]: USE error correction mode
Apr 10 14:12:29.23: [31988]: SEND file "docq/cover18;f1"
Apr 10 14:12:29.23: [31988]: USE A4 page width (215 mm)
Apr 10 14:12:29.23: [31988]: USE unlimited page length
Apr 10 14:12:29.23: [31988]: USE 7.7 line/mm
Apr 10 14:12:29.23: [31988]: USE 2-D MMR
Apr 10 14:12:29.23: [31988]: USE 0 ms/scanline
Apr 10 14:12:29.23: [31988]: <-- data [23]

On Mon, Apr 10, 2017 at 1:57 PM, Ross Boylan
<[email protected]> wrote:
> I wasn't able to send a fax and got the error in the subject line, along with
> The destination appears to have trouble with V.34-Fax.
>
> Any suggestions?  I'm using a MultiTech MT9234ZPX-PCIE modem.
>
> Thanks.
> Ross Boylan
>
> Here are the logs from the last attempt:
> Apr 10 13:32:22.75: [30115]: SESSION BEGIN 000000112 12127326941
> Apr 10 13:32:22.75: [30115]: HylaFAX (tm) Version 6.0.6
> Apr 10 13:32:22.75: [30115]: SEND FAX: JOB 17 DEST 1 (212)-732-6941
> COMMID 000000112 DEVICE '/dev/ttyS1' FROM 'Ross Boylan
> <ross@localhost>' USER ross
> Apr 10 13:32:22.75: [30115]: <-- [14:AT+FCLASS=1.0\r]
> Apr 10 13:32:22.76: [30115]: --> [2:OK]
> Apr 10 13:32:22.77: [30115]: DIAL 1(212)7326941
> Apr 10 13:32:22.77: [30115]: <-- [18:ATDT1(212)7326941\r]
> Apr 10 13:32:58.95: [30115]: --> [9:+F34:12,1]
> Apr 10 13:32:58.95: [30115]: V.8 handshaking succeeded, V.34-Fax
> (SuperG3) capability enabled.
> Apr 10 13:32:58.95: [30115]: Primary channel rate: 28800 bit/s,
> Control channel rate: 1200 bit/s.
> Apr 10 13:32:58.95: [30115]: --> [7:CONNECT]
> Apr 10 13:32:58.96: [30115]: Control channel selected
> Apr 10 13:32:59.53: [30115]: REMOTE NSF "00 00 11 80 00 8A 48 00 82 00
> 80 80 80 00"
> Apr 10 13:32:59.53: [30115]: NSF remote fax equipment: Canon Laser Class 2060
> Apr 10 13:32:59.64: [30115]: REMOTE best rate 33600 bit/s
> Apr 10 13:32:59.64: [30115]: REMOTE max A4 page width (215 mm)
> Apr 10 13:32:59.64: [30115]: REMOTE max unlimited page length
> Apr 10 13:32:59.64: [30115]: REMOTE best vres R16 x 15.4 line/mm
> Apr 10 13:32:59.64: [30115]: REMOTE format support: MH, MR, MMR, JBIG
> Apr 10 13:32:59.64: [30115]: REMOTE supports T.30 Annex A, 256-byte ECM
> Apr 10 13:32:59.64: [30115]: REMOTE best 0 ms/scanline
> Apr 10 13:32:59.64: [30115]: USE 28800 bit/s
> Apr 10 13:32:59.64: [30115]: USE error correction mode
> Apr 10 13:32:59.64: [30115]: SEND file "docq/cover17;f1"
> Apr 10 13:32:59.64: [30115]: USE A4 page width (215 mm)
> Apr 10 13:32:59.64: [30115]: USE unlimited page length
> Apr 10 13:32:59.64: [30115]: USE 7.7 line/mm
> Apr 10 13:32:59.64: [30115]: USE JBIG
> Apr 10 13:32:59.64: [30115]: USE 0 ms/scanline
> Apr 10 13:32:59.64: [30115]: <-- data [23]
> Apr 10 13:32:59.64: [30115]: <-- data [2]
> Apr 10 13:32:59.64: [30115]: <-- data [13]
> Apr 10 13:32:59.64: [30115]: <-- data [2]
> Apr 10 13:33:01.26: [30115]: SEND begin page
> Apr 10 13:33:01.27: [30115]: Reading MMR-compressed image file
> Apr 10 13:33:01.29: [30115]: SEND send frame number 0
> Apr 10 13:33:01.29: [30115]: SEND send frame number 1
> Apr 10 13:33:01.29: [30115]: SEND send frame number 2
> Apr 10 13:33:01.29: [30115]: SEND send frame number 3
> Apr 10 13:33:01.29: [30115]: SEND send frame number 4
> Apr 10 13:33:01.29: [30115]: SEND send frame number 5
> Apr 10 13:33:01.29: [30115]: SEND send frame number 6
> Apr 10 13:33:01.29: [30115]: SEND send frame number 7
> Apr 10 13:33:01.29: [30115]: SEND send frame number 8
> Apr 10 13:33:01.29: [30115]: SEND send frame number 9
> Apr 10 13:33:01.29: [30115]: SEND send frame number 10
> Apr 10 13:33:01.29: [30115]: SEND send frame number 11
> Apr 10 13:33:01.29: [30115]: SEND send frame number 12
> Apr 10 13:33:01.29: [30115]: SEND send frame number 13
> Apr 10 13:33:01.29: [30115]: SEND send frame number 14
> Apr 10 13:33:01.30: [30115]: SEND send frame number 15
> Apr 10 13:33:01.30: [30115]: SEND send frame number 16
> Apr 10 13:33:01.30: [30115]: SEND send frame number 17
> Apr 10 13:33:01.30: [30115]: SEND send frame number 18
> Apr 10 13:33:01.30: [30115]: SEND send frame number 19
> Apr 10 13:33:01.30: [30115]: SEND send frame number 20
> Apr 10 13:33:01.30: [30115]: SEND send frame number 21
> Apr 10 13:33:01.30: [30115]: SEND send frame number 22
> Apr 10 13:33:01.30: [30115]: SEND send frame number 23
> Apr 10 13:33:01.30: [30115]: SEND send frame number 24
> Apr 10 13:33:01.30: [30115]: SEND send frame number 25
> Apr 10 13:33:01.30: [30115]: SEND send frame number 26
> Apr 10 13:33:01.30: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: Primary channel selected
> Apr 10 13:33:03.27: [30115]: <-- data [261]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [260]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [261]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [260]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [263]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [260]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [262]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [261]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [262]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [260]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [261]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [260]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [261]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [261]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [262]
> Apr 10 13:33:03.27: [30115]: <-- data [2]
> Apr 10 13:33:03.27: [30115]: <-- data [260]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [262]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [261]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [261]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [263]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [260]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [265]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [260]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [260]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [261]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [262]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [260]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [3]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [3]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [3]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:05.27: [30115]: <-- data [2]
> Apr 10 13:33:08.46: [30115]: Control channel selected
> Apr 10 13:33:08.46: [30115]: <-- data [7]
> Apr 10 13:33:08.46: [30115]: <-- data [2]
> Apr 10 13:33:08.46: [30115]: SEND send PPS (partial page signal)
> Apr 10 13:33:08.46: [30115]: SEND send MPS (more pages, same document)
> Apr 10 13:33:10.06: [30115]: SEND recv PPR (partial page request)
> Apr 10 13:33:10.06: [30115]: SEND send frame number 0
> Apr 10 13:33:10.06: [30115]: SEND send frame number 1
> Apr 10 13:33:10.06: [30115]: SEND send frame number 2
> Apr 10 13:33:10.06: [30115]: SEND send frame number 3
> Apr 10 13:33:10.06: [30115]: SEND send frame number 4
> Apr 10 13:33:10.06: [30115]: SEND send frame number 5
> Apr 10 13:33:10.06: [30115]: SEND send frame number 6
> Apr 10 13:33:10.06: [30115]: SEND send frame number 7
> Apr 10 13:33:10.06: [30115]: SEND send frame number 8
> Apr 10 13:33:10.06: [30115]: SEND send frame number 9
> Apr 10 13:33:10.06: [30115]: SEND send frame number 10
> Apr 10 13:33:10.06: [30115]: SEND send frame number 11
> Apr 10 13:33:10.06: [30115]: SEND send frame number 12
> Apr 10 13:33:10.06: [30115]: SEND send frame number 13
> Apr 10 13:33:10.06: [30115]: SEND send frame number 14
> Apr 10 13:33:10.06: [30115]: SEND send frame number 15
> Apr 10 13:33:10.06: [30115]: SEND send frame number 16
> Apr 10 13:33:10.06: [30115]: SEND send frame number 17
> Apr 10 13:33:10.06: [30115]: SEND send frame number 18
> Apr 10 13:33:10.06: [30115]: SEND send frame number 19
> Apr 10 13:33:10.06: [30115]: SEND send frame number 20
> Apr 10 13:33:10.06: [30115]: SEND send frame number 21
> Apr 10 13:33:10.06: [30115]: SEND send frame number 22
> Apr 10 13:33:10.06: [30115]: SEND send frame number 23
> Apr 10 13:33:10.06: [30115]: SEND send frame number 24
> Apr 10 13:33:10.06: [30115]: SEND send frame number 25
> Apr 10 13:33:10.06: [30115]: SEND send frame number 26
> Apr 10 13:33:10.06: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: Primary channel selected
> Apr 10 13:33:12.03: [30115]: <-- data [4]
> Apr 10 13:33:12.03: [30115]: Request primary rate renegotiation (limit
> 24000 bit/s).
> Apr 10 13:33:12.03: [30115]: <-- data [261]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [260]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [261]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [260]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [263]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [260]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [262]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [261]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [262]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [260]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [261]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [260]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [261]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [261]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [262]
> Apr 10 13:33:12.03: [30115]: <-- data [2]
> Apr 10 13:33:12.03: [30115]: <-- data [260]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [262]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [261]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [261]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [263]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [260]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [265]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [260]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [260]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [261]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [262]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [260]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [3]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [3]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [3]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:14.00: [30115]: <-- data [2]
> Apr 10 13:33:18.55: [30115]: Control channel selected
> Apr 10 13:33:18.55: [30115]: Primary channel rate now 24000 bit/s
> Apr 10 13:33:18.55: [30115]: <-- data [7]
> Apr 10 13:33:18.55: [30115]: <-- data [2]
> Apr 10 13:33:18.55: [30115]: SEND send PPS (partial page signal)
> Apr 10 13:33:18.55: [30115]: SEND send MPS (more pages, same document)
> Apr 10 13:33:20.15: [30115]: SEND recv PPR (partial page request)
> Apr 10 13:33:20.15: [30115]: SEND send frame number 0
> Apr 10 13:33:20.15: [30115]: SEND send frame number 1
> Apr 10 13:33:20.15: [30115]: SEND send frame number 2
> Apr 10 13:33:20.15: [30115]: SEND send frame number 3
> Apr 10 13:33:20.15: [30115]: SEND send frame number 4
> Apr 10 13:33:20.15: [30115]: SEND send frame number 5
> Apr 10 13:33:20.15: [30115]: SEND send frame number 6
> Apr 10 13:33:20.15: [30115]: SEND send frame number 7
> Apr 10 13:33:20.15: [30115]: SEND send frame number 8
> Apr 10 13:33:20.15: [30115]: SEND send frame number 9
> Apr 10 13:33:20.15: [30115]: SEND send frame number 10
> Apr 10 13:33:20.15: [30115]: SEND send frame number 11
> Apr 10 13:33:20.15: [30115]: SEND send frame number 12
> Apr 10 13:33:20.15: [30115]: SEND send frame number 13
> Apr 10 13:33:20.15: [30115]: SEND send frame number 14
> Apr 10 13:33:20.15: [30115]: SEND send frame number 15
> Apr 10 13:33:20.15: [30115]: SEND send frame number 16
> Apr 10 13:33:20.15: [30115]: SEND send frame number 17
> Apr 10 13:33:20.15: [30115]: SEND send frame number 18
> Apr 10 13:33:20.15: [30115]: SEND send frame number 19
> Apr 10 13:33:20.15: [30115]: SEND send frame number 20
> Apr 10 13:33:20.15: [30115]: SEND send frame number 21
> Apr 10 13:33:20.15: [30115]: SEND send frame number 22
> Apr 10 13:33:20.15: [30115]: SEND send frame number 23
> Apr 10 13:33:20.15: [30115]: SEND send frame number 24
> Apr 10 13:33:20.15: [30115]: SEND send frame number 25
> Apr 10 13:33:20.15: [30115]: SEND send frame number 26
> Apr 10 13:33:20.15: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: Primary channel selected
> Apr 10 13:33:22.12: [30115]: <-- data [4]
> Apr 10 13:33:22.12: [30115]: Request primary rate renegotiation (limit
> 19200 bit/s).
> Apr 10 13:33:22.12: [30115]: <-- data [261]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [260]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [261]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [260]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [263]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [260]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [262]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [261]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [262]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [260]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [261]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [260]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [261]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [261]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [262]
> Apr 10 13:33:22.12: [30115]: <-- data [2]
> Apr 10 13:33:22.12: [30115]: <-- data [260]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [262]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [261]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [261]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [263]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [260]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [265]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [260]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [260]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [261]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [262]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [260]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [3]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [3]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [3]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:24.09: [30115]: <-- data [2]
> Apr 10 13:33:28.66: [30115]: Control channel selected
> Apr 10 13:33:28.66: [30115]: Primary channel rate now 19200 bit/s
> Apr 10 13:33:28.66: [30115]: <-- data [7]
> Apr 10 13:33:28.66: [30115]: <-- data [2]
> Apr 10 13:33:28.66: [30115]: SEND send PPS (partial page signal)
> Apr 10 13:33:28.66: [30115]: SEND send MPS (more pages, same document)
> Apr 10 13:33:30.26: [30115]: SEND recv PPR (partial page request)
> Apr 10 13:33:30.26: [30115]: SEND send frame number 0
> Apr 10 13:33:30.26: [30115]: SEND send frame number 1
> Apr 10 13:33:30.26: [30115]: SEND send frame number 2
> Apr 10 13:33:30.26: [30115]: SEND send frame number 3
> Apr 10 13:33:30.26: [30115]: SEND send frame number 4
> Apr 10 13:33:30.26: [30115]: SEND send frame number 5
> Apr 10 13:33:30.26: [30115]: SEND send frame number 6
> Apr 10 13:33:30.26: [30115]: SEND send frame number 7
> Apr 10 13:33:30.26: [30115]: SEND send frame number 8
> Apr 10 13:33:30.26: [30115]: SEND send frame number 9
> Apr 10 13:33:30.26: [30115]: SEND send frame number 10
> Apr 10 13:33:30.26: [30115]: SEND send frame number 11
> Apr 10 13:33:30.26: [30115]: SEND send frame number 12
> Apr 10 13:33:30.26: [30115]: SEND send frame number 13
> Apr 10 13:33:30.26: [30115]: SEND send frame number 14
> Apr 10 13:33:30.26: [30115]: SEND send frame number 15
> Apr 10 13:33:30.26: [30115]: SEND send frame number 16
> Apr 10 13:33:30.26: [30115]: SEND send frame number 17
> Apr 10 13:33:30.26: [30115]: SEND send frame number 18
> Apr 10 13:33:30.26: [30115]: SEND send frame number 19
> Apr 10 13:33:30.26: [30115]: SEND send frame number 20
> Apr 10 13:33:30.26: [30115]: SEND send frame number 21
> Apr 10 13:33:30.26: [30115]: SEND send frame number 22
> Apr 10 13:33:30.26: [30115]: SEND send frame number 23
> Apr 10 13:33:30.26: [30115]: SEND send frame number 24
> Apr 10 13:33:30.26: [30115]: SEND send frame number 25
> Apr 10 13:33:30.26: [30115]: SEND send frame number 26
> Apr 10 13:33:30.26: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: Primary channel selected
> Apr 10 13:33:32.23: [30115]: <-- data [4]
> Apr 10 13:33:32.23: [30115]: Request primary rate renegotiation (limit
> 14400 bit/s).
> Apr 10 13:33:32.23: [30115]: <-- data [261]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [260]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [261]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [260]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [263]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [260]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [262]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [261]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [262]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [260]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [261]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [260]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [261]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [261]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [262]
> Apr 10 13:33:32.23: [30115]: <-- data [2]
> Apr 10 13:33:32.23: [30115]: <-- data [260]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [262]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [261]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [261]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [263]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [260]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [265]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [260]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [260]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [261]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [262]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [260]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [3]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [3]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [3]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:34.20: [30115]: <-- data [2]
> Apr 10 13:33:38.79: [30115]: Control channel selected
> Apr 10 13:33:38.79: [30115]: Primary channel rate now 14400 bit/s
> Apr 10 13:33:38.79: [30115]: <-- data [7]
> Apr 10 13:33:38.79: [30115]: <-- data [2]
> Apr 10 13:33:38.79: [30115]: SEND send PPS (partial page signal)
> Apr 10 13:33:38.79: [30115]: SEND send MPS (more pages, same document)
> Apr 10 13:33:40.38: [30115]: SEND recv PPR (partial page request)
> Apr 10 13:33:40.38: [30115]: Failure to transmit clean ECM image data.
> Apr 10 13:33:40.38: [30115]: SEND end page
> Apr 10 13:33:40.38: [30115]: The destination appears to have trouble
> with V.34-Fax.
> Apr 10 13:33:40.38: [30115]: SEND: Giving up after 3 attempts to send
> same page "docq/cover17;f1", dirnum 0
> Apr 10 13:33:40.38: [30115]: <-- data [3]
> Apr 10 13:33:40.38: [30115]: <-- data [2]
> Apr 10 13:33:41.38: [30115]: <-- data [2]
> Apr 10 13:33:41.58: [30115]: --> [2:  ]
> Apr 10 13:33:41.59: [30115]: <-- [5:ATH0\r]
> Apr 10 13:33:41.59: [30115]: --> [2:OK]
> Apr 10 13:33:41.59: [30115]: SESSION END


____________________ HylaFAX(tm) Users Mailing List _______________________
  To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi
 On UNIX: mail -s unsubscribe [email protected] < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail [email protected].*