Hi Bodo,
I think this is similar to the problem that I have seen (and just reported
a day ago).
The reason the first attempt (14:56 job) had a "no response" and the
second (15:07 job) printed OK is this:
Job 1: The paper out occurred AFTER ifhp has sent everything to the
printer's buffer.
Job 2: The paper out occurred WHILE ifhp is sending the job, but
are not done sending yet. I.e., the printer's buffer is full
when the paper out occurred.
In job 1, ifhp has gone into the Do_waitend() sub, and the timeout in
there is a little... :-) In job 2, ifhp has not done sending the file
yet, so it has not gone into Do_waitend(), and the timeout has not
started yet.
If you look carefully, for job 1, the time between the file is completely
sent (14:59) and the "no response" message is exactly 5 minutes, or 300s,
or the default waitend_interval in ifhp.conf.
It needs to be fixed, and I have a suggestion on how to fix it, but I
don't know if the suggestion will break other stuff... :-)
On Fri, Oct 27, 2000 at 06:02:43PM +0200, Bodo Moeller wrote:
> Filter_status: transferring 1172967 bytes at 14:56:32.443
> Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 14:56:32.503
> Filter_status: 25 percent done at 14:56:34.647
> Filter_status: 50 percent done at 14:56:37.429
> Filter_status: 75 percent done at 14:56:40.206
> Filter_status: data sent at 14:59:01.803
^^^^^^^^^^^^^^^^^^^^^^^^^
Data completely sent for job 1, at 14:59, and ifhp is in Do_waitend()
loop now.
> Filter_status: sent job file at 14:59:01.804
> Filter_status: getting end using 'pjl job/eoj' at 14:59:01.810
> Filter_status: code = 41204, 'PC/Upper/Tray2 - A4 Paper', ALERT OPERATOR at
>15:02:59.641
> Filter_status: no response from printer at 15:04:01.148
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Error message, exactly 300 seconds later.
> Filter_status: (of) OF process running at 15:04:01.170
> Filter_status: (of) getting end using 'pjl job/eoj' at 15:04:01.185
> Filter_status: (of) code = 41204, 'PC/Upper/Tray2 - A4 Paper', ALERT OPERATOR at
>15:04:03.164
> Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 15:04:11.688
> Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 15:06:50.385
> Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 15:06:50.417
> Filter_status: (of) end of job detected at 15:07:02.309
> Filter_status: (of) pagecounter 241748 at 15:07:03.777
> Filter_status: (of) pagecounter 241748, pages 109 at 15:07:03.779
> Filter_status: (of) ending OF mode passthrough at 15:07:03.782
> Filter_status: (of) done at 15:07:03.783
> Filter_status: (of) using model 'hp5m' at 15:07:19.997
> Filter_status: (of) pagecount using 'pjl info pagecount' at 15:07:20.018
> Filter_status: (of) setting up printer at 15:07:20.020
> Filter_status: (of) starting OF mode passthrough at 15:07:20.061
> Filter_status: (of) getting sync using 'pjl echo' at 15:07:20.062
> Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 15:07:20.463
> Filter_status: (of) sync done at 15:07:21.696
> Filter_status: (of) pagecounter 241748 at 15:07:21.698
> Filter_status: (of) pagecounter 241748 at 15:07:21.700
> Filter_status: (of) getting sync using 'pjl echo' at 15:07:23.049
> Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 15:07:23.175
> Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 15:07:24.523
> Filter_status: (of) sync done at 15:07:24.536
> Filter_status: (of) sending 8741 bytes of OF input at 15:07:24.544
> Filter_status: (of) starting transfer at 15:07:24.545
> Filter_status: (of) initial job type 'POSTSCRIPT' at 15:07:24.547
> Filter_status: (of) decoded job type 'POSTSCRIPT' at 15:07:24.549
> Filter_status: (of) job type 'POSTSCRIPT' at 15:07:24.550
> Filter_status: (of) transferring 8741 bytes at 15:07:24.555
> Filter_status: (of) 100 percent done at 15:07:24.561
> Filter_status: (of) data sent at 15:07:24.563
> Filter_status: (of) OF process suspending at 15:07:24.569
> Filter_status: using model 'hp5m' at 15:07:24.789
> Filter_status: pagecount using 'pjl info pagecount' at 15:07:24.810
> Filter_status: setting up printer at 15:07:24.811
> Filter_status: getting sync using 'pjl echo' at 15:07:24.812
> Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 15:07:24.819
> Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 15:07:44.313
> Filter_status: sync done at 15:07:45.294
> Filter_status: pagecounter 241749 at 15:07:45.297
> Filter_status: pagecounter 241749 at 15:07:45.298
> Filter_status: sending job file at 15:07:45.307
> Filter_status: starting transfer at 15:07:45.308
> Filter_status: initial job type 'POSTSCRIPT' at 15:07:45.310
> Filter_status: decoded job type 'POSTSCRIPT' at 15:07:45.312
> Filter_status: job type 'POSTSCRIPT' at 15:07:45.313
> Filter_status: transferring 1172967 bytes at 15:07:45.321
> Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 15:07:45.381
> Filter_status: 25 percent done at 15:07:47.528
> Filter_status: 50 percent done at 15:07:50.313
> Filter_status: 75 percent done at 15:07:53.095
> Filter_status: code = 41204, 'PC/Upper/Tray2 - A4 Paper', ALERT OPERATOR at
>15:09:52.313
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Job 2, paper out occurred before ifhp has complete sent the file, so ifhp
has not gone into Do_waitend() loop, so it will not time out.
> Filter_status: code = 10003, 'Warming Up' at 15:55:14.213
> Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 15:55:19.549
> Filter_status: data sent at 15:55:44.048
^^^^^^^^^^^^^^^^^^^^^^^^^
You fixed the paper out problem, now only ifhp is done sending the file, and
the Do_waitend() loop has a full 300 seconds (waitend_interval) to get
the EOJ from the printer, which it did in...
> Filter_status: sent job file at 15:55:44.049
> Filter_status: getting end using 'pjl job/eoj' at 15:55:44.055
> Filter_status: code = 10007, 'Cancelling Job' at 15:56:18.835
> Filter_status: end of job detected at 15:56:33.734
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
50 seconds.
> Filter_status: pagecounter 241785 at 15:56:35.594
> Filter_status: pagecounter 241785, pages 36 at 15:56:35.596
> Filter_status: done at 15:56:35.606
For me, the problem is this: I have a small file, but many pages (also
to a HP 5m). The job got sent to the printer in a short time only, and
ifhp goes into Do_waitend(). But the printer takes 10 minutes to print,
way beyond the 300s (waitend_interval). So ifhp gives me "no response",
and LPRng requeue the job. And the exact same thing happen again.
Since I have send_try=300, LPRng will try 300 times before giving up,
and I'll end up with 300 copies of a large document. :-)
Cheers,
Edwin Lim <[EMAIL PROTECTED]> 973-360-7058 fp c254 ----------------
-----------------------------------------------------------------------------
YOU MUST BE A LIST MEMBER IN ORDER TO POST TO THE LPRNG MAILING LIST
The address you post from MUST be your subscription address
If you need help, send email to [EMAIL PROTECTED] (or lprng-requests
or lprng-digest-requests) with the word 'help' in the body. For the impatient,
to subscribe to a list with name LIST, send mail to [EMAIL PROTECTED]
with: | example:
subscribe LIST <mailaddr> | subscribe lprng-digest [EMAIL PROTECTED]
unsubscribe LIST <mailaddr> | unsubscribe lprng [EMAIL PROTECTED]
If you have major problems, send email to [EMAIL PROTECTED] with the word
LPRNGLIST in the SUBJECT line.
-----------------------------------------------------------------------------
