[Pkg-cups-devel] Bug#421063: cupsys: Finished print job remains in queue, cancel/lprm causes ipp to eat up cpu

Peter Mao pmdebian at yahoo.com
Thu Apr 26 07:59:07 UTC 2007


Package: cupsys
Version: 1.2.7-4
Severity: important

CUPS printing was working fine for me when I was using Sarge.
But after a fresh installation of Etch desktop task, printing becomes
more troublesome.

I'm able to print, either the test page from the CUPS web interface,
or using lp/lpr from an xterm.  The problem is that after the printout
is sitting on the printer, even after the printer goes to sleeping mode,
the print job still shows up in lpstat/lpq, and in http://localhost:631/jobs.
In the web interface, the printer status shows "Waiting for job to complete,"
and the job desciption says "processing since"...

The only way to print anything else is to cancel the current job.
However, both canceling from the xterm using cancel/lprm or
from the web interface results in the total consumption of one of the CPUs.

"ps fax" shows somthing like
 4145 ?        Ss     0:08 /usr/sbin/cupsd
 4365 ?        R      1:00  \_ ipp://printserver/lpt3 18 pm test.txt 1 job-u
and "top" shows "ipp" run by "root" with the same PID 4365 is eating up the
CPU.

After killing the process using root, I can print another file
with no problem.  But I have to repeat the above cancel and kill process.


The following is the content of /var/log/cups/error_log interlaced with
the commands causing the output:

I [26/Apr/2007:03:07:11 -0400] Listening to 127.0.0.1:631 (IPv4)
I [26/Apr/2007:03:07:11 -0400] Listening to /var/run/cups/cups.sock (Domain)
I [26/Apr/2007:03:07:11 -0400] Loaded configuration file "/etc/cups/cupsd.conf"
I [26/Apr/2007:03:07:11 -0400] Using default TempDir of /var/spool/cups/tmp...
I [26/Apr/2007:03:07:11 -0400] Cleaning out old temporary files in
"/var/spool/cups/tmp"...
I [26/Apr/2007:03:07:11 -0400] Configured for up to 100 clients.
I [26/Apr/2007:03:07:11 -0400] Allowing up to 100 client connections per host.
I [26/Apr/2007:03:07:11 -0400] Using policy "default" as the default!
I [26/Apr/2007:03:07:11 -0400] Full reload is required.
I [26/Apr/2007:03:07:11 -0400] Loaded MIME database from '/etc/cups': 34 types,
39 filters...
D [26/Apr/2007:03:07:11 -0400] Loading printer brother...
I [26/Apr/2007:03:07:11 -0400] Loading job cache file
"/var/cache/cups/job.cache"...
D [26/Apr/2007:03:07:11 -0400] Loading job 19 from cache...
I [26/Apr/2007:03:07:11 -0400] Full reload complete.
I [26/Apr/2007:03:07:11 -0400] Listening to 127.0.0.1:631 on fd 2...
I [26/Apr/2007:03:07:11 -0400] Listening to /var/run/cups/cups.sock on fd 3...


>>> lpq

D [26/Apr/2007:03:08:50 -0400] cupsdAcceptClient: 7 from localhost (Domain)
D [26/Apr/2007:03:08:50 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:08:50 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:08:50 -0400] CUPS-Get-Printers
D [26/Apr/2007:03:08:50 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:08:50 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:08:50 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:08:50 -0400] CUPS-Get-Classes
D [26/Apr/2007:03:08:50 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:08:50 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:08:50 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:08:50 -0400] CUPS-Get-Default
D [26/Apr/2007:03:08:50 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:08:50 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:08:50 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:08:50 -0400] Get-Printer-Attributes
ipp://localhost/printers/brother
D [26/Apr/2007:03:08:50 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:08:50 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:08:50 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:08:50 -0400] Get-Jobs ipp://localhost/printers/brother
D [26/Apr/2007:03:08:50 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:08:50 -0400] cupsdCloseClient: 7


>>> lpr test.txt

