On Mon, Oct 30, 2000 at 06:05:46PM +0100, Bodo Moeller wrote:

[...]
> The new Write_read_timeout function insists on writing all of the
> buffer in one timeout interval (usually 300 seconds), or it will
> report an error -- its return value is either 0 (success) or -1 (error),
> it's no longer possible to report that some data could be read
> but not all data could be written.
[...]

This explains the problem with print jobs that take a long time,
but not our problem with duplicate banner pages.

Fortunately (?), the problem occured again and this time I saved the
'lpq -L' output; see below.  What happens is that a one-page
postscript file is printed, preceded by two copies of the banner
page.  I've never seen this with the old version of ifhp,
so I guess this is due to the changes made in the program
(this time it's Do_sync, not Do_waitend).  sync_interval
is set to 20 seconds, and that's the value we have been using
all the time.


 Status: printing job 'skrobic@cdc23+235' at 10:42:43.236
 Status: creating banner at 10:42:43.240
 Status: Filter_file: pgm '/opt/LPRng/libexec/filters/psbanner' exited with status 
'JSUCC' at 10:42:48.398
 Status: printing 'skrobic@cdc23+235' starting OF 'ifhp' at 10:42:48.401
 Status: OF filter suspended at 10:42:53.384
 Status: processing 'dfA235cdc23.cdc.informatik.tu-darmstadt.de', size 147816, format 
'f', IF filter 'ifhp' at 10:42:53.388
 Status: IF filter 'ifhp' filter finished at 10:43:35.783
 Status: OF filter finished at 10:43:39.563
 Status: printing finished at 10:43:39.665
 Status: subserver pid 21779 exit status 'JSUCC' at 10:43:39.739
 Status: hplj@cdc-intern: job 'skrobic@cdc23+235' printed at 10:43:39.748
 Status: job 'skrobic@cdc23+235' removed at 10:43:39.975
 Status: waiting for subserver to exit at 10:44:23.876
 Status: subserver pid 21861 starting at 10:44:23.961
 Status: accounting at start at 10:44:23.962
 Status: opening device 'cdc-lj5%9100' at 10:44:23.966
 Status: printing job 'skrobic@cdc23+251' at 10:44:24.023
 Status: creating banner at 10:44:24.026
 Status: Filter_file: pgm '/opt/LPRng/libexec/filters/psbanner' exited with status 
'JSUCC' at 10:44:29.213
 Status: printing 'skrobic@cdc23+251' starting OF 'ifhp' at 10:44:29.217
 Status: OF filter suspended at 10:44:33.417
 Status: processing 'dfA251cdc23.cdc.informatik.tu-darmstadt.de', size 147816, format 
'f', IF filter 'ifhp' at 10:44:33.422
 Status: IF filter 'ifhp' filter msg - 'ifhp 10:44:53.459 [21937] Do_sync: no response 
from printer' at 10:44:53.472
 Status: IF filter 'ifhp' filter exit status 'JFAIL' at 10:44:53.475
 Status: OF filter finished at 10:44:56.949
 Status: printing finished at 10:44:56.951
 Status: accounting at end at 10:44:56.959
 Status: finished 'skrobic@cdc23+251', status 'JFAIL' at 10:44:56.963
 Status: subserver pid 21861 exit status 'JFAIL' at 10:44:57.038
 Status: job 'skrobic@cdc23+251' attempt 1 of 30, retrying at 10:44:57.149
 Status: waiting for subserver to exit at 10:44:57.428
 Status: attempt 2, sleeping 10 before retry at 10:44:57.550
 Status: subserver pid 21938 starting at 10:45:07.559
 Status: accounting at start at 10:45:07.561
 Status: opening device 'cdc-lj5%9100' at 10:45:07.563
 Status: printing job 'skrobic@cdc23+251' at 10:45:07.616
 Status: creating banner at 10:45:07.619
 Status: Filter_file: pgm '/opt/LPRng/libexec/filters/psbanner' exited with status 
'JSUCC' at 10:45:12.967
 Status: printing 'skrobic@cdc23+251' starting OF 'ifhp' at 10:45:12.970
 Status: OF filter suspended at 10:45:17.775
 Status: processing 'dfA251cdc23.cdc.informatik.tu-darmstadt.de', size 147816, format 
'f', IF filter 'ifhp' at 10:45:17.780
 Status: IF filter 'ifhp' filter finished at 10:45:58.283
 Status: OF filter finished at 10:46:02.073
 Status: printing finished at 10:46:02.075
 Status: accounting at end at 10:46:02.084
 Status: finished 'skrobic@cdc23+251', status 'JSUCC' at 10:46:02.087
 Status: subserver pid 21938 exit status 'JSUCC' at 10:46:02.146
 Status: hplj@cdc-intern: job 'skrobic@cdc23+251' printed at 10:46:02.156
 Status: job 'skrobic@cdc23+251' removed at 10:46:02.373
 Filter_status: initial job type 'POSTSCRIPT' at 10:43:15.021
 Filter_status: decoded job type 'POSTSCRIPT' at 10:43:15.023
 Filter_status: job type 'POSTSCRIPT' at 10:43:15.024
 Filter_status: transferring 147816 bytes at 10:43:15.031
 Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:43:15.091
 Filter_status: 27 percent done at 10:43:15.206
 Filter_status: 55 percent done at 10:43:15.382
 Filter_status: 83 percent done at 10:43:15.555
 Filter_status: data sent at 10:43:15.669
 Filter_status: sent job file at 10:43:15.670
 Filter_status: getting end using 'pjl job/eoj' at 10:43:15.675
 Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:43:19.566
 Filter_status: end of job detected at 10:43:34.475
 Filter_status: pagecounter 243615 at 10:43:35.765
 Filter_status: pagecounter 243615, pages 1 at 10:43:35.767
 Filter_status: done at 10:43:35.770
 Filter_status: (of) OF process running at 10:43:35.787
 Filter_status: (of) getting end using 'pjl job/eoj' at 10:43:35.824
 Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:43:37.615
 Filter_status: (of) end of job detected at 10:43:37.645
 Filter_status: (of) pagecounter 243615 at 10:43:39.544
 Filter_status: (of) pagecounter 243615, pages 2 at 10:43:39.547
 Filter_status: (of) ending OF mode passthrough at 10:43:39.549
 Filter_status: (of) done at 10:43:39.551
 Filter_status: (of) using model 'hp5m' at 10:44:29.424
 Filter_status: (of) pagecount using 'pjl info pagecount' at 10:44:29.446
 Filter_status: (of) setting up printer at 10:44:29.447
 Filter_status: (of) starting OF mode passthrough at 10:44:29.483
 Filter_status: (of) getting sync using 'pjl echo' at 10:44:29.484
 Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:44:29.491
 Filter_status: (of) sync done at 10:44:30.501
 Filter_status: (of) pagecounter 243615 at 10:44:30.504
 Filter_status: (of) pagecounter 243615 at 10:44:30.505
 Filter_status: (of) getting sync using 'pjl echo' at 10:44:31.795
 Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:44:31.975
 Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:44:33.323
 Filter_status: (of) sync done at 10:44:33.381
 Filter_status: (of) sending 8737 bytes of OF input at 10:44:33.389
 Filter_status: (of) starting transfer at 10:44:33.391
 Filter_status: (of) initial job type 'POSTSCRIPT' at 10:44:33.393
 Filter_status: (of) decoded job type 'POSTSCRIPT' at 10:44:33.395
 Filter_status: (of) job type 'POSTSCRIPT' at 10:44:33.396
 Filter_status: (of) transferring 8737 bytes at 10:44:33.401
 Filter_status: (of) 100 percent done at 10:44:33.406
 Filter_status: (of) data sent at 10:44:33.408
 Filter_status: (of) OF process suspending at 10:44:33.414
 Filter_status: using model 'hp5m' at 10:44:33.628
 Filter_status: pagecount using 'pjl info pagecount' at 10:44:33.648
 Filter_status: setting up printer at 10:44:33.649
 Filter_status: getting sync using 'pjl echo' at 10:44:33.651
 Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:44:33.657
 Filter_status: no response from printer at 10:44:53.460
 Filter_status: (of) OF process running at 10:44:53.477
 Filter_status: (of) getting end using 'pjl job/eoj' at 10:44:53.509
 Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:44:53.947
 Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:44:54.993
 Filter_status: (of) end of job detected at 10:44:55.027
 Filter_status: (of) pagecounter 243616 at 10:44:56.930
 Filter_status: (of) pagecounter 243616, pages 1 at 10:44:56.932
 Filter_status: (of) ending OF mode passthrough at 10:44:56.935
 Filter_status: (of) done at 10:44:56.936
 Filter_status: (of) using model 'hp5m' at 10:45:13.176
 Filter_status: (of) pagecount using 'pjl info pagecount' at 10:45:13.197
 Filter_status: (of) setting up printer at 10:45:13.198
 Filter_status: (of) starting OF mode passthrough at 10:45:13.243
 Filter_status: (of) getting sync using 'pjl echo' at 10:45:13.245
 Filter_status: (of) sync done at 10:45:14.868
 Filter_status: (of) pagecounter 243616 at 10:45:14.871
 Filter_status: (of) pagecounter 243616 at 10:45:14.872
 Filter_status: (of) getting sync using 'pjl echo' at 10:45:16.173
 Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:45:16.332
 Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:45:17.679
 Filter_status: (of) sync done at 10:45:17.738
 Filter_status: (of) sending 8737 bytes of OF input at 10:45:17.746
 Filter_status: (of) starting transfer at 10:45:17.748
 Filter_status: (of) initial job type 'POSTSCRIPT' at 10:45:17.749
 Filter_status: (of) decoded job type 'POSTSCRIPT' at 10:45:17.752
 Filter_status: (of) job type 'POSTSCRIPT' at 10:45:17.753
 Filter_status: (of) transferring 8737 bytes at 10:45:17.758
 Filter_status: (of) 100 percent done at 10:45:17.763
 Filter_status: (of) data sent at 10:45:17.765
 Filter_status: (of) OF process suspending at 10:45:17.771
 Filter_status: using model 'hp5m' at 10:45:17.986
 Filter_status: pagecount using 'pjl info pagecount' at 10:45:18.007
 Filter_status: setting up printer at 10:45:18.008
 Filter_status: getting sync using 'pjl echo' at 10:45:18.009
 Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:45:18.016
 Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:45:37.528
 Filter_status: sync done at 10:45:37.567
 Filter_status: pagecounter 243617 at 10:45:37.569
 Filter_status: pagecounter 243617 at 10:45:37.570
 Filter_status: sending job file at 10:45:37.580
 Filter_status: starting transfer at 10:45:37.581
 Filter_status: initial job type 'POSTSCRIPT' at 10:45:37.583
 Filter_status: decoded job type 'POSTSCRIPT' at 10:45:37.585
 Filter_status: job type 'POSTSCRIPT' at 10:45:37.587
 Filter_status: transferring 147816 bytes at 10:45:37.594
 Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:45:37.653
 Filter_status: 27 percent done at 10:45:37.767
 Filter_status: 55 percent done at 10:45:37.942
 Filter_status: 83 percent done at 10:45:38.118
 Filter_status: data sent at 10:45:38.232
 Filter_status: sent job file at 10:45:38.233
 Filter_status: getting end using 'pjl job/eoj' at 10:45:38.238
 Filter_status: code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:45:42.139
 Filter_status: end of job detected at 10:45:56.746
 Filter_status: pagecounter 243618 at 10:45:58.266
 Filter_status: pagecounter 243618, pages 1 at 10:45:58.268
 Filter_status: done at 10:45:58.270
 Filter_status: (of) OF process running at 10:45:58.286
 Filter_status: (of) getting end using 'pjl job/eoj' at 10:45:58.309
 Filter_status: (of) code = 11004, 'MP/Tray1/DuplexTray - A4 Paper' at 10:46:00.118
 Filter_status: (of) end of job detected at 10:46:00.148
 Filter_status: (of) pagecounter 243618 at 10:46:02.056
 Filter_status: (of) pagecounter 243618, pages 2 at 10:46:02.058
 Filter_status: (of) ending OF mode passthrough at 10:46:02.060
 Filter_status: (of) done at 10:46:02.062


-- 
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.
-----------------------------------------------------------------------------

Reply via email to