Your message dated Tue, 14 Oct 2014 14:00:26 +0200
with message-id <[email protected]>
and subject line Re: Bug#762338: Generated PDF has tiny font size
has caused the Debian Bug report #762338,
regarding Generated PDF has tiny font size
to be marked as done.

This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.

(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact [email protected]
immediately.)


-- 
762338: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=762338
Debian Bug Tracking System
Contact [email protected] with problems
--- Begin Message ---
Package: cups-pdf
Version: 2.6.1-12
Severity: normal

On a new Sid installation (Ghostscript 9.06~dfsg-1, but same problem
with 9.15~rc1~dfsg-1 from experimental), the command

  echo bla | lp -d PDF

produces the attached PDF, which contains text with the tiniest
possible font size.

The cupsd debug log of the job is attached.

-- System Information:
Debian Release: jessie/sid
  APT prefers unstable
  APT policy: (500, 'unstable'), (1, 'experimental')
Architecture: amd64 (x86_64)
Foreign Architectures: i386

Kernel: Linux 3.16-1-amd64 (SMP w/4 CPU cores)
Locale: LANG=en_NZ, LC_CTYPE=en_NZ.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash

Versions of packages cups-pdf depends on:
ii  printer-driver-cups-pdf  2.6.1-12

cups-pdf recommends no packages.

cups-pdf suggests no packages.

-- no debconf information