D [26/Apr/2007:03:09:39 -0400] cupsdAcceptClient: 7 from localhost (Domain)
D [26/Apr/2007:03:09:39 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:09:39 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:09:39 -0400] CUPS-Get-Printers
D [26/Apr/2007:03:09:39 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:09:39 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:09:39 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:09:39 -0400] CUPS-Get-Classes
D [26/Apr/2007:03:09:39 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:09:39 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:09:39 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:09:39 -0400] CUPS-Get-Default
D [26/Apr/2007:03:09:39 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:09:39 -0400] cupsdCloseClient: 7
D [26/Apr/2007:03:09:39 -0400] cupsdAcceptClient: 7 from localhost (Domain)
D [26/Apr/2007:03:09:39 -0400] cupsdReadClient: 7 POST /printers/brother
HTTP/1.1
D [26/Apr/2007:03:09:39 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:09:39 -0400] Print-Job ipp://localhost/printers/brother
D [26/Apr/2007:03:09:39 -0400] print_job: auto-typing file...
D [26/Apr/2007:03:09:39 -0400] print_job: request file type is text/plain.
D [26/Apr/2007:03:09:39 -0400] add_job: requesting-user-name="pm"
I [26/Apr/2007:03:09:39 -0400] Adding start banner page "none" to job 20.
D [26/Apr/2007:03:09:39 -0400] Discarding unused job-created event...
I [26/Apr/2007:03:09:39 -0400] Adding end banner page "none" to job 20.
I [26/Apr/2007:03:09:39 -0400] Job 20 queued on "brother" by "pm".
D [26/Apr/2007:03:09:39 -0400] Job 20 hold_until = 0
D [26/Apr/2007:03:09:39 -0400] Discarding unused printer-state-changed event...
D [26/Apr/2007:03:09:39 -0400] job-sheets=none,none
D [26/Apr/2007:03:09:39 -0400] banner_page = 0
D [26/Apr/2007:03:09:39 -0400] [Job 20] argv[0]="brother"
D [26/Apr/2007:03:09:39 -0400] [Job 20] argv[1]="20"
D [26/Apr/2007:03:09:39 -0400] [Job 20] argv[2]="pm"
D [26/Apr/2007:03:09:39 -0400] [Job 20] argv[3]="test.txt"
D [26/Apr/2007:03:09:39 -0400] [Job 20] argv[4]="1"
D [26/Apr/2007:03:09:39 -0400] [Job 20] argv[5]="job-uuid"
D [26/Apr/2007:03:09:39 -0400] [Job 20] argv[6]="/var/spool/cups/d00020-001"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [26/Apr/2007:03:09:39 -0400] [Job 20]
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [26/Apr/2007:03:09:39 -0400] [Job 20]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Apr/2007:03:09:39 -0400] [Job 20]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [26/Apr/2007:03:09:39 -0400] [Job 20]
envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Apr/2007:03:09:39 -0400] [Job 20]
envp[9]="SERVER_ADMIN=root at beijing.china"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[10]="SOFTWARE=CUPS/1.2.7"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[12]="TZ=US/Eastern"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[13]="USER=root"
D [26/Apr/2007:03:09:39 -0400] [Job 20]
envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[16]="IPP_PORT=631"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[17]="CHARSET=utf-8"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[18]="LANG=en_US"
D [26/Apr/2007:03:09:39 -0400] [Job 20]
envp[19]="PPD=/etc/cups/ppd/brother.ppd"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[20]="RIP_MAX_CACHE=8m"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[21]="CONTENT_TYPE=text/plain"
D [26/Apr/2007:03:09:39 -0400] [Job 20]
envp[22]="DEVICE_URI=ipp://printserver/lpt3"
D [26/Apr/2007:03:09:39 -0400] [Job 20] envp[23]="PRINTER=brother"
D [26/Apr/2007:03:09:39 -0400] [Job 20]
envp[24]="FINAL_CONTENT_TYPE=printer/brother"
I [26/Apr/2007:03:09:39 -0400] Started filter /usr/lib/cups/filter/texttops
(PID 4910) for job 20.
I [26/Apr/2007:03:09:39 -0400] Started filter /usr/lib/cups/filter/pstops (PID
4911) for job 20.
I [26/Apr/2007:03:09:39 -0400] Started filter
/usr/lib/cups/filter/brlpdwrapperHL2040 (PID 4912) for job 20.
I [26/Apr/2007:03:09:39 -0400] Started backend /usr/lib/cups/backend/ipp (PID
4915) for job 20.
D [26/Apr/2007:03:09:39 -0400] Discarding unused job-state event...
D [26/Apr/2007:03:09:39 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:09:39 -0400] [Job 20] Page = 612x792; 18,12 to 594,780
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->num_fonts = 66
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[0] = AvantGarde-Book
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[1] = AvantGarde-BookOblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[2] = AvantGarde-Demi
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[3] = AvantGarde-DemiOblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[4] = Bookman-Demi
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[5] = Bookman-DemiItalic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[6] = Bookman-Light
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[7] = Bookman-LightItalic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[8] = Courier
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[9] = Courier-Bold
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->num_fonts = 66
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[0] = AvantGarde-Book
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[1] = AvantGarde-BookOblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[2] = AvantGarde-Demi
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[3] = AvantGarde-DemiOblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[4] = Bookman-Demi
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[5] = Bookman-DemiItalic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[6] = Bookman-Light
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[7] = Bookman-LightItalic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[8] = Courier
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->num_fonts = 66

