[Date Prev][Date Next] [Thread Prev][Thread Next] [Date Index] [Thread Index]

Bug#762338: marked as done (Generated PDF has tiny font size)

Your message dated Tue, 14 Oct 2014 14:00:26 +0200
with message-id <20141014120026.GA23868@albatross.lehel.madduck.net>
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 owner@bugs.debian.org
immediately.)


-- 
762338: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=762338
Debian Bug Tracking System
Contact owner@bugs.debian.org 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 <madduck@d.o> @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 <madduck@d.o> @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 to: