[cups-devel] print appears to be successful, but no output

Helge Blischke HelgeBlischke at web.de
Thu Mar 6 02:01:34 PST 2014


Your error_log reveals that your cupsd does not recognize a suitable  
file type
for the data coming from your Win box but defaults to
application/octet-stream
But all the PPDs for this printer (as far as I could retrieve) specify  
this
printer as one using HP's IJS protocol and require a special filter
(e.g. hpcups) to be run as the final filter.
Instead, your cupsd transmits the data unprocessed to the printer via
the backend, and the printer obviously ignores all the stuff.

Could you please post (an URL to) a sample file?
(The easiest way to get one is:
–	set the printer offline
–	print a file from Windows
–	copy the file
	/var/spool/cups/dnnnnn-001
	where nnnnn is the job number, to a location of your
	choice (need to do it as root or via sudo)
–	post (an URL to) the saved file)

After looking into this file, I probably can tell you how to modify
your CUPS mime database to successfully determine the
real dodument type.

Helge

Am 06.03.2014 um 03:34 schrieb Dean Guenther:

> I have cupsd 1.4.2 running on a CentOS 6.5 box. I have an HP Psc 1200
> printer attached to USB connection. When I print to the printer from  
> the
> CentOS box, it goes through. But when I print remotely from a  
> windows 7
> box, it appears to be successful, Eg no errors that I detect. But no  
> print
> actually gets sent to the printer.
>
> From the Windows 7 box, I am able to use the print interface:
>
>  http://192.168.0.2:631/admin
>
> From this printer
>
>  http://192.168.0.2:631/admin
>
> this is what shows:
>
> Description:	Hewlett-Packard psc 1200 series
> Location:	localhost.localdomain
> Driver:	HP PSC 1200 Series, hpcups 3.12.4 (color)
> Connection:	usb://HP/psc%201200%20series?serial=MY36CC941Q5H
> Defaults:	job-sheets=none, none media=na_letter_8.5x11in
>
> So then I send a print job and it seems to go through, but nothing
> actually prints.
>
> In the access_log it looks good:
>
> 192.168.0.144 - - [05/Mar/2014:06:58:11 -0800] "POST
> /printers/psc-1200-series HTTP/1.1" 200 233592 Print-Job successful-ok
>
> In the page_log it looks good:
>
> psc-1200-series 6 Mary [05/Mar/2014:06:58:13 -0800] 1 1 -  
> 192.168.0.144
> PCS_BOYD-1 - -
>
> When I show completed jobs it is there (the second job from "root" was
> printed locally from the root account on the CentOS box):
>
> psc-1200-series-6  	PCS_BOYD-1  	Mary  	228k  	1  	completed at
> Wed 05 Mar 2014 06:58:13 AM PST
>
> psc-1200-series-4  	psc-1200-series  	root  	5k  	2  	completed at
> Tue 04 Mar 2014 10:04:27 PM PST
>
> Here is what I see with debug turned on in the error_log. How do I  
> pursue
> this? thanks -- Dean guenther
>
> D [05/Mar/2014:06:57:55 -0800] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Mar/2014:06:57:55 -0800] cupsdNetIFUpdate: "eth0" =  
> 192.168.0.2:631
> D [05/Mar/2014:06:57:55 -0800] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Mar/2014:06:57:55 -0800] cupsdNetIFUpdate: "eth0" =
> fe80::20c:76ff:fea4:b4f3%eth0:631
> D [05/Mar/2014:06:57:55 -0800] Report: clients=0
> D [05/Mar/2014:06:57:55 -0800] Report: jobs=5
> D [05/Mar/2014:06:57:55 -0800] Report: jobs-active=0
> D [05/Mar/2014:06:57:55 -0800] Report: printers=1
> D [05/Mar/2014:06:57:55 -0800] Report: printers-implicit=0
> D [05/Mar/2014:06:57:55 -0800] Report: stringpool-string-count=526
> D [05/Mar/2014:06:57:55 -0800] Report: stringpool-alloc-bytes=6784
> D [05/Mar/2014:06:57:55 -0800] Report: stringpool-total-bytes=11048
> D [05/Mar/2014:06:57:58 -0800] cupsdAcceptClient: skipping  
> getpeercon()
> D [05/Mar/2014:06:57:58 -0800] cupsdAcceptClient: 12 from
> 192.168.0.144:631 (IPv4)
> D [05/Mar/2014:06:57:58 -0800] cupsdReadClient: 12 POST
> /printers/psc-1200-series HTTP/1.1
> D [05/Mar/2014:06:57:58 -0800] cupsdSetBusyState: Active clients
> D [05/Mar/2014:06:57:58 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:57:58 -0800] cupsdReadClient: 12 1.0
> Get-Printer-Attributes 11
> D [05/Mar/2014:06:57:58 -0800] Get-Printer-Attributes
> http://192.168.0.2:631/printers/psc-1200-series
> D [05/Mar/2014:06:57:58 -0800] Returning IPP successful-ok for
> Get-Printer-Attributes (http://192.168.0.2:631/printers/psc-1200-series 
> )
> from 192.168.0.144
> D [05/Mar/2014:06:57:58 -0800] cupsdSetBusyState: Not busy
> D [05/Mar/2014:06:57:59 -0800] cupsdReadClient: 12 POST
> /printers/psc-1200-series HTTP/1.1
> D [05/Mar/2014:06:57:59 -0800] cupsdSetBusyState: Active clients
> D [05/Mar/2014:06:57:59 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:57:59 -0800] cupsdReadClient: 12 1.0
> Get-Printer-Attributes 11
> D [05/Mar/2014:06:57:59 -0800] Get-Printer-Attributes
> http://192.168.0.2:631/printers/psc-1200-series
> D [05/Mar/2014:06:57:59 -0800] Returning IPP successful-ok for
> Get-Printer-Attributes (http://192.168.0.2:631/printers/psc-1200-series 
> )
> from 192.168.0.144
> D [05/Mar/2014:06:57:59 -0800] cupsdSetBusyState: Not busy
> D [05/Mar/2014:06:58:09 -0800] cupsdReadClient: 12 POST
> /printers/psc-1200-series HTTP/1.1
> D [05/Mar/2014:06:58:09 -0800] cupsdSetBusyState: Active clients
> D [05/Mar/2014:06:58:09 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:58:09 -0800] cupsdReadClient: 12 1.0
> Get-Printer-Attributes 11
> D [05/Mar/2014:06:58:09 -0800] Get-Printer-Attributes
> http://192.168.0.2:631/printers/psc-1200-series
> D [05/Mar/2014:06:58:09 -0800] Returning IPP successful-ok for
> Get-Printer-Attributes (http://192.168.0.2:631/printers/psc-1200-series 
> )
> from 192.168.0.144
> D [05/Mar/2014:06:58:09 -0800] cupsdSetBusyState: Not busy
> D [05/Mar/2014:06:58:09 -0800] cupsdReadClient: 12 POST
> /printers/psc-1200-series HTTP/1.1
> D [05/Mar/2014:06:58:09 -0800] cupsdSetBusyState: Active clients
> D [05/Mar/2014:06:58:09 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:58:09 -0800] cupsdReadClient: 12 1.0 Get-Jobs 10
> D [05/Mar/2014:06:58:09 -0800] Get-Jobs
> http://192.168.0.2:631/printers/psc-1200-series
> D [05/Mar/2014:06:58:09 -0800] Returning IPP successful-ok for Get- 
> Jobs
> (http://192.168.0.2:631/printers/psc-1200-series) from 192.168.0.144
> D [05/Mar/2014:06:58:09 -0800] cupsdSetBusyState: Not busy
> D [05/Mar/2014:06:58:10 -0800] cupsdReadClient: 12 POST
> /printers/psc-1200-series HTTP/1.1
> D [05/Mar/2014:06:58:10 -0800] cupsdSetBusyState: Active clients
> D [05/Mar/2014:06:58:10 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:58:10 -0800] cupsdReadClient: 12 1.0
> Get-Printer-Attributes 11
> D [05/Mar/2014:06:58:10 -0800] Get-Printer-Attributes
> http://192.168.0.2:631/printers/psc-1200-series
> D [05/Mar/2014:06:58:10 -0800] Returning IPP successful-ok for
> Get-Printer-Attributes (http://192.168.0.2:631/printers/psc-1200-series 
> )
> from 192.168.0.144
> D [05/Mar/2014:06:58:10 -0800] cupsdSetBusyState: Not busy
> D [05/Mar/2014:06:58:11 -0800] cupsdReadClient: 12 POST
> /printers/psc-1200-series HTTP/1.1
> D [05/Mar/2014:06:58:11 -0800] cupsdSetBusyState: Active clients
> D [05/Mar/2014:06:58:11 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:58:11 -0800] cupsdReadClient: 12 1.0 Print-Job 2
> D [05/Mar/2014:06:58:13 -0800] Print-Job
> http://192.168.0.2:631/printers/psc-1200-series
> D [05/Mar/2014:06:58:13 -0800] [Job ???] Auto-typing file...
> I [05/Mar/2014:06:58:13 -0800] [Job ???] Request file type is
> application/octet-stream.
> D [05/Mar/2014:06:58:13 -0800] add_job: setting context of job to  
> UNKNOWN SL
> D [05/Mar/2014:06:58:13 -0800] cupsdMarkDirty(----J-)
> D [05/Mar/2014:06:58:13 -0800] cupsdSetBusyState: Active clients and  
> dirty
> files
> D [05/Mar/2014:06:58:13 -0800] add_job: requesting-user-name="Mary"
> D [05/Mar/2014:06:58:13 -0800] Adding default job-sheets values
> "none,none"...
> I [05/Mar/2014:06:58:13 -0800] [Job 6] Adding start banner page  
> "none".
> D [05/Mar/2014:06:58:13 -0800] Discarding unused job-created event...
> D [05/Mar/2014:06:58:13 -0800] cupsdMarkDirty(----J-)
> I [05/Mar/2014:06:58:13 -0800] [Job 6] Adding end banner page "none".
> I [05/Mar/2014:06:58:13 -0800] [Job 6] File of type
> application/octet-stream queued by "Mary".
> D [05/Mar/2014:06:58:13 -0800] [Job 6] hold_until=0
> I [05/Mar/2014:06:58:13 -0800] [Job 6] Queued on "psc-1200-series"  
> by "Mary".
> D [05/Mar/2014:06:58:13 -0800] cupsdMarkDirty(----J-)
> D [05/Mar/2014:06:58:13 -0800] cupsdSetBusyState: Active clients,  
> printing
> jobs, and dirty files
> D [05/Mar/2014:06:58:13 -0800] Discarding unused printer-state-changed
> event...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] job-sheets=none,none
> D [05/Mar/2014:06:58:13 -0800] [Job 6] argv[0]="psc-1200-series"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] argv[1]="6"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] argv[2]="Mary"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] argv[3]="PCS_BOYD-1"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] argv[4]="1"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> argv[5]="job-uuid=urn:uuid:d15345ce-1d19-3129-51cb-83cf86c8644c
> job-originating-host-name=192.168.0.144"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] argv[6]="/var/spool/cups/ 
> d00006-001"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[0]="CUPS_CACHEDIR=/var/cache/cups"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[1]="CUPS_DATADIR=/usr/ 
> share/cups"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[5]="CUPS_SERVERBIN=/usr/ 
> lib/cups"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[6]="CUPS_SERVERROOT=/etc/ 
> cups"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[7]="CUPS_STATEDIR=/var/ 
> run/cups"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[8]="HOME=/var/spool/cups/ 
> tmp"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[10]="SERVER_ADMIN=root at localhost.localdomain"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[11]="SOFTWARE=CUPS/1.4.2"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[12]="TMPDIR=/var/spool/ 
> cups/tmp"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[13]="USER=root"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]  
> envp[15]="CUPS_ENCRYPTION=IfRequested"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[16]="IPP_PORT=631"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[17]="CHARSET=utf-8"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[18]="LANG=en_US.UTF-8"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[19]="PPD=/etc/cups/ppd/psc-1200-series.ppd"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[20]="RIP_MAX_CACHE=128m"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[21]="CONTENT_TYPE=application/octet-stream"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[22]="DEVICE_URI=usb://HP/psc%201200%20series?serial=MY36CC941Q5H"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[23]="PRINTER_INFO=Hewlett-Packard psc 1200 series"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]
> envp[24]="PRINTER_LOCATION=localhost.localdomain"
> D [05/Mar/2014:06:58:13 -0800] [Job 6] envp[25]="PRINTER=psc-1200- 
> series"
> D [05/Mar/2014:06:58:13 -0800] [Job 6]  
> envp[26]="CUPS_FILETYPE=document"
> I [05/Mar/2014:06:58:13 -0800] [Job 6] Started backend
> /usr/lib/cups/backend/usb (PID 11531)
> D [05/Mar/2014:06:58:13 -0800] Discarding unused job-state-changed  
> event...
> D [05/Mar/2014:06:58:13 -0800] Returning IPP successful-ok for Print- 
> Job
> (http://192.168.0.2:631/printers/psc-1200-series) from 192.168.0.144
> D [05/Mar/2014:06:58:13 -0800] cupsdSetBusyState: Printing jobs and  
> dirty
> files
> D [05/Mar/2014:06:58:13 -0800] [Job 6] STATE: +connecting-to-device
> D [05/Mar/2014:06:58:13 -0800] Discarding unused printer-state-changed
> event...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Printer using device file
> "/dev/usblp0"...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] STATE: -connecting-to-device
> D [05/Mar/2014:06:58:13 -0800] Discarding unused printer-state-changed
> event...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] PAGE: 1 1
> D [05/Mar/2014:06:58:13 -0800] Discarding unused job-progress event...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] backendRunLoop(print_fd=5,
> device_fd=6, snmp_fd=-1, addr=(nil), use_bc=1, side_cb=0xbaf5d0)
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] STATE: -media-empty-warning
> D [05/Mar/2014:06:58:13 -0800] [Job 6] STATE: -offline-report
> I [05/Mar/2014:06:58:13 -0800] [Job 6] Printer is now online.
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] Discarding unused printer-state-changed
> event...
> D [05/Mar/2014:06:58:13 -0800] Discarding unused job-progress event...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 8192 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Read 4026 bytes of print  
> data...
> D [05/Mar/2014:06:58:13 -0800] [Job 6] Wrote 4026 bytes of print  
> data...
> I [05/Mar/2014:06:58:13 -0800] [Job 6] Sent print file, 233402  
> bytes...
> D [05/Mar/2014:06:58:13 -0800] Discarding unused printer-state-changed
> event...
> D [05/Mar/2014:06:58:13 -0800] Discarding unused job-progress event...
> D [05/Mar/2014:06:58:13 -0800] PID 11531 (/usr/lib/cups/backend/usb)
> exited with no errors.
> D [05/Mar/2014:06:58:13 -0800] Discarding unused job-completed  
> event...
> I [05/Mar/2014:06:58:13 -0800] [Job 6] Job completed.
> D [05/Mar/2014:06:58:13 -0800] cupsdMarkDirty(----J-)
> D [05/Mar/2014:06:58:13 -0800] Discarding unused printer-state-changed
> event...
> D [05/Mar/2014:06:58:14 -0800] [Job 6] Unloading...
> D [05/Mar/2014:06:58:20 -0800] cupsdAcceptClient: skipping  
> getpeercon()
> D [05/Mar/2014:06:58:20 -0800] cupsdAcceptClient: 13 from
> 192.168.0.144:631 (IPv4)
> D [05/Mar/2014:06:58:25 -0800] cupsdReadClient: 13 WAITING Closing  
> on EOF
> D [05/Mar/2014:06:58:25 -0800] cupsdCloseClient: 13
> D [05/Mar/2014:06:58:25 -0800] cupsdSetBusyState: Dirty files
> D [05/Mar/2014:06:58:31 -0800] cupsdAcceptClient: skipping  
> getpeercon()
> D [05/Mar/2014:06:58:31 -0800] cupsdAcceptClient: 13 from
> 192.168.0.144:631 (IPv4)
> D [05/Mar/2014:06:58:36 -0800] cupsdReadClient: 13 WAITING Closing  
> on EOF
> D [05/Mar/2014:06:58:36 -0800] cupsdCloseClient: 13
> I [05/Mar/2014:06:58:44 -0800] Saving job cache file
> "/var/cache/cups/job.cache"...
> D [05/Mar/2014:06:58:44 -0800] cupsdSetBusyState: Not busy
> D [05/Mar/2014:06:58:46 -0800] cupsdAcceptClient: skipping  
> getpeercon()
> D [05/Mar/2014:06:58:46 -0800] cupsdAcceptClient: 13 from
> 192.168.0.144:631 (IPv4)
> D [05/Mar/2014:06:58:46 -0800] cupsdReadClient: 13 GET /jobs/ HTTP/1.1
> D [05/Mar/2014:06:58:46 -0800] cupsdSetBusyState: Active clients
> D [05/Mar/2014:06:58:46 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:58:46 -0800] [CGI] argv[0] =
> "/usr/lib/cups/cgi-bin/jobs.cgi"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[0] =
> "CUPS_CACHEDIR=/var/cache/cups"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/ 
> share/cups"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[2] =
> "CUPS_DOCROOT=/usr/share/cups/www"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[3] =
> "CUPS_FONTPATH=/usr/share/cups/fonts"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[4] =
> "CUPS_REQUESTROOT=/var/spool/cups"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/ 
> lib/cups"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/ 
> cups"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[7] = "CUPS_STATEDIR=/var/ 
> run/cups"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[8] = "HOME=/var/spool/cups/ 
> tmp"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[9] =
> "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[10] =
> "SERVER_ADMIN=root at localhost.localdomain"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.2"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[12] = "TMPDIR=/var/spool/ 
> cups/tmp"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[13] = "USER=root"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[14] =
> "CUPS_SERVER=/var/run/cups/cups.sock"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[15] =  
> "CUPS_ENCRYPTION=IfRequested"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[16] = "IPP_PORT=631"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[17] = "LANG=en_US.UTF8"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[18] = "REDIRECT_STATUS=1"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[19] =  
> "GATEWAY_INTERFACE=CGI/1.1"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[20] =  
> "SERVER_NAME=192.168.0.2"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[21] = "SERVER_PORT=631"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[22] =  
> "REMOTE_ADDR=192.168.0.144"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[23] =  
> "REMOTE_HOST=192.168.0.144"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[24] = "SCRIPT_NAME=/jobs/"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[25] =
> "SCRIPT_FILENAME=/usr/share/cups/www/jobs/"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[26] =  
> "SERVER_PROTOCOL=HTTP/1.1"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[27] =
> "HTTP_COOKIE=org.cups.sid=0fc413a15ad3b5edaac3e6d056a39507"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[28] =
> "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 6.1; WOW64; rv:27.0)
> Gecko/20100101 Firefox/27.0"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[29] =
> "HTTP_REFERER=http://192.168.0.2:631/admin/?ADVANCEDSETTINGS=YES"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[30] = "REQUEST_METHOD=GET"
> D [05/Mar/2014:06:58:46 -0800] [CGI] envp[31] = "QUERY_STRING="
> D [05/Mar/2014:06:58:46 -0800] [CGI] Started
> /usr/lib/cups/cgi-bin/jobs.cgi (PID 11533)
> I [05/Mar/2014:06:58:46 -0800] Started "/usr/lib/cups/cgi-bin/ 
> jobs.cgi"
> (pid=11533)
> D [05/Mar/2014:06:58:46 -0800] cupsdSendCommand: 13 file=17
> D [05/Mar/2014:06:58:46 -0800] [CGI] org.cups.sid cookie is
> "0fc413a15ad3b5edaac3e6d056a39507"
> D [05/Mar/2014:06:58:46 -0800] cupsdAcceptClient: skipping  
> getpeercon()
> D [05/Mar/2014:06:58:46 -0800] cupsdAcceptClient: 18 from localhost  
> (Domain)
> D [05/Mar/2014:06:58:46 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:58:46 -0800] Script header: Content-Type:
> text/html;charset=utf-8
> D [05/Mar/2014:06:58:46 -0800] Script header:
> D [05/Mar/2014:06:58:46 -0800] cupsdReadClient: 18 POST / HTTP/1.1
> D [05/Mar/2014:06:58:46 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:58:46 -0800] cupsdReadClient: 18 1.1 Get-Jobs 1
> D [05/Mar/2014:06:58:46 -0800] Get-Jobs ipp://localhost/
> D [05/Mar/2014:06:58:46 -0800] Returning IPP successful-ok for Get- 
> Jobs
> (ipp://localhost/) from localhost
> D [05/Mar/2014:06:58:46 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:58:46 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:58:46 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:58:46 -0800] cupsdReadClient: 18 WAITING Closing  
> on EOF
> D [05/Mar/2014:06:58:46 -0800] cupsdCloseClient: 18
> D [05/Mar/2014:06:58:46 -0800] PID 11533 (/usr/lib/cups/cgi-bin/ 
> jobs.cgi)
> exited with no errors.
> D [05/Mar/2014:06:58:46 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:58:46 -0800] cupsdSetBusyState: Not busy
> D [05/Mar/2014:06:58:50 -0800] cupsdReadClient: 13 GET
> /jobs?which_jobs=completed HTTP/1.1
> D [05/Mar/2014:06:58:50 -0800] cupsdSetBusyState: Active clients
> D [05/Mar/2014:06:58:50 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:58:50 -0800] [CGI] argv[0] =
> "/usr/lib/cups/cgi-bin/jobs.cgi"
> D [05/Mar/2014:06:58:50 -0800] [CGI] argv[1] = "which_jobs=completed"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[0] =
> "CUPS_CACHEDIR=/var/cache/cups"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/ 
> share/cups"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[2] =
> "CUPS_DOCROOT=/usr/share/cups/www"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[3] =
> "CUPS_FONTPATH=/usr/share/cups/fonts"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[4] =
> "CUPS_REQUESTROOT=/var/spool/cups"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/ 
> lib/cups"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/ 
> cups"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[7] = "CUPS_STATEDIR=/var/ 
> run/cups"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[8] = "HOME=/var/spool/cups/ 
> tmp"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[9] =
> "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[10] =
> "SERVER_ADMIN=root at localhost.localdomain"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.2"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[12] = "TMPDIR=/var/spool/ 
> cups/tmp"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[13] = "USER=root"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[14] =
> "CUPS_SERVER=/var/run/cups/cups.sock"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[15] =  
> "CUPS_ENCRYPTION=IfRequested"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[16] = "IPP_PORT=631"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[17] = "LANG=en_US.UTF8"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[18] = "REDIRECT_STATUS=1"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[19] =  
> "GATEWAY_INTERFACE=CGI/1.1"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[20] =  
> "SERVER_NAME=192.168.0.2"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[21] = "SERVER_PORT=631"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[22] =  
> "REMOTE_ADDR=192.168.0.144"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[23] =  
> "REMOTE_HOST=192.168.0.144"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[24] = "SCRIPT_NAME=/jobs"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[25] =
> "SCRIPT_FILENAME=/usr/share/cups/www/jobs"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[26] =  
> "SERVER_PROTOCOL=HTTP/1.1"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[27] =
> "HTTP_COOKIE=org.cups.sid=0fc413a15ad3b5edaac3e6d056a39507"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[28] =
> "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 6.1; WOW64; rv:27.0)
> Gecko/20100101 Firefox/27.0"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[29] =
> "HTTP_REFERER=http://192.168.0.2:631/jobs/"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[30] = "REQUEST_METHOD=GET"
> D [05/Mar/2014:06:58:50 -0800] [CGI] envp[31] =
> "QUERY_STRING=which_jobs=completed"
> D [05/Mar/2014:06:58:50 -0800] [CGI] Started
> /usr/lib/cups/cgi-bin/jobs.cgi (PID 11534)
> I [05/Mar/2014:06:58:50 -0800] Started "/usr/lib/cups/cgi-bin/ 
> jobs.cgi"
> (pid=11534)
> D [05/Mar/2014:06:58:50 -0800] cupsdSendCommand: 13 file=17
> D [05/Mar/2014:06:58:50 -0800] [CGI] org.cups.sid cookie is
> "0fc413a15ad3b5edaac3e6d056a39507"
> D [05/Mar/2014:06:58:50 -0800] cupsdAcceptClient: skipping  
> getpeercon()
> D [05/Mar/2014:06:58:50 -0800] cupsdAcceptClient: 18 from localhost  
> (Domain)
> D [05/Mar/2014:06:58:50 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:58:50 -0800] Script header: Content-Type:
> text/html;charset=utf-8
> D [05/Mar/2014:06:58:50 -0800] Script header:
> D [05/Mar/2014:06:58:50 -0800] cupsdReadClient: 18 POST / HTTP/1.1
> D [05/Mar/2014:06:58:50 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:58:50 -0800] cupsdReadClient: 18 1.1 Get-Jobs 1
> D [05/Mar/2014:06:58:50 -0800] Get-Jobs ipp://localhost/
> D [05/Mar/2014:06:58:50 -0800] [Job 1] Loading attributes...
> D [05/Mar/2014:06:58:50 -0800] [Job 2] Loading attributes...
> D [05/Mar/2014:06:58:50 -0800] [Job 3] Loading attributes...
> D [05/Mar/2014:06:58:50 -0800] [Job 4] Loading attributes...
> D [05/Mar/2014:06:58:50 -0800] [Job 5] Loading attributes...
> D [05/Mar/2014:06:58:50 -0800] [Job 6] Loading attributes...
> D [05/Mar/2014:06:58:50 -0800] Returning IPP successful-ok for Get- 
> Jobs
> (ipp://localhost/) from localhost
> D [05/Mar/2014:06:58:50 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:58:50 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:58:50 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:58:50 -0800] cupsdReadClient: 18 WAITING Closing  
> on EOF
> D [05/Mar/2014:06:58:50 -0800] cupsdCloseClient: 18
> D [05/Mar/2014:06:58:50 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:58:50 -0800] cupsdSetBusyState: Not busy
> D [05/Mar/2014:06:58:50 -0800] PID 11534 (/usr/lib/cups/cgi-bin/ 
> jobs.cgi)
> exited with no errors.
> D [05/Mar/2014:06:59:08 -0800] cupsdReadClient: 13 GET /admin HTTP/1.1
> D [05/Mar/2014:06:59:08 -0800] cupsdSetBusyState: Active clients
> D [05/Mar/2014:06:59:08 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:59:08 -0800] [CGI] argv[0] =
> "/usr/lib/cups/cgi-bin/admin.cgi"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[0] =
> "CUPS_CACHEDIR=/var/cache/cups"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/ 
> share/cups"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[2] =
> "CUPS_DOCROOT=/usr/share/cups/www"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[3] =
> "CUPS_FONTPATH=/usr/share/cups/fonts"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[4] =
> "CUPS_REQUESTROOT=/var/spool/cups"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/ 
> lib/cups"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/ 
> cups"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[7] = "CUPS_STATEDIR=/var/ 
> run/cups"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[8] = "HOME=/var/spool/cups/ 
> tmp"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[9] =
> "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[10] =
> "SERVER_ADMIN=root at localhost.localdomain"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.2"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[12] = "TMPDIR=/var/spool/ 
> cups/tmp"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[13] = "USER=root"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[14] =
> "CUPS_SERVER=/var/run/cups/cups.sock"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[15] =  
> "CUPS_ENCRYPTION=IfRequested"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[16] = "IPP_PORT=631"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[17] = "LANG=en_US.UTF8"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[18] = "REDIRECT_STATUS=1"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[19] =  
> "GATEWAY_INTERFACE=CGI/1.1"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[20] =  
> "SERVER_NAME=192.168.0.2"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[21] = "SERVER_PORT=631"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[22] =  
> "REMOTE_ADDR=192.168.0.144"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[23] =  
> "REMOTE_HOST=192.168.0.144"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[24] = "SCRIPT_NAME=/admin"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[25] =
> "SCRIPT_FILENAME=/usr/share/cups/www/admin"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[26] =  
> "SERVER_PROTOCOL=HTTP/1.1"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[27] =
> "HTTP_COOKIE=org.cups.sid=0fc413a15ad3b5edaac3e6d056a39507"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[28] =
> "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 6.1; WOW64; rv:27.0)
> Gecko/20100101 Firefox/27.0"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[29] =
> "HTTP_REFERER=http://192.168.0.2:631/jobs?which_jobs=completed"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[30] = "REQUEST_METHOD=GET"
> D [05/Mar/2014:06:59:08 -0800] [CGI] envp[31] = "QUERY_STRING="
> D [05/Mar/2014:06:59:08 -0800] [CGI] Started
> /usr/lib/cups/cgi-bin/admin.cgi (PID 11536)
> I [05/Mar/2014:06:59:08 -0800] Started "/usr/lib/cups/cgi-bin/ 
> admin.cgi"
> (pid=11536)
> D [05/Mar/2014:06:59:08 -0800] cupsdSendCommand: 13 file=17
> D [05/Mar/2014:06:59:08 -0800] Report: clients=2
> D [05/Mar/2014:06:59:08 -0800] Report: jobs=6
> D [05/Mar/2014:06:59:08 -0800] Report: jobs-active=0
> D [05/Mar/2014:06:59:08 -0800] Report: printers=1
> D [05/Mar/2014:06:59:08 -0800] Report: printers-implicit=0
> D [05/Mar/2014:06:59:08 -0800] Report: stringpool-string-count=751
> D [05/Mar/2014:06:59:08 -0800] Report: stringpool-alloc-bytes=8048
> D [05/Mar/2014:06:59:08 -0800] Report: stringpool-total-bytes=15752
> D [05/Mar/2014:06:59:08 -0800] [CGI] admin.cgi started...
> D [05/Mar/2014:06:59:08 -0800] cupsdAcceptClient: skipping  
> getpeercon()
> D [05/Mar/2014:06:59:08 -0800] cupsdAcceptClient: 18 from localhost  
> (Domain)
> D [05/Mar/2014:06:59:08 -0800] [CGI] http=0x220b5d8
> D [05/Mar/2014:06:59:08 -0800] [CGI] org.cups.sid cookie is
> "0fc413a15ad3b5edaac3e6d056a39507"
> D [05/Mar/2014:06:59:08 -0800] [CGI] No form data, showing main  
> menu...
> D [05/Mar/2014:06:59:08 -0800] [CGI] /usr/share/cups/drivers/ 
> pscript5.dll:
> No such file or directory
> D [05/Mar/2014:06:59:08 -0800] cupsdReadClient: 18 POST / HTTP/1.1
> D [05/Mar/2014:06:59:08 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:59:08 -0800] cupsdReadClient: 18 1.1 Get- 
> Subscriptions 1
> D [05/Mar/2014:06:59:08 -0800] Get-Subscriptions ipp://localhost/
> D [05/Mar/2014:06:59:08 -0800] Get-Subscriptions client-error-not- 
> found:
> No subscriptions found.
> D [05/Mar/2014:06:59:08 -0800] Returning IPP client-error-not-found  
> for
> Get-Subscriptions (ipp://localhost/) from localhost
> D [05/Mar/2014:06:59:08 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:59:08 -0800] Script header: Content-Type:
> text/html;charset=utf-8
> D [05/Mar/2014:06:59:08 -0800] Script header:
> D [05/Mar/2014:06:59:08 -0800] cupsdReadClient: 18 WAITING Closing  
> on EOF
> D [05/Mar/2014:06:59:08 -0800] cupsdCloseClient: 18
> D [05/Mar/2014:06:59:08 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:59:08 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:59:08 -0800] cupsdSetBusyState: Not busy
> D [05/Mar/2014:06:59:08 -0800] PID 11536 (/usr/lib/cups/cgi-bin/ 
> admin.cgi)
> exited with no errors.
> D [05/Mar/2014:06:59:13 -0800] cupsdReadClient: 12 WAITING Closing  
> on EOF
> D [05/Mar/2014:06:59:13 -0800] cupsdCloseClient: 12
> D [05/Mar/2014:06:59:16 -0800] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Mar/2014:06:59:16 -0800] cupsdNetIFUpdate: "eth0" =  
> 192.168.0.2:631
> D [05/Mar/2014:06:59:16 -0800] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Mar/2014:06:59:16 -0800] cupsdNetIFUpdate: "eth0" =
> fe80::20c:76ff:fea4:b4f3%eth0:631
> D [05/Mar/2014:06:59:38 -0800] cupsdReadClient: 13 WAITING Closing  
> on EOF
> D [05/Mar/2014:06:59:38 -0800] cupsdCloseClient: 13
> D [05/Mar/2014:06:59:59 -0800] cupsdAcceptClient: skipping  
> getpeercon()
> D [05/Mar/2014:06:59:59 -0800] cupsdAcceptClient: 12 from
> 192.168.0.144:631 (IPv4)
> D [05/Mar/2014:06:59:59 -0800] [Job 1] Unloading...
> D [05/Mar/2014:06:59:59 -0800] [Job 2] Unloading...
> D [05/Mar/2014:06:59:59 -0800] [Job 3] Unloading...
> D [05/Mar/2014:06:59:59 -0800] [Job 4] Unloading...
> D [05/Mar/2014:06:59:59 -0800] [Job 5] Unloading...
> D [05/Mar/2014:06:59:59 -0800] [Job 6] Unloading...
> D [05/Mar/2014:06:59:59 -0800] cupsdReadClient: 12 GET /printers/  
> HTTP/1.1
> D [05/Mar/2014:06:59:59 -0800] cupsdSetBusyState: Active clients
> D [05/Mar/2014:06:59:59 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:59:59 -0800] [CGI] argv[0] =
> "/usr/lib/cups/cgi-bin/printers.cgi"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[0] =
> "CUPS_CACHEDIR=/var/cache/cups"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/ 
> share/cups"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[2] =
> "CUPS_DOCROOT=/usr/share/cups/www"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[3] =
> "CUPS_FONTPATH=/usr/share/cups/fonts"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[4] =
> "CUPS_REQUESTROOT=/var/spool/cups"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/ 
> lib/cups"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/ 
> cups"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[7] = "CUPS_STATEDIR=/var/ 
> run/cups"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[8] = "HOME=/var/spool/cups/ 
> tmp"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[9] =
> "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[10] =
> "SERVER_ADMIN=root at localhost.localdomain"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.2"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[12] = "TMPDIR=/var/spool/ 
> cups/tmp"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[13] = "USER=root"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[14] =
> "CUPS_SERVER=/var/run/cups/cups.sock"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[15] =  
> "CUPS_ENCRYPTION=IfRequested"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[16] = "IPP_PORT=631"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[17] = "LANG=en_US.UTF8"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[18] = "REDIRECT_STATUS=1"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[19] =  
> "GATEWAY_INTERFACE=CGI/1.1"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[20] =  
> "SERVER_NAME=192.168.0.2"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[21] = "SERVER_PORT=631"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[22] =  
> "REMOTE_ADDR=192.168.0.144"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[23] =  
> "REMOTE_HOST=192.168.0.144"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[24] = "SCRIPT_NAME=/ 
> printers/"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[25] =
> "SCRIPT_FILENAME=/usr/share/cups/www/printers/"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[26] =  
> "SERVER_PROTOCOL=HTTP/1.1"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[27] =
> "HTTP_COOKIE=org.cups.sid=0fc413a15ad3b5edaac3e6d056a39507"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[28] =
> "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 6.1; WOW64; rv:27.0)
> Gecko/20100101 Firefox/27.0"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[29] =
> "HTTP_REFERER=http://192.168.0.2:631/admin"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[30] = "REQUEST_METHOD=GET"
> D [05/Mar/2014:06:59:59 -0800] [CGI] envp[31] = "QUERY_STRING="
> D [05/Mar/2014:06:59:59 -0800] [CGI] Started
> /usr/lib/cups/cgi-bin/printers.cgi (PID 11541)
> I [05/Mar/2014:06:59:59 -0800] Started
> "/usr/lib/cups/cgi-bin/printers.cgi" (pid=11541)
> D [05/Mar/2014:06:59:59 -0800] cupsdSendCommand: 12 file=13
> D [05/Mar/2014:06:59:59 -0800] [CGI] org.cups.sid cookie is
> "0fc413a15ad3b5edaac3e6d056a39507"
> D [05/Mar/2014:06:59:59 -0800] cupsdAcceptClient: skipping  
> getpeercon()
> D [05/Mar/2014:06:59:59 -0800] cupsdAcceptClient: 17 from localhost  
> (Domain)
> D [05/Mar/2014:06:59:59 -0800] cupsdReadClient: 17 POST / HTTP/1.1
> D [05/Mar/2014:06:59:59 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:59:59 -0800] cupsdReadClient: 17 1.1 CUPS-Get- 
> Default 1
> D [05/Mar/2014:06:59:59 -0800] CUPS-Get-Default
> D [05/Mar/2014:06:59:59 -0800] Returning IPP successful-ok for
> CUPS-Get-Default (no URI) from localhost
> D [05/Mar/2014:06:59:59 -0800] [CGI] show_all_printers(http=0x13030d8,
> user="(null)")
> D [05/Mar/2014:06:59:59 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:59:59 -0800] Script header: Content-Type:
> text/html;charset=utf-8
> D [05/Mar/2014:06:59:59 -0800] Script header:
> D [05/Mar/2014:06:59:59 -0800] cupsdReadClient: 17 POST / HTTP/1.1
> D [05/Mar/2014:06:59:59 -0800] cupsdAuthorize: No authentication data
> provided.
> D [05/Mar/2014:06:59:59 -0800] cupsdReadClient: 17 1.1 CUPS-Get- 
> Printers 1
> D [05/Mar/2014:06:59:59 -0800] CUPS-Get-Printers
> D [05/Mar/2014:06:59:59 -0800] Returning IPP successful-ok for
> CUPS-Get-Printers (no URI) from localhost
> D [05/Mar/2014:06:59:59 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:59:59 -0800] cupsdReadClient: 17 WAITING Closing  
> on EOF
> D [05/Mar/2014:06:59:59 -0800] cupsdCloseClient: 17
> D [05/Mar/2014:06:59:59 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:59:59 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:59:59 -0800] [CGI] lang="en_US.UTF8", locale="/ 
> en_US"...
> D [05/Mar/2014:06:59:59 -0800] cupsdSetBusyState: Not busy
> D [05/Mar/2014:06:59:59 -0800] PID 11541
> (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
> _______________________________________________
> cups-devel mailing list
> cups-devel at cups.org
> https://www.cups.org/mailman/listinfo/cups-devel




More information about the cups-devel mailing list