D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[0] = AvantGarde-Book
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[1] = AvantGarde-BookOblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[2] = AvantGarde-Demi
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[3] = AvantGarde-DemiOblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[4] = Bookman-Demi
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[5] = Bookman-DemiItalic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[6] = Bookman-Light
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[7] = Bookman-LightItalic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[8] = Courier
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[9] = Courier-Bold
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[10] = Courier-BoldOblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[11] = Courier-Oblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[12] = Helvetica
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[13] = Helvetica-Bold
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[14] = Helvetica-BoldOblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[15] = Helvetica-Narrow
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[16] = Helvetica-Narrow-Bold
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[17] =
Helvetica-Narrow-BoldOblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[18] =
Helvetica-Narrow-Oblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[19] = Helvetica-Oblique
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[20] = NewCenturySchlbk-Bold
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[21] =
NewCenturySchlbk-BoldItalic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[22] =
NewCenturySchlbk-Italic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[23] = NewCenturySchlbk-Roman
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[24] = Palatino-Bold
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[25] = Palatino-BoldItalic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[26] = Palatino-Italic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[27] = Palatino-Roman
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[28] = Times-Bold
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[29] = Times-BoldItalic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[30] = Times-Italic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[31] = Times-Roman
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[32] =
ZapfChancery-MediumItalic
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[33] = ZapfDingbats
D [26/Apr/2007:03:09:39 -0400] [Job 20] ppd->fonts[34] = Symbol
D [26/Apr/2007:03:09:39 -0400] [Job 20] Page = 612x792; 18,12 to 594,780
D [26/Apr/2007:03:09:39 -0400] [Job 20] slow_collate=0, slow_duplex=0,
slow_order=0
D [26/Apr/2007:03:09:39 -0400] [Job 20] Before copy_comments - %!PS-Adobe-3.0
D [26/Apr/2007:03:09:39 -0400] [Job 20] %!PS-Adobe-3.0
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%BoundingBox: 0 0 612 792
D [26/Apr/2007:03:09:39 -0400] [Job 20] %cupsRotation: 0
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%Creator: texttops/CUPS v1.2.7
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%CreationDate: Thu Apr 26 03:09:39
2007
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%Title: (test.txt)
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%For: (pm)
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%Pages: (atend)
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%DocumentNeededResources: font
Courier-Bold
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%+ font Courier
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%+ font Symbol
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%DocumentSuppliedResources: procset
texttops 1.1 0
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%+ font Courier-Bold
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%+ font Courier
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%+ font Symbol
D [26/Apr/2007:03:09:39 -0400] [Job 20] %%EndComments
D [26/Apr/2007:03:09:39 -0400] [Job 20] Before copy_prolog - %%BeginProlog
D [26/Apr/2007:03:09:39 -0400] cupsdCloseClient: 7
D [26/Apr/2007:03:09:39 -0400] PID 4910 (/usr/lib/cups/filter/texttops) exited
with no errors.
D [26/Apr/2007:03:09:39 -0400] PID 4911 (/usr/lib/cups/filter/pstops) exited
with no errors.
D [26/Apr/2007:03:09:39 -0400] [Job 20] Before copy_setup - %%Page: 1 1
D [26/Apr/2007:03:09:39 -0400] [Job 20] Before page loop - %%Page: 1 1
D [26/Apr/2007:03:09:39 -0400] [Job 20] Copying page 1...
D [26/Apr/2007:03:09:39 -0400] [Job 20] pagew = 576.0, pagel = 768.0
D [26/Apr/2007:03:09:39 -0400] [Job 20] bboxw = 612, bboxl = 792
D [26/Apr/2007:03:09:39 -0400] [Job 20] PageLeft = 18.0, PageRight = 594.0
D [26/Apr/2007:03:09:39 -0400] [Job 20] PageTop = 780.0, PageBottom = 12.0
D [26/Apr/2007:03:09:39 -0400] [Job 20] PageWidth = 612.0, PageLength = 792.0
D [26/Apr/2007:03:09:39 -0400] [Job 20] Wrote 1 pages...
D [26/Apr/2007:03:09:39 -0400] PID 4912
(/usr/lib/cups/filter/brlpdwrapperHL2040) exited with no errors.
D [26/Apr/2007:03:09:39 -0400] [Job 20] 1 files to send in job...
D [26/Apr/2007:03:09:39 -0400] Discarding unused printer-state-changed event...
D [26/Apr/2007:03:09:39 -0400] Discarding unused printer-state-changed event...
D [26/Apr/2007:03:09:39 -0400] [Job 20] Connected to 192.168.1.11:631 (IPv4)...
D [26/Apr/2007:03:09:39 -0400] [Job 20] Getting supported attributes...
D [26/Apr/2007:03:09:39 -0400] [Job 20] printer-uri =
"ipp://printserver:631/lpt3"
D [26/Apr/2007:03:09:39 -0400] [Job 20] requesting-user-name = "pm"
D [26/Apr/2007:03:09:39 -0400] [Job 20] job-name = "test.txt"
N [26/Apr/2007:03:10:00 -0400] [Job 20] Print file accepted - job ID 98.
D [26/Apr/2007:03:10:00 -0400] Discarding unused printer-state-changed event...


