Attachment 574039 Details for Bug 808542 – cups error_log snippet as shown by gnome3 printing tool during troubleshooting wizard

cups error_log snippet as shown by gnome3 printing tool during troubleshooting wizard

error_log (text/plain), 33.01 KB, created by Alberto on 2012-03-30 16:35:25 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Alberto

Created: 2012-03-30 16:35:25 UTC

Size: 33.01 KB

patch

obsolete

>D [30/Mar/2012:12:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:19 -0400] cupsdReadClient: 17 POST / HTTP/1.1
>D [30/Mar/2012:12:05:19 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:19 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:19 -0400] cupsdReadClient: 17 1.1 Get-Jobs 1
>D [30/Mar/2012:12:05:19 -0400] Get-Jobs ipp://localhost/printers/
>D [30/Mar/2012:12:05:19 -0400] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
>D [30/Mar/2012:12:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:19 -0400] cupsdReadClient: 17 POST / HTTP/1.1
>D [30/Mar/2012:12:05:19 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:19 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:19 -0400] cupsdReadClient: 17 1.1 Get-Jobs 1
>D [30/Mar/2012:12:05:19 -0400] Get-Jobs ipp://localhost/printers/
>D [30/Mar/2012:12:05:19 -0400] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
>D [30/Mar/2012:12:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:19 -0400] cupsdReadClient: 17 POST / HTTP/1.1
>D [30/Mar/2012:12:05:19 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:19 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:19 -0400] cupsdReadClient: 17 1.1 Create-Printer-Subscription 1
>D [30/Mar/2012:12:05:19 -0400] Create-Printer-Subscription /
>D [30/Mar/2012:12:05:19 -0400] cupsdCreateSubscription(con=0x7f31869b1f60(17), uri="/")
>D [30/Mar/2012:12:05:19 -0400] pullmethod="ippget"
>D [30/Mar/2012:12:05:19 -0400] notify-lease-duration=86400
>D [30/Mar/2012:12:05:19 -0400] notify-time-interval=0
>D [30/Mar/2012:12:05:19 -0400] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")
>D [30/Mar/2012:12:05:19 -0400] Added subscription #22 for server.
>D [30/Mar/2012:12:05:19 -0400] cupsdMarkDirty(-----S)
>D [30/Mar/2012:12:05:19 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:19 -0400] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
>D [30/Mar/2012:12:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:20 -0400] cupsdReadClient: 17 POST / HTTP/1.1
>D [30/Mar/2012:12:05:20 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:20 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:20 -0400] cupsdReadClient: 17 1.1 Get-Notifications 1
>D [30/Mar/2012:12:05:20 -0400] Get-Notifications /
>D [30/Mar/2012:12:05:20 -0400] cupsdIsAuthorized: requesting-user-name="chiniquea"
>D [30/Mar/2012:12:05:20 -0400] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [30/Mar/2012:12:05:20 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdAcceptClient: skipping getpeercon()
>D [30/Mar/2012:12:05:24 -0400] cupsdAcceptClient: 18 from localhost (Domain)
>I [30/Mar/2012:12:05:24 -0400] Generating printcap /etc/printcap...
>I [30/Mar/2012:12:05:24 -0400] Saving subscriptions.conf...
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 18 POST /printers/Officejet_Pro_8500_A910 HTTP/1.1
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [30/Mar/2012:12:05:24 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 18 1.1 Print-Job 1
>D [30/Mar/2012:12:05:24 -0400] Print-Job ipp://localhost/printers/Officejet_Pro_8500_A910
>D [30/Mar/2012:12:05:24 -0400] [Job ???] Auto-typing file...
>I [30/Mar/2012:12:05:24 -0400] [Job ???] Request file type is application/vnd.cups-banner.
>D [30/Mar/2012:12:05:24 -0400] add_job: setting context of job to UNKNOWN SL
>D [30/Mar/2012:12:05:24 -0400] cupsdMarkDirty(----J-)
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
>D [30/Mar/2012:12:05:24 -0400] add_job: requesting-user-name="chiniquea"
>D [30/Mar/2012:12:05:24 -0400] Adding default job-sheets values "none,none"...
>I [30/Mar/2012:12:05:24 -0400] [Job 18] Adding start banner page "none".
>D [30/Mar/2012:12:05:24 -0400] Notifier dbus started - PID = 4350
>D [30/Mar/2012:12:05:24 -0400] cupsdMarkDirty(-----S)
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdMarkDirty(----J-)
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>I [30/Mar/2012:12:05:24 -0400] [Job 18] Adding end banner page "none".
>I [30/Mar/2012:12:05:24 -0400] [Job 18] File of type application/vnd.cups-banner queued by "chiniquea".
>D [30/Mar/2012:12:05:24 -0400] [Job 18] hold_until=0
>I [30/Mar/2012:12:05:24 -0400] [Job 18] Queued on "Officejet_Pro_8500_A910" by "chiniquea".
>D [30/Mar/2012:12:05:24 -0400] cupsdMarkDirty(----J-)
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdMarkDirty(-----S)
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] job-sheets=none,none
>D [30/Mar/2012:12:05:24 -0400] [Job 18] argv[0]="Officejet_Pro_8500_A910"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] argv[1]="18"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] argv[2]="chiniquea"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] argv[3]="Test Page"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] argv[4]="1"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] argv[5]="job-uuid=urn:uuid:0110e42f-29a3-3f08-46f2-1a0a46738507 job-originating-host-name=localhost time-at-creation=1333123524 time-at-processing=1333123524 AP_D_InputSlot="
>D [30/Mar/2012:12:05:24 -0400] [Job 18] argv[6]="/var/spool/cups/d00018-001"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[1]="CUPS_DATADIR=/usr/share/cups"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[6]="CUPS_SERVERROOT=/etc/cups"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[7]="CUPS_STATEDIR=/var/run/cups"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[8]="HOME=/var/spool/cups/tmp"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[10]="SERVER_ADMIN=root@CMNet-Laptop-1"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[11]="SOFTWARE=CUPS/1.5.2"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[12]="TMPDIR=/var/spool/cups/tmp"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[13]="USER=root"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[15]="CUPS_ENCRYPTION=IfRequested"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[16]="IPP_PORT=631"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[17]="CHARSET=utf-8"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[18]="LANG=en_US.UTF-8"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[19]="PPD=/etc/cups/ppd/Officejet_Pro_8500_A910.ppd"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[20]="RIP_MAX_CACHE=128m"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[21]="CONTENT_TYPE=application/vnd.cups-banner"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[22]="DEVICE_URI=hp:/net/Officejet_Pro_8500_A910?ip=192.168.1.8"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[23]="PRINTER_INFO="
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[24]="PRINTER_LOCATION=Home"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[25]="PRINTER=Officejet_Pro_8500_A910"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[26]="PRINTER_STATE_REASONS=none"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[27]="CUPS_FILETYPE=document"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[28]="FINAL_CONTENT_TYPE=printer/Officejet_Pro_8500_A910"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] envp[29]="AUTH_I****"
>I [30/Mar/2012:12:05:24 -0400] [Job 18] Started filter /usr/lib/cups/filter/bannertops (PID 4351)
>I [30/Mar/2012:12:05:24 -0400] [Job 18] Started filter /usr/lib/cups/filter/pstops (PID 4352)
>I [30/Mar/2012:12:05:24 -0400] [Job 18] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4353)
>I [30/Mar/2012:12:05:24 -0400] [Job 18] Started backend /usr/lib/cups/backend/hp (PID 4354)
>D [30/Mar/2012:12:05:24 -0400] cupsdMarkDirty(-----S)
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:24 -0400] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/Officejet_Pro_8500_A910) from localhost
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Getting input from file
>D [30/Mar/2012:12:05:24 -0400] [Job 18] foomatic-rip version 4.0.13.247 running...
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Parsing PPD file ...
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option ColorSpace
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option Resolution
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option PageSize
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option Model
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option PrintoutMode
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option InputSlot
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option Duplex
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option DryTime
>D [30/Mar/2012:12:05:24 -0400] [Job 18] load_banner(filename="/var/spool/cups/d00018-001")
>D [30/Mar/2012:12:05:24 -0400] [Job 18] 1 #CUPS-BANNER
>D [30/Mar/2012:12:05:24 -0400] [Job 18] 2 Show printer-name printer-info printer-location printer-make-and-model printer-driver-name printer-driver-version paper-size imageable-area
>D [30/Mar/2012:12:05:24 -0400] [Job 18] 3 Header Printer Test Page
>D [30/Mar/2012:12:05:24 -0400] [Job 18] 4 Footer Printer Test Page
>D [30/Mar/2012:12:05:24 -0400] [Job 18] 5 Notice CUPS 1.5.2.
>D [30/Mar/2012:12:05:24 -0400] [Job 18] 6 Image images/cups.png
>D [30/Mar/2012:12:05:24 -0400] [Job 18] 7 Image images/color-wheel.png
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option Quality
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option ImageableArea
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option PaperDimension
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Added option Font
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Parameter Summary
>D [30/Mar/2012:12:05:24 -0400] [Job 18] -----------------
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Spooler: cups
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Printer: Officejet_Pro_8500_A910
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Shell: /bin/bash
>D [30/Mar/2012:12:05:24 -0400] [Job 18] PPD file: /etc/cups/ppd/Officejet_Pro_8500_A910.ppd
>D [30/Mar/2012:12:05:24 -0400] [Job 18] ATTR file:
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Printer model: HP Officejet Pro 8500 a910 hpijs, 3.12.2
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Job title: Test Page
>D [30/Mar/2012:12:05:24 -0400] [Job 18] File(s) to be printed:
>D [30/Mar/2012:12:05:24 -0400] [Job 18] <STDIN>
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Printing system options:
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Pondering option 'job-uuid=urn:uuid:0110e42f-29a3-3f08-46f2-1a0a46738507'
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Unknown option job-uuid=urn:uuid:0110e42f-29a3-3f08-46f2-1a0a46738507.
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Pondering option 'job-originating-host-name=localhost'
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Unknown option job-originating-host-name=localhost.
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Pondering option 'time-at-creation=1333123524'
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Unknown option time-at-creation=1333123524.
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Pondering option 'time-at-processing=1333123524'
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Unknown option time-at-processing=1333123524.
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Pondering option 'AP_D_InputSlot'
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Unknown boolean option "AP_D_InputSlot".
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Options from the PPD file:
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] ================================================
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] File: <STDIN>
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] ================================================
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Notifier] state=3
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Page = 612x792; 18,36 to 594,783
>D [30/Mar/2012:12:05:24 -0400] [Notifier] Connected to D-BUS
>D [30/Mar/2012:12:05:24 -0400] [Notifier] state=3
>D [30/Mar/2012:12:05:24 -0400] [Notifier] state=3
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Page = 612x792; 18,36 to 594,783
>D [30/Mar/2012:12:05:24 -0400] [Job 18] slow_collate=0, slow_duplex=0, slow_order=0
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Before copy_comments - %!PS-Adobe-3.0
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %!PS-Adobe-3.0
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%BoundingBox: 18 36 594 783
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %cupsRotation: 0
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%Creator: bannertops/CUPS v1.5.2
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%CreationDate: Fri 30 Mar 2012 12:05:24 PM EDT
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%LanguageLevel: 2
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%DocumentData: Clean7Bit
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%Title: (Test Page)
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%For: (chiniquea)
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%Pages: 1
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%DocumentSuppliedResources: font Monospace
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%+ font Monospace-Bold
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%+ font Monospace-BoldOblique
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%+ font Monospace-Oblique
>D [30/Mar/2012:12:05:24 -0400] [Job 18] %%EndComments
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Before copy_prolog - %%BeginProlog
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Filetype: PostScript
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Reading PostScript input ...
>D [30/Mar/2012:12:05:24 -0400] [Job 18] --> This document is DSC-conforming!
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found %RBINumCopies: 1
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] -----------
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %%BeginProlog
>D [30/Mar/2012:12:05:24 -0400] [Job 18] PNG image: 128x128x8, color_type=6 (RGB+ALPHA)
>D [30/Mar/2012:12:05:24 -0400] [Job 18] PNG image: 192x128x8, color_type=2 (RGB)
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Before copy_setup - %%Page: coverpage 1
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Before page loop - %%Page: coverpage 1
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Copying page 1...
>D [30/Mar/2012:12:05:24 -0400] [Job 18] pagew = 576.0, pagel = 747.0
>D [30/Mar/2012:12:05:24 -0400] [Job 18] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
>D [30/Mar/2012:12:05:24 -0400] [Job 18] PageLeft = 18.0, PageRight = 594.0
>D [30/Mar/2012:12:05:24 -0400] [Job 18] PageTop = 783.0, PageBottom = 36.0
>D [30/Mar/2012:12:05:24 -0400] [Job 18] PageWidth = 612.0, PageLength = 792.0
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %%EndProlog
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] -----------
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %%BeginSetup
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %%BeginFeature: *PageSize Letter
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Option: PageSize=Letter
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Setting option
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Option: PageSize=Letter
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Setting option
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %%BeginFeature: *PrintoutMode Normal
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Option: PrintoutMode=Normal
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Setting option
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Option: PrintoutMode=Normal
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Setting option
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %%BeginFeature: *Quality FromPrintoutMode
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Option: Quality=FromPrintoutMode
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Setting option
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Option: Quality=FromPrintoutMode
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Setting option
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %%BeginFeature: *Duplex None
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Option: Duplex=None
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Setting option
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %% FoomaticRIPOptionSetting: Duplex=None
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Option: Duplex=None
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Setting option
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %%BeginFeature: *DryTime Zero
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Option: DryTime=Zero
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Setting option
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %% FoomaticRIPOptionSetting: DryTime=Zero
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Option: DryTime=Zero
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Setting option
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %%EndSetup
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Inserting PostScript code for CUPS' page accounting
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] -----------
>D [30/Mar/2012:12:05:24 -0400] [Job 18] New page: %%Page: coverpage 1
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Found: %%BeginPageSetup
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Inserting option code into "PageSetup" section.
>D [30/Mar/2012:12:05:24 -0400] PID 4351 (/usr/lib/cups/filter/bannertops) exited with no errors.
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Wrote 1 pages...
>D [30/Mar/2012:12:05:24 -0400] PID 4352 (/usr/lib/cups/filter/pstops) exited with no errors.
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Flushing FIFO.
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Starting renderer with command: "gs -sstdout=%stderr  -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="deskjet 5600" -dDuplex=false -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=%stdout -_"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Starting process "kid3" (generation 1)
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Starting process "kid4" (generation 2)
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Starting process "renderer" (generation 2)
>D [30/Mar/2012:12:05:24 -0400] [Job 18] JCL: %-12345X@PJL
>D [30/Mar/2012:12:05:24 -0400] [Job 18] <job data>
>D [30/Mar/2012:12:05:24 -0400] [Job 18]
>D [30/Mar/2012:12:05:24 -0400] cupsdAcceptClient: skipping getpeercon()
>D [30/Mar/2012:12:05:24 -0400] cupsdAcceptClient: 23 from localhost (Domain)
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 23 POST / HTTP/1.1
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 23 1.1 Get-Notifications 1
>D [30/Mar/2012:12:05:24 -0400] Get-Notifications /
>D [30/Mar/2012:12:05:24 -0400] cupsdIsAuthorized: requesting-user-name="chiniquea"
>D [30/Mar/2012:12:05:24 -0400] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 23 WAITING Closing on EOF
>D [30/Mar/2012:12:05:24 -0400] cupsdCloseClient: 23
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 17 POST / HTTP/1.1
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 17 1.1 Get-Notifications 1
>D [30/Mar/2012:12:05:24 -0400] Get-Notifications /
>D [30/Mar/2012:12:05:24 -0400] cupsdIsAuthorized: requesting-user-name="chiniquea"
>D [30/Mar/2012:12:05:24 -0400] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] prnt/hpijs/hpijs.cpp 639: unable to open PrintContext object err=2
>D [30/Mar/2012:12:05:24 -0400] [Job 18] GPL Ghostscript 9.04: Can't start ijs server "hpijs"
>D [30/Mar/2012:12:05:24 -0400] [Job 18] **** Unable to open the initial device, quitting.
>D [30/Mar/2012:12:05:24 -0400] [Job 18] renderer exited with status 1
>D [30/Mar/2012:12:05:24 -0400] PID 4353 (/usr/lib/cups/filter/foomatic-rip) did not catch or ignore signal 13.
>D [30/Mar/2012:12:05:24 -0400] [Job 18] Possible error on renderer command line or PostScript error. Check options.prnt/backend/hp.c 833: ERROR: null print job total=0
>D [30/Mar/2012:12:05:24 -0400] PID 4354 (/usr/lib/cups/backend/hp) exited with no errors.
>D [30/Mar/2012:12:05:24 -0400] cupsdMarkDirty(-----S)
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>I [30/Mar/2012:12:05:24 -0400] [Job 18] Job completed.
>D [30/Mar/2012:12:05:24 -0400] cupsdMarkDirty(----J-)
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdMarkDirty(-----S)
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [30/Mar/2012:12:05:24 -0400] [Notifier] state=3
>D [30/Mar/2012:12:05:24 -0400] [Notifier] state=3
>D [30/Mar/2012:12:05:24 -0400] cupsdAcceptClient: skipping getpeercon()
>D [30/Mar/2012:12:05:24 -0400] cupsdAcceptClient: 21 from localhost (Domain)
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 21 POST / HTTP/1.1
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
>D [30/Mar/2012:12:05:24 -0400] CUPS-Get-Printers
>D [30/Mar/2012:12:05:24 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 21 POST / HTTP/1.1
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 21 1.1 CUPS-Get-Classes 1
>D [30/Mar/2012:12:05:24 -0400] CUPS-Get-Classes
>D [30/Mar/2012:12:05:24 -0400] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 21 POST / HTTP/1.1
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:24 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:24 -0400] cupsdReadClient: 21 1.1 CUPS-Get-Default 1
>D [30/Mar/2012:12:05:24 -0400] CUPS-Get-Default
>D [30/Mar/2012:12:05:24 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [30/Mar/2012:12:05:24 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdAcceptClient: skipping getpeercon()
>D [30/Mar/2012:12:05:25 -0400] cupsdAcceptClient: 23 from localhost (Domain)
>D [30/Mar/2012:12:05:25 -0400] [Job 18] Unloading...
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 23 POST / HTTP/1.1
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 23 1.1 Get-Notifications 1
>D [30/Mar/2012:12:05:25 -0400] Get-Notifications /
>D [30/Mar/2012:12:05:25 -0400] cupsdIsAuthorized: requesting-user-name="chiniquea"
>D [30/Mar/2012:12:05:25 -0400] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 23 WAITING Closing on EOF
>D [30/Mar/2012:12:05:25 -0400] cupsdCloseClient: 23
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 17 POST / HTTP/1.1
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 17 1.1 Get-Notifications 1
>D [30/Mar/2012:12:05:25 -0400] Get-Notifications /
>D [30/Mar/2012:12:05:25 -0400] cupsdIsAuthorized: requesting-user-name="chiniquea"
>D [30/Mar/2012:12:05:25 -0400] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 21 POST / HTTP/1.1
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
>D [30/Mar/2012:12:05:25 -0400] CUPS-Get-Printers
>D [30/Mar/2012:12:05:25 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 21 POST / HTTP/1.1
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 21 1.1 CUPS-Get-Classes 1
>D [30/Mar/2012:12:05:25 -0400] CUPS-Get-Classes
>D [30/Mar/2012:12:05:25 -0400] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 21 POST / HTTP/1.1
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:25 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:25 -0400] cupsdReadClient: 21 1.1 CUPS-Get-Default 1
>D [30/Mar/2012:12:05:25 -0400] CUPS-Get-Default
>D [30/Mar/2012:12:05:25 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [30/Mar/2012:12:05:25 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:41 -0400] cupsdReadClient: 17 POST / HTTP/1.1
>D [30/Mar/2012:12:05:41 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:41 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:41 -0400] cupsdReadClient: 17 1.1 Get-Job-Attributes 1
>D [30/Mar/2012:12:05:41 -0400] Get-Job-Attributes ipp://localhost/jobs/18
>D [30/Mar/2012:12:05:41 -0400] [Job 18] Loading attributes...
>D [30/Mar/2012:12:05:41 -0400] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/18) from localhost
>D [30/Mar/2012:12:05:41 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:41 -0400] cupsdReadClient: 17 POST / HTTP/1.1
>D [30/Mar/2012:12:05:41 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:41 -0400] cupsdAuthorize: No authentication data provided.
>D [30/Mar/2012:12:05:41 -0400] cupsdReadClient: 17 1.1 Cancel-Subscription 1
>D [30/Mar/2012:12:05:41 -0400] Cancel-Subscription /
>D [30/Mar/2012:12:05:41 -0400] cupsdIsAuthorized: requesting-user-name="chiniquea"
>D [30/Mar/2012:12:05:41 -0400] cupsdMarkDirty(-----S)
>D [30/Mar/2012:12:05:41 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:41 -0400] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
>D [30/Mar/2012:12:05:41 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:42 -0400] cupsdReadClient: 15 PUT /admin/conf/cupsd.conf HTTP/1.1
>D [30/Mar/2012:12:05:42 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:42 -0400] cupsdAuthorize: Authorized as root using PeerCred
>D [30/Mar/2012:12:05:42 -0400] cupsdIsAuthorized: username="root"
>I [30/Mar/2012:12:05:42 -0400] Installing config file "/etc/cups/cupsd.conf"...
>D [30/Mar/2012:12:05:42 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [30/Mar/2012:12:05:42 -0400] cupsdCloseClient: 15
>D [30/Mar/2012:12:05:42 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:42 -0400] cupsdCloseClient: 17
>D [30/Mar/2012:12:05:42 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:42 -0400] cupsdCloseClient: 18
>D [30/Mar/2012:12:05:42 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:42 -0400] cupsdCloseClient: 21
>D [30/Mar/2012:12:05:42 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [30/Mar/2012:12:05:42 -0400] cupsdDeregisterPrinter(p=0x7f31869255a0(Officejet_Pro_8500_A910), removeit=1)
>D [30/Mar/2012:12:05:42 -0400] cupsdDeregisterPrinter(p=0x7f3186949e80(Officejet_Pro_8500_A910_fax), removeit=1)
>I [30/Mar/2012:12:05:42 -0400] Saving job.cache...
>I [30/Mar/2012:12:05:42 -0400] Saving subscriptions.conf...
>D [30/Mar/2012:12:05:42 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
>W [30/Mar/2012:12:05:42 -0400] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet_Pro_8500_A910-Gray..' already exists
>W [30/Mar/2012:12:05:42 -0400] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet_Pro_8500_A910-RGB..' already exists
>W [30/Mar/2012:12:05:42 -0400] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-Officejet_Pro_8500_A910' already exists
>W [30/Mar/2012:12:05:42 -0400] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet_Pro_8500_A910_fax-Gray..' already exists
>W [30/Mar/2012:12:05:42 -0400] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet_Pro_8500_A910_fax-RGB..' already exists
>W [30/Mar/2012:12:05:42 -0400] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-Officejet_Pro_8500_A910_fax' already exists
>E [30/Mar/2012:12:05:42 -0400] Browsing=1
>E [30/Mar/2012:12:05:42 -0400] BrowseLocalProtocols=0
>E [30/Mar/2012:12:05:42 -0400] BrowseRemoteProtocols=1
>E [30/Mar/2012:12:05:42 -0400] BROWSE_CUPS=1

Actions: View

Attachments on bug 808542: 574039