Since I've upgraded from LPRng 3.6.15 with ifhp 3.3.13 to LPRng 3.6.25
with ifhp 3.4.1, a new problem occurs with our LaserJet 5M printer
(lp=foo%9100): Occasionally a second printing attempt is started
even though the first one worked perfectly well. I've seen
this under 'special conditions' (i.e. the printer waiting for paper),
but a couple of times I've seen the PS panner page being printed
twice for no apparent reaons. This never happened with the old
versions.
Now I've looked in the 'status' file and found 'no response from printer'
errors. The 'lpq -L' extract below is from one job that was started
at 14:56 and successfully completed at 15:07 after the printer had
to wait for paper from 15:02 to 15:04. Just one minute after
the first 'ALERT OPERATOR' status code, there's a 'no response ...'
error message. Later when the job has completed, a second attempt
is started, which is delayed when the paper is empty again (15:09); it
isn't refilled until 15:55, but this time no 'no respone ...' error
message occurs and everthing works well. Finally we've cancelled
the job because we noticed that this was just a duplicate of someting
that we had already printed.
Apparently something has changed in the behaviour of LPRng and/or
ifhp. There is a number of differences in Do_waitend in ifhp.c
(e.g., 'nested_job' and 'banner_page' flags have been introduced),
which I think is responsible for this 'no response from printer'
message. (Is it? This message text occurs in three functions,
namely in Do_sync, Do_waitend, and Current_pagecounter.) So maybe
ifhp.c is to blame.
But I don't have old 'status' files to compare -- maybe the 'no
response from printer' error isn't new and the problem is just that
printing is now retried after the first attempt has finally succeeded?
It seems that the filter finally does find out that printing has
worked, so why is a second attempt started?
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
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
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
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
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
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
The printcap entry is
hplj:cm=HP LaserJet 5M, 600 dpi (Raum 212)
:server:oh=cdc-intern
:sd=/var/spool/lpd/%P
:lf=log:af=acct
:append_z=a4
:ifhp=model=hp5m
:filter=/opt/LPRng/libexec/filters/ifhp
:of=/opt/LPRng/libexec/filters/ifhp
:bp=/opt/LPRng/libexec/filters/psbanner
:lp=cdc-lj5%9100
(not changed at version transition). I did a number of changes to
our ifhp.conf according to the diff between the two 'ifhp.conf.sample'
files.
--
Bodo M�ller <[EMAIL PROTECTED]>
PGP http://www.informatik.tu-darmstadt.de/TI/Mitarbeiter/moeller/0x36d2c658.html
* TU Darmstadt, Theoretische Informatik, Alexanderstr. 10, D-64283 Darmstadt
* Tel. +49-6151-16-6628, Fax +49-6151-16-6036
-----------------------------------------------------------------------------
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.
-----------------------------------------------------------------------------