>>> lpq

D [26/Apr/2007:03:10:25 -0400] cupsdAcceptClient: 7 from localhost (Domain)
D [26/Apr/2007:03:10:25 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:10:25 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:10:25 -0400] CUPS-Get-Printers
D [26/Apr/2007:03:10:25 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:10:25 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:10:25 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:10:25 -0400] CUPS-Get-Classes
D [26/Apr/2007:03:10:25 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:10:25 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:10:25 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:10:25 -0400] CUPS-Get-Default
D [26/Apr/2007:03:10:25 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:10:25 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:10:25 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:10:25 -0400] Get-Printer-Attributes
ipp://localhost/printers/brother
D [26/Apr/2007:03:10:25 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:10:25 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:10:25 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:10:25 -0400] Get-Jobs ipp://localhost/printers/brother
D [26/Apr/2007:03:10:25 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:10:25 -0400] cupsdCloseClient: 7


>>> lprm 20, the job in queue

D [26/Apr/2007:03:10:56 -0400] cupsdAcceptClient: 7 from localhost (Domain)
D [26/Apr/2007:03:10:56 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:10:56 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:10:56 -0400] CUPS-Get-Printers
D [26/Apr/2007:03:10:56 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:10:56 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:10:56 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:10:56 -0400] CUPS-Get-Classes
D [26/Apr/2007:03:10:56 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:10:56 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [26/Apr/2007:03:10:56 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:10:56 -0400] CUPS-Get-Default
D [26/Apr/2007:03:10:56 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:10:56 -0400] cupsdReadClient: 7 POST /jobs/ HTTP/1.1
D [26/Apr/2007:03:10:56 -0400] cupsdAuthorize: No authentication data provided.
D [26/Apr/2007:03:10:56 -0400] Cancel-Job ipp://localhost/jobs/20
D [26/Apr/2007:03:10:56 -0400] Discarding unused printer-state-changed event...
D [26/Apr/2007:03:10:56 -0400] Discarding unused job-completed event...
I [26/Apr/2007:03:10:56 -0400] Job 20 was canceled by "pm".
D [26/Apr/2007:03:10:56 -0400] cupsdProcessIPPRequest: 7 status_code=0
(successful-ok)
D [26/Apr/2007:03:10:56 -0400] cupsdCloseClient: 7
D [26/Apr/2007:03:10:57 -0400] Unloading job 20...


>>> now CUP consumption is 100%, have to kill the ipp process

E [26/Apr/2007:03:11:19 -0400] PID 4915 (/usr/lib/cups/backend/ipp) stopped
with status 1!



A similar problem was reported at
 http://lists.debian.org/debian-printing/2007/02/msg00036.html
but no excessive CPU consumption was reported there.

Thank you!

Peter



-- System Information:
Debian Release: 4.0
  APT prefers stable
  APT policy: (500, 'stable')
Architecture: i386 (i686)
Shell:  /bin/sh linked to /bin/bash
Kernel: Linux 2.6.18-4-686
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)

Versions of packages cupsys depends on:
ii  adduser                  3.102           Add and remove users and groups
ii  cupsys-common            1.2.7-4         Common UNIX Printing System(tm) -
ii  debconf [debconf-2.0]    1.5.11          Debian configuration management sy
ii  gs-esp                   8.15.3.dfsg.1-1 The Ghostscript PostScript interpr
ii  libc6                    2.3.6.ds1-13    GNU C Library: Shared libraries
ii  libcupsimage2            1.2.7-4         Common UNIX Printing System(tm) -
ii  libcupsys2               1.2.7-4         Common UNIX Printing System(tm) -
ii  libdbus-1-3              1.0.2-1         simple interprocess messaging syst
ii  libgnutls13              1.4.4-3         the GNU TLS library - runtime libr
ii  libldap2                 2.1.30-13.3     OpenLDAP libraries
ii  libpam0g                 0.79-4          Pluggable Authentication Modules l
ii  libpaper1                1.1.21          Library for handling paper charact
ii  libslp1                  1.2.1-6.2       OpenSLP libraries
ii  lsb-base                 3.1-23.1        Linux Standard Base 3.1 init scrip
ii  patch                    2.5.9-4         Apply a diff file to an original
ii  perl-modules             5.8.8-7         Core Perl modules
ii  poppler-utils [xpdf-util 0.4.5-5.1       PDF utilitites (based on libpopple
ii  procps                   1:3.2.7-3       /proc file system utilities
ii  zlib1g                   1:1.2.3-13      compression library - runtime

Versions of packages cupsys recommends:
ii  cupsys-client         1.2.7-4            Common UNIX Printing System(tm) -
ii  foomatic-filters      3.0.2-20061031-1.2 linuxprinting.org printer support
pn  smbclient             <none>             (no description available)

-- debconf information:
  cupsys/raw-print: true
  cupsys/backend: ipp, lpd, parallel, socket, usb








__________________________________________________
Do You Yahoo!?
Tired of spam?  Yahoo! Mail has the best spam protection around 
http://mail.yahoo.com 

__________________________________________________
Do You Yahoo!?
Tired of spam?  Yahoo! Mail has the best spam protection around 
http://mail.yahoo.com 




More information about the Pkg-cups-devel mailing list