-- 
 .''`.   martin f. krafft <[email protected]> @martinkrafft
: :'  :  proud Debian developer
`. `'`   http://people.debian.org/~madduck
  `-  Debian - when you have better things to do than fixing systems

Attachment: _stdin_.pdf
Description: Adobe PDF document

I [21/Sep/2014:12:17:20 +0200] Listening to [v1.::1]:631 (IPv6)
I [21/Sep/2014:12:17:20 +0200] Listening to 127.0.0.1:631 (IPv4)
I [21/Sep/2014:12:17:20 +0200] Listening to /var/run/cups/cups.sock (Domain)
I [21/Sep/2014:12:17:20 +0200] Remote access is disabled.
D [21/Sep/2014:12:17:20 +0200] Added auto ServerAlias fishbowl
I [21/Sep/2014:12:17:20 +0200] Loaded configuration file "/etc/cups/cupsd.conf"
I [21/Sep/2014:12:17:20 +0200] Using default TempDir of /var/spool/cups/tmp...
I [21/Sep/2014:12:17:20 +0200] Configured for up to 100 clients.
I [21/Sep/2014:12:17:20 +0200] Allowing up to 100 client connections per host.
I [21/Sep/2014:12:17:20 +0200] Using policy "default" as the default.
I [21/Sep/2014:12:17:20 +0200] Full reload is required.
I [21/Sep/2014:12:17:20 +0200] Loaded MIME database from "/usr/share/cups/mime" 
and "/etc/cups": 39 types, 53 filters...
D [21/Sep/2014:12:17:20 +0200] Loading printer hplj3052...
D [21/Sep/2014:12:17:20 +0200] cupsdMarkDirty(P----)
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Not busy"
D [21/Sep/2014:12:17:20 +0200] load_ppd: Loading /etc/cups/ppd/hplj3052.ppd...
D [21/Sep/2014:12:17:20 +0200] cupsdRegisterPrinter(p=0x7fafe54dbf50(hplj3052))
D [21/Sep/2014:12:17:20 +0200] Loading printer PDF...
D [21/Sep/2014:12:17:20 +0200] load_ppd: Loading /var/cache/cups/PDF.data...
D [21/Sep/2014:12:17:20 +0200] cupsdRegisterPrinter(p=0x7fafe54e0060(PDF))
I [21/Sep/2014:12:17:20 +0200] Loading job cache file 
"/var/cache/cups/job.cache"...
D [21/Sep/2014:12:17:20 +0200] [Job 1] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 5] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 7] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 8] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 12] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 14] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 15] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 16] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 17] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 18] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 19] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 20] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 21] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 22] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 23] Loading from cache...
D [21/Sep/2014:12:17:20 +0200] [Job 24] Loading from cache...
I [21/Sep/2014:12:17:20 +0200] Full reload complete.
D [21/Sep/2014:12:17:20 +0200] cupsdCleanFiles(path="/var/spool/cups/tmp", 
pattern="(null)")
I [21/Sep/2014:12:17:20 +0200] Cleaning out old files in "/var/spool/cups/tmp".
D [21/Sep/2014:12:17:20 +0200] cupsdCleanFiles(path="/var/cache/cups", 
pattern="*.ipp")
I [21/Sep/2014:12:17:20 +0200] Cleaning out old files in "/var/cache/cups".
D [21/Sep/2014:12:17:20 +0200] systemd_checkin: Matched existing listener 
/var/run/cups/cups.sock with fd 3...
D [21/Sep/2014:12:17:20 +0200] systemd_checkin: Matched existing listener 
127.0.0.1 with fd 4...
D [21/Sep/2014:12:17:20 +0200] systemd_checkin: Matched existing listener 
[v1.::1] with fd 5...
D [21/Sep/2014:12:17:20 +0200] systemd_checkin: Activate exit-on-idle mode, 
timeout: 30 seconds.
D [21/Sep/2014:12:17:20 +0200] Calling FindDeviceById(cups-hplj3052)
D [21/Sep/2014:12:17:20 +0200] FindDeviceById failed: 
org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager 
was not provided by any .service files
D [21/Sep/2014:12:17:20 +0200] Calling FindDeviceById(cups-PDF)
D [21/Sep/2014:12:17:20 +0200] FindDeviceById failed: 
org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager 
was not provided by any .service files
D [21/Sep/2014:12:17:20 +0200] Using profile ID "PDF-Gray..".
D [21/Sep/2014:12:17:20 +0200] Calling CreateProfile(PDF-Gray..,temp)
W [21/Sep/2014:12:17:20 +0200] CreateProfile failed: 
org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager 
was not provided by any .service files
D [21/Sep/2014:12:17:20 +0200] Using profile ID "PDF-RGB..".
D [21/Sep/2014:12:17:20 +0200] Calling CreateProfile(PDF-RGB..,temp)
W [21/Sep/2014:12:17:20 +0200] CreateProfile failed: 
org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager 
was not provided by any .service files
I [21/Sep/2014:12:17:20 +0200] Registering ICC color profiles for "PDF".
D [21/Sep/2014:12:17:20 +0200] Calling CreateDevice(cups-PDF,temp)
W [21/Sep/2014:12:17:20 +0200] CreateDevice failed: 
org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager 
was not provided by any .service files
I [21/Sep/2014:12:17:20 +0200] Listening to [v1.::1]:631 on fd 5...
I [21/Sep/2014:12:17:20 +0200] Listening to 127.0.0.1:631 on fd 4...
I [21/Sep/2014:12:17:20 +0200] Listening to /var/run/cups/cups.sock:631 on fd 
3...
I [21/Sep/2014:12:17:20 +0200] Resuming new connection processing...
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:20 +0200] Discarding unused server-started event...
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 14] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:20 +0200] [Client 14] Waiting for request.
D [21/Sep/2014:12:17:20 +0200] Report: clients=1
D [21/Sep/2014:12:17:20 +0200] Report: jobs=16
D [21/Sep/2014:12:17:20 +0200] Report: jobs-active=0
D [21/Sep/2014:12:17:20 +0200] Report: printers=2
D [21/Sep/2014:12:17:20 +0200] Report: stringpool-string-count=1267
D [21/Sep/2014:12:17:20 +0200] Report: stringpool-alloc-bytes=11304
D [21/Sep/2014:12:17:20 +0200] Report: stringpool-total-bytes=24160
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 14] POST / HTTP/1.1
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:20 +0200] [Client 14] No authentication data provided.
D [21/Sep/2014:12:17:20 +0200] [Client 14] 2.0 CUPS-Get-Printers 1
D [21/Sep/2014:12:17:20 +0200] CUPS-Get-Printers
D [21/Sep/2014:12:17:20 +0200] [Client 14] Returning IPP successful-ok for 
CUPS-Get-Printers (no URI) from localhost
D [21/Sep/2014:12:17:20 +0200] [Client 14] Content-Length: 1811
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 14] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=1811, response=0x7fafe5521560(IPP_IDLE), pipe_pid=0, file=-1
D [21/Sep/2014:12:17:20 +0200] [Client 14] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [21/Sep/2014:12:17:20 +0200] [Client 14] bytes=0, http_state=0, 
data_remaining=0
D [21/Sep/2014:12:17:20 +0200] [Client 14] Waiting for request.
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 14] POST / HTTP/1.1
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:20 +0200] [Client 14] No authentication data provided.
D [21/Sep/2014:12:17:20 +0200] [Client 14] 2.0 CUPS-Get-Default 2
D [21/Sep/2014:12:17:20 +0200] CUPS-Get-Default
D [21/Sep/2014:12:17:20 +0200] [Client 14] Returning IPP successful-ok for 
CUPS-Get-Default (no URI) from localhost
D [21/Sep/2014:12:17:20 +0200] [Client 14] Content-Length: 12563
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 14] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=12563, response=0x7fafe5521b00(IPP_IDLE), pipe_pid=0, file=-1
D [21/Sep/2014:12:17:20 +0200] [Client 14] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [21/Sep/2014:12:17:20 +0200] [Client 14] bytes=0, http_state=0, 
data_remaining=0
D [21/Sep/2014:12:17:20 +0200] [Client 14] Waiting for request.
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 14] POST / HTTP/1.1
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:20 +0200] [Client 14] No authentication data provided.
D [21/Sep/2014:12:17:20 +0200] [Client 14] 2.0 CUPS-Get-Printers 3
D [21/Sep/2014:12:17:20 +0200] CUPS-Get-Printers
D [21/Sep/2014:12:17:20 +0200] [Client 14] Returning IPP successful-ok for 
CUPS-Get-Printers (no URI) from localhost
D [21/Sep/2014:12:17:20 +0200] [Client 14] Content-Length: 1811
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 14] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=1811, response=0x7fafe5521b50(IPP_IDLE), pipe_pid=0, file=-1
D [21/Sep/2014:12:17:20 +0200] [Client 14] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [21/Sep/2014:12:17:20 +0200] [Client 14] bytes=0, http_state=0, 
data_remaining=0
D [21/Sep/2014:12:17:20 +0200] [Client 14] Waiting for request.
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 14] POST / HTTP/1.1
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:20 +0200] [Client 14] No authentication data provided.
D [21/Sep/2014:12:17:20 +0200] [Client 14] 2.0 CUPS-Get-Default 4
D [21/Sep/2014:12:17:20 +0200] CUPS-Get-Default
D [21/Sep/2014:12:17:20 +0200] [Client 14] Returning IPP successful-ok for 
CUPS-Get-Default (no URI) from localhost
D [21/Sep/2014:12:17:20 +0200] [Client 14] Content-Length: 12563
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 14] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=12563, response=0x7fafe5521b00(IPP_IDLE), pipe_pid=0, file=-1
D [21/Sep/2014:12:17:20 +0200] [Client 14] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [21/Sep/2014:12:17:20 +0200] [Client 14] bytes=0, http_state=0, 
data_remaining=0
D [21/Sep/2014:12:17:20 +0200] [Client 14] Waiting for request.
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 15] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:20 +0200] [Client 15] Waiting for request.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 16] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:20 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:20 +0200] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:20 +0200] [Client 15] Closing connection.
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 16] POST / HTTP/1.1
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:20 +0200] [Client 16] No authentication data provided.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 16] 2.0 CUPS-Get-Printers 1
D [21/Sep/2014:12:17:20 +0200] CUPS-Get-Printers
D [21/Sep/2014:12:17:20 +0200] [Client 16] Returning IPP successful-ok for 
CUPS-Get-Printers (no URI) from localhost
D [21/Sep/2014:12:17:20 +0200] [Client 16] Content-Length: 1653
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:20 +0200] [Client 16] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=1653, response=0x7fafe5521510(IPP_IDLE), pipe_pid=0, file=-1
D [21/Sep/2014:12:17:20 +0200] [Client 16] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [21/Sep/2014:12:17:20 +0200] [Client 16] bytes=0, http_state=0, 
data_remaining=0
D [21/Sep/2014:12:17:20 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:20 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [21/Sep/2014:12:17:20 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:21 +0200] [Client 15] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:21 +0200] [Client 16] Closing connection.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] GET /printers/PDF.ppd HTTP/1.1
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] [Client 15] No authentication data provided.
D [21/Sep/2014:12:17:21 +0200] [Client 15] Sending file.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=20940, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=18892, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=16844, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=14796, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=12748, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=10700, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=8652, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=6604, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=4556, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=2508, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=460, response=(nil)(), pipe_pid=0, file=16
D [21/Sep/2014:12:17:21 +0200] [Client 15] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:21 +0200] [Client 15] Closing connection.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:21 +0200] [Client 15] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:21 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:21 +0200] [Client 15] Closing connection.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] POST / HTTP/1.1
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] [Client 16] No authentication data provided.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] 2.0 CUPS-Get-Printers 1
D [21/Sep/2014:12:17:21 +0200] CUPS-Get-Printers
D [21/Sep/2014:12:17:21 +0200] [Client 16] Returning IPP successful-ok for 
CUPS-Get-Printers (no URI) from localhost
D [21/Sep/2014:12:17:21 +0200] [Client 16] Content-Length: 1653
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=1653, response=0x7fafe5521510(IPP_IDLE), pipe_pid=0, file=-1
D [21/Sep/2014:12:17:21 +0200] [Client 16] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [21/Sep/2014:12:17:21 +0200] [Client 16] bytes=0, http_state=0, 
data_remaining=0
D [21/Sep/2014:12:17:21 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:21 +0200] [Client 16] Closing connection.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:21 +0200] [Client 15] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:21 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:21 +0200] [Client 15] Closing connection.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] POST / HTTP/1.1
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] [Client 16] No authentication data provided.
D [21/Sep/2014:12:17:21 +0200] [Client 16] 2.0 CUPS-Get-Printers 1
D [21/Sep/2014:12:17:21 +0200] CUPS-Get-Printers
D [21/Sep/2014:12:17:21 +0200] [Client 16] Returning IPP successful-ok for 
CUPS-Get-Printers (no URI) from localhost
D [21/Sep/2014:12:17:21 +0200] [Client 16] Content-Length: 1653
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=1653, response=0x7fafe5521510(IPP_IDLE), pipe_pid=0, file=-1
D [21/Sep/2014:12:17:21 +0200] [Client 16] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [21/Sep/2014:12:17:21 +0200] [Client 16] bytes=0, http_state=0, 
data_remaining=0
D [21/Sep/2014:12:17:21 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:21 +0200] [Client 16] Closing connection.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:21 +0200] [Client 15] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:21 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:21 +0200] [Client 15] Closing connection.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] POST / HTTP/1.1
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] [Client 16] No authentication data provided.
D [21/Sep/2014:12:17:21 +0200] [Client 16] 2.0 CUPS-Get-Printers 1
D [21/Sep/2014:12:17:21 +0200] CUPS-Get-Printers
D [21/Sep/2014:12:17:21 +0200] [Client 16] Returning IPP successful-ok for 
CUPS-Get-Printers (no URI) from localhost
D [21/Sep/2014:12:17:21 +0200] [Client 16] Content-Length: 1653
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=1653, response=0x7fafe5521510(IPP_IDLE), pipe_pid=0, file=-1
D [21/Sep/2014:12:17:21 +0200] [Client 16] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [21/Sep/2014:12:17:21 +0200] [Client 16] bytes=0, http_state=0, 
data_remaining=0
D [21/Sep/2014:12:17:21 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:21 +0200] [Client 16] Closing connection.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 15] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:21 +0200] [Client 15] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:21 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:21 +0200] [Client 15] Closing connection.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] POST / HTTP/1.1
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] [Client 16] No authentication data provided.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] 2.0 CUPS-Get-Printers 1
D [21/Sep/2014:12:17:21 +0200] CUPS-Get-Printers
D [21/Sep/2014:12:17:21 +0200] [Client 16] Returning IPP successful-ok for 
CUPS-Get-Printers (no URI) from localhost
D [21/Sep/2014:12:17:21 +0200] [Client 16] Content-Length: 1653
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=1653, response=0x7fafe5521510(IPP_IDLE), pipe_pid=0, file=-1
D [21/Sep/2014:12:17:21 +0200] [Client 16] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [21/Sep/2014:12:17:21 +0200] [Client 16] bytes=0, http_state=0, 
data_remaining=0
D [21/Sep/2014:12:17:21 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:21 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:21 +0200] [Client 16] Closing connection.
D [21/Sep/2014:12:17:21 +0200] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [21/Sep/2014:12:17:21 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] [Client 15] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:22 +0200] [Client 15] Waiting for request.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] [Client 16] Accepted from localhost (Domain)
D [21/Sep/2014:12:17:22 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] [Client 16] POST /printers/PDF HTTP/1.1
D [21/Sep/2014:12:17:22 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [21/Sep/2014:12:17:22 +0200] [Client 16] No authentication data provided.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] [Client 16] 2.1 Print-Job 1
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] Print-Job ipp://localhost:631/printers/PDF
D [21/Sep/2014:12:17:22 +0200] [Job ???] Auto-typing file...
I [21/Sep/2014:12:17:22 +0200] [Job ???] Request file type is application/pdf.
D [21/Sep/2014:12:17:22 +0200] cupsdMarkDirty(---J-)
D [21/Sep/2014:12:17:22 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [21/Sep/2014:12:17:22 +0200] add_job: requesting-user-name="madduck"
I [21/Sep/2014:12:17:22 +0200] [Job 25] Adding start banner page "none".
D [21/Sep/2014:12:17:22 +0200] Discarding unused job-created event...
D [21/Sep/2014:12:17:22 +0200] cupsdMarkDirty(---J-)
D [21/Sep/2014:12:17:22 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
I [21/Sep/2014:12:17:22 +0200] [Job 25] Adding end banner page "none".
I [21/Sep/2014:12:17:22 +0200] [Job 25] File of type application/pdf queued by 
"madduck".
D [21/Sep/2014:12:17:22 +0200] [Job 25] hold_until=0
I [21/Sep/2014:12:17:22 +0200] [Job 25] Queued on "PDF" by "madduck".
D [21/Sep/2014:12:17:22 +0200] [Job 25] time-at-processing=1411294642
D [21/Sep/2014:12:17:22 +0200] cupsdMarkDirty(---J-)
D [21/Sep/2014:12:17:22 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [21/Sep/2014:12:17:22 +0200] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [21/Sep/2014:12:17:22 +0200] Discarding unused printer-state-changed event...
D [21/Sep/2014:12:17:22 +0200] [Job 25] 3 filters for job:
D [21/Sep/2014:12:17:22 +0200] [Job 25] pdftopdf (application/pdf to 
application/vnd.cups-pdf, cost 66)
D [21/Sep/2014:12:17:22 +0200] [Job 25] pdftops (application/vnd.cups-pdf to 
application/vnd.cups-postscript, cost 100)
D [21/Sep/2014:12:17:22 +0200] [Job 25] - (application/vnd.cups-postscript to 
printer/PDF, cost 0)
D [21/Sep/2014:12:17:22 +0200] [Job 25] job-sheets=none,none
D [21/Sep/2014:12:17:22 +0200] [Job 25] argv[0]="PDF"
D [21/Sep/2014:12:17:22 +0200] [Job 25] argv[1]="25"
D [21/Sep/2014:12:17:22 +0200] [Job 25] argv[2]="madduck"
D [21/Sep/2014:12:17:22 +0200] [Job 25] argv[3]="django-gitcms/load.py at 
master · luispedro/django-gitcms"
D [21/Sep/2014:12:17:22 +0200] [Job 25] argv[4]="1"
D [21/Sep/2014:12:17:22 +0200] [Job 25] argv[5]="PageSize=A4 Resolution=300dpi 
number-up=1 job-uuid=urn:uuid:1441d73c-f580-3aed-6b53-0bad28664a4d 
job-originating-host-name=localhost time-at-creation=1411294642 
time-at-processing=1411294642"
D [21/Sep/2014:12:17:22 +0200] [Job 25] argv[6]="/var/spool/cups/d00025-001"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [21/Sep/2014:12:17:22 +0200] [Job 25] 
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [21/Sep/2014:12:17:22 +0200] [Job 25] 
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [21/Sep/2014:12:17:22 +0200] [Job 25] 
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[8]="HOME=/var/spool/cups/tmp"
D [21/Sep/2014:12:17:22 +0200] [Job 25] 
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[10]="SERVER_ADMIN=root@fishbowl"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[11]="SOFTWARE=CUPS/1.7.5"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[13]="USER=root"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[14]="CUPS_MAX_MESSAGE=2047"
D [21/Sep/2014:12:17:22 +0200] [Job 25] 
envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[17]="IPP_PORT=631"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[18]="CHARSET=utf-8"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[19]="LANG=en_NZ.UTF-8"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[20]="PPD=/etc/cups/ppd/PDF.ppd"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[21]="RIP_MAX_CACHE=128m"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[22]="CONTENT_TYPE=application/pdf"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[23]="DEVICE_URI=cups-pdf:/"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[24]="PRINTER_INFO=PDF"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[25]="PRINTER_LOCATION="
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[26]="PRINTER=PDF"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[27]="PRINTER_STATE_REASONS=none"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[28]="CUPS_FILETYPE=document"
D [21/Sep/2014:12:17:22 +0200] [Job 25] 
envp[29]="FINAL_CONTENT_TYPE=printer/PDF"
D [21/Sep/2014:12:17:22 +0200] [Job 25] envp[30]="AUTH_I****"
I [21/Sep/2014:12:17:22 +0200] [Job 25] Started filter 
/usr/lib/cups/filter/pdftopdf (PID 18562)
I [21/Sep/2014:12:17:22 +0200] [Job 25] Started filter 
/usr/lib/cups/filter/pdftops (PID 18563)
I [21/Sep/2014:12:17:22 +0200] [Job 25] Started backend 
/usr/lib/cups/backend/cups-pdf (PID 18564)
D [21/Sep/2014:12:17:22 +0200] Discarding unused job-state-changed event...
D [21/Sep/2014:12:17:22 +0200] [Client 16] Returning IPP successful-ok for 
Print-Job (ipp://localhost:631/printers/PDF) from localhost
D [21/Sep/2014:12:17:22 +0200] [Client 16] Content-Length: 174
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] [Client 16] cupsdWriteClient error=0, used=0, 
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=174, response=0x7fafe5521780(IPP_IDLE), pipe_pid=0, file=-1
D [21/Sep/2014:12:17:22 +0200] [Client 16] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [21/Sep/2014:12:17:22 +0200] [Client 16] bytes=0, http_state=0, 
data_remaining=0
D [21/Sep/2014:12:17:22 +0200] [Client 16] Waiting for request.
D [21/Sep/2014:12:17:22 +0200] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Active clients and dirty files"
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] [Job 25] pdftops - copying to temp print file 
"/var/spool/cups/tmp/04883542bb8f8"
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:22 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [21/Sep/2014:12:17:22 +0200] [Client 16] Closing connection.
D [21/Sep/2014:12:17:22 +0200] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [21/Sep/2014:12:17:22 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:23 +0200] [Job 25] PID 18562 
(/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [21/Sep/2014:12:17:23 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:23 +0200] [Job 25] Printer make and model: Generic CUPS 
v1.1
D [21/Sep/2014:12:17:23 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:23 +0200] [Job 25] Running command line for pstops: pstops 
25 madduck 'django-gitcms/load.py at master · luispedro/django-gitcms' 1 
'PageSize=A4 Resolution=300dpi 
job-uuid=urn:uuid:1441d73c-f580-3aed-6b53-0bad28664a4d 
job-originating-host-name=localhost time-at-creation=1411294642 
time-at-processing=1411294642'
D [21/Sep/2014:12:17:23 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:23 +0200] [Job 25] Using image rendering resolution 300 dpi
D [21/Sep/2014:12:17:23 +0200] [Job 25] Running command line for gs: gs -q 
-dNOPAUSE -dBATCH -dSAFER -sDEVICE=ps2write -sOUTPUTFILE=%stdout 
-dLanguageLevel=2 -r300 -dCompressFonts=false -dNoT3CCITT -dNOINTERPOLATE -c 
'save pop' -f /var/spool/cups/tmp/04883542bb8f8
D [21/Sep/2014:12:17:23 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:23 +0200] [Job 25] Started filter gs (PID 18565)
D [21/Sep/2014:12:17:23 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:23 +0200] [Job 25] Started filter pstops (PID 18566)
D [21/Sep/2014:12:17:23 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] [Job 25] Page = 595x842; 0,0 to 595,842
D [21/Sep/2014:12:17:24 +0200] [Job 25] slow_collate=0, slow_duplex=0, 
slow_order=0
D [21/Sep/2014:12:17:24 +0200] [Job 25] Before copy_comments - %!PS-Adobe-3.0
D [21/Sep/2014:12:17:24 +0200] [Job 25] %!PS-Adobe-3.0
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] [Job 25] %%BoundingBox: 0 0 596 842
D [21/Sep/2014:12:17:24 +0200] [Job 25] %%HiResBoundingBox: 0 0 596.00 842.00
D [21/Sep/2014:12:17:24 +0200] [Job 25] %%Creator: GPL Ghostscript RELEASE 
CANDIDATE 1 915 (ps2write)
D [21/Sep/2014:12:17:24 +0200] [Job 25] %%LanguageLevel: 2
D [21/Sep/2014:12:17:24 +0200] [Job 25] %%CreationDate: D:20140921121723+02'00'
D [21/Sep/2014:12:17:24 +0200] [Job 25] %%Pages: 1
D [21/Sep/2014:12:17:24 +0200] [Job 25] %%EndComments
D [21/Sep/2014:12:17:24 +0200] [Job 25] Before copy_prolog - %%BeginProlog
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] [Job 25] Before copy_setup - %%Page: 1 1
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] [Job 25] Before page loop - %%Page: 1 1
D [21/Sep/2014:12:17:24 +0200] [Job 25] Copying page 1...
D [21/Sep/2014:12:17:24 +0200] [Job 25] PAGE: 1 1
D [21/Sep/2014:12:17:24 +0200] Discarding unused job-progress event...
D [21/Sep/2014:12:17:24 +0200] [Job 25] pagew = 595.0, pagel = 842.0
D [21/Sep/2014:12:17:24 +0200] [Job 25] bboxx = 0, bboxy = 0, bboxw = 595, 
bboxl = 842
D [21/Sep/2014:12:17:24 +0200] [Job 25] PageLeft = 0.0, PageRight = 595.0
D [21/Sep/2014:12:17:24 +0200] [Job 25] PageTop = 842.0, PageBottom = 0.0
D [21/Sep/2014:12:17:24 +0200] [Job 25] PageWidth = 595.0, PageLength = 842.0
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] [Job 25] Wrote 1 pages...
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] [Job 25] PID 18566 (pstops) exited with no 
errors.
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] [Job 25] PID 18565 (gs) exited with no errors.
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] [Job 25] PID 18563 
(/usr/lib/cups/filter/pdftops) exited with no errors.
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] [Job 25] PID 18564 
(/usr/lib/cups/backend/cups-pdf) exited with no errors.
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:24 +0200] [Job 25] time-at-completed=1411294644
D [21/Sep/2014:12:17:24 +0200] Discarding unused job-completed event...
I [21/Sep/2014:12:17:24 +0200] [Job 25] Job completed.
D [21/Sep/2014:12:17:24 +0200] cupsdMarkDirty(---J-)
D [21/Sep/2014:12:17:24 +0200] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [21/Sep/2014:12:17:24 +0200] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [21/Sep/2014:12:17:24 +0200] Discarding unused printer-state-changed event...
D [21/Sep/2014:12:17:24 +0200] cupsdMarkDirty(---J-)
D [21/Sep/2014:12:17:24 +0200] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [21/Sep/2014:12:17:24 +0200] cupsdMarkDirty(---J-)
D [21/Sep/2014:12:17:24 +0200] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [21/Sep/2014:12:17:24 +0200] cupsd is not idle any more, canceling shutdown.
D [21/Sep/2014:12:17:25 +0200] [Job 25] Unloading...
D [21/Sep/2014:12:17:25 +0200] cupsd is not idle any more, canceling shutdown.

Attachment: digital_signature_gpg.asc
Description: Digital signature (see http://martin-krafft.net/gpg/sig-policy/999bbcc4/current)


--- End Message ---
--- Begin Message ---
So after not being able to reproduce this problem anywhere other
than Firefox for days, I removed all print.* settings from prefs.js
and now it works again. This is really weird though and there must
have been some event or somesuch that caused a change to migrate to
the various printer settings, which then cured itself everywhere
other than Firefox.

Anyway, sorry for the noise, but thanks for the help!

-- 
 .''`.   martin f. krafft <[email protected]> @martinkrafft
: :'  :  proud Debian developer
`. `'`   http://people.debian.org/~madduck
  `-  Debian - when you have better things to do than fixing systems

Attachment: digital_signature_gpg.asc
Description: Digital signature (see http://martin-krafft.net/gpg/sig-policy/999bbcc4/current)


--- End Message ---

Reply via email to