I [17/Nov/2011:22:02:34 -0800] Saving subscriptions.conf...
D [17/Nov/2011:22:02:34 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [17/Nov/2011:22:02:34 -0800] Avahi client started
D [17/Nov/2011:22:02:41 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:41 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:42 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [17/Nov/2011:22:02:42 -0800] CUPS-Get-Printers
D [17/Nov/2011:22:02:42 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:42 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 1.1 CUPS-Get-Classes 1
D [17/Nov/2011:22:02:42 -0800] CUPS-Get-Classes
D [17/Nov/2011:22:02:42 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:42 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
D [17/Nov/2011:22:02:42 -0800] CUPS-Get-Default
D [17/Nov/2011:22:02:42 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:42 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1
D [17/Nov/2011:22:02:42 -0800] Get-Printer-Attributes ipp://localhost/printers/Officejet_Pro_8000_A809
D [17/Nov/2011:22:02:42 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Officejet_Pro_8000_A809) from localhost
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:42 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
D [17/Nov/2011:22:02:42 -0800] CUPS-Get-Default
D [17/Nov/2011:22:02:42 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:42 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1
D [17/Nov/2011:22:02:42 -0800] Get-Printer-Attributes ipp://localhost/printers/Officejet_Pro_8000_A809
D [17/Nov/2011:22:02:42 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Officejet_Pro_8000_A809) from localhost
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:42 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:42 -0800] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
D [17/Nov/2011:22:02:42 -0800] CUPS-Get-Default
D [17/Nov/2011:22:02:42 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/Nov/2011:22:02:42 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/Nov/2011:22:02:43 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:43 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:43 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:43 -0800] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1
D [17/Nov/2011:22:02:43 -0800] Get-Printer-Attributes ipp://localhost/printers/Officejet_Pro_8000_A809
D [17/Nov/2011:22:02:43 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Officejet_Pro_8000_A809) from localhost
D [17/Nov/2011:22:02:43 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/Nov/2011:22:02:43 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:43 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:43 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:43 -0800] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
D [17/Nov/2011:22:02:43 -0800] CUPS-Get-Default
D [17/Nov/2011:22:02:43 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/Nov/2011:22:02:43 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 17 1.1 Get-Jobs 1
D [17/Nov/2011:22:02:45 -0800] Get-Jobs ipp://localhost/printers/
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 17 POST /printers/Officejet_Pro_8000_A809 HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 17 1.1 Print-Job 1
D [17/Nov/2011:22:02:45 -0800] Print-Job ipp://localhost/printers/Officejet_Pro_8000_A809
D [17/Nov/2011:22:02:45 -0800] [Job ???] Auto-typing file...
I [17/Nov/2011:22:02:45 -0800] [Job ???] Request file type is application/vnd.cups-banner.
D [17/Nov/2011:22:02:45 -0800] add_job: setting context of job to UNKNOWN SL
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(----J-)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [17/Nov/2011:22:02:45 -0800] add_job: requesting-user-name="root"
D [17/Nov/2011:22:02:45 -0800] Adding default job-sheets values "none,none"...
I [17/Nov/2011:22:02:45 -0800] [Job 21] Adding start banner page "none".
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(----J-)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [17/Nov/2011:22:02:45 -0800] [Job 21] Adding end banner page "none".
I [17/Nov/2011:22:02:45 -0800] [Job 21] File of type application/vnd.cups-banner queued by "root".
D [17/Nov/2011:22:02:45 -0800] [Job 21] hold_until=0
I [17/Nov/2011:22:02:45 -0800] [Job 21] Queued on "Officejet_Pro_8000_A809" by "root".
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(----J-)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] [Job 21] job-sheets=none,none
D [17/Nov/2011:22:02:45 -0800] [Job 21] argv[0]="Officejet_Pro_8000_A809"
D [17/Nov/2011:22:02:45 -0800] [Job 21] argv[1]="21"
D [17/Nov/2011:22:02:45 -0800] [Job 21] argv[2]="root"
D [17/Nov/2011:22:02:45 -0800] [Job 21] argv[3]="Test Page"
D [17/Nov/2011:22:02:45 -0800] [Job 21] argv[4]="1"
D [17/Nov/2011:22:02:45 -0800] [Job 21] argv[5]="job-uuid=urn:uuid:c1e4f2f5-7704-3dc4-6da9-2ee210f78935 job-originating-host-name=localhost time-at-creation=1321596165 time-at-processing=1321596165 AP_D_InputSlot="
D [17/Nov/2011:22:02:45 -0800] [Job 21] argv[6]="/var/spool/cups/d00021-001"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[8]="HOME=/var/spool/cups/tmp"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[10]="SERVER_ADMIN=root@mercury"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[11]="SOFTWARE=CUPS/1.5.0"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[13]="USER=root"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[16]="IPP_PORT=631"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[17]="CHARSET=utf-8"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[18]="LANG=en_US.UTF-8"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[19]="PPD=/etc/cups/ppd/Officejet_Pro_8000_A809.ppd"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[20]="RIP_MAX_CACHE=128m"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[21]="CONTENT_TYPE=application/vnd.cups-banner"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[22]="DEVICE_URI=socket://192.168.1.160:9100"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[23]="PRINTER_INFO=Officejet Pro 8000"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[24]="PRINTER_LOCATION=Downstairs"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[25]="PRINTER=Officejet_Pro_8000_A809"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[26]="PRINTER_STATE_REASONS=none"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[27]="CUPS_FILETYPE=document"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[28]="FINAL_CONTENT_TYPE=printer/Officejet_Pro_8000_A809"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[29]="AUTH_I****"
I [17/Nov/2011:22:02:45 -0800] [Job 21] Started filter /usr/lib/cups/filter/bannertops (PID 2070)
I [17/Nov/2011:22:02:45 -0800] [Job 21] Started filter /usr/lib/cups/filter/pstops (PID 2071)
I [17/Nov/2011:22:02:45 -0800] [Job 21] Started filter /usr/lib/cups/filter/gstoraster (PID 2072)
I [17/Nov/2011:22:02:45 -0800] [Job 21] Started filter /usr/lib/cups/filter/hpcups (PID 2073)
I [17/Nov/2011:22:02:45 -0800] [Job 21] Started backend /usr/lib/cups/backend/socket (PID 2074)
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/Officejet_Pro_8000_A809) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 17 1.1 Get-Jobs 1
D [17/Nov/2011:22:02:45 -0800] Get-Jobs ipp://localhost/printers/
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:02:45 -0800] [Job 21] load_banner(filename="/var/spool/cups/d00021-001")
D [17/Nov/2011:22:02:45 -0800] [Job 21] 1 #CUPS-BANNER
D [17/Nov/2011:22:02:45 -0800] [Job 21] 2 Show printer-name printer-info printer-location printer-make-and-model printer-driver-name printer-driver-version paper-size imageable-area
D [17/Nov/2011:22:02:45 -0800] [Job 21] 3 Header Printer Test Page
D [17/Nov/2011:22:02:45 -0800] [Job 21] 4 Footer Printer Test Page
D [17/Nov/2011:22:02:45 -0800] [Job 21] 5 Notice CUPS 1.5.0.
D [17/Nov/2011:22:02:45 -0800] [Job 21] 6 Image images/cups.png
D [17/Nov/2011:22:02:45 -0800] [Job 21] 7 Image images/color-wheel.png
D [17/Nov/2011:22:02:45 -0800] [Job 21] Page = 612x792; 9,9 to 603,783
D [17/Nov/2011:22:02:45 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 461: argv[0] = Officejet_Pro_8000_A809
D [17/Nov/2011:22:02:45 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 461: argv[1] = 21
D [17/Nov/2011:22:02:45 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 461: argv[2] = root
D [17/Nov/2011:22:02:45 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 461: argv[3] = Test Page
D [17/Nov/2011:22:02:45 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 461: argv[4] = 1
D [17/Nov/2011:22:02:45 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 461: argv[5] = job-uuid=urn:uuid:c1e4f2f5-7704-3dc4-6da9-2ee210f78935 job-originating-host-name=localhost time-at-creation=1321596165 time-at-processing=1321596165 AP_D_InputSlot=
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -marker-supply-low-warning
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: +connecting-to-device
D [17/Nov/2011:22:02:45 -0800] [Job 21] Looking up "192.168.1.160"...
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:45 -0800] [Job 21] Page = 612x792; 9,9 to 603,783
D [17/Nov/2011:22:02:45 -0800] [Job 21] slow_collate=0, slow_duplex=1, slow_order=0
D [17/Nov/2011:22:02:45 -0800] [Job 21] Before copy_comments - %!PS-Adobe-3.0
D [17/Nov/2011:22:02:45 -0800] [Job 21] %!PS-Adobe-3.0
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%BoundingBox: 9 9 603 783
D [17/Nov/2011:22:02:45 -0800] [Job 21] %cupsRotation: 0
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%Creator: bannertops/CUPS v1.5.0
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%CreationDate: Thu 17 Nov 2011 10:02:45 PM PST
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%LanguageLevel: 2
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%DocumentData: Clean7Bit
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%Title: (Test Page)
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%For: (root)
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%Pages: 2
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%DocumentSuppliedResources: font Monospace
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%+ font Monospace-Bold
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%+ font Monospace-BoldOblique
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%+ font Monospace-Oblique
D [17/Nov/2011:22:02:45 -0800] [Job 21] %%EndComments
D [17/Nov/2011:22:02:45 -0800] [Job 21] Before copy_prolog - %%BeginProlog
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: 19 from localhost (Domain)
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:45 -0800] Get-Notifications /
D [17/Nov/2011:22:02:45 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 1.1 Get-Job-Attributes 1
D [17/Nov/2011:22:02:45 -0800] Get-Job-Attributes ipp://localhost/jobs/21
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/21) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 20 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [17/Nov/2011:22:02:45 -0800] Get-Printer-Attributes 
D [17/Nov/2011:22:02:45 -0800] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [17/Nov/2011:22:02:45 -0800] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [17/Nov/2011:22:02:45 -0800] cupsdCloseClient: 20
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 20 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [17/Nov/2011:22:02:45 -0800] Get-Printer-Attributes ipp://mercury/printers/Officejet_Pro_8000_A809
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://mercury/printers/Officejet_Pro_8000_A809) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [17/Nov/2011:22:02:45 -0800] cupsdCloseClient: 20
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 WAITING Closing on EOF
D [17/Nov/2011:22:02:45 -0800] cupsdCloseClient: 19
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: 19 from localhost (Domain)
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:45 -0800] Get-Notifications /
D [17/Nov/2011:22:02:45 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 WAITING Closing on EOF
D [17/Nov/2011:22:02:45 -0800] cupsdCloseClient: 19
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: 19 from localhost (Domain)
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:45 -0800] Get-Notifications /
D [17/Nov/2011:22:02:45 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 WAITING Closing on EOF
D [17/Nov/2011:22:02:45 -0800] cupsdCloseClient: 19
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] [Job 21] PNG image: 128x128x8, color_type=6 (RGB+ALPHA)
D [17/Nov/2011:22:02:45 -0800] [Job 21] PNG image: 192x128x8, color_type=2 (RGB)
D [17/Nov/2011:22:02:45 -0800] [Job 21] Before copy_setup - %%Page: coverpage 1
D [17/Nov/2011:22:02:45 -0800] [Job 21] Before page loop - %%Page: coverpage 1
D [17/Nov/2011:22:02:45 -0800] [Job 21] Copying page 1...
D [17/Nov/2011:22:02:45 -0800] [Job 21] pagew = 593.3, pagel = 773.3
D [17/Nov/2011:22:02:45 -0800] [Job 21] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [17/Nov/2011:22:02:45 -0800] [Job 21] PageLeft = 9.4, PageRight = 602.6
D [17/Nov/2011:22:02:45 -0800] [Job 21] PageTop = 782.6, PageBottom = 9.4
D [17/Nov/2011:22:02:45 -0800] [Job 21] PageWidth = 612.0, PageLength = 792.0
D [17/Nov/2011:22:02:45 -0800] [Job 21] Copying page 2...
D [17/Nov/2011:22:02:45 -0800] [Job 21] pagew = 593.3, pagel = 773.3
D [17/Nov/2011:22:02:45 -0800] [Job 21] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [17/Nov/2011:22:02:45 -0800] [Job 21] PageLeft = 9.4, PageRight = 602.6
D [17/Nov/2011:22:02:45 -0800] [Job 21] PageTop = 782.6, PageBottom = 9.4
D [17/Nov/2011:22:02:45 -0800] [Job 21] PageWidth = 612.0, PageLength = 792.0
D [17/Nov/2011:22:02:45 -0800] PID 2070 (/usr/lib/cups/filter/bannertops) exited with no errors.
D [17/Nov/2011:22:02:45 -0800] [Job 21] Wrote 2 pages...
D [17/Nov/2011:22:02:45 -0800] [Job 21] PPD uses qualifier 'RGB.Plain.'
D [17/Nov/2011:22:02:45 -0800] [Job 21] Calling FindDeviceById(Officejet_Pro_8000_A809)
D [17/Nov/2011:22:02:45 -0800] [Job 21] Failed to send: org.freedesktop.ColorManager.Failed:device id 'Officejet_Pro_8000_A809' does not exists
D [17/Nov/2011:22:02:45 -0800] [Job 21] Failed to get profile filename!
I [17/Nov/2011:22:02:45 -0800] [Job 21] no profiles specified in PPD
D [17/Nov/2011:22:02:45 -0800] [Job 21] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
D [17/Nov/2011:22:02:45 -0800] [Job 21] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -I/usr/share/cups/fonts -c -f -_
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[8]="HOME=/var/spool/cups/tmp"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[10]="SERVER_ADMIN=root@mercury"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[11]="SOFTWARE=CUPS/1.5.0"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[13]="USER=root"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[16]="IPP_PORT=631"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[17]="CHARSET=utf-8"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[18]="LANG=en_US.UTF-8"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[19]="PPD=/etc/cups/ppd/Officejet_Pro_8000_A809.ppd"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[20]="RIP_MAX_CACHE=128m"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[21]="CONTENT_TYPE=application/vnd.cups-banner"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[22]="DEVICE_URI=socket://192.168.1.160:9100"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[23]="PRINTER_INFO=Officejet Pro 8000"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[24]="PRINTER_LOCATION=Downstairs"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[25]="PRINTER=Officejet_Pro_8000_A809"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[26]="PRINTER_STATE_REASONS=none"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[27]="CUPS_FILETYPE=document"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[28]="FINAL_CONTENT_TYPE=printer/Officejet_Pro_8000_A809"
D [17/Nov/2011:22:02:45 -0800] [Job 21] envp[29]="AUTH_INFO_REQUIRED=none"
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] PID 2071 (/usr/lib/cups/filter/pstops) exited with no errors.
D [17/Nov/2011:22:02:45 -0800] [Job 21] ATTR: marker-colors=none,none,none,none
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] [Job 21] ATTR: marker-names="black ink C4906A","yellow ink C4905S","magenta ink C4904S","cyan ink C4903S"
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] [Job 21] ATTR: marker-types=ink,ink,ink,ink
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] [Job 21] ATTR: marker-levels=11,14,23,20
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] [Job 21] new_supply_state=0, change_state=ffff
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -developer-low-report
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -developer-empty-warning
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -marker-supply-low-report
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -marker-supply-empty-warning
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -opc-near-eol-report
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -opc-life-over-warning
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -toner-low-report
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -toner-empty-warning
D [17/Nov/2011:22:02:45 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] [Job 21] new_state=2000, change_state=ffff
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -media-low-report
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -media-empty-warning
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -door-open-report
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -media-jam-warning
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -input-tray-missing-warning
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -output-tray-missing-warning
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -marker-supply-missing-warning
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -output-area-almost-full-report
D [17/Nov/2011:22:02:45 -0800] [Job 21] STATE: -output-area-full-warning
D [17/Nov/2011:22:02:45 -0800] [Job 21] backendWaitLoop(snmp_fd=5, addr=0x7fa0912ca9b8, side_cb=0x7fa0907ccef0)
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: 19 from localhost (Domain)
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:45 -0800] Get-Notifications /
D [17/Nov/2011:22:02:45 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 WAITING Closing on EOF
D [17/Nov/2011:22:02:45 -0800] cupsdCloseClient: 19
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: 19 from localhost (Domain)
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:45 -0800] Get-Notifications /
D [17/Nov/2011:22:02:45 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 WAITING Closing on EOF
D [17/Nov/2011:22:02:45 -0800] cupsdCloseClient: 19
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:45 -0800] cupsdAcceptClient: 19 from localhost (Domain)
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:45 -0800] Get-Notifications /
D [17/Nov/2011:22:02:45 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:45 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:45 -0800] cupsdReadClient: 19 WAITING Closing on EOF
D [17/Nov/2011:22:02:45 -0800] cupsdCloseClient: 19
D [17/Nov/2011:22:02:45 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [17/Nov/2011:22:02:46 -0800] [Job 21] Start rendering...
D [17/Nov/2011:22:02:46 -0800] [Job 21] Set job-printer-state-message to "Start rendering...", current level=INFO
I [17/Nov/2011:22:02:46 -0800] [Job 21] Processing page 1...
D [17/Nov/2011:22:02:46 -0800] [Job 21] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [17/Nov/2011:22:02:46 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:46 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:46 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:46 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:46 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:46 -0800] cupsdAcceptClient: 19 from localhost (Domain)
D [17/Nov/2011:22:02:46 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Nov/2011:22:02:46 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:46 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:46 -0800] cupsdReadClient: 19 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:46 -0800] Get-Notifications /
D [17/Nov/2011:22:02:46 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:46 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:46 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:02:46 -0800] cupsdReadClient: 19 WAITING Closing on EOF
D [17/Nov/2011:22:02:46 -0800] cupsdCloseClient: 19
D [17/Nov/2011:22:02:46 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 741: DEBUG: startPage...
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 742: DEBUG: MediaClass = ""
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 743: DEBUG: MediaColor = ""
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 744: DEBUG: MediaType = "Plain"
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 745: DEBUG: OutputType = "0"
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 746: DEBUG: AdvanceDistance = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 747: DEBUG: AdvanceMedia = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 748: DEBUG: Collate = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 749: DEBUG: CutMedia = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 750: DEBUG: Duplex = 1
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 751: DEBUG: HWResolution = [ 600 600 ]
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 754: DEBUG: ImagingBoundingBox = [ 9 9 602 782 ]
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 755: DEBUG: InsertSheet = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 756: DEBUG: Jog = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 757: DEBUG: LeadingEdge = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 758: DEBUG: Margins = [ 9 9 ]
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 759: DEBUG: ManualFeed = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 760: DEBUG: MediaPosition = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 761: DEBUG: MediaWeight = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 762: DEBUG: MirrorPrint = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 763: DEBUG: NegativePrint = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 764: DEBUG: NumCopies = 1
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 765: DEBUG: Orientation = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 766: DEBUG: OutputFaceUp = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 767: DEBUG: PageSize = [ 612 792 ]
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 768: DEBUG: Separations = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 769: DEBUG: TraySwitch = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 770: DEBUG: Tumble = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 771: DEBUG: cupsWidth = 4944
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 772: DEBUG: cupsHeight = 6444
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 773: DEBUG: cupsMediaType = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 774: DEBUG: cupsRowStep = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 775: DEBUG: cupsBitsPerColor = 8
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 776: DEBUG: cupsBitsPerPixel = 32
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 777: DEBUG: cupsBytesPerLine = 19776
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 778: DEBUG: cupsColorOrder = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 779: DEBUG: cupsColorSpace = 17
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 780: DEBUG: cupsCompression = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 781: DEBUG: cupsPageSizeName = 
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 782: DEBUG: cupsInteger0 = 2
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 783: DEBUG: cupsInteger1 = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 784: DEBUG: cupsInteger2 = 0
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 785: DEBUG: cupsString0 = 
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 786: DEBUG: cupsReal0 = 0.000000
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 787: DEBUG: cupsReal1 = 0.000000
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 373: HPCUPS: found Printer Language, it is - pcl3gui2
D [17/Nov/2011:22:02:46 -0800] [Job 21] Connecting to 192.168.1.160:9100
I [17/Nov/2011:22:02:46 -0800] [Job 21] Connecting to printer.
D [17/Nov/2011:22:02:46 -0800] [Job 21] Set job-printer-state-message to "Connecting to printer.", current level=INFO
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 425: HPCUPS: returning NO_ERROR from startPage
D [17/Nov/2011:22:02:46 -0800] [Job 21] STATE: -connecting-to-device
I [17/Nov/2011:22:02:46 -0800] [Job 21] Connected to printer.
D [17/Nov/2011:22:02:46 -0800] [Job 21] Set job-printer-state-message to "Connected to printer.", current level=INFO
D [17/Nov/2011:22:02:46 -0800] [Job 21] Connected to 192.168.1.160:9100...
D [17/Nov/2011:22:02:46 -0800] [Job 21] backendRunLoop(print_fd=0, device_fd=7, snmp_fd=5, addr=0x7fa0912ca9b8, use_bc=1, side_cb=0x7fa0907ccef0)
D [17/Nov/2011:22:02:46 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:46 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:46 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [17/Nov/2011:22:02:46 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:46 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 625: 9......
D [17/Nov/2011:22:02:46 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 628: 10......
D [17/Nov/2011:22:02:46 -0800] [Job 21] PAGE: 1 1
D [17/Nov/2011:22:02:46 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:46 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:47 -0800] [Job 21] ATTR: marker-levels=11,14,23,19
D [17/Nov/2011:22:02:47 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:02:47 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:47 -0800] [Job 21] new_supply_state=0, change_state=0
D [17/Nov/2011:22:02:47 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:47 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:47 -0800] [Job 21] new_state=2000, change_state=0
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 2966 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 2966 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 1706 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 1706 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 1779 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 1779 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 1720 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:47 -0800] cupsdAcceptClient: 19 from localhost (Domain)
D [17/Nov/2011:22:02:47 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Nov/2011:22:02:47 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:47 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:47 -0800] cupsdReadClient: 19 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:47 -0800] Get-Notifications /
D [17/Nov/2011:22:02:47 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:47 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:47 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:02:47 -0800] cupsdReadClient: 19 WAITING Closing on EOF
D [17/Nov/2011:22:02:47 -0800] cupsdCloseClient: 19
D [17/Nov/2011:22:02:47 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 1720 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 1805 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 1805 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:47 -0800] cupsdAcceptClient: 19 from localhost (Domain)
D [17/Nov/2011:22:02:47 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Nov/2011:22:02:47 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:47 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:47 -0800] cupsdReadClient: 19 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:47 -0800] Get-Notifications /
D [17/Nov/2011:22:02:47 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:47 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:47 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:02:47 -0800] cupsdReadClient: 19 WAITING Closing on EOF
D [17/Nov/2011:22:02:47 -0800] cupsdCloseClient: 19
D [17/Nov/2011:22:02:47 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 1807 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 1807 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 1807 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 1807 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:47 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:48 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:02:48 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:02:48 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:53 -0800] [Job 21] ATTR: marker-levels=11,14,23,19
D [17/Nov/2011:22:02:53 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:02:53 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:53 -0800] [Job 21] new_supply_state=0, change_state=0
D [17/Nov/2011:22:02:53 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:53 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:53 -0800] [Job 21] new_state=2000, change_state=0
D [17/Nov/2011:22:02:53 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:53 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:02:53 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:53 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:53 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:53 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:53 -0800] Get-Notifications /
D [17/Nov/2011:22:02:53 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:53 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:53 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:02:53 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:02:53 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:02:53 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:56 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:56 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
I [17/Nov/2011:22:02:57 -0800] [Job 21] Processing page 2...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Set job-printer-state-message to "Processing page 2...", current level=INFO
D [17/Nov/2011:22:02:57 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:57 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:57 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:57 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 625: 9......
D [17/Nov/2011:22:02:57 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 628: 10......
D [17/Nov/2011:22:02:57 -0800] [Job 21] PAGE: 2 1
D [17/Nov/2011:22:02:57 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:57 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:57 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:57 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:02:57 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:57 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:57 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:57 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:57 -0800] Get-Notifications /
D [17/Nov/2011:22:02:57 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:57 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:57 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:02:57 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:02:57 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:02:57 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:57 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] ATTR: marker-levels=11,14,23,19
D [17/Nov/2011:22:02:58 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:02:58 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:58 -0800] [Job 21] new_supply_state=0, change_state=0
D [17/Nov/2011:22:02:58 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:02:58 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:58 -0800] [Job 21] new_state=2000, change_state=0
D [17/Nov/2011:22:02:58 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:02:58 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:02:58 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:02:58 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:58 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:02:58 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:02:58 -0800] Get-Notifications /
D [17/Nov/2011:22:02:58 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:02:58 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:02:58 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:02:58 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:02:58 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:02:58 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:02:58 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:58 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:02:59 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:02:59 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:00 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:00 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:00 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:00 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:00 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:00 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:00 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:00 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:00 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:00 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:01 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:02 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:02 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:02 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:02 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:03 -0800] Report: clients=0
D [17/Nov/2011:22:03:03 -0800] Report: jobs=9
D [17/Nov/2011:22:03:03 -0800] Report: jobs-active=1
D [17/Nov/2011:22:03:03 -0800] Report: printers=1
D [17/Nov/2011:22:03:03 -0800] Report: printers-implicit=0
D [17/Nov/2011:22:03:03 -0800] Report: stringpool-string-count=2230
D [17/Nov/2011:22:03:03 -0800] Report: stringpool-alloc-bytes=12376
D [17/Nov/2011:22:03:03 -0800] Report: stringpool-total-bytes=46184
D [17/Nov/2011:22:03:04 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:04 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:04 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:04 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:04 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:04 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:04 -0800] Get-Notifications /
D [17/Nov/2011:22:03:04 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:04 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:04 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:03:04 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:04 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:04 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:07 -0800] [Job 21] ATTR: marker-levels=11,14,23,19
D [17/Nov/2011:22:03:07 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:03:07 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:07 -0800] [Job 21] new_supply_state=0, change_state=0
D [17/Nov/2011:22:03:07 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:07 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:07 -0800] [Job 21] new_state=2000, change_state=0
D [17/Nov/2011:22:03:07 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:07 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:07 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:07 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:07 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:07 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:07 -0800] Get-Notifications /
D [17/Nov/2011:22:03:07 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:07 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:07 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:03:07 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:07 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:07 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:12 -0800] [Job 21] ATTR: marker-levels=11,14,23,19
D [17/Nov/2011:22:03:12 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:03:12 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:12 -0800] [Job 21] new_supply_state=0, change_state=0
D [17/Nov/2011:22:03:12 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:12 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:12 -0800] [Job 21] new_state=2000, change_state=0
D [17/Nov/2011:22:03:12 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:12 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:12 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:12 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:12 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:12 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:12 -0800] Get-Notifications /
D [17/Nov/2011:22:03:12 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:12 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:12 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:03:12 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:12 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:12 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [17/Nov/2011:22:03:16 -0800] Saving printers.conf...
I [17/Nov/2011:22:03:16 -0800] Saving job.cache...
I [17/Nov/2011:22:03:17 -0800] Saving subscriptions.conf...
D [17/Nov/2011:22:03:17 -0800] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:17 -0800] [Job 21] ATTR: marker-levels=11,14,23,19
D [17/Nov/2011:22:03:17 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:03:17 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs"
D [17/Nov/2011:22:03:17 -0800] [Job 21] new_supply_state=0, change_state=0
D [17/Nov/2011:22:03:17 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:17 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:17 -0800] [Job 21] new_state=2000, change_state=0
D [17/Nov/2011:22:03:17 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:17 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:17 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:17 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:17 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:17 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:17 -0800] Get-Notifications /
D [17/Nov/2011:22:03:17 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:17 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:17 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:03:17 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:17 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:17 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:22 -0800] [Job 21] ATTR: marker-levels=11,14,23,19
D [17/Nov/2011:22:03:22 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:03:22 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:22 -0800] [Job 21] new_supply_state=0, change_state=0
D [17/Nov/2011:22:03:22 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:22 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:22 -0800] [Job 21] new_state=2000, change_state=0
D [17/Nov/2011:22:03:22 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:22 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:22 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:22 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:22 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:22 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:22 -0800] Get-Notifications /
D [17/Nov/2011:22:03:22 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:22 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:22 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:03:22 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:22 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:22 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:23 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:23 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:24 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Read 8192 bytes of print data...
I [17/Nov/2011:22:03:25 -0800] [Job 21] Processing page 3...
D [17/Nov/2011:22:03:25 -0800] [Job 21] Set job-printer-state-message to "Processing page 3...", current level=INFO
D [17/Nov/2011:22:03:25 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:25 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:25 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:25 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [17/Nov/2011:22:03:25 -0800] [Job 21] Rendering completed
D [17/Nov/2011:22:03:25 -0800] [Job 21] Set job-printer-state-message to "Rendering completed", current level=INFO
D [17/Nov/2011:22:03:25 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:25 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:25 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:25 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:25 -0800] PID 2072 (/usr/lib/cups/filter/gstoraster) exited with no errors.
D [17/Nov/2011:22:03:25 -0800] [Job 21] prnt/hpcups/HPCupsFilter.cpp 522: HPCUPS: StartPrintJob end of job, calling closeFilter()
D [17/Nov/2011:22:03:25 -0800] PID 2073 (/usr/lib/cups/filter/hpcups) exited with no errors.
D [17/Nov/2011:22:03:25 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:25 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:25 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:25 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:25 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:25 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:25 -0800] Get-Notifications /
D [17/Nov/2011:22:03:25 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:25 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:25 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:03:25 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:25 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:25 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:26 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:26 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:26 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:26 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:26 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:26 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:26 -0800] Get-Notifications /
D [17/Nov/2011:22:03:26 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:26 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:26 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:03:26 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:26 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:26 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:26 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Read 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Wrote 8192 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Read 2479 bytes of print data...
D [17/Nov/2011:22:03:26 -0800] [Job 21] Wrote 2479 bytes of print data...
I [17/Nov/2011:22:03:26 -0800] [Job 21] Waiting for printer to finish.
D [17/Nov/2011:22:03:26 -0800] [Job 21] Set job-printer-state-message to "Waiting for printer to finish.", current level=INFO
D [17/Nov/2011:22:03:26 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:26 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:26 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:26 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:26 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:26 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:26 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:26 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:26 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:26 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:26 -0800] Get-Notifications /
D [17/Nov/2011:22:03:26 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:26 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:26 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Nov/2011:22:03:26 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:26 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:26 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:30 -0800] [Job 21] ATTR: marker-levels=11,14,23,19
D [17/Nov/2011:22:03:30 -0800] cupsdMarkDirty(P-----)
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:30 -0800] [Job 21] new_supply_state=0, change_state=0
D [17/Nov/2011:22:03:30 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:30 -0800] [Job 21] new_state=2000, change_state=0
D [17/Nov/2011:22:03:30 -0800] [Job 21] PAGE: total 2
D [17/Nov/2011:22:03:30 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [17/Nov/2011:22:03:30 -0800] [Job 21] Ready to print.
D [17/Nov/2011:22:03:30 -0800] [Job 21] Set job-printer-state-message to "Ready to print.", current level=INFO
D [17/Nov/2011:22:03:30 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:30 -0800] PID 2074 (/usr/lib/cups/backend/socket) exited with no errors.
D [17/Nov/2011:22:03:30 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [17/Nov/2011:22:03:30 -0800] [Job 21] Job completed.
D [17/Nov/2011:22:03:30 -0800] cupsdMarkDirty(----J-)
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdMarkDirty(-----S)
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:30 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:30 -0800] Get-Notifications /
D [17/Nov/2011:22:03:30 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:30 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:30 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:30 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:30 -0800] Get-Notifications /
D [17/Nov/2011:22:03:30 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:30 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:30 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:30 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:30 -0800] Get-Notifications /
D [17/Nov/2011:22:03:30 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:30 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:30 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdAcceptClient: skipping getpeercon()
D [17/Nov/2011:22:03:30 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdAuthorize: No authentication data provided.
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1
D [17/Nov/2011:22:03:30 -0800] Get-Notifications /
D [17/Nov/2011:22:03:30 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [17/Nov/2011:22:03:30 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Nov/2011:22:03:30 -0800] cupsdReadClient: 17 WAITING Closing on EOF
D [17/Nov/2011:22:03:30 -0800] cupsdCloseClient: 17
D [17/Nov/2011:22:03:30 -0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/Nov/2011:22:03:31 -0800] [Job 21] Unloading...
I [17/Nov/2011:22:03:48 -0800] Saving printers.conf...
I [17/Nov/2011:22:03:48 -0800] Saving job.cache...
I [17/Nov/2011:22:03:48 -0800] Saving subscriptions.conf...
D [17/Nov/2011:22:03:48 -0800] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"