D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Printers
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default client-error-not-found: No default printer.
D [22/May/2012:12:11:03 +0200] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Printers
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default client-error-not-found: No default printer.
D [22/May/2012:12:11:03 +0200] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Printers
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default client-error-not-found: No default printer.
D [22/May/2012:12:11:03 +0200] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Printers
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default client-error-not-found: No default printer.
D [22/May/2012:12:11:03 +0200] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Printers
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default client-error-not-found: No default printer.
D [22/May/2012:12:11:03 +0200] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Printers
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default
D [22/May/2012:12:11:03 +0200] CUPS-Get-Default client-error-not-found: No default printer.
D [22/May/2012:12:11:03 +0200] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdAcceptClient: 15 from localhost (Domain)
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 15 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 15 1.1 Get-Printer-Attributes 1
D [22/May/2012:12:11:03 +0200] Get-Printer-Attributes ipp://localhost:631/printers/lsarc
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/lsarc) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 15 POST /printers/lsarc HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 15 1.1 Create-Job 1
D [22/May/2012:12:11:03 +0200] Create-Job ipp://localhost:631/printers/lsarc
D [22/May/2012:12:11:03 +0200] cupsdIsAuthorized: username=""
D [22/May/2012:12:11:03 +0200] Returning HTTP Unauthorized for Create-Job (ipp://localhost:631/printers/lsarc) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSendHeader: 15 WWW-Authenticate: Basic realm="CUPS"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 15 WAITING Closing on EOF
D [22/May/2012:12:11:03 +0200] cupsdCloseClient: 15
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [22/May/2012:12:11:03 +0200] cupsdAcceptClient: 15 from localhost (Domain)
D [22/May/2012:12:11:03 +0200] cupsdAcceptClient: 17 from localhost (Domain)
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 15 WAITING Closing on EOF
D [22/May/2012:12:11:03 +0200] cupsdCloseClient: 15
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 POST /printers/lsarc HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: Authorized as silvest using PeerCred
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 1.1 Create-Job 1
D [22/May/2012:12:11:03 +0200] Create-Job ipp://localhost:631/printers/lsarc
D [22/May/2012:12:11:03 +0200] cupsdIsAuthorized: username="silvest"
D [22/May/2012:12:11:03 +0200] cupsdMarkDirty(----J-)
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
I [22/May/2012:12:11:03 +0200] [Job 11] Adding start banner page "none".
D [22/May/2012:12:11:03 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [22/May/2012:12:11:03 +0200] [Job 11] Queued on "lsarc" by "silvest".
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/lsarc) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 POST /printers/lsarc HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: Authorized as silvest using PeerCred
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 1.1 Send-Document 1
D [22/May/2012:12:11:03 +0200] Send-Document ipp://localhost:631/printers/lsarc
D [22/May/2012:12:11:03 +0200] cupsdIsAuthorized: username="silvest"
D [22/May/2012:12:11:03 +0200] [Job 11] Auto-typing file...
D [22/May/2012:12:11:03 +0200] [Job 11] Request file type is application/postscript.
D [22/May/2012:12:11:03 +0200] cupsdMarkDirty(----J-)
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [22/May/2012:12:11:03 +0200] [Job 11] File of type application/postscript queued by "silvest".
I [22/May/2012:12:11:03 +0200] [Job 11] Adding end banner page "none".
D [22/May/2012:12:11:03 +0200] cupsdMarkDirty(----J-)
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdMarkDirty(----J-)
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] [Job 11] job-sheets=none,none
D [22/May/2012:12:11:03 +0200] [Job 11] argv[0]="lsarc"
D [22/May/2012:12:11:03 +0200] [Job 11] argv[1]="11"
D [22/May/2012:12:11:03 +0200] [Job 11] argv[2]="silvest"
D [22/May/2012:12:11:03 +0200] [Job 11] argv[3]="1205.4670.pdf"
D [22/May/2012:12:11:03 +0200] [Job 11] argv[4]="1"
D [22/May/2012:12:11:03 +0200] [Job 11] argv[5]="Collate finishings=3 media=A4 number-up=1 outputorder=normal portrait sides=two-sided-long-edge job-uuid=urn:uuid:d3a0a8b6-767c-3cb7-6142-fa239e8aada6 job-originating-host-name=localhost time-at-creation=1337681463 time-at-processing=1337681463 Duplex=DuplexNoTumble PageSize=A4"
D [22/May/2012:12:11:03 +0200] [Job 11] argv[6]="/var/spool/cups/d00011-001"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[8]="HOME=/var/spool/cups/tmp"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[10]="SERVER_ADMIN=root@homer"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[11]="SOFTWARE=CUPS/1.5.3"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[13]="USER=root"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[16]="IPP_PORT=631"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[17]="CHARSET=utf-8"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[18]="LANG=en_US.UTF-8"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[19]="PPD=/etc/cups/ppd/lsarc.ppd"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[20]="RIP_MAX_CACHE=128m"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[21]="CONTENT_TYPE=application/postscript"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[22]="DEVICE_URI=https://cups.roma1.infn.it:631/printers/lsarc"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[23]="PRINTER_INFO=Samsung ML-5510MD"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[24]="PRINTER_LOCATION=corridoio teorici"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[25]="PRINTER=lsarc"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[26]="PRINTER_STATE_REASONS=none"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[27]="CUPS_FILETYPE=document"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[28]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[29]="AUTH_I****"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[30]="AUTH_U****"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[31]="AUTH_P****"
D [22/May/2012:12:11:03 +0200] [Job 11] envp[32]="AUTH_U****"
I [22/May/2012:12:11:03 +0200] [Job 11] Started filter /usr/lib/cups/filter/pstops (PID 8188)
I [22/May/2012:12:11:03 +0200] [Job 11] Started backend /usr/lib/cups/backend/https (PID 8189)
D [22/May/2012:12:11:03 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/lsarc) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 WAITING Closing on EOF
D [22/May/2012:12:11:03 +0200] cupsdCloseClient: 17
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:03 +0200] [Job 11] Sending stdin for job...
D [22/May/2012:12:11:03 +0200] [Job 11] update_reasons(attr=0(), s="+connecting-to-device")
D [22/May/2012:12:11:03 +0200] [Job 11] STATE: +connecting-to-device
D [22/May/2012:12:11:03 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:03 +0200] [Job 11] Looking up "cups.roma1.infn.it"...
D [22/May/2012:12:11:03 +0200] [Job 11] Page = 595x842; 12,12 to 582,830
D [22/May/2012:12:11:03 +0200] [Job 11] slow_collate=0, slow_duplex=0, slow_order=0
D [22/May/2012:12:11:03 +0200] [Job 11] Before copy_comments - %!PS-Adobe-3.0
D [22/May/2012:12:11:03 +0200] [Job 11] %!PS-Adobe-3.0
D [22/May/2012:12:11:03 +0200] [Job 11] %Produced by poppler pdftops version: 0.18.4 (http://poppler.freedesktop.org)
D [22/May/2012:12:11:03 +0200] [Job 11] %%Creator: LaTeX with hyperref package
D [22/May/2012:12:11:03 +0200] [Job 11] %%Title: 1205.4670.pdf
D [22/May/2012:12:11:03 +0200] [Job 11] %%LanguageLevel: 2
D [22/May/2012:12:11:03 +0200] [Job 11] %%DocumentSuppliedResources: (atend)
D [22/May/2012:12:11:03 +0200] [Job 11] %%DocumentMedia: plain 595 842 0 () ()
D [22/May/2012:12:11:03 +0200] [Job 11] %%BoundingBox: 0 0 595 842
D [22/May/2012:12:11:03 +0200] [Job 11] %%Pages: 44
D [22/May/2012:12:11:03 +0200] [Job 11] %%EndComments
D [22/May/2012:12:11:03 +0200] [Job 11] Before copy_prolog - %%BeginDefaults
D [22/May/2012:12:11:03 +0200] [Job 11] Before copy_setup - %%BeginSetup
D [22/May/2012:12:11:03 +0200] cupsdAcceptClient: 17 from localhost (Domain)
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 1.1 Get-Notifications 1
D [22/May/2012:12:11:03 +0200] Get-Notifications /
D [22/May/2012:12:11:03 +0200] cupsdIsAuthorized: requesting-user-name="silvest"
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 1.1 Get-Job-Attributes 1
D [22/May/2012:12:11:03 +0200] Get-Job-Attributes ipp://localhost/jobs/11
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/11) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAcceptClient: 18 from localhost (Domain)
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 18 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 18 1.1 Get-Printer-Attributes 1
D [22/May/2012:12:11:03 +0200] Get-Printer-Attributes 
D [22/May/2012:12:11:03 +0200] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [22/May/2012:12:11:03 +0200] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 18 WAITING Closing on EOF
D [22/May/2012:12:11:03 +0200] cupsdCloseClient: 18
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 WAITING Closing on EOF
D [22/May/2012:12:11:03 +0200] cupsdCloseClient: 17
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAcceptClient: 17 from localhost (Domain)
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 1.1 Get-Notifications 1
D [22/May/2012:12:11:03 +0200] Get-Notifications /
D [22/May/2012:12:11:03 +0200] cupsdIsAuthorized: requesting-user-name="silvest"
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 WAITING Closing on EOF
D [22/May/2012:12:11:03 +0200] cupsdCloseClient: 17
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAcceptClient: 17 from localhost (Domain)
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 1.1 Get-Notifications 1
D [22/May/2012:12:11:03 +0200] Get-Notifications /
D [22/May/2012:12:11:03 +0200] cupsdIsAuthorized: requesting-user-name="silvest"
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 WAITING Closing on EOF
D [22/May/2012:12:11:03 +0200] cupsdCloseClient: 17
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAcceptClient: 17 from localhost (Domain)
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 POST / HTTP/1.1
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:03 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 1.1 Get-Notifications 1
D [22/May/2012:12:11:03 +0200] Get-Notifications /
D [22/May/2012:12:11:03 +0200] cupsdIsAuthorized: requesting-user-name="silvest"
D [22/May/2012:12:11:03 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:03 +0200] cupsdReadClient: 17 WAITING Closing on EOF
D [22/May/2012:12:11:03 +0200] cupsdCloseClient: 17
D [22/May/2012:12:11:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [22/May/2012:12:11:06 +0200] cupsdAcceptClient: 17 from localhost:631 (IPv6)
D [22/May/2012:12:11:06 +0200] cupsdReadClient: 17 GET /admin/log/error_log HTTP/1.1
D [22/May/2012:12:11:06 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:06 +0200] cupsdAuthorize: Authorized as root using Basic
D [22/May/2012:12:11:06 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] prtGeneralCurrentLocalization type is 0, expected 2!
D [22/May/2012:12:11:07 +0200] [Job 11] backendWaitLoop(snmp_fd=6, addr=0x7fcd60240048, side_cb=0x7fcd5e3470c0)
D [22/May/2012:12:11:07 +0200] [Job 11] Connecting to cups.roma1.infn.it:631
I [22/May/2012:12:11:07 +0200] [Job 11] Connecting to printer.
D [22/May/2012:12:11:07 +0200] [Job 11] Set job-printer-state-message to "Connecting to printer.", current level=INFO
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] update_reasons(attr=0(), s="-cups-certificate-error")
D [22/May/2012:12:11:07 +0200] [Job 11] update_reasons(attr=0(), s="-connecting-to-device")
D [22/May/2012:12:11:07 +0200] [Job 11] STATE: -connecting-to-device
I [22/May/2012:12:11:07 +0200] [Job 11] Connected to printer.
D [22/May/2012:12:11:07 +0200] [Job 11] Set job-printer-state-message to "Connected to printer.", current level=INFO
D [22/May/2012:12:11:07 +0200] [Job 11] Connected to 141.108.26.7:631...
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] Getting supported attributes...
D [22/May/2012:12:11:07 +0200] [Job 11] Get-Printer-Attributes: successful-ok (successful-ok)
D [22/May/2012:12:11:07 +0200] [Job 11] copies-supported=1-9999
D [22/May/2012:12:11:07 +0200] [Job 11] document-format-supported (38 values)
D [22/May/2012:12:11:07 +0200] [Job 11] [0] = "application/octet-stream"
D [22/May/2012:12:11:07 +0200] [Job 11] [1] = "application/pdf"
D [22/May/2012:12:11:07 +0200] [Job 11] [2] = "application/postscript"
D [22/May/2012:12:11:07 +0200] [Job 11] [3] = "application/rss+xml"
D [22/May/2012:12:11:07 +0200] [Job 11] [4] = "application/vnd.cups-banner"
D [22/May/2012:12:11:07 +0200] [Job 11] [5] = "application/vnd.cups-command"
D [22/May/2012:12:11:07 +0200] [Job 11] [6] = "application/vnd.cups-pdf"
D [22/May/2012:12:11:07 +0200] [Job 11] [7] = "application/vnd.cups-postscript"
D [22/May/2012:12:11:07 +0200] [Job 11] [8] = "application/vnd.cups-ppd"
D [22/May/2012:12:11:07 +0200] [Job 11] [9] = "application/vnd.cups-raster"
D [22/May/2012:12:11:07 +0200] [Job 11] [10] = "application/vnd.cups-raw"
D [22/May/2012:12:11:07 +0200] [Job 11] [11] = "application/x-cshell"
D [22/May/2012:12:11:07 +0200] [Job 11] [12] = "application/x-csource"
D [22/May/2012:12:11:07 +0200] [Job 11] [13] = "application/x-httpd-cgi"
D [22/May/2012:12:11:07 +0200] [Job 11] [14] = "application/x-httpd-php"
D [22/May/2012:12:11:07 +0200] [Job 11] [15] = "application/x-httpd-python"
D [22/May/2012:12:11:07 +0200] [Job 11] [16] = "application/x-perl"
D [22/May/2012:12:11:07 +0200] [Job 11] [17] = "application/x-shell"
D [22/May/2012:12:11:07 +0200] [Job 11] [18] = "image/gif"
D [22/May/2012:12:11:07 +0200] [Job 11] [19] = "image/jpeg"
D [22/May/2012:12:11:07 +0200] [Job 11] [20] = "image/png"
D [22/May/2012:12:11:07 +0200] [Job 11] [21] = "image/pwg-raster"
D [22/May/2012:12:11:07 +0200] [Job 11] [22] = "image/tiff"
D [22/May/2012:12:11:07 +0200] [Job 11] [23] = "image/x-alias"
D [22/May/2012:12:11:07 +0200] [Job 11] [24] = "image/x-bitmap"
D [22/May/2012:12:11:07 +0200] [Job 11] [25] = "image/x-icon"
D [22/May/2012:12:11:07 +0200] [Job 11] [26] = "image/x-photocd"
D [22/May/2012:12:11:07 +0200] [Job 11] [27] = "image/x-portable-anymap"
D [22/May/2012:12:11:07 +0200] [Job 11] [28] = "image/x-portable-bitmap"
D [22/May/2012:12:11:07 +0200] [Job 11] [29] = "image/x-portable-graymap"
D [22/May/2012:12:11:07 +0200] [Job 11] [30] = "image/x-portable-pixmap"
D [22/May/2012:12:11:07 +0200] [Job 11] [31] = "image/x-sgi-rgb"
D [22/May/2012:12:11:07 +0200] [Job 11] [32] = "image/x-sun-raster"
D [22/May/2012:12:11:07 +0200] [Job 11] [33] = "image/x-xbitmap"
D [22/May/2012:12:11:07 +0200] [Job 11] [34] = "image/x-xpixmap"
D [22/May/2012:12:11:07 +0200] [Job 11] [35] = "text/css"
D [22/May/2012:12:11:07 +0200] [Job 11] [36] = "text/html"
D [22/May/2012:12:11:07 +0200] [Job 11] [37] = "text/plain"
D [22/May/2012:12:11:07 +0200] [Job 11] media-col-supported (7 values)
D [22/May/2012:12:11:07 +0200] [Job 11] [0] = "media-bottom-margin"
D [22/May/2012:12:11:07 +0200] [Job 11] [1] = "media-left-margin"
D [22/May/2012:12:11:07 +0200] [Job 11] [2] = "media-right-margin"
D [22/May/2012:12:11:07 +0200] [Job 11] [3] = "media-size"
D [22/May/2012:12:11:07 +0200] [Job 11] [4] = "media-source"
D [22/May/2012:12:11:07 +0200] [Job 11] [5] = "media-top-margin"
D [22/May/2012:12:11:07 +0200] [Job 11] [6] = "media-type"
I [22/May/2012:12:11:07 +0200] [Job 11] Ready to print.
D [22/May/2012:12:11:07 +0200] [Job 11] Set job-printer-state-message to "Ready to print.", current level=INFO
D [22/May/2012:12:11:07 +0200] [Job 11] update_reasons(attr=1(none), s="(null)")
D [22/May/2012:12:11:07 +0200] [Job 11] STATE: -none
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] final_content_type="application/vnd.cups-postscript", document_format="application/vnd.cups-postscript"
D [22/May/2012:12:11:07 +0200] [Job 11] Validate-Job IPP/2.0
D [22/May/2012:12:11:07 +0200] [Job 11] printer-uri="https://cups.roma1.infn.it:631/printers/lsarc"
D [22/May/2012:12:11:07 +0200] [Job 11] requesting-user-name="silvest"
D [22/May/2012:12:11:07 +0200] [Job 11] job-name="1205.4670.pdf"
D [22/May/2012:12:11:07 +0200] [Job 11] document-format="application/vnd.cups-postscript"
D [22/May/2012:12:11:07 +0200] [Job 11] password_cb(prompt="Password for silvest on cups.roma1.infn.it? "), password=0x7fff6e969fcd, password_tries=0
D [22/May/2012:12:11:07 +0200] [Job 11] Validate-Job: cups-authorization-canceled (Unauthorized)
D [22/May/2012:12:11:07 +0200] [Job 11] ATTR: auth-info-required=username,password
D [22/May/2012:12:11:07 +0200] load_ppd: Loading /var/cache/cups/lsarc.data...
D [22/May/2012:12:11:07 +0200] cupsdRegisterPrinter(p=0x7fa88145a300(lsarc))
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(P-----)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] PID 8189 (/usr/lib/cups/backend/https) stopped with status 2.
D [22/May/2012:12:11:07 +0200] [Job 11] Before page loop - %%Page: 1 1
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 1...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 1 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 2...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 2 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 3...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 3 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 4...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 4 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 5...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 5 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 6...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 6 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 7...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 7 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 8...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 8 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 9...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 9 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 10...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 10 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 11...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 11 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 12...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 12 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 13...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 13 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 14...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 14 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 15...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 15 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 16...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 16 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 17...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 17 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 18...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 18 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 19...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 19 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 20...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 20 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 21...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 21 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 22...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 22 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 23...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 23 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 24...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 24 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 25...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 25 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 26...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 26 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 27...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 27 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 28...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 28 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 29...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 29 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 30...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 30 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 31...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 31 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 32...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 32 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 33...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 33 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 34...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 34 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 35...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 35 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 36...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 36 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 37...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 37 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 38...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 38 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 39...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 39 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 40...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 40 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 41...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 41 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 42...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 42 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 43...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 43 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Copying page 44...
D [22/May/2012:12:11:07 +0200] [Job 11] PAGE: 44 1
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] [Job 11] pagew = 570.0, pagel = 817.0
D [22/May/2012:12:11:07 +0200] [Job 11] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [22/May/2012:12:11:07 +0200] [Job 11] PageLeft = 12.5, PageRight = 582.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageTop = 829.5, PageBottom = 12.5
D [22/May/2012:12:11:07 +0200] [Job 11] PageWidth = 595.0, PageLength = 842.0
D [22/May/2012:12:11:07 +0200] [Job 11] Wrote 44 pages...
D [22/May/2012:12:11:07 +0200] PID 8188 (/usr/lib/cups/filter/pstops) exited with no errors.
I [22/May/2012:12:11:07 +0200] [Job 11] Backend returned status 2 (authentication required)
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(----J-)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [22/May/2012:12:11:07 +0200] [Job 11] Job held for authentication.
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(----J-)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] cupsdMarkDirty(-----S)
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] cupsdAcceptClient: 15 from localhost (Domain)
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 POST / HTTP/1.1
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [22/May/2012:12:11:07 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 1.1 Get-Notifications 1
D [22/May/2012:12:11:07 +0200] Get-Notifications /
D [22/May/2012:12:11:07 +0200] cupsdIsAuthorized: requesting-user-name="silvest"
D [22/May/2012:12:11:07 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 WAITING Closing on EOF
D [22/May/2012:12:11:07 +0200] cupsdCloseClient: 15
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [22/May/2012:12:11:07 +0200] cupsdAcceptClient: 15 from localhost (Domain)
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 POST / HTTP/1.1
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:07 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 1.1 Get-Notifications 1
D [22/May/2012:12:11:07 +0200] Get-Notifications /
D [22/May/2012:12:11:07 +0200] cupsdIsAuthorized: requesting-user-name="silvest"
D [22/May/2012:12:11:07 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 WAITING Closing on EOF
D [22/May/2012:12:11:07 +0200] cupsdCloseClient: 15
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [22/May/2012:12:11:07 +0200] cupsdAcceptClient: 15 from localhost (Domain)
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 POST / HTTP/1.1
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:07 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 1.1 Get-Notifications 1
D [22/May/2012:12:11:07 +0200] Get-Notifications /
D [22/May/2012:12:11:07 +0200] cupsdIsAuthorized: requesting-user-name="silvest"
D [22/May/2012:12:11:07 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 WAITING Closing on EOF
D [22/May/2012:12:11:07 +0200] cupsdCloseClient: 15
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [22/May/2012:12:11:07 +0200] cupsdAcceptClient: 15 from localhost (Domain)
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 POST / HTTP/1.1
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [22/May/2012:12:11:07 +0200] cupsdAuthorize: No authentication data provided.
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 1.1 Get-Notifications 1
D [22/May/2012:12:11:07 +0200] Get-Notifications /
D [22/May/2012:12:11:07 +0200] cupsdIsAuthorized: requesting-user-name="silvest"
D [22/May/2012:12:11:07 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [22/May/2012:12:11:07 +0200] cupsdReadClient: 15 WAITING Closing on EOF
D [22/May/2012:12:11:07 +0200] cupsdCloseClient: 15
D [22/May/2012:12:11:07 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [22/May/2012:12:11:33 +0200] Saving printers.conf...
I [22/May/2012:12:11:33 +0200] Saving job.cache...
I [22/May/2012:12:11:33 +0200] Saving subscriptions.conf...
D [22/May/2012:12:11:33 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [22/May/2012:12:11:34 +0200] Report: clients=2
D [22/May/2012:12:11:34 +0200] Report: jobs=11
D [22/May/2012:12:11:34 +0200] Report: jobs-active=4
D [22/May/2012:12:11:34 +0200] Report: printers=2
D [22/May/2012:12:11:34 +0200] Report: printers-implicit=0
D [22/May/2012:12:11:34 +0200] Report: stringpool-string-count=16208
D [22/May/2012:12:11:34 +0200] Report: stringpool-alloc-bytes=13928
D [22/May/2012:12:11:34 +0200] Report: stringpool-total-bytes=297424
D [22/May/2012:12:11:36 +0200] cupsdReadClient: 17 WAITING Closing on EOF
D [22/May/2012:12:11:36 +0200] cupsdCloseClient: 17
D [22/May/2012:12:11:36 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [22/May/2012:12:12:34 +0200] Report: clients=1
D [22/May/2012:12:12:34 +0200] Report: jobs=11
D [22/May/2012:12:12:34 +0200] Report: jobs-active=4
D [22/May/2012:12:12:34 +0200] Report: printers=2
D [22/May/2012:12:12:34 +0200] Report: printers-implicit=0
D [22/May/2012:12:12:34 +0200] Report: stringpool-string-count=16208
D [22/May/2012:12:12:34 +0200] Report: stringpool-alloc-bytes=13928
D [22/May/2012:12:12:34 +0200] Report: stringpool-total-bytes=297424
D [22/May/2012:12:12:45 +0200] cupsdAcceptClient: 15 from localhost:631 (IPv6)
D [22/May/2012:12:12:45 +0200] cupsdReadClient: 15 GET /admin/log/error_log HTTP/1.1
D [22/May/2012:12:12:45 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [22/May/2012:12:12:45 +0200] cupsdAuthorize: Authorized as root using Basic