Print job "canceling" itself

Jen Roidt jenr at cooperfarms.com
Wed May 16 12:01:47 PDT 2012


> We had a user try to print today and nothing printed and nothing showed in the logs.  Prior to this print request (less than 5 min) she had printed another report with out any issues.  In the cups/error_log it said aborting job because it has no files?  What does this mean?  We run this same program daily and it works fine (job number is 12259)  We don't fully understand how read this error_log so any help would be appreciated.
>
> We've had printing issues ever since our conversion to RHEL and using cups about 3 1/2 weeks ago.
>
>
>
> I [16/May/2012:12:55:47 -0400] [Job 12259] Adding start banner page "none".
> D [16/May/2012:12:55:47 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:12:55:47 -0400] [Job 12259] Queued on "nm098pr" by "maryjean".
> D [16/May/2012:12:55:47 -0400] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/nm098pr) from localhost
> D [16/May/2012:12:55:47 -0400] cupsdSetBusyState: Dirty files
> D [16/May/2012:12:55:47 -0400] cupsdReadClient: 16 POST /printers/nm098pr HTTP/1.1
> D [16/May/2012:12:55:47 -0400] cupsdSetBusyState: Active clients and dirty files
> D [16/May/2012:12:55:47 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:55:47 -0400] cupsdReadClient: 16 1.1 Send-Document 1
> D [16/May/2012:12:56:01 -0400] Report: clients=1
> D [16/May/2012:12:56:01 -0400] Report: jobs=499
> D [16/May/2012:12:56:01 -0400] Report: jobs-active=2
> D [16/May/2012:12:56:01 -0400] Report: printers=286
> D [16/May/2012:12:56:01 -0400] Report: printers-implicit=0
> D [16/May/2012:12:56:01 -0400] Report: stringpool-string-count=318031
> D [16/May/2012:12:56:01 -0400] Report: stringpool-alloc-bytes=75920
> D [16/May/2012:12:56:01 -0400] Report: stringpool-total-bytes=4255184
> I [16/May/2012:12:56:17 -0400] Saving job cache file "/var/cache/cups/job.cache"...
> I [16/May/2012:12:56:17 -0400] Saving subscriptions.conf...
> D [16/May/2012:12:56:17 -0400] cupsdSetBusyState: Active clients
> D [16/May/2012:12:56:47 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:12:56:47 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:12:56:47 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:12:56:47 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:56:47 -0400] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
> D [16/May/2012:12:56:47 -0400] Get-Printer-Attributes ipp://localhost:631/printers/sh105pr
> D [16/May/2012:12:56:47 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/sh105pr) from localhost
> D [16/May/2012:12:56:47 -0400] cupsdReadClient: 19 POST /printers/sh105pr HTTP/1.1
> D [16/May/2012:12:56:47 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:56:47 -0400] cupsdReadClient: 19 1.1 Create-Job 1
> D [16/May/2012:12:56:47 -0400] Create-Job ipp://localhost:631/printers/sh105pr
> D [16/May/2012:12:56:47 -0400] add_job: setting context of job to UNKNOWN SL
> D [16/May/2012:12:56:47 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:56:47 -0400] cupsdSetBusyState: Active clients and dirty files
> D [16/May/2012:12:56:47 -0400] add_job: requesting-user-name="marthae"
> I [16/May/2012:12:56:47 -0400] [Job 12260] Adding start banner page "none".
> D [16/May/2012:12:56:47 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:12:56:47 -0400] [Job 12260] Queued on "sh105pr" by "marthae".
> D [16/May/2012:12:56:47 -0400] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/sh105pr) from localhost
> D [16/May/2012:12:56:47 -0400] cupsdReadClient: 19 POST /printers/sh105pr HTTP/1.1
> D [16/May/2012:12:56:47 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:56:47 -0400] cupsdReadClient: 19 1.1 Send-Document 1
> D [16/May/2012:12:56:47 -0400] Send-Document ipp://localhost:631/printers/sh105pr
> D [16/May/2012:12:56:47 -0400] cupsdIsAuthorized: requesting-user-name="marthae"
> D [16/May/2012:12:56:47 -0400] cupsdMarkDirty(----J-)
> I [16/May/2012:12:56:47 -0400] [Job 12260] File of type application/vnd.cups-raw queued by "marthae".
> I [16/May/2012:12:56:47 -0400] [Job 12260] Adding end banner page "none".
> D [16/May/2012:12:56:47 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:56:47 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:56:47 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:12:56:47 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:47 -0400] [Job 12260] job-sheets=none,none
> D [16/May/2012:12:56:47 -0400] [Job 12260] argv[0]="sh105pr"
> D [16/May/2012:12:56:47 -0400] [Job 12260] argv[1]="12260"
> D [16/May/2012:12:56:47 -0400] [Job 12260] argv[2]="marthae"
> D [16/May/2012:12:56:47 -0400] [Job 12260] argv[3]="(stdin)"
> D [16/May/2012:12:56:47 -0400] [Job 12260] argv[4]="1"
> D [16/May/2012:12:56:47 -0400] [Job 12260] argv[5]="finishings=3 media=na_letter_8.5x11in number-up=1 job-uuid=urn:uuid:1e1a2f43-9797-3e23-56b8-2af37e1aa13d job-originating-host-name=localhost"
> D [16/May/2012:12:56:47 -0400] [Job 12260] argv[6]="/var/spool/cups/d12260-001"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[1]="CUPS_DATADIR=/usr/share/cups"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[6]="CUPS_SERVERROOT=/etc/cups"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[7]="CUPS_STATEDIR=/var/run/cups"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[8]="HOME=/var/spool/cups/tmp"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[10]="SERVER_ADMIN=root at cas.corp.cooperfarms.local"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[11]="SOFTWARE=CUPS/1.4.2"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[12]="TMPDIR=/var/spool/cups/tmp"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[13]="USER=root"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[15]="CUPS_ENCRYPTION=IfRequested"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[16]="IPP_PORT=631"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[17]="CHARSET=utf-8"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[18]="LANG=en_US.UTF-8"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[19]="PPD=/etc/cups/ppd/sh105pr.ppd"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[20]="RIP_MAX_CACHE=128m"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[21]="CONTENT_TYPE=application/vnd.cups-raw"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[22]="DEVICE_URI=lpd://10.0.4.105/PASSTHRU"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[23]="PRINTER_INFO=HP LaserJet p4014"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[24]="PRINTER_LOCATION=SH Shipping Laserjet"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[25]="PRINTER=sh105pr"
> D [16/May/2012:12:56:47 -0400] [Job 12260] envp[26]="CUPS_FILETYPE=document"
> I [16/May/2012:12:56:47 -0400] [Job 12260] Started backend /usr/lib/cups/backend/lpd (PID 31659)
> D [16/May/2012:12:56:47 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:47 -0400] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/sh105pr) from localhost
> D [16/May/2012:12:56:47 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:12:56:47 -0400] cupsdCloseClient: 19
> D [16/May/2012:12:56:47 -0400] [Job 12260] STATE: +connecting-to-device
> D [16/May/2012:12:56:47 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] [Job 12260] Looking up "10.0.4.105"...
> D [16/May/2012:12:56:48 -0400] [Job 12260] Connecting to 10.0.4.105:515 for printer PASSTHRU
> I [16/May/2012:12:56:48 -0400] [Job 12260] Connecting to printer...
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -connecting-to-device
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:12:56:48 -0400] [Job 12260] Connected to printer...
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:48 -0400] [Job 12260] Connected to 10.0.4.105:515 (IPv4) (local port 1023)...
> D [16/May/2012:12:56:48 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:12:56:48 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:12:56:48 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:12:56:48 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:56:48 -0400] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
> D [16/May/2012:12:56:48 -0400] Get-Printer-Attributes ipp://localhost:631/printers/sh105pr
> D [16/May/2012:12:56:48 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/sh105pr) from localhost
> D [16/May/2012:12:56:48 -0400] cupsdReadClient: 19 POST /printers/sh105pr HTTP/1.1
> D [16/May/2012:12:56:48 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:56:48 -0400] cupsdReadClient: 19 1.1 Create-Job 1
> D [16/May/2012:12:56:48 -0400] Create-Job ipp://localhost:631/printers/sh105pr
> D [16/May/2012:12:56:48 -0400] add_job: setting context of job to UNKNOWN SL
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:56:48 -0400] add_job: requesting-user-name="marthae"
> I [16/May/2012:12:56:48 -0400] [Job 12261] Adding start banner page "none".
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:12:56:48 -0400] [Job 12261] Queued on "sh105pr" by "marthae".
> D [16/May/2012:12:56:48 -0400] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/sh105pr) from localhost
> D [16/May/2012:12:56:48 -0400] cupsdReadClient: 19 POST /printers/sh105pr HTTP/1.1
> D [16/May/2012:12:56:48 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:56:48 -0400] cupsdReadClient: 19 1.1 Send-Document 1
> D [16/May/2012:12:56:48 -0400] Send-Document ipp://localhost:631/printers/sh105pr
> D [16/May/2012:12:56:48 -0400] cupsdIsAuthorized: requesting-user-name="marthae"
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(----J-)
> I [16/May/2012:12:56:48 -0400] [Job 12261] File of type application/vnd.cups-raw queued by "marthae".
> I [16/May/2012:12:56:48 -0400] [Job 12261] Adding end banner page "none".
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:56:48 -0400] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/sh105pr) from localhost
> D [16/May/2012:12:56:48 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:12:56:48 -0400] cupsdCloseClient: 19
> D [16/May/2012:12:56:48 -0400] [Job 12260] ATTR: marker-colors=#000000,none
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:56:48 -0400] [Job 12260] ATTR: marker-names="Black Cartridge HP CC364A","Maintenance Kit HP 110V-CB388A, 220V-CB389A"
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:56:48 -0400] [Job 12260] ATTR: marker-types=tonerCartridge,fuser
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:56:48 -0400] [Job 12260] ATTR: marker-levels=21,44
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -media-low-report
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -media-empty-warning
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -toner-low-report
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -toner-empty-warning
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -door-open-report
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -media-jam-warning
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -input-tray-missing-warning
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -output-tray-missing-warning
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -marker-supply-missing-warning
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -output-area-almost-full-report
> D [16/May/2012:12:56:48 -0400] [Job 12260] STATE: -output-area-full-warning
> D [16/May/2012:12:56:48 -0400] [Job 12260] lpd_command 02 PASSTHRU
> D [16/May/2012:12:56:48 -0400] [Job 12260] Sending command string (10 bytes)...
> D [16/May/2012:12:56:48 -0400] [Job 12260] Reading command status...
> D [16/May/2012:12:56:48 -0400] [Job 12260] lpd_command returning 0
> D [16/May/2012:12:56:48 -0400] [Job 12260] Control file is:
> D [16/May/2012:12:56:48 -0400] [Job 12260] Hcas.corp.cooperfarms.local
> D [16/May/2012:12:56:48 -0400] [Job 12260] Pmarthae
> D [16/May/2012:12:56:48 -0400] [Job 12260] J_stdin_
> D [16/May/2012:12:56:48 -0400] [Job 12260] ldfA659cas.corp.cooper
> D [16/May/2012:12:56:48 -0400] [Job 12260] UdfA659cas.corp.cooper
> D [16/May/2012:12:56:48 -0400] [Job 12260] N_stdin_
> D [16/May/2012:12:56:48 -0400] [Job 12260] lpd_command 02 101 cfA659cas.corp.cooper
> D [16/May/2012:12:56:48 -0400] [Job 12260] Sending command string (27 bytes)...
> D [16/May/2012:12:56:48 -0400] [Job 12260] Reading command status...
> D [16/May/2012:12:56:48 -0400] [Job 12260] lpd_command returning 0
> I [16/May/2012:12:56:48 -0400] [Job 12260] Sending control file (101 bytes)
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] Discarding unused job-progress event...
> I [16/May/2012:12:56:48 -0400] [Job 12260] Control file sent successfully
> D [16/May/2012:12:56:48 -0400] [Job 12260] lpd_command 03 2770 dfA659cas.corp.cooper
> D [16/May/2012:12:56:48 -0400] [Job 12260] Sending command string (28 bytes)...
> D [16/May/2012:12:56:48 -0400] [Job 12260] Reading command status...
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:48 -0400] [Job 12260] lpd_command returning 0
> I [16/May/2012:12:56:48 -0400] [Job 12260] Sending data file (2770 bytes)
> I [16/May/2012:12:56:48 -0400] [Job 12260] Spooling LPR job, 0% complete...
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] Discarding unused job-progress event...
> I [16/May/2012:12:56:48 -0400] [Job 12260] Data file sent successfully
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:48 -0400] [Job 12260] ATTR: marker-levels=20,38
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] [Job 12260] PAGE: 1 1
> D [16/May/2012:12:56:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:48 -0400] PID 31659 (/usr/lib/cups/backend/lpd) exited with no errors.
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:12:56:48 -0400] [Job 12260] Job completed.
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] [Job 12261] job-sheets=none,none
> D [16/May/2012:12:56:48 -0400] [Job 12261] argv[0]="sh105pr"
> D [16/May/2012:12:56:48 -0400] [Job 12261] argv[1]="12261"
> D [16/May/2012:12:56:48 -0400] [Job 12261] argv[2]="marthae"
> D [16/May/2012:12:56:48 -0400] [Job 12261] argv[3]="(stdin)"
> D [16/May/2012:12:56:48 -0400] [Job 12261] argv[4]="1"
> D [16/May/2012:12:56:48 -0400] [Job 12261] argv[5]="finishings=3 media=na_letter_8.5x11in number-up=1 job-uuid=urn:uuid:c35fd066-7be8-358b-6f10-6b8499fbd59c job-originating-host-name=localhost"
> D [16/May/2012:12:56:48 -0400] [Job 12261] argv[6]="/var/spool/cups/d12261-001"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[1]="CUPS_DATADIR=/usr/share/cups"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[6]="CUPS_SERVERROOT=/etc/cups"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[7]="CUPS_STATEDIR=/var/run/cups"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[8]="HOME=/var/spool/cups/tmp"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[10]="SERVER_ADMIN=root at cas.corp.cooperfarms.local"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[11]="SOFTWARE=CUPS/1.4.2"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[12]="TMPDIR=/var/spool/cups/tmp"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[13]="USER=root"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[15]="CUPS_ENCRYPTION=IfRequested"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[16]="IPP_PORT=631"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[17]="CHARSET=utf-8"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[18]="LANG=en_US.UTF-8"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[19]="PPD=/etc/cups/ppd/sh105pr.ppd"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[20]="RIP_MAX_CACHE=128m"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[21]="CONTENT_TYPE=application/vnd.cups-raw"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[22]="DEVICE_URI=lpd://10.0.4.105/PASSTHRU"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[23]="PRINTER_INFO=HP LaserJet p4014"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[24]="PRINTER_LOCATION=SH Shipping Laserjet"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[25]="PRINTER=sh105pr"
> D [16/May/2012:12:56:48 -0400] [Job 12261] envp[26]="CUPS_FILETYPE=document"
> I [16/May/2012:12:56:48 -0400] [Job 12261] Started backend /usr/lib/cups/backend/lpd (PID 31661)
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: +connecting-to-device
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] [Job 12261] Looking up "10.0.4.105"...
> D [16/May/2012:12:56:48 -0400] [Job 12261] Connecting to 10.0.4.105:515 for printer PASSTHRU
> I [16/May/2012:12:56:48 -0400] [Job 12261] Connecting to printer...
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -connecting-to-device
> I [16/May/2012:12:56:48 -0400] [Job 12261] Connected to printer...
> D [16/May/2012:12:56:48 -0400] [Job 12261] Connected to 10.0.4.105:515 (IPv4) (local port 1022)...
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:48 -0400] [Job 12261] ATTR: marker-colors=#000000,none
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:56:48 -0400] [Job 12261] ATTR: marker-names="Black Cartridge HP CC364A","Maintenance Kit HP 110V-CB388A, 220V-CB389A"
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:56:48 -0400] [Job 12261] ATTR: marker-types=tonerCartridge,fuser
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:56:48 -0400] [Job 12261] ATTR: marker-levels=21,44
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:56:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -media-low-report
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -media-empty-warning
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -toner-low-report
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -toner-empty-warning
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -door-open-report
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -media-jam-warning
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -input-tray-missing-warning
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -output-tray-missing-warning
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -marker-supply-missing-warning
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -output-area-almost-full-report
> D [16/May/2012:12:56:48 -0400] [Job 12261] STATE: -output-area-full-warning
> D [16/May/2012:12:56:48 -0400] [Job 12261] lpd_command 02 PASSTHRU
> D [16/May/2012:12:56:48 -0400] [Job 12261] Sending command string (10 bytes)...
> D [16/May/2012:12:56:48 -0400] [Job 12261] Reading command status...
> D [16/May/2012:12:56:48 -0400] [Job 12261] lpd_command returning 0
> D [16/May/2012:12:56:48 -0400] [Job 12261] Control file is:
> D [16/May/2012:12:56:48 -0400] [Job 12261] Hcas.corp.cooperfarms.local
> D [16/May/2012:12:56:48 -0400] [Job 12261] Pmarthae
> D [16/May/2012:12:56:48 -0400] [Job 12261] J_stdin_
> D [16/May/2012:12:56:48 -0400] [Job 12261] ldfA661cas.corp.cooper
> D [16/May/2012:12:56:48 -0400] [Job 12261] UdfA661cas.corp.cooper
> D [16/May/2012:12:56:48 -0400] [Job 12261] N_stdin_
> D [16/May/2012:12:56:48 -0400] [Job 12261] lpd_command 02 101 cfA661cas.corp.cooper
> D [16/May/2012:12:56:48 -0400] [Job 12261] Sending command string (27 bytes)...
> D [16/May/2012:12:56:48 -0400] [Job 12261] Reading command status...
> D [16/May/2012:12:56:49 -0400] [Job 12261] lpd_command returning 0
> I [16/May/2012:12:56:49 -0400] [Job 12261] Sending control file (101 bytes)
> D [16/May/2012:12:56:49 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:49 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:49 -0400] [Job 12260] Unloading...
> I [16/May/2012:12:56:49 -0400] [Job 12261] Control file sent successfully
> D [16/May/2012:12:56:49 -0400] [Job 12261] lpd_command 03 352 dfA661cas.corp.cooper
> D [16/May/2012:12:56:49 -0400] [Job 12261] Sending command string (27 bytes)...
> D [16/May/2012:12:56:49 -0400] [Job 12261] Reading command status...
> D [16/May/2012:12:56:49 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:49 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:49 -0400] [Job 12261] lpd_command returning 0
> I [16/May/2012:12:56:49 -0400] [Job 12261] Sending data file (352 bytes)
> I [16/May/2012:12:56:49 -0400] [Job 12261] Spooling LPR job, 0% complete...
> D [16/May/2012:12:56:49 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:49 -0400] Discarding unused job-progress event...
> I [16/May/2012:12:56:49 -0400] [Job 12261] Data file sent successfully
> D [16/May/2012:12:56:49 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:49 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:49 -0400] [Job 12261] ATTR: marker-levels=20,38
> D [16/May/2012:12:56:49 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:56:49 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:49 -0400] [Job 12261] PAGE: 1 1
> D [16/May/2012:12:56:49 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:56:49 -0400] PID 31661 (/usr/lib/cups/backend/lpd) exited with no errors.
> D [16/May/2012:12:56:49 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:12:56:49 -0400] [Job 12261] Job completed.
> D [16/May/2012:12:56:49 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:56:49 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:56:50 -0400] [Job 12261] Unloading...
> D [16/May/2012:12:57:01 -0400] Report: clients=1
> D [16/May/2012:12:57:01 -0400] Report: jobs=499
> D [16/May/2012:12:57:01 -0400] Report: jobs-active=2
> D [16/May/2012:12:57:01 -0400] Report: printers=286
> D [16/May/2012:12:57:01 -0400] Report: printers-implicit=0
> D [16/May/2012:12:57:01 -0400] Report: stringpool-string-count=317971
> D [16/May/2012:12:57:01 -0400] Report: stringpool-alloc-bytes=75992
> D [16/May/2012:12:57:01 -0400] Report: stringpool-total-bytes=4254272
> D [16/May/2012:12:57:09 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:12:57:09 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:12:57:09 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:12:57:09 -0400] cupsdSetBusyState: Active clients and dirty files
> D [16/May/2012:12:57:09 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:57:09 -0400] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
> D [16/May/2012:12:57:09 -0400] Get-Printer-Attributes ipp://localhost:631/printers/sh105pr
> D [16/May/2012:12:57:09 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/sh105pr) from localhost
> D [16/May/2012:12:57:09 -0400] cupsdReadClient: 19 POST /printers/sh105pr HTTP/1.1
> D [16/May/2012:12:57:09 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:57:09 -0400] cupsdReadClient: 19 1.1 Create-Job 1
> D [16/May/2012:12:57:09 -0400] Create-Job ipp://localhost:631/printers/sh105pr
> D [16/May/2012:12:57:09 -0400] add_job: setting context of job to UNKNOWN SL
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:57:09 -0400] add_job: requesting-user-name="marthae"
> I [16/May/2012:12:57:09 -0400] [Job 12262] Adding start banner page "none".
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:12:57:09 -0400] [Job 12262] Queued on "sh105pr" by "marthae".
> D [16/May/2012:12:57:09 -0400] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/sh105pr) from localhost
> D [16/May/2012:12:57:09 -0400] cupsdReadClient: 19 POST /printers/sh105pr HTTP/1.1
> D [16/May/2012:12:57:09 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:57:09 -0400] cupsdReadClient: 19 1.1 Send-Document 1
> D [16/May/2012:12:57:09 -0400] Send-Document ipp://localhost:631/printers/sh105pr
> D [16/May/2012:12:57:09 -0400] cupsdIsAuthorized: requesting-user-name="marthae"
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(----J-)
> I [16/May/2012:12:57:09 -0400] [Job 12262] File of type application/vnd.cups-raw queued by "marthae".
> I [16/May/2012:12:57:09 -0400] [Job 12262] Adding end banner page "none".
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:57:09 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] [Job 12262] job-sheets=none,none
> D [16/May/2012:12:57:09 -0400] [Job 12262] argv[0]="sh105pr"
> D [16/May/2012:12:57:09 -0400] [Job 12262] argv[1]="12262"
> D [16/May/2012:12:57:09 -0400] [Job 12262] argv[2]="marthae"
> D [16/May/2012:12:57:09 -0400] [Job 12262] argv[3]="(stdin)"
> D [16/May/2012:12:57:09 -0400] [Job 12262] argv[4]="1"
> D [16/May/2012:12:57:09 -0400] [Job 12262] argv[5]="finishings=3 media=na_letter_8.5x11in number-up=1 job-uuid=urn:uuid:93ce3a39-dd13-323c-5993-ad484b0ef2fa job-originating-host-name=localhost"
> D [16/May/2012:12:57:09 -0400] [Job 12262] argv[6]="/var/spool/cups/d12262-001"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[1]="CUPS_DATADIR=/usr/share/cups"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[6]="CUPS_SERVERROOT=/etc/cups"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[7]="CUPS_STATEDIR=/var/run/cups"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[8]="HOME=/var/spool/cups/tmp"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[10]="SERVER_ADMIN=root at cas.corp.cooperfarms.local"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[11]="SOFTWARE=CUPS/1.4.2"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[12]="TMPDIR=/var/spool/cups/tmp"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[13]="USER=root"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[15]="CUPS_ENCRYPTION=IfRequested"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[16]="IPP_PORT=631"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[17]="CHARSET=utf-8"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[18]="LANG=en_US.UTF-8"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[19]="PPD=/etc/cups/ppd/sh105pr.ppd"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[20]="RIP_MAX_CACHE=128m"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[21]="CONTENT_TYPE=application/vnd.cups-raw"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[22]="DEVICE_URI=lpd://10.0.4.105/PASSTHRU"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[23]="PRINTER_INFO=HP LaserJet p4014"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[24]="PRINTER_LOCATION=SH Shipping Laserjet"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[25]="PRINTER=sh105pr"
> D [16/May/2012:12:57:09 -0400] [Job 12262] envp[26]="CUPS_FILETYPE=document"
> I [16/May/2012:12:57:09 -0400] [Job 12262] Started backend /usr/lib/cups/backend/lpd (PID 31664)
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/sh105pr) from localhost
> D [16/May/2012:12:57:09 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:12:57:09 -0400] cupsdCloseClient: 19
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: +connecting-to-device
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] [Job 12262] Looking up "10.0.4.105"...
> D [16/May/2012:12:57:09 -0400] [Job 12262] Connecting to 10.0.4.105:515 for printer PASSTHRU
> I [16/May/2012:12:57:09 -0400] [Job 12262] Connecting to printer...
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -connecting-to-device
> I [16/May/2012:12:57:09 -0400] [Job 12262] Connected to printer...
> D [16/May/2012:12:57:09 -0400] [Job 12262] Connected to 10.0.4.105:515 (IPv4) (local port 1021)...
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:57:09 -0400] [Job 12262] ATTR: marker-colors=#000000,none
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:57:09 -0400] [Job 12262] ATTR: marker-names="Black Cartridge HP CC364A","Maintenance Kit HP 110V-CB388A, 220V-CB389A"
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:57:09 -0400] [Job 12262] ATTR: marker-types=tonerCartridge,fuser
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:57:09 -0400] [Job 12262] ATTR: marker-levels=21,44
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -media-low-report
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -media-empty-warning
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -toner-low-report
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -toner-empty-warning
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -door-open-report
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -media-jam-warning
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -input-tray-missing-warning
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -output-tray-missing-warning
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -marker-supply-missing-warning
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -output-area-almost-full-report
> D [16/May/2012:12:57:09 -0400] [Job 12262] STATE: -output-area-full-warning
> D [16/May/2012:12:57:09 -0400] [Job 12262] lpd_command 02 PASSTHRU
> D [16/May/2012:12:57:09 -0400] [Job 12262] Sending command string (10 bytes)...
> D [16/May/2012:12:57:09 -0400] [Job 12262] Reading command status...
> D [16/May/2012:12:57:09 -0400] [Job 12262] lpd_command returning 0
> D [16/May/2012:12:57:09 -0400] [Job 12262] Control file is:
> D [16/May/2012:12:57:09 -0400] [Job 12262] Hcas.corp.cooperfarms.local
> D [16/May/2012:12:57:09 -0400] [Job 12262] Pmarthae
> D [16/May/2012:12:57:09 -0400] [Job 12262] J_stdin_
> D [16/May/2012:12:57:09 -0400] [Job 12262] ldfA664cas.corp.cooper
> D [16/May/2012:12:57:09 -0400] [Job 12262] UdfA664cas.corp.cooper
> D [16/May/2012:12:57:09 -0400] [Job 12262] N_stdin_
> D [16/May/2012:12:57:09 -0400] [Job 12262] lpd_command 02 101 cfA664cas.corp.cooper
> D [16/May/2012:12:57:09 -0400] [Job 12262] Sending command string (27 bytes)...
> D [16/May/2012:12:57:09 -0400] [Job 12262] Reading command status...
> D [16/May/2012:12:57:09 -0400] [Job 12262] lpd_command returning 0
> I [16/May/2012:12:57:09 -0400] [Job 12262] Sending control file (101 bytes)
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] Discarding unused job-progress event...
> I [16/May/2012:12:57:09 -0400] [Job 12262] Control file sent successfully
> D [16/May/2012:12:57:09 -0400] [Job 12262] lpd_command 03 2770 dfA664cas.corp.cooper
> D [16/May/2012:12:57:09 -0400] [Job 12262] Sending command string (28 bytes)...
> D [16/May/2012:12:57:09 -0400] [Job 12262] Reading command status...
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:57:09 -0400] [Job 12262] lpd_command returning 0
> I [16/May/2012:12:57:09 -0400] [Job 12262] Sending data file (2770 bytes)
> I [16/May/2012:12:57:09 -0400] [Job 12262] Spooling LPR job, 0% complete...
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] Discarding unused job-progress event...
> I [16/May/2012:12:57:09 -0400] [Job 12262] Data file sent successfully
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:57:09 -0400] [Job 12262] ATTR: marker-levels=20,38
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:09 -0400] [Job 12262] PAGE: 1 1
> D [16/May/2012:12:57:09 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:57:09 -0400] PID 31664 (/usr/lib/cups/backend/lpd) exited with no errors.
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:12:57:09 -0400] [Job 12262] Job completed.
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:57:09 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:57:10 -0400] [Job 12262] Unloading...
> I [16/May/2012:12:57:17 -0400] Saving printers.conf...
> I [16/May/2012:12:57:17 -0400] Saving job cache file "/var/cache/cups/job.cache"...
> I [16/May/2012:12:57:17 -0400] Saving subscriptions.conf...
> D [16/May/2012:12:57:17 -0400] cupsdSetBusyState: Active clients
> D [16/May/2012:12:58:01 -0400] Report: clients=1
> D [16/May/2012:12:58:01 -0400] Report: jobs=499
> D [16/May/2012:12:58:01 -0400] Report: jobs-active=2
> D [16/May/2012:12:58:01 -0400] Report: printers=286
> D [16/May/2012:12:58:01 -0400] Report: printers-implicit=0
> D [16/May/2012:12:58:01 -0400] Report: stringpool-string-count=317941
> D [16/May/2012:12:58:01 -0400] Report: stringpool-alloc-bytes=75960
> D [16/May/2012:12:58:01 -0400] Report: stringpool-total-bytes=4253792
> D [16/May/2012:12:59:01 -0400] Report: clients=1
> D [16/May/2012:12:59:01 -0400] Report: jobs=499
> D [16/May/2012:12:59:01 -0400] Report: jobs-active=2
> D [16/May/2012:12:59:01 -0400] Report: printers=286
> D [16/May/2012:12:59:01 -0400] Report: printers-implicit=0
> D [16/May/2012:12:59:01 -0400] Report: stringpool-string-count=317941
> D [16/May/2012:12:59:01 -0400] Report: stringpool-alloc-bytes=75960
> D [16/May/2012:12:59:01 -0400] Report: stringpool-total-bytes=4253792
> D [16/May/2012:12:59:36 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:12:59:36 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:12:59:36 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:12:59:36 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:59:36 -0400] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
> D [16/May/2012:12:59:36 -0400] Get-Printer-Attributes ipp://localhost:631/printers/fr057dm
> D [16/May/2012:12:59:36 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/fr057dm) from localhost
> D [16/May/2012:12:59:36 -0400] cupsdReadClient: 19 POST /printers/fr057dm HTTP/1.1
> D [16/May/2012:12:59:36 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:59:36 -0400] cupsdReadClient: 19 1.1 Create-Job 1
> D [16/May/2012:12:59:36 -0400] Create-Job ipp://localhost:631/printers/fr057dm
> D [16/May/2012:12:59:36 -0400] add_job: setting context of job to UNKNOWN SL
> D [16/May/2012:12:59:36 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:59:36 -0400] cupsdSetBusyState: Active clients and dirty files
> D [16/May/2012:12:59:36 -0400] add_job: requesting-user-name="carolk"
> I [16/May/2012:12:59:36 -0400] [Job 12263] Adding start banner page "none".
> D [16/May/2012:12:59:36 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:12:59:36 -0400] [Job 12263] Queued on "fr057dm" by "carolk".
> D [16/May/2012:12:59:36 -0400] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/fr057dm) from localhost
> D [16/May/2012:12:59:36 -0400] cupsdReadClient: 19 POST /printers/fr057dm HTTP/1.1
> D [16/May/2012:12:59:36 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:12:59:36 -0400] cupsdReadClient: 19 1.1 Send-Document 1
> D [16/May/2012:12:59:36 -0400] Send-Document ipp://localhost:631/printers/fr057dm
> D [16/May/2012:12:59:36 -0400] cupsdIsAuthorized: requesting-user-name="carolk"
> D [16/May/2012:12:59:36 -0400] [Job 12263] Auto-typing file...
> D [16/May/2012:12:59:36 -0400] [Job 12263] Request file type is text/plain.
> D [16/May/2012:12:59:36 -0400] cupsdMarkDirty(----J-)
> I [16/May/2012:12:59:36 -0400] [Job 12263] File of type text/plain queued by "carolk".
> I [16/May/2012:12:59:36 -0400] [Job 12263] Adding end banner page "none".
> D [16/May/2012:12:59:36 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:59:36 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:59:36 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:12:59:36 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:59:36 -0400] [Job 12263] job-sheets=none,none
> D [16/May/2012:12:59:36 -0400] [Job 12263] argv[0]="fr057dm"
> D [16/May/2012:12:59:36 -0400] [Job 12263] argv[1]="12263"
> D [16/May/2012:12:59:36 -0400] [Job 12263] argv[2]="carolk"
> D [16/May/2012:12:59:36 -0400] [Job 12263] argv[3]="(stdin)"
> D [16/May/2012:12:59:36 -0400] [Job 12263] argv[4]="1"
> D [16/May/2012:12:59:36 -0400] [Job 12263] argv[5]="finishings=3 media=na_letter_8.5x11in number-up=1 job-uuid=urn:uuid:be49a489-9349-310f-7eb9-fccf99ff0bbc job-originating-host-name=localhost"
> D [16/May/2012:12:59:36 -0400] [Job 12263] argv[6]="/var/spool/cups/d12263-001"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[1]="CUPS_DATADIR=/usr/share/cups"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[6]="CUPS_SERVERROOT=/etc/cups"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[7]="CUPS_STATEDIR=/var/run/cups"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[8]="HOME=/var/spool/cups/tmp"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[10]="SERVER_ADMIN=root at cas.corp.cooperfarms.local"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[11]="SOFTWARE=CUPS/1.4.2"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[12]="TMPDIR=/var/spool/cups/tmp"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[13]="USER=root"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[15]="CUPS_ENCRYPTION=IfRequested"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[16]="IPP_PORT=631"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[17]="CHARSET=utf-8"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[18]="LANG=en_US.UTF-8"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[19]="PPD=/etc/cups/ppd/fr057dm.ppd"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[20]="RIP_MAX_CACHE=128m"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[21]="CONTENT_TYPE=text/plain"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[22]="DEVICE_URI=smb://10.0.2.57/fr057dm"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[23]="PRINTER_INFO=Generic text-only printer"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[24]="PRINTER_LOCATION=FR SMill a/r area"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[25]="PRINTER=fr057dm"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[26]="CUPS_FILETYPE=document"
> D [16/May/2012:12:59:36 -0400] [Job 12263] envp[27]="FINAL_CONTENT_TYPE=printer/fr057dm"
> I [16/May/2012:12:59:36 -0400] [Job 12263] Started filter /usr/lib/cups/filter/textonly (PID 31812)
> I [16/May/2012:12:59:36 -0400] [Job 12263] Started backend /usr/lib/cups/backend/smb (PID 31813)
> D [16/May/2012:12:59:36 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:59:36 -0400] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/fr057dm) from localhost
> D [16/May/2012:12:59:36 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:12:59:36 -0400] cupsdCloseClient: 19
> D [16/May/2012:12:59:36 -0400] [Job 12263] PAGE: 1 1
> D [16/May/2012:12:59:36 -0400] Discarding unused job-progress event...
> D [16/May/2012:12:59:36 -0400] [Job 12263] Connected with username/password...
> D [16/May/2012:12:59:36 -0400] [Job 12263] /usr/lib/cups/filter/textonly: line 123: /bin/rm: Permission denied
> D [16/May/2012:12:59:36 -0400] PID 31812 (/usr/lib/cups/filter/textonly) exited with no errors.
> D [16/May/2012:12:59:36 -0400] PID 31813 (/usr/lib/cups/backend/smb) exited with no errors.
> D [16/May/2012:12:59:36 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:12:59:36 -0400] [Job 12263] Job completed.
> D [16/May/2012:12:59:36 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:12:59:36 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:12:59:37 -0400] [Job 12263] Unloading...
> D [16/May/2012:13:00:01 -0400] Report: clients=1
> D [16/May/2012:13:00:01 -0400] Report: jobs=499
> D [16/May/2012:13:00:01 -0400] Report: jobs-active=2
> D [16/May/2012:13:00:01 -0400] Report: printers=286
> D [16/May/2012:13:00:01 -0400] Report: printers-implicit=0
> D [16/May/2012:13:00:01 -0400] Report: stringpool-string-count=317943
> D [16/May/2012:13:00:01 -0400] Report: stringpool-alloc-bytes=76008
> D [16/May/2012:13:00:01 -0400] Report: stringpool-total-bytes=4253816
> I [16/May/2012:13:00:06 -0400] Saving job cache file "/var/cache/cups/job.cache"...
> I [16/May/2012:13:00:06 -0400] Saving subscriptions.conf...
> D [16/May/2012:13:00:06 -0400] cupsdSetBusyState: Active clients
> D [16/May/2012:13:00:33 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:33 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:33 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:33 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:33 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:33 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Default 1
> D [16/May/2012:13:00:33 -0400] CUPS-Get-Default
> D [16/May/2012:13:00:33 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:33 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Classes 1
> D [16/May/2012:13:00:33 -0400] CUPS-Get-Classes
> D [16/May/2012:13:00:33 -0400] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:33 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:33 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:33 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:13:00:33 -0400] cupsdCloseClient: 19
> D [16/May/2012:13:00:33 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:33 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:33 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
> D [16/May/2012:13:00:33 -0400] Get-Printer-Attributes ipp://localhost:631/printers/ol127lj
> D [16/May/2012:13:00:33 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/ol127lj) from localhost
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:33 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:33 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:33 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:13:00:33 -0400] cupsdCloseClient: 19
> D [16/May/2012:13:00:33 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:33 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:33 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:33 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:33 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:33 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Default 1
> D [16/May/2012:13:00:33 -0400] CUPS-Get-Default
> D [16/May/2012:13:00:33 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:33 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:33 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:33 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:33 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:13:00:33 -0400] cupsdCloseClient: 19
> D [16/May/2012:13:00:42 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:42 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:42 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:42 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:42 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:42 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Default 1
> D [16/May/2012:13:00:42 -0400] CUPS-Get-Default
> D [16/May/2012:13:00:42 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:42 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Classes 1
> D [16/May/2012:13:00:42 -0400] CUPS-Get-Classes
> D [16/May/2012:13:00:42 -0400] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:42 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:42 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:42 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:13:00:42 -0400] cupsdCloseClient: 19
> D [16/May/2012:13:00:42 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:42 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:42 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
> D [16/May/2012:13:00:42 -0400] Get-Printer-Attributes ipp://localhost:631/printers/pa100pr
> D [16/May/2012:13:00:42 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/pa100pr) from localhost
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:42 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:42 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:42 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:13:00:42 -0400] cupsdCloseClient: 19
> D [16/May/2012:13:00:42 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:42 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:42 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:42 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:42 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:42 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Default 1
> D [16/May/2012:13:00:42 -0400] CUPS-Get-Default
> D [16/May/2012:13:00:42 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:42 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:42 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:42 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:42 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:13:00:42 -0400] cupsdCloseClient: 19
> D [16/May/2012:13:00:45 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:45 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:45 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
> D [16/May/2012:13:00:45 -0400] Get-Printer-Attributes ipp://localhost:631/printers/ol127lj
> D [16/May/2012:13:00:45 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/ol127lj) from localhost
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 POST /printers/ol127lj HTTP/1.1
> D [16/May/2012:13:00:45 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 1.1 Create-Job 1
> D [16/May/2012:13:00:45 -0400] Create-Job ipp://localhost:631/printers/ol127lj
> D [16/May/2012:13:00:45 -0400] add_job: setting context of job to UNKNOWN SL
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:13:00:45 -0400] cupsdSetBusyState: Active clients and dirty files
> D [16/May/2012:13:00:45 -0400] add_job: requesting-user-name="olsons"
> I [16/May/2012:13:00:45 -0400] [Job 12264] Adding start banner page "none".
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:13:00:45 -0400] [Job 12264] Queued on "ol127lj" by "olsons".
> D [16/May/2012:13:00:45 -0400] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/ol127lj) from localhost
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 POST /printers/ol127lj HTTP/1.1
> D [16/May/2012:13:00:45 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 1.1 Send-Document 1
> D [16/May/2012:13:00:45 -0400] Send-Document ipp://localhost:631/printers/ol127lj
> D [16/May/2012:13:00:45 -0400] cupsdIsAuthorized: requesting-user-name="olsons"
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(----J-)
> I [16/May/2012:13:00:45 -0400] [Job 12264] File of type application/vnd.cups-raw queued by "olsons".
> I [16/May/2012:13:00:45 -0400] [Job 12264] Adding end banner page "none".
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:13:00:45 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:45 -0400] [Job 12264] job-sheets=none,none
> D [16/May/2012:13:00:45 -0400] [Job 12264] argv[0]="ol127lj"
> D [16/May/2012:13:00:45 -0400] [Job 12264] argv[1]="12264"
> D [16/May/2012:13:00:45 -0400] [Job 12264] argv[2]="olsons"
> D [16/May/2012:13:00:45 -0400] [Job 12264] argv[3]="(stdin)"
> D [16/May/2012:13:00:45 -0400] [Job 12264] argv[4]="1"
> D [16/May/2012:13:00:45 -0400] [Job 12264] argv[5]="finishings=3 media=na_letter_8.5x11in number-up=1 job-uuid=urn:uuid:84541ea7-40b2-34f0-676b-215bd6b6c555 job-originating-host-name=localhost"
> D [16/May/2012:13:00:45 -0400] [Job 12264] argv[6]="/var/spool/cups/d12264-001"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[1]="CUPS_DATADIR=/usr/share/cups"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[6]="CUPS_SERVERROOT=/etc/cups"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[7]="CUPS_STATEDIR=/var/run/cups"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[8]="HOME=/var/spool/cups/tmp"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[10]="SERVER_ADMIN=root at cas.corp.cooperfarms.local"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[11]="SOFTWARE=CUPS/1.4.2"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[12]="TMPDIR=/var/spool/cups/tmp"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[13]="USER=root"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[15]="CUPS_ENCRYPTION=IfRequested"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[16]="IPP_PORT=631"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[17]="CHARSET=utf-8"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[18]="LANG=en_US.UTF-8"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[19]="PPD=/etc/cups/ppd/ol127lj.ppd"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[20]="RIP_MAX_CACHE=128m"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[21]="CONTENT_TYPE=application/vnd.cups-raw"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[22]="DEVICE_URI=lpd://10.9.1.127/PASSTHRU"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[23]="PRINTER_INFO=HP LaserJet p4014"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[24]="PRINTER_LOCATION=10.9.1.127"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[25]="PRINTER=ol127lj"
> D [16/May/2012:13:00:45 -0400] [Job 12264] envp[26]="CUPS_FILETYPE=document"
> I [16/May/2012:13:00:45 -0400] [Job 12264] Started backend /usr/lib/cups/backend/lpd (PID 32183)
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:45 -0400] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/ol127lj) from localhost
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:13:00:45 -0400] cupsdCloseClient: 19
> D [16/May/2012:13:00:45 -0400] [Job 12264] STATE: +connecting-to-device
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:45 -0400] [Job 12264] Looking up "10.9.1.127"...
> D [16/May/2012:13:00:45 -0400] [Job 12264] Connecting to 10.9.1.127:515 for printer PASSTHRU
> I [16/May/2012:13:00:45 -0400] [Job 12264] Connecting to printer...
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:45 -0400] Discarding unused job-progress event...
> D [16/May/2012:13:00:45 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:45 -0400] cupsdAcceptClient: 19 from localhost (Domain)
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 POST / HTTP/1.1
> D [16/May/2012:13:00:45 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
> D [16/May/2012:13:00:45 -0400] Get-Printer-Attributes ipp://localhost:631/printers/ol127lj
> D [16/May/2012:13:00:45 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/ol127lj) from localhost
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 POST /printers/ol127lj HTTP/1.1
> D [16/May/2012:13:00:45 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:45 -0400] [Job 12264] STATE: -connecting-to-device
> I [16/May/2012:13:00:45 -0400] [Job 12264] Connected to printer...
> D [16/May/2012:13:00:45 -0400] [Job 12264] Connected to 10.9.1.127:515 (IPv4) (local port 1023)...
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:45 -0400] Discarding unused job-progress event...
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 1.1 Create-Job 1
> D [16/May/2012:13:00:45 -0400] Create-Job ipp://localhost:631/printers/ol127lj
> D [16/May/2012:13:00:45 -0400] add_job: setting context of job to UNKNOWN SL
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:13:00:45 -0400] add_job: requesting-user-name="olsons"
> I [16/May/2012:13:00:45 -0400] [Job 12265] Adding start banner page "none".
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:13:00:45 -0400] [Job 12265] Queued on "ol127lj" by "olsons".
> D [16/May/2012:13:00:45 -0400] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/ol127lj) from localhost
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 POST /printers/ol127lj HTTP/1.1
> D [16/May/2012:13:00:45 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 1.1 Send-Document 1
> D [16/May/2012:13:00:45 -0400] Send-Document ipp://localhost:631/printers/ol127lj
> D [16/May/2012:13:00:45 -0400] cupsdIsAuthorized: requesting-user-name="olsons"
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(----J-)
> I [16/May/2012:13:00:45 -0400] [Job 12265] File of type application/vnd.cups-raw queued by "olsons".
> I [16/May/2012:13:00:45 -0400] [Job 12265] Adding end banner page "none".
> D [16/May/2012:13:00:45 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:13:00:45 -0400] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/ol127lj) from localhost
> D [16/May/2012:13:00:45 -0400] cupsdReadClient: 19 WAITING Closing on EOF
> D [16/May/2012:13:00:45 -0400] cupsdCloseClient: 19
> D [16/May/2012:13:00:47 -0400] [Job 12264] ATTR: marker-colors=none,none
> D [16/May/2012:13:00:47 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:13:00:47 -0400] [Job 12264] ATTR: marker-names="Black Cartridge HP CC364A","Maintenance Kit HP 110V-CB388A, 220V-CB389A"
> D [16/May/2012:13:00:47 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:13:00:47 -0400] [Job 12264] ATTR: marker-types=tonerCartridge,fuser
> D [16/May/2012:13:00:47 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:13:00:47 -0400] [Job 12264] ATTR: marker-levels=7,0
> D [16/May/2012:13:00:47 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:13:00:47 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: -media-low-report
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: -media-empty-warning
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: +toner-low-report
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: +toner-empty-warning
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: -door-open-report
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: -media-jam-warning
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: -input-tray-missing-warning
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: -output-tray-missing-warning
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: -marker-supply-missing-warning
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: -output-area-almost-full-report
> D [16/May/2012:13:00:47 -0400] [Job 12264] STATE: -output-area-full-warning
> D [16/May/2012:13:00:47 -0400] [Job 12264] lpd_command 02 PASSTHRU
> D [16/May/2012:13:00:47 -0400] [Job 12264] Sending command string (10 bytes)...
> D [16/May/2012:13:00:47 -0400] [Job 12264] Reading command status...
> D [16/May/2012:13:00:47 -0400] [Job 12264] lpd_command returning 0
> D [16/May/2012:13:00:47 -0400] [Job 12264] Control file is:
> D [16/May/2012:13:00:47 -0400] [Job 12264] Hcas.corp.cooperfarms.local
> D [16/May/2012:13:00:47 -0400] [Job 12264] Polsons
> D [16/May/2012:13:00:47 -0400] [Job 12264] J_stdin_
> D [16/May/2012:13:00:47 -0400] [Job 12264] ldfA183cas.corp.cooper
> D [16/May/2012:13:00:47 -0400] [Job 12264] UdfA183cas.corp.cooper
> D [16/May/2012:13:00:47 -0400] [Job 12264] N_stdin_
> D [16/May/2012:13:00:47 -0400] [Job 12264] lpd_command 02 100 cfA183cas.corp.cooper
> D [16/May/2012:13:00:47 -0400] [Job 12264] Sending command string (27 bytes)...
> D [16/May/2012:13:00:47 -0400] [Job 12264] Reading command status...
> D [16/May/2012:13:00:47 -0400] [Job 12264] lpd_command returning 0
> I [16/May/2012:13:00:47 -0400] [Job 12264] Sending control file (100 bytes)
> D [16/May/2012:13:00:47 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:47 -0400] Discarding unused job-progress event...
> I [16/May/2012:13:00:47 -0400] [Job 12264] Control file sent successfully
> D [16/May/2012:13:00:47 -0400] [Job 12264] lpd_command 03 77249 dfA183cas.corp.cooper
> D [16/May/2012:13:00:47 -0400] [Job 12264] Sending command string (29 bytes)...
> D [16/May/2012:13:00:47 -0400] [Job 12264] Reading command status...
> D [16/May/2012:13:00:47 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:47 -0400] Discarding unused job-progress event...
> D [16/May/2012:13:00:47 -0400] [Job 12264] lpd_command returning 0
> I [16/May/2012:13:00:47 -0400] [Job 12264] Sending data file (77249 bytes)
> I [16/May/2012:13:00:47 -0400] [Job 12264] Spooling LPR job, 0% complete...
> D [16/May/2012:13:00:47 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:47 -0400] Discarding unused job-progress event...
> I [16/May/2012:13:00:47 -0400] [Job 12264] Spooling LPR job, 42% complete...
> D [16/May/2012:13:00:47 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:47 -0400] Discarding unused job-progress event...
> I [16/May/2012:13:00:47 -0400] [Job 12264] Spooling LPR job, 85% complete...
> D [16/May/2012:13:00:47 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:47 -0400] Discarding unused job-progress event...
> I [16/May/2012:13:00:48 -0400] [Job 12264] Data file sent successfully
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:13:00:48 -0400] Closing client 16 after 300 seconds of inactivity...
> D [16/May/2012:13:00:48 -0400] cupsdCloseClient: 16
> D [16/May/2012:13:00:48 -0400] cupsdSetBusyState: Printing jobs and dirty files
> I [16/May/2012:13:00:48 -0400] [Job 12259] Adding end banner page "none".
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:13:00:48 -0400] [Job 12259] Job submission timed out.
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> E [16/May/2012:13:00:48 -0400] [Job 12259] Aborting job because it has no files.
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:13:00:48 -0400] [Job 12264] ATTR: marker-levels=62,0
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:48 -0400] [Job 12264] PAGE: 1 1
> D [16/May/2012:13:00:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:13:00:48 -0400] PID 32183 (/usr/lib/cups/backend/lpd) exited with no errors.
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> I [16/May/2012:13:00:48 -0400] [Job 12264] Job completed.
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(----J-)
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:48 -0400] [Job 12265] job-sheets=none,none
> D [16/May/2012:13:00:48 -0400] [Job 12265] argv[0]="ol127lj"
> D [16/May/2012:13:00:48 -0400] [Job 12265] argv[1]="12265"
> D [16/May/2012:13:00:48 -0400] [Job 12265] argv[2]="olsons"
> D [16/May/2012:13:00:48 -0400] [Job 12265] argv[3]="(stdin)"
> D [16/May/2012:13:00:48 -0400] [Job 12265] argv[4]="1"
> D [16/May/2012:13:00:48 -0400] [Job 12265] argv[5]="finishings=3 media=na_letter_8.5x11in number-up=1 job-uuid=urn:uuid:6599307a-f69d-3507-5a67-5b8d5684fba4 job-originating-host-name=localhost"
> D [16/May/2012:13:00:48 -0400] [Job 12265] argv[6]="/var/spool/cups/d12265-001"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[1]="CUPS_DATADIR=/usr/share/cups"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[6]="CUPS_SERVERROOT=/etc/cups"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[7]="CUPS_STATEDIR=/var/run/cups"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[8]="HOME=/var/spool/cups/tmp"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[10]="SERVER_ADMIN=root at cas.corp.cooperfarms.local"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[11]="SOFTWARE=CUPS/1.4.2"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[12]="TMPDIR=/var/spool/cups/tmp"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[13]="USER=root"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[15]="CUPS_ENCRYPTION=IfRequested"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[16]="IPP_PORT=631"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[17]="CHARSET=utf-8"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[18]="LANG=en_US.UTF-8"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[19]="PPD=/etc/cups/ppd/ol127lj.ppd"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[20]="RIP_MAX_CACHE=128m"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[21]="CONTENT_TYPE=application/vnd.cups-raw"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[22]="DEVICE_URI=lpd://10.9.1.127/PASSTHRU"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[23]="PRINTER_INFO=HP LaserJet p4014"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[24]="PRINTER_LOCATION=10.9.1.127"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[25]="PRINTER=ol127lj"
> D [16/May/2012:13:00:48 -0400] [Job 12265] envp[26]="CUPS_FILETYPE=document"
> I [16/May/2012:13:00:48 -0400] [Job 12265] Started backend /usr/lib/cups/backend/lpd (PID 32214)
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: +connecting-to-device
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:48 -0400] [Job 12265] Looking up "10.9.1.127"...
> D [16/May/2012:13:00:48 -0400] [Job 12265] Connecting to 10.9.1.127:515 for printer PASSTHRU
> I [16/May/2012:13:00:48 -0400] [Job 12265] Connecting to printer...
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: -connecting-to-device
> I [16/May/2012:13:00:48 -0400] [Job 12265] Connected to printer...
> D [16/May/2012:13:00:48 -0400] [Job 12265] Connected to 10.9.1.127:515 (IPv4) (local port 1022)...
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:48 -0400] Discarding unused job-progress event...
> D [16/May/2012:13:00:48 -0400] [Job 12265] ATTR: marker-colors=#000000,none
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:13:00:48 -0400] [Job 12265] ATTR: marker-names="Black Cartridge HP CC364A","Maintenance Kit HP 110V-CB388A, 220V-CB389A"
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:13:00:48 -0400] [Job 12265] ATTR: marker-types=tonerCartridge,fuser
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:13:00:48 -0400] [Job 12265] ATTR: marker-levels=7,0
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(P-----)
> D [16/May/2012:13:00:48 -0400] cupsdMarkDirty(-----S)
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: -media-low-report
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: -media-empty-warning
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: +toner-low-report
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: +toner-empty-warning
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: -door-open-report
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: -media-jam-warning
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: -input-tray-missing-warning
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: -output-tray-missing-warning
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: -marker-supply-missing-warning
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: -output-area-almost-full-report
> D [16/May/2012:13:00:48 -0400] [Job 12265] STATE: -output-area-full-warning
> D [16/May/2012:13:00:48 -0400] [Job 12265] lpd_command 02 PASSTHRU
> D [16/May/2012:13:00:48 -0400] [Job 12265] Sending command string (10 bytes)...
> D [16/May/2012:13:00:48 -0400] [Job 12265] Reading command status...
> D [16/May/2012:13:00:48 -0400] [Job 12265] lpd_command returning 0
> D [16/May/2012:13:00:48 -0400] [Job 12265] Control file is:
> D [16/May/2012:13:00:48 -0400] [Job 12265] Hcas.corp.cooperfarms.local
> D [16/May/2012:13:00:48 -0400] [Job 12265] Polsons
> D [16/May/2012:13:00:48 -0400] [Job 12265] J_stdin_
> D [16/May/2012:13:00:48 -0400] [Job 12265] ldfA214cas.corp.cooper
> D [16/May/2012:13:00:48 -0400] [Job 12265] UdfA214cas.corp.cooper
> D [16/May/2012:13:00:48 -0400] [Job 12265] N_stdin_
> D [16/May/2012:13:00:48 -0400] [Job 12265] lpd_command 02 100 cfA214cas.corp.cooper
> D [16/May/2012:13:00:48 -0400] [Job 12265] Sending command string (27 bytes)...
> D [16/May/2012:13:00:48 -0400] [Job 12265] Reading command status...
> D [16/May/2012:13:00:49 -0400] [Job 12259] Unloading...
> D [16/May/2012:13:00:49 -0400] [Job 12264] Unloading...
> D [16/May/2012:13:00:54 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:54 -0400] cupsdAcceptClient: 17 from localhost (Domain)
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 POST / HTTP/1.1
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:54 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:54 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Printing jobs and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 POST / HTTP/1.1
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
> D [16/May/2012:13:00:54 -0400] CUPS-Get-Default
> D [16/May/2012:13:00:54 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Printing jobs and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 POST / HTTP/1.1
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 1.1 CUPS-Get-Classes 1
> D [16/May/2012:13:00:54 -0400] CUPS-Get-Classes
> D [16/May/2012:13:00:54 -0400] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Printing jobs and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 POST / HTTP/1.1
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:54 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:54 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Printing jobs and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 WAITING Closing on EOF
> D [16/May/2012:13:00:54 -0400] cupsdCloseClient: 17
> D [16/May/2012:13:00:54 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:54 -0400] cupsdAcceptClient: 17 from localhost (Domain)
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 POST / HTTP/1.1
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1
> D [16/May/2012:13:00:54 -0400] Get-Printer-Attributes ipp://localhost:631/printers/nm115dm
> D [16/May/2012:13:00:54 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/nm115dm) from localhost
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Printing jobs and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 POST / HTTP/1.1
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:54 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:54 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Printing jobs and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 WAITING Closing on EOF
> D [16/May/2012:13:00:54 -0400] cupsdCloseClient: 17
> D [16/May/2012:13:00:54 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:00:54 -0400] cupsdAcceptClient: 17 from localhost (Domain)
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 POST / HTTP/1.1
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:54 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:54 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Printing jobs and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 POST / HTTP/1.1
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
> D [16/May/2012:13:00:54 -0400] CUPS-Get-Default
> D [16/May/2012:13:00:54 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Printing jobs and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 POST / HTTP/1.1
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
> D [16/May/2012:13:00:54 -0400] CUPS-Get-Printers
> D [16/May/2012:13:00:54 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [16/May/2012:13:00:54 -0400] cupsdSetBusyState: Printing jobs and dirty files
> D [16/May/2012:13:00:54 -0400] cupsdReadClient: 17 WAITING Closing on EOF
> D [16/May/2012:13:00:54 -0400] cupsdCloseClient: 17
> D [16/May/2012:13:01:01 -0400] Report: clients=0
> D [16/May/2012:13:01:01 -0400] Report: jobs=499
> D [16/May/2012:13:01:01 -0400] Report: jobs-active=2
> D [16/May/2012:13:01:01 -0400] Report: printers=286
> D [16/May/2012:13:01:01 -0400] Report: printers-implicit=0
> D [16/May/2012:13:01:01 -0400] Report: stringpool-string-count=318158
> D [16/May/2012:13:01:01 -0400] Report: stringpool-alloc-bytes=76184
> D [16/May/2012:13:01:01 -0400] Report: stringpool-total-bytes=4257712
> I [16/May/2012:13:01:15 -0400] Saving printers.conf...
> I [16/May/2012:13:01:15 -0400] Saving job cache file "/var/cache/cups/job.cache"...
> I [16/May/2012:13:01:15 -0400] Saving subscriptions.conf...
> D [16/May/2012:13:01:15 -0400] cupsdSetBusyState: Printing jobs
> D [16/May/2012:13:01:21 -0400] cupsdAcceptClient: skipping getpeercon()
> D [16/May/2012:13:01:21 -0400] cupsdAcceptClient: 17 from localhost (Domain)
> D [16/May/2012:13:01:21 -0400] cupsdReadClient: 17 POST /jobs/ HTTP/1.1
> D [16/May/2012:13:01:21 -0400] cupsdSetBusyState: Active clients and printing jobs
> D [16/May/2012:13:01:21 -0400] cupsdAuthorize: No authentication data provided.
> D [16/May/2012:13:01:21 -0400] cupsdReadClient: 17 1.1 Cancel-Job 1
> D [16/May/2012:13:01:21 -0400] Cancel-Job ipp://localhost:631/printers/nm098pr
> D [16/May/2012:13:01:21 -0400] cupsdIsAuthorized: requesting-user-name="maryjean"
> D [16/May/2012:13:01:21 -0400] Cancel-Job client-error-not-possible: Job #12259 is already aborted - can't cancel.
> E [16/May/2012:13:01:21 -0400] Returning IPP client-error-not-possible for Cancel-Job (ipp://localhost:631/printers/nm098pr) from localhost
> D [16/May/2012:13:01:21 -0400] cupsdSetBusyState: Printing jobs
> D [16/May/2012:13:01:21 -0400] cupsdReadClient: 17 WAITING Closing on EOF
> D [16/May/2012:13:01:21 -0400] cupsdCloseClient: 17
>


I meant to say nothing showed in the print spool (not logs)




More information about the cups mailing list