Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 589141 Details for
Bug 827632
Printing PDF fails (although log says it's been ok), but postscript is ok
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
error log from lpr text file to printer setup in S-C-P - printing successful
lpr_error_log (text/plain), 101.74 KB, created by
Dan Naughton
on 2012-06-04 12:53:49 UTC
(
hide
)
Description:
error log from lpr text file to printer setup in S-C-P - printing successful
Filename:
MIME Type:
Creator:
Dan Naughton
Created:
2012-06-04 12:53:49 UTC
Size:
101.74 KB
patch
obsolete
>I [04/Jun/2012:07:42:03 -0500] Remote access is disabled. >D [04/Jun/2012:07:42:03 -0500] Added auto ServerAlias mcase820 >I [04/Jun/2012:07:42:03 -0500] Loaded configuration file "/etc/cups/cupsd.conf" >I [04/Jun/2012:07:42:03 -0500] Using default TempDir of /var/spool/cups/tmp... >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: CUPS_CACHEDIR=/var/cache/cups >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: CUPS_DATADIR=/usr/share/cups >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: CUPS_DOCROOT=/usr/share/cups/www >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: CUPS_FONTPATH=/usr/share/cups/fonts >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: CUPS_REQUESTROOT=/var/spool/cups >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: CUPS_SERVERBIN=/usr/lib/cups >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: CUPS_SERVERROOT=/etc/cups >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: CUPS_STATEDIR=/var/run/cups >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: HOME=/var/spool/cups/tmp >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: SERVER_ADMIN=root@mcase820 >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: SOFTWARE=CUPS/1.5.2 >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: TMPDIR=/var/spool/cups/tmp >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: USER=root >I [04/Jun/2012:07:42:03 -0500] Configured for up to 100 clients. >I [04/Jun/2012:07:42:03 -0500] Allowing up to 100 client connections per host. >I [04/Jun/2012:07:42:03 -0500] Using policy "default" as the default. >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: NumPolicies=2 >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: Policies[0]="authenticated" >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: Policies[1]="default" >I [04/Jun/2012:07:42:03 -0500] Full reload is required. >I [04/Jun/2012:07:42:03 -0500] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 35 types, 46 filters... >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/octet-stream >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/pdf >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/postscript >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/rss+xml >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/vnd.cups-banner >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/vnd.cups-command >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/vnd.cups-pdf >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/vnd.cups-postscript >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/vnd.cups-ppd >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/vnd.cups-raster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/vnd.cups-raw >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/x-cshell >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/x-csource >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/x-perl >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type application/x-shell >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/gif >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/jpeg >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/png >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/pwg-raster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/tiff >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-alias >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-bitmap >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-icon >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-photocd >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-portable-anymap >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-portable-bitmap >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-portable-graymap >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-portable-pixmap >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-sgi-rgb >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-sun-raster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-xbitmap >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type image/x-xpixmap >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type text/css >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type text/html >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: type text/plain >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/octet-stream to application/vnd.cups-raw 0 - >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/pdf to application/vnd.cups-postscript 66 pdftops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/pdf to image/tiff 80 pstotiff >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/postscript to application/vnd.cups-postscript 66 pstops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/postscript to image/tiff 80 pstotiff >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/vnd.cups-banner to application/postscript 33 bannertops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/vnd.cups-pdf to application/vnd.cups-raster 66 gstoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/vnd.cups-pdf to image/tiff 80 pstotiff >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/vnd.cups-postscript to application/vnd.cups-raster 100 gstoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/vnd.cups-postscript to image/tiff 80 pstotiff >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/vnd.cups-raster to image/pwg-raster 100 rastertopwg >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/x-cshell to application/postscript 33 texttops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/x-csource to application/postscript 33 texttops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/x-perl to application/postscript 33 texttops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter application/x-shell to application/postscript 33 texttops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/gif to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/gif to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/jpeg to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/jpeg to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/png to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/png to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/tiff to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/tiff to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-bitmap to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-bitmap to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-photocd to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-photocd to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-portable-anymap to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-portable-anymap to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-portable-bitmap to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-portable-bitmap to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-portable-graymap to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-portable-graymap to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-portable-pixmap to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-portable-pixmap to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-sgi-rgb to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-sgi-rgb to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-sun-raster to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-sun-raster to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-xbitmap to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-xbitmap to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-xpixmap to application/vnd.cups-postscript 66 imagetops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter image/x-xpixmap to application/vnd.cups-raster 100 imagetoraster >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter text/css to application/postscript 33 texttops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter text/html to application/postscript 33 texttops >d [04/Jun/2012:07:42:03 -0500] cupsdReadConfiguration: filter text/plain to application/postscript 30 texttopaps >D [04/Jun/2012:07:42:03 -0500] Loading printer RICOH-Aficio-CL1000N... >d [04/Jun/2012:07:42:03 -0500] cupsdAddPrinter("RICOH-Aficio-CL1000N") >d [04/Jun/2012:07:42:03 -0500] cupsdAddPrinter: Adding RICOH-Aficio-CL1000N to Printers >d [04/Jun/2012:07:42:03 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-cups-insecure-filter-warning,cups-missing-filter-warning" >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: uri = "/printers/RICOH-Aficio-CL1000N"... >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0 >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: Location /admin/conf Limit 7f >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: Location /admin Limit 7f >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: Location / Limit 7f >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: best = / >d [04/Jun/2012:07:42:03 -0500] cupsdFindPolicyOp(p=0x7fa313c7d070, op=2(Print-Job)) >d [04/Jun/2012:07:42:03 -0500] cupsdFindPolicyOp: Found exact match... >D [04/Jun/2012:07:42:03 -0500] load_ppd: Loading /var/cache/cups/RICOH-Aficio-CL1000N.data... >d [04/Jun/2012:07:42:03 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-cups-missing-filter-warning,cups-insecure-filter-warning" >d [04/Jun/2012:07:42:03 -0500] add_printer_filter(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), filtertype=0x7fa313c951a0(printer/RICOH-Aficio-CL1000N), filter="application/vnd.cups-raw application/octet-stream 0 -") >d [04/Jun/2012:07:42:03 -0500] add_printer_filter: RICOH-Aficio-CL1000N: adding filter application/vnd.cups-raw printer/RICOH-Aficio-CL1000N/application/octet-stream 0 - >d [04/Jun/2012:07:42:03 -0500] add_printer_filter: RICOH-Aficio-CL1000N: adding filter printer/RICOH-Aficio-CL1000N/application/octet-stream printer/RICOH-Aficio-CL1000N 0 - >d [04/Jun/2012:07:42:03 -0500] add_printer_filter(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), filtertype=0x7fa313c951a0(printer/RICOH-Aficio-CL1000N), filter="application/vnd.cups-postscript 0 foomatic-rip") >d [04/Jun/2012:07:42:03 -0500] RICOH-Aficio-CL1000N: File "/usr/lib/cups/filter/foomatic-rip" permissions OK (040755/uid=0/gid=0). >d [04/Jun/2012:07:42:03 -0500] add_printer_filter: RICOH-Aficio-CL1000N: adding filter application/vnd.cups-postscript printer/RICOH-Aficio-CL1000N 0 foomatic-rip >d [04/Jun/2012:07:42:03 -0500] add_printer_filter(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), filtertype=0x7fa313c951a0(printer/RICOH-Aficio-CL1000N), filter="application/vnd.cups-command application/postscript 100 commandtops") >d [04/Jun/2012:07:42:03 -0500] RICOH-Aficio-CL1000N: File "/usr/lib/cups/filter/commandtops" permissions OK (040755/uid=0/gid=0). >d [04/Jun/2012:07:42:03 -0500] add_printer_filter: RICOH-Aficio-CL1000N: adding filter application/vnd.cups-command printer/RICOH-Aficio-CL1000N/application/postscript 100 commandtops >d [04/Jun/2012:07:42:03 -0500] add_printer_filter: RICOH-Aficio-CL1000N: adding filter printer/RICOH-Aficio-CL1000N/application/postscript printer/RICOH-Aficio-CL1000N 0 - >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: 38 types, 51 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/octet-stream needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/pdf needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/postscript needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/rss+xml not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-banner needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-command needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-pdf needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-postscript needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-ppd not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-raster not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-raw needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/x-cshell needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/x-csource needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/x-perl needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/x-shell needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/gif needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/jpeg needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/png needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/pwg-raster not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/tiff needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-alias not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-bitmap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-icon not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-photocd needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-portable-anymap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-portable-bitmap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-portable-graymap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-portable-pixmap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-sgi-rgb needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-sun-raster needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-xbitmap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-xpixmap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: text/css needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: text/html needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: text/plain needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: 29 supported types >D [04/Jun/2012:07:42:03 -0500] Calling FindDeviceById(cups-RICOH-Aficio-CL1000N) >D [04/Jun/2012:07:42:03 -0500] failed to DeleteDevice: org.freedesktop.ColorManager.Failed:device id 'cups-RICOH-Aficio-CL1000N' does not exists >W [04/Jun/2012:07:42:03 -0500] failed to find device: cups-RICOH-Aficio-CL1000N >D [04/Jun/2012:07:42:03 -0500] Using profile id of RICOH-Aficio-CL1000N-Gray.. >D [04/Jun/2012:07:42:03 -0500] Calling CreateProfile(RICOH-Aficio-CL1000N-Gray..,temp) >D [04/Jun/2012:07:42:03 -0500] created profile /org/freedesktop/ColorManager/profiles/RICOH_Aficio_CL1000N_Gray__ >D [04/Jun/2012:07:42:03 -0500] Using profile id of RICOH-Aficio-CL1000N-CMYK.. >D [04/Jun/2012:07:42:03 -0500] Calling CreateProfile(RICOH-Aficio-CL1000N-CMYK..,temp) >D [04/Jun/2012:07:42:03 -0500] created profile /org/freedesktop/ColorManager/profiles/RICOH_Aficio_CL1000N_CMYK__ >I [04/Jun/2012:07:42:03 -0500] Registering ICC color profiles for "RICOH-Aficio-CL1000N" >D [04/Jun/2012:07:42:03 -0500] Calling CreateDevice(cups-RICOH-Aficio-CL1000N,temp) >D [04/Jun/2012:07:42:03 -0500] created device /org/freedesktop/ColorManager/devices/cups_RICOH_Aficio_CL1000N >D [04/Jun/2012:07:42:03 -0500] Calling /org/freedesktop/ColorManager/devices/cups_RICOH_Aficio_CL1000N:AddProfile(/org/freedesktop/ColorManager/profiles/RICOH_Aficio_CL1000N_Gray__) [soft] >D [04/Jun/2012:07:42:03 -0500] Calling /org/freedesktop/ColorManager/devices/cups_RICOH_Aficio_CL1000N:AddProfile(/org/freedesktop/ColorManager/profiles/RICOH_Aficio_CL1000N_CMYK__) [soft] >D [04/Jun/2012:07:42:03 -0500] cupsdRegisterPrinter(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N)) >d [04/Jun/2012:07:42:03 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-cups-insecure-filter-warning,cups-missing-filter-warning" >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: uri = "/printers/RICOH-Aficio-CL1000N"... >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0 >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: Location /admin/conf Limit 7f >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: Location /admin Limit 7f >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: Location / Limit 7f >d [04/Jun/2012:07:42:03 -0500] cupsdFindBest: best = / >d [04/Jun/2012:07:42:03 -0500] cupsdFindPolicyOp(p=0x7fa313c7d070, op=2(Print-Job)) >d [04/Jun/2012:07:42:03 -0500] cupsdFindPolicyOp: Found exact match... >D [04/Jun/2012:07:42:03 -0500] load_ppd: Loading /var/cache/cups/RICOH-Aficio-CL1000N.data... >d [04/Jun/2012:07:42:03 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-cups-missing-filter-warning,cups-insecure-filter-warning" >d [04/Jun/2012:07:42:03 -0500] add_printer_filter(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), filtertype=0x7fa313c951a0(printer/RICOH-Aficio-CL1000N), filter="application/vnd.cups-raw application/octet-stream 0 -") >d [04/Jun/2012:07:42:03 -0500] add_printer_filter: RICOH-Aficio-CL1000N: adding filter application/vnd.cups-raw printer/RICOH-Aficio-CL1000N/application/octet-stream 0 - >d [04/Jun/2012:07:42:03 -0500] add_printer_filter: RICOH-Aficio-CL1000N: adding filter printer/RICOH-Aficio-CL1000N/application/octet-stream printer/RICOH-Aficio-CL1000N 0 - >d [04/Jun/2012:07:42:03 -0500] add_printer_filter(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), filtertype=0x7fa313c951a0(printer/RICOH-Aficio-CL1000N), filter="application/vnd.cups-postscript 0 foomatic-rip") >d [04/Jun/2012:07:42:03 -0500] RICOH-Aficio-CL1000N: File "/usr/lib/cups/filter/foomatic-rip" permissions OK (040755/uid=0/gid=0). >d [04/Jun/2012:07:42:03 -0500] add_printer_filter: RICOH-Aficio-CL1000N: adding filter application/vnd.cups-postscript printer/RICOH-Aficio-CL1000N 0 foomatic-rip >d [04/Jun/2012:07:42:03 -0500] add_printer_filter(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), filtertype=0x7fa313c951a0(printer/RICOH-Aficio-CL1000N), filter="application/vnd.cups-command application/postscript 100 commandtops") >d [04/Jun/2012:07:42:03 -0500] RICOH-Aficio-CL1000N: File "/usr/lib/cups/filter/commandtops" permissions OK (040755/uid=0/gid=0). >d [04/Jun/2012:07:42:03 -0500] add_printer_filter: RICOH-Aficio-CL1000N: adding filter application/vnd.cups-command printer/RICOH-Aficio-CL1000N/application/postscript 100 commandtops >d [04/Jun/2012:07:42:03 -0500] add_printer_filter: RICOH-Aficio-CL1000N: adding filter printer/RICOH-Aficio-CL1000N/application/postscript printer/RICOH-Aficio-CL1000N 0 - >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: 38 types, 51 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/octet-stream needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/pdf needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/postscript needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/rss+xml not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-banner needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-command needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-pdf needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-postscript needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-ppd not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-raster not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/vnd.cups-raw needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/x-cshell needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/x-csource needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/x-perl needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: application/x-shell needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/gif needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/jpeg needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/png needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/pwg-raster not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/tiff needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-alias not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-bitmap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-icon not supported >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-photocd needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-portable-anymap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-portable-bitmap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-portable-graymap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-portable-pixmap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-sgi-rgb needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-sun-raster needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-xbitmap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: image/x-xpixmap needs 1 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: text/css needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: text/html needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: text/plain needs 2 filters >d [04/Jun/2012:07:42:03 -0500] add_printer_formats: RICOH-Aficio-CL1000N: 29 supported types >D [04/Jun/2012:07:42:03 -0500] Calling FindDeviceById(cups-RICOH-Aficio-CL1000N) >D [04/Jun/2012:07:42:03 -0500] Calling DeleteDevice(cups-RICOH-Aficio-CL1000N) >D [04/Jun/2012:07:42:03 -0500] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)' >D [04/Jun/2012:07:42:03 -0500] Using profile id of RICOH-Aficio-CL1000N-Gray.. >D [04/Jun/2012:07:42:03 -0500] Calling CreateProfile(RICOH-Aficio-CL1000N-Gray..,temp) >W [04/Jun/2012:07:42:03 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'RICOH-Aficio-CL1000N-Gray..' already exists >D [04/Jun/2012:07:42:03 -0500] Using profile id of RICOH-Aficio-CL1000N-CMYK.. >D [04/Jun/2012:07:42:03 -0500] Calling CreateProfile(RICOH-Aficio-CL1000N-CMYK..,temp) >W [04/Jun/2012:07:42:03 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'RICOH-Aficio-CL1000N-CMYK..' already exists >I [04/Jun/2012:07:42:03 -0500] Registering ICC color profiles for "RICOH-Aficio-CL1000N" >D [04/Jun/2012:07:42:03 -0500] Calling CreateDevice(cups-RICOH-Aficio-CL1000N,temp) >W [04/Jun/2012:07:42:03 -0500] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-RICOH-Aficio-CL1000N' already exists >D [04/Jun/2012:07:42:03 -0500] cupsdRegisterPrinter(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N)) >I [04/Jun/2012:07:42:03 -0500] Loading job cache file "/var/cache/cups/job.cache"... >D [04/Jun/2012:07:42:03 -0500] [Job 6] Loading from cache... >D [04/Jun/2012:07:42:03 -0500] [Job 7] Loading from cache... >D [04/Jun/2012:07:42:03 -0500] [Job 8] Loading from cache... >D [04/Jun/2012:07:42:03 -0500] [Job 9] Loading from cache... >D [04/Jun/2012:07:42:03 -0500] [Job 10] Loading from cache... >D [04/Jun/2012:07:42:03 -0500] [Job 11] Loading from cache... >D [04/Jun/2012:07:42:03 -0500] [Job 12] Loading from cache... >D [04/Jun/2012:07:42:03 -0500] [Job 13] Loading from cache... >D [04/Jun/2012:07:42:03 -0500] [Job 14] Loading from cache... >D [04/Jun/2012:07:42:03 -0500] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)") >D [04/Jun/2012:07:42:03 -0500] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)") >I [04/Jun/2012:07:42:03 -0500] Full reload complete. >D [04/Jun/2012:07:42:03 -0500] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)") >I [04/Jun/2012:07:42:03 -0500] Cleaning out old files in "/var/spool/cups/tmp"... >D [04/Jun/2012:07:42:03 -0500] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp") >I [04/Jun/2012:07:42:03 -0500] Cleaning out old files in "/var/cache/cups"... >D [04/Jun/2012:07:42:03 -0500] systemd_checkin: Matched existing listener /var/run/cups/cups.sock with fd 3... >D [04/Jun/2012:07:42:03 -0500] systemd_checkin: Adding new listener [v1.::] with fd 4... >D [04/Jun/2012:07:42:03 -0500] systemd_checkin: Matched browse (port 631) with fd 5:0.0.0.0... >d [04/Jun/2012:07:42:03 -0500] cupsdCreateProfile(job_id=0) = NULL >d [04/Jun/2012:07:42:03 -0500] cupsdStartListening: 4 Listeners >E [04/Jun/2012:07:42:03 -0500] Unable to bind socket for address [v1.::1]:631 - Address already in use. >I [04/Jun/2012:07:42:03 -0500] Listening to 127.0.0.1:631 on fd 13... >I [04/Jun/2012:07:42:03 -0500] Listening to /var/run/cups/cups.sock:631 on fd 3... >I [04/Jun/2012:07:42:03 -0500] Listening to [v1.::]:631 on fd 4... >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: CUPS_SERVER=/var/run/cups/cups.sock >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: CUPS_ENCRYPTION=IfRequested >d [04/Jun/2012:07:42:03 -0500] cupsdSetEnv: IPP_PORT=631 >I [04/Jun/2012:07:42:03 -0500] Resuming new connection processing... >d [04/Jun/2012:07:42:03 -0500] cupsdResumeListening: Setting input bits... >d [04/Jun/2012:07:42:03 -0500] cupsdAddSelect(fd=-1, read_cb=0x7fa311c92e30, write_cb=(nil), data=0x7fa313c7c750) >d [04/Jun/2012:07:42:03 -0500] cupsdAddSelect(fd=13, read_cb=0x7fa311c92e30, write_cb=(nil), data=0x7fa313c7c900) >d [04/Jun/2012:07:42:03 -0500] cupsdAddSelect(fd=3, read_cb=0x7fa311c92e30, write_cb=(nil), data=0x7fa313c7ca20) >d [04/Jun/2012:07:42:03 -0500] cupsdAddSelect(fd=4, read_cb=0x7fa311c92e30, write_cb=(nil), data=0x7fa313cf7330) >d [04/Jun/2012:07:42:03 -0500] cupsdAddSelect(fd=5, read_cb=0x7fa311c9e360, write_cb=(nil), data=(nil)) >D [04/Jun/2012:07:42:03 -0500] cupsdRegisterPrinter(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N)) >d [04/Jun/2012:07:42:03 -0500] cupsdAddSelect(fd=14, read_cb=0x7fa311c92c10, write_cb=(nil), data=(nil)) >D [04/Jun/2012:07:42:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" >d [04/Jun/2012:07:42:03 -0500] cupsdAddCert: Adding certificate for PID 0 >d [04/Jun/2012:07:42:03 -0500] cupsdAddCert: NumSystemGroups=2 >d [04/Jun/2012:07:42:03 -0500] cupsdAddEvent(event=server-started, dest=(nil)(), job=(nil)(0), text="Scheduler started in foreground.", ...) >D [04/Jun/2012:07:42:03 -0500] Discarding unused server-started event... >d [04/Jun/2012:07:42:03 -0500] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0 >D [04/Jun/2012:07:42:04 -0500] Report: clients=0 >D [04/Jun/2012:07:42:04 -0500] Report: jobs=9 >D [04/Jun/2012:07:42:04 -0500] Report: jobs-active=0 >D [04/Jun/2012:07:42:04 -0500] Report: printers=1 >D [04/Jun/2012:07:42:04 -0500] Report: printers-implicit=0 >D [04/Jun/2012:07:42:04 -0500] Report: stringpool-string-count=2054 >D [04/Jun/2012:07:42:04 -0500] Report: stringpool-alloc-bytes=8912 >D [04/Jun/2012:07:42:04 -0500] Report: stringpool-total-bytes=37232 >d [04/Jun/2012:07:42:04 -0500] select_timeout(0): 23454 seconds to expire subscription >d [04/Jun/2012:07:42:09 -0500] cupsdAcceptClient(lis=0x7fa313c7ca20(3)) Clients=0 >D [04/Jun/2012:07:42:09 -0500] cupsdAcceptClient: skipping getpeercon() >D [04/Jun/2012:07:42:09 -0500] cupsdAcceptClient: 16 from localhost (Domain) >d [04/Jun/2012:07:42:09 -0500] cupsdAddSelect(fd=16, read_cb=0x7fa311c8ffb0, write_cb=(nil), data=0x7fa313ce6db0) >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1 >D [04/Jun/2012:07:42:09 -0500] cupsdReadClient: 16 POST / HTTP/1.1 >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: uri = "/"... >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0 >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location /admin/conf Limit 7f >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location /admin Limit 7f >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location / Limit 7f >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: best = / >d [04/Jun/2012:07:42:09 -0500] cupsdAuthorize: con->uri="/", con->best=0x7fa313c7ccb0(/) >d [04/Jun/2012:07:42:09 -0500] cupsdAuthorize: Authorization="" >D [04/Jun/2012:07:42:09 -0500] cupsdAuthorize: No authentication data provided. >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: con->uri="/", con->best=0x7fa313c7ccb0(/) >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=627, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=627, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=598, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=598, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=561, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=561, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=518, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=518, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=503, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=503, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=480, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=480, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=457, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=457, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=439, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=439, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=416, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=416, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=398, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=398, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=376, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=376, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=357, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=357, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=340, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=340, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=323, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=323, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=302, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=302, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=281, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=281, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=264, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=264, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=234, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=234, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=212, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=212, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=191, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=191, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=164, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=164, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=147, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=147, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=129, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=129, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=99, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=99, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=73, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=73, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=56, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=56, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=30, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=30, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1 >D [04/Jun/2012:07:42:09 -0500] cupsdReadClient: 16 1.1 CUPS-Get-Default 1 >d [04/Jun/2012:07:42:09 -0500] cupsdProcessIPPRequest(0x7fa313ce6db0[16]): operation_id = 4001 >D [04/Jun/2012:07:42:09 -0500] CUPS-Get-Default >d [04/Jun/2012:07:42:09 -0500] get_default(0x7fa313ce6db0[16]) >d [04/Jun/2012:07:42:09 -0500] cupsdFindPolicyOp(p=0x7fa313c7d070, op=4001(CUPS-Get-Default)) >d [04/Jun/2012:07:42:09 -0500] cupsdFindPolicyOp: Found wildcard match... >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: con->uri="/", con->best=0x7fa313c81b70((null)) >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: op=0(0x0000) >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >d [04/Jun/2012:07:42:09 -0500] add_printer_state_reasons(0x7fa313ce6db0[16], 0x7fa313c9dcc0[RICOH-Aficio-CL1000N]) >d [04/Jun/2012:07:42:09 -0500] printer-uri-supported="ipp://localhost:631/printers/RICOH-Aficio-CL1000N" >d [04/Jun/2012:07:42:09 -0500] copy_attrs(to=0x7fa313ceb3f0, from=0x7fa313cf6350, ra=0x7fa313ceb4c0, group=0, quickcopy=0) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313ca1ef0[printer-name,4,42]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313ca2a90[printer-location,4,41]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313ca2ad0[printer-info,4,41]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313ca2e60[auth-info-required,4,44]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313ca2ea0[job-sheets-default,4,42]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313c8f550[device-uri,4,45]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313ca3d30[marker-colors,4,42]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313ca3db0[marker-levels,4,21]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313ca3e30[marker-names,4,42]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313ca3eb0[marker-types,4,44]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313c915e0[copies-default,4,21]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313c915a0[document-format-default,4,49]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313c91510[job-hold-until-default,4,44]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313c914a0[job-priority-default,4,21]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313c91460[number-up-default,4,21]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313c91380[orientation-requested-default,4,13]) >d [04/Jun/2012:07:42:09 -0500] copy_attrs(to=0x7fa313ceb3f0, from=0x7fa313ca99a0, ra=0x7fa313ceb4c0, group=0, quickcopy=0) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313c90ba0[printer-make-and-model,4,41]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313c90c70[media-col-default,4,34]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313cda310[printer-commands,4,44]) >d [04/Jun/2012:07:42:09 -0500] copy_attribute(0x7fa313ceb3f0, 0x7fa313cda520[finishings-default,4,23]) >d [04/Jun/2012:07:42:09 -0500] copy_attrs(to=0x7fa313ceb3f0, from=0x7fa313c95500, ra=0x7fa313ceb4c0, group=0, quickcopy=-2147483648) >D [04/Jun/2012:07:42:09 -0500] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost >d [04/Jun/2012:07:42:09 -0500] cupsdProcessIPPRequest: ippLength(response)=1486 >d [04/Jun/2012:07:42:09 -0500] cupsdAddSelect(fd=16, read_cb=0x7fa311c8ffb0, write_cb=0x7fa311c8f8f0, data=0x7fa313ce6db0) >d [04/Jun/2012:07:42:09 -0500] select_timeout(0): 301 seconds to timeout a client connection >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(0), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb3f0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdAddSelect(fd=16, read_cb=0x7fa311c8ffb0, write_cb=(nil), data=0x7fa313ce6db0) >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1 >D [04/Jun/2012:07:42:09 -0500] cupsdReadClient: 16 POST /printers/RICOH-Aficio-CL1000N HTTP/1.1 >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: uri = "/printers/RICOH-Aficio-CL1000N"... >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0 >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location /admin/conf Limit 7f >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location /admin Limit 7f >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location / Limit 7f >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: best = / >d [04/Jun/2012:07:42:09 -0500] cupsdAuthorize: con->uri="/printers/RICOH-Aficio-CL1000N", con->best=0x7fa313c7ccb0(/) >d [04/Jun/2012:07:42:09 -0500] cupsdAuthorize: Authorization="" >D [04/Jun/2012:07:42:09 -0500] cupsdAuthorize: No authentication data provided. >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: con->uri="/printers/RICOH-Aficio-CL1000N", con->best=0x7fa313c7ccb0(/) >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=373, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=373, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=344, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=344, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=307, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=307, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=242, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=242, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=213, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=213, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=173, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=173, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=129, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=129, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=113, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=113, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=94, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=94, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=68, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=68, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=47, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=47, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=28, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=28, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=19, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=19, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1 >D [04/Jun/2012:07:42:09 -0500] cupsdReadClient: 16 1.1 Create-Job 1 >d [04/Jun/2012:07:42:09 -0500] cupsdProcessIPPRequest(0x7fa313ce6db0[16]): operation_id = 0005 >D [04/Jun/2012:07:42:09 -0500] Create-Job ipp://localhost:631/printers/RICOH-Aficio-CL1000N >d [04/Jun/2012:07:42:09 -0500] create_job(0x7fa313ce6db0[16], ipp://localhost:631/printers/RICOH-Aficio-CL1000N) >d [04/Jun/2012:07:42:09 -0500] add_job(0x7fa313ce6db0[16], 0x7fa313c9dcc0(RICOH-Aficio-CL1000N), (nil)(none/none)) >d [04/Jun/2012:07:42:09 -0500] cupsdFindPolicyOp(p=0x7fa313c7d070, op=5(Create-Job)) >d [04/Jun/2012:07:42:09 -0500] cupsdFindPolicyOp: Found exact match... >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: con->uri="/printers/RICOH-Aficio-CL1000N", con->best=0x7fa313c7dc90((null)) >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: op=5(Create-Job) >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >d [04/Jun/2012:07:42:09 -0500] check_quotas(0x7fa313ce6db0[16], 0x7fa313c9dcc0[RICOH-Aficio-CL1000N]) >D [04/Jun/2012:07:42:09 -0500] add_job: setting context of job to UNKNOWN SL >D [04/Jun/2012:07:42:09 -0500] cupsdMarkDirty(----J-) >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" >D [04/Jun/2012:07:42:09 -0500] add_job: requesting-user-name="root" >I [04/Jun/2012:07:42:09 -0500] [Job 15] Adding start banner page "none". >d [04/Jun/2012:07:42:09 -0500] copy_banner(con=0x7fa313ce6db0[16], job=0x7fa313ceb4c0[15], name="none") >d [04/Jun/2012:07:42:09 -0500] add_job_state_reasons(0x7fa313ce6db0[16], 15) >d [04/Jun/2012:07:42:09 -0500] cupsdAddEvent(event=job-created, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=0x7fa313ceb4c0(15), text="Job created.", ...) >D [04/Jun/2012:07:42:09 -0500] Discarding unused job-created event... >I [04/Jun/2012:07:42:09 -0500] [Job 15] Queued on "RICOH-Aficio-CL1000N" by "root". >D [04/Jun/2012:07:42:09 -0500] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/RICOH-Aficio-CL1000N) from localhost >d [04/Jun/2012:07:42:09 -0500] cupsdProcessIPPRequest: ippLength(response)=182 >d [04/Jun/2012:07:42:09 -0500] cupsdAddSelect(fd=16, read_cb=0x7fa311c8ffb0, write_cb=0x7fa311c8f8f0, data=0x7fa313ce6db0) >d [04/Jun/2012:07:42:09 -0500] select_timeout(0): 31 seconds to write dirty config/state files >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb8b0(0), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb8b0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb8b0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb8b0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb8b0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb8b0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceb8b0(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdAddSelect(fd=16, read_cb=0x7fa311c8ffb0, write_cb=(nil), data=0x7fa313ce6db0) >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1 >D [04/Jun/2012:07:42:09 -0500] cupsdReadClient: 16 POST /printers/RICOH-Aficio-CL1000N HTTP/1.1 >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: uri = "/printers/RICOH-Aficio-CL1000N"... >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0 >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location /admin/conf Limit 7f >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location /admin Limit 7f >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: Location / Limit 7f >d [04/Jun/2012:07:42:09 -0500] cupsdFindBest: best = / >d [04/Jun/2012:07:42:09 -0500] cupsdAuthorize: con->uri="/printers/RICOH-Aficio-CL1000N", con->best=0x7fa313c7ccb0(/) >d [04/Jun/2012:07:42:09 -0500] cupsdAuthorize: Authorization="" >D [04/Jun/2012:07:42:09 -0500] cupsdAuthorize: No authentication data provided. >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: con->uri="/printers/RICOH-Aficio-CL1000N", con->best=0x7fa313c7ccb0(/) >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=286, con->http.state=6 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=284, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=257, con->http.state=6 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=255, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=220, con->http.state=6 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=218, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=155, con->http.state=6 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=153, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=140, con->http.state=6 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=138, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=111, con->http.state=6 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=109, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=66, con->http.state=6 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=64, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=22, con->http.state=6 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=20, con->file=-1 >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=3, con->http.state=6 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=1, con->file=-1 >D [04/Jun/2012:07:42:09 -0500] cupsdReadClient: 16 1.1 Send-Document 1 >d [04/Jun/2012:07:42:09 -0500] cupsdProcessIPPRequest(0x7fa313ce6db0[16]): operation_id = 0006 >D [04/Jun/2012:07:42:09 -0500] Send-Document ipp://localhost:631/printers/RICOH-Aficio-CL1000N >d [04/Jun/2012:07:42:09 -0500] send_document(0x7fa313ce6db0[16], ipp://localhost:631/printers/RICOH-Aficio-CL1000N) >d [04/Jun/2012:07:42:09 -0500] validate_user(job=15, con=16, owner="root", username=0x7fffed1f6be6, userlen=1024) >d [04/Jun/2012:07:42:09 -0500] cupsdFindPolicyOp(p=0x7fa313c7d070, op=6(Send-Document)) >d [04/Jun/2012:07:42:09 -0500] cupsdFindPolicyOp: Found exact match... >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: con->uri="/printers/RICOH-Aficio-CL1000N", con->best=0x7fa313c7de60((null)) >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: owner="root" >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=2 >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: op=6(Send-Document) >d [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >D [04/Jun/2012:07:42:09 -0500] cupsdIsAuthorized: requesting-user-name="root" >D [04/Jun/2012:07:42:09 -0500] [Job 15] Auto-typing file... >D [04/Jun/2012:07:42:09 -0500] [Job 15] Request file type is text/plain. >d [04/Jun/2012:07:42:09 -0500] add_file(con=0x7fa313ce6db0[16], job=15, filetype=text/plain, compression=0) >D [04/Jun/2012:07:42:09 -0500] cupsdMarkDirty(----J-) >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >I [04/Jun/2012:07:42:09 -0500] [Job 15] File of type text/plain queued by "root". >I [04/Jun/2012:07:42:09 -0500] [Job 15] Adding end banner page "none". >d [04/Jun/2012:07:42:09 -0500] copy_banner(con=(nil)[-1], job=0x7fa313ceb4c0[15], name="none") >D [04/Jun/2012:07:42:09 -0500] cupsdMarkDirty(----J-) >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >d [04/Jun/2012:07:42:09 -0500] add_job_state_reasons(0x7fa313ce6db0[16], 15) >d [04/Jun/2012:07:42:09 -0500] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 >d [04/Jun/2012:07:42:09 -0500] start_job(job=0x7fa313ceb4c0(15), printer=0x7fa313c9dcc0(RICOH-Aficio-CL1000N)) >d [04/Jun/2012:07:42:09 -0500] cupsdSetJobState(job=0x7fa313ceb4c0(15), state=3, newstate=5, action=0, message="(null)") >D [04/Jun/2012:07:42:09 -0500] cupsdMarkDirty(----J-) >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >d [04/Jun/2012:07:42:09 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="%s "%s" state changed to %s.", ...) >d [04/Jun/2012:07:42:09 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313cebc20(printer-state-changed)) >d [04/Jun/2012:07:42:09 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313cec9d0(printer-state-changed)) >D [04/Jun/2012:07:42:09 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >d [04/Jun/2012:07:42:09 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-paused" >d [04/Jun/2012:07:42:09 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-cups-remote-pending,cups-remote-pending-held,cups-remote-processing,cups-remote-stopped,cups-remote-canceled,cups-remote-aborted,cups-remote-completed" >d [04/Jun/2012:07:42:09 -0500] cupsdCreateProfile(job_id=15) = NULL >d [04/Jun/2012:07:42:09 -0500] cupsdContinueJob(job=0x7fa313ceb4c0(15)): current_file=0, num_files=1 >D [04/Jun/2012:07:42:09 -0500] [Job 15] job-sheets=none,none >D [04/Jun/2012:07:42:09 -0500] [Job 15] argv[0]="RICOH-Aficio-CL1000N" >D [04/Jun/2012:07:42:09 -0500] [Job 15] argv[1]="15" >D [04/Jun/2012:07:42:09 -0500] [Job 15] argv[2]="root" >D [04/Jun/2012:07:42:09 -0500] [Job 15] argv[3]="testdocumentforprinting.txt" >D [04/Jun/2012:07:42:09 -0500] [Job 15] argv[4]="1" >D [04/Jun/2012:07:42:09 -0500] [Job 15] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:2353a4bf-f888-3323-682b-ebf246949790 job-originating-host-name=localhost time-at-creation=1338813729 time-at-processing=1338813729 AP_D_InputSlot=" >D [04/Jun/2012:07:42:09 -0500] [Job 15] argv[6]="/var/spool/cups/d00015-001" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[0]="CUPS_CACHEDIR=/var/cache/cups" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[1]="CUPS_DATADIR=/usr/share/cups" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[2]="CUPS_DOCROOT=/usr/share/cups/www" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[6]="CUPS_SERVERROOT=/etc/cups" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[7]="CUPS_STATEDIR=/var/run/cups" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[8]="HOME=/var/spool/cups/tmp" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[10]="SERVER_ADMIN=root@mcase820" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[11]="SOFTWARE=CUPS/1.5.2" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[12]="TMPDIR=/var/spool/cups/tmp" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[13]="USER=root" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[15]="CUPS_ENCRYPTION=IfRequested" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[16]="IPP_PORT=631" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[17]="CHARSET=utf-8" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[18]="LANG=en_US.UTF-8" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[19]="PPD=/etc/cups/ppd/RICOH-Aficio-CL1000N.ppd" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[20]="RIP_MAX_CACHE=128m" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[21]="CONTENT_TYPE=text/plain" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[22]="DEVICE_URI=dnssd://RICOH%20Aficio%20CL1000N._pdl-datastream._tcp.local/" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[23]="PRINTER_INFO=RICOH Aficio CL1000N" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[24]="PRINTER_LOCATION=" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[25]="PRINTER=RICOH-Aficio-CL1000N" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[26]="PRINTER_STATE_REASONS=none" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[27]="CUPS_FILETYPE=document" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[28]="FINAL_CONTENT_TYPE=printer/RICOH-Aficio-CL1000N" >D [04/Jun/2012:07:42:09 -0500] [Job 15] envp[29]="AUTH_I****" >d [04/Jun/2012:07:42:09 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-cups-missing-filter-warning,cups-insecure-filter-warning" >d [04/Jun/2012:07:42:09 -0500] RICOH-Aficio-CL1000N: File "/usr/lib/cups/filter/texttopaps" permissions OK (040755/uid=0/gid=0). >d [04/Jun/2012:07:42:09 -0500] cupsdStartProcess(command="/usr/lib/cups/filter/texttopaps", argv=0x7fa313cdd850, envp=0x7fffed1f3350, infd=-1, outfd=24, errfd=18, backfd=19, sidefd=21, root=0, profile=(nil), job=0x7fa313ceb4c0(15), pid=0x7fa313ceb594) = 22025 >I [04/Jun/2012:07:42:09 -0500] [Job 15] Started filter /usr/lib/cups/filter/texttopaps (PID 22025) >d [04/Jun/2012:07:42:09 -0500] RICOH-Aficio-CL1000N: File "/usr/lib/cups/filter/pstops" permissions OK (040755/uid=0/gid=0). >d [04/Jun/2012:07:42:09 -0500] cupsdStartProcess(command="/usr/lib/cups/filter/pstops", argv=0x7fa313cdd850, envp=0x7fffed1f3350, infd=23, outfd=26, errfd=18, backfd=19, sidefd=21, root=0, profile=(nil), job=0x7fa313ceb4c0(15), pid=0x7fa313ceb598) = 22026 >I [04/Jun/2012:07:42:09 -0500] [Job 15] Started filter /usr/lib/cups/filter/pstops (PID 22026) >d [04/Jun/2012:07:42:09 -0500] RICOH-Aficio-CL1000N: File "/usr/lib/cups/filter/foomatic-rip" permissions OK (040755/uid=0/gid=0). >d [04/Jun/2012:07:42:09 -0500] cupsdStartProcess(command="/usr/lib/cups/filter/foomatic-rip", argv=0x7fa313cdd850, envp=0x7fffed1f3350, infd=25, outfd=24, errfd=18, backfd=19, sidefd=21, root=0, profile=(nil), job=0x7fa313ceb4c0(15), pid=0x7fa313ceb59c) = 22027 >I [04/Jun/2012:07:42:09 -0500] [Job 15] Started filter /usr/lib/cups/filter/foomatic-rip (PID 22027) >d [04/Jun/2012:07:42:09 -0500] RICOH-Aficio-CL1000N: File "/usr/lib/cups/backend/dnssd" permissions OK (040755/uid=0/gid=0). >d [04/Jun/2012:07:42:09 -0500] cupsdStartProcess(command="/usr/lib/cups/backend/dnssd", argv=0x7fa313cdd850, envp=0x7fffed1f3350, infd=23, outfd=-1, errfd=18, backfd=20, sidefd=22, root=1, profile=(nil), job=0x7fa313ceb4c0(15), pid=0x7fa313ceb5e8) = 22028 >I [04/Jun/2012:07:42:09 -0500] [Job 15] Started backend /usr/lib/cups/backend/dnssd (PID 22028) >d [04/Jun/2012:07:42:09 -0500] cupsdAddSelect(fd=17, read_cb=0x7fa311cb0f40, write_cb=(nil), data=0x7fa313ceb4c0) >d [04/Jun/2012:07:42:09 -0500] cupsdAddEvent(event=job-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=0x7fa313ceb4c0(15), text="Job #%d started.", ...) >D [04/Jun/2012:07:42:09 -0500] Discarding unused job-state-changed event... >D [04/Jun/2012:07:42:09 -0500] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/RICOH-Aficio-CL1000N) from localhost >d [04/Jun/2012:07:42:09 -0500] cupsdProcessIPPRequest: ippLength(response)=174 >d [04/Jun/2012:07:42:09 -0500] cupsdAddSelect(fd=16, read_cb=0x7fa311c8ffb0, write_cb=0x7fa311c8f8f0, data=0x7fa313ce6db0) >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceaf60(0), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceaf60(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceaf60(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceaf60(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceaf60(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceaf60(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdWriteClient(con=0x7fa313ce6db0(16)) response=0x7fa313ceaf60(2), file=-1 pipe_pid=0 state=7 >d [04/Jun/2012:07:42:09 -0500] cupsdAddSelect(fd=16, read_cb=0x7fa311c8ffb0, write_cb=(nil), data=0x7fa313ce6db0) >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files" >d [04/Jun/2012:07:42:09 -0500] cupsdReadClient(con=0x7fa313ce6db0(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1 >D [04/Jun/2012:07:42:09 -0500] cupsdReadClient: 16 WAITING Closing on EOF >D [04/Jun/2012:07:42:09 -0500] cupsdCloseClient: 16 >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >d [04/Jun/2012:07:42:09 -0500] cupsdRemoveSelect(fd=16) >D [04/Jun/2012:07:42:09 -0500] [Job 15] Getting input from file >D [04/Jun/2012:07:42:09 -0500] [Job 15] foomatic-rip version 4.0.13.247 running... >D [04/Jun/2012:07:42:09 -0500] [Job 15] Parsing PPD file ... >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option ColorSpace >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option Option1 >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option Option5 >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option Resolution >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RIPaperPolicy >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option PageSize >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option ImageableArea >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option PaperDimension >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option InputSlot >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option LeadingEdge >D [04/Jun/2012:07:42:09 -0500] [Job 15] Resolving "RICOH Aficio CL1000N._pdl-datastream._tcp.local"... >D [04/Jun/2012:07:42:09 -0500] [Job 15] STATE: +connecting-to-device >d [04/Jun/2012:07:42:09 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="+connecting-to-device" >D [04/Jun/2012:07:42:09 -0500] [Job 15] Resolving "RICOH Aficio CL1000N", regtype="_pdl-datastream._tcp", domain="local."... >d [04/Jun/2012:07:42:09 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="Printer "%s" state changed.", ...) >d [04/Jun/2012:07:42:09 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313ced830(printer-state-changed)) >d [04/Jun/2012:07:42:09 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313ced860(printer-state-changed)) >D [04/Jun/2012:07:42:09 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:09 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option Duplex >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option Collate >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RIPrintMode >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option ColorModel >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RPSBitsPerPixel >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RPSRGBcorrect >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RPSColorRendDict >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RPSDitherType >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RPSBlackMode >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RPSBlackOverPrint >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option Rcmyksimulation >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option MediaType >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RIWatermark >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RIWMText >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RIwmFont >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RIwmSize >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RIwmAngle >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option RIwmTextStyle >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option ScreenProc >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option Font >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option ColorSep >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option JobType >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option LockedPrintPassword >D [04/Jun/2012:07:42:09 -0500] [Job 15] Added option UserCode >D [04/Jun/2012:07:42:09 -0500] [Job 15] >D [04/Jun/2012:07:42:09 -0500] [Job 15] Parameter Summary >D [04/Jun/2012:07:42:09 -0500] [Job 15] ----------------- >D [04/Jun/2012:07:42:09 -0500] [Job 15] >D [04/Jun/2012:07:42:09 -0500] [Job 15] Spooler: cups >D [04/Jun/2012:07:42:09 -0500] [Job 15] Printer: RICOH-Aficio-CL1000N >D [04/Jun/2012:07:42:09 -0500] [Job 15] Shell: /bin/bash >D [04/Jun/2012:07:42:09 -0500] [Job 15] PPD file: /etc/cups/ppd/RICOH-Aficio-CL1000N.ppd >D [04/Jun/2012:07:42:09 -0500] [Job 15] ATTR file: >D [04/Jun/2012:07:42:09 -0500] [Job 15] Printer model: Ricoh Aficio CL1000N PS >D [04/Jun/2012:07:42:09 -0500] [Job 15] Job title: testdocumentforprinting.txt >D [04/Jun/2012:07:42:09 -0500] [Job 15] File(s) to be printed: >D [04/Jun/2012:07:42:09 -0500] [Job 15] <STDIN> >D [04/Jun/2012:07:42:09 -0500] [Job 15] >D [04/Jun/2012:07:42:09 -0500] [Job 15] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts >D [04/Jun/2012:07:42:09 -0500] [Job 15] Printing system options: >D [04/Jun/2012:07:42:09 -0500] [Job 15] Pondering option 'finishings=3' >D [04/Jun/2012:07:42:09 -0500] [Job 15] Unknown option finishings=3. >D [04/Jun/2012:07:42:09 -0500] [Job 15] Pondering option 'number-up=1' >D [04/Jun/2012:07:42:09 -0500] [Job 15] Unknown option number-up=1. >D [04/Jun/2012:07:42:09 -0500] [Job 15] Pondering option 'job-uuid=urn:uuid:2353a4bf-f888-3323-682b-ebf246949790' >D [04/Jun/2012:07:42:09 -0500] [Job 15] Unknown option job-uuid=urn:uuid:2353a4bf-f888-3323-682b-ebf246949790. >D [04/Jun/2012:07:42:09 -0500] [Job 15] Pondering option 'job-originating-host-name=localhost' >D [04/Jun/2012:07:42:09 -0500] [Job 15] Unknown option job-originating-host-name=localhost. >D [04/Jun/2012:07:42:09 -0500] [Job 15] Pondering option 'time-at-creation=1338813729' >D [04/Jun/2012:07:42:09 -0500] [Job 15] Unknown option time-at-creation=1338813729. >D [04/Jun/2012:07:42:09 -0500] [Job 15] Pondering option 'time-at-processing=1338813729' >D [04/Jun/2012:07:42:09 -0500] [Job 15] Unknown option time-at-processing=1338813729. >D [04/Jun/2012:07:42:09 -0500] [Job 15] Pondering option 'AP_D_InputSlot' >D [04/Jun/2012:07:42:09 -0500] [Job 15] Unknown boolean option "AP_D_InputSlot". >D [04/Jun/2012:07:42:09 -0500] [Job 15] Options from the PPD file: >D [04/Jun/2012:07:42:09 -0500] [Job 15] >D [04/Jun/2012:07:42:09 -0500] [Job 15] ================================================ >D [04/Jun/2012:07:42:09 -0500] [Job 15] >D [04/Jun/2012:07:42:09 -0500] [Job 15] File: <STDIN> >D [04/Jun/2012:07:42:09 -0500] [Job 15] >D [04/Jun/2012:07:42:09 -0500] [Job 15] ================================================ >D [04/Jun/2012:07:42:09 -0500] [Job 15] >d [04/Jun/2012:07:42:09 -0500] process_children() >d [04/Jun/2012:07:42:09 -0500] cupsdFinishProcess(pid=22025, name=0x7fffed204e50, namelen=1024, job_id=0x7fffed204e4c(15)) = "/usr/lib/cups/filter/texttopaps" >D [04/Jun/2012:07:42:09 -0500] PID 22025 (/usr/lib/cups/filter/texttopaps) exited with no errors. >d [04/Jun/2012:07:42:09 -0500] select_timeout(-1): 31 seconds to write dirty config/state files >D [04/Jun/2012:07:42:09 -0500] [Job 15] Page = 612x792; 12,12 to 600,780 >D [04/Jun/2012:07:42:09 -0500] [Job 15] slow_collate=0, slow_duplex=0, slow_order=0 >D [04/Jun/2012:07:42:09 -0500] [Job 15] Before copy_comments - %!PS-Adobe-3.0 >D [04/Jun/2012:07:42:09 -0500] [Job 15] %!PS-Adobe-3.0 >D [04/Jun/2012:07:42:09 -0500] [Job 15] %cupsRotation: 0 >D [04/Jun/2012:07:42:09 -0500] [Job 15] %%Title: testdocumentforprinting.txt >D [04/Jun/2012:07:42:09 -0500] [Job 15] %%Creator: paps version 0.6.7 by Dov Grobgeld >D [04/Jun/2012:07:42:09 -0500] [Job 15] %%Pages: (atend) >D [04/Jun/2012:07:42:09 -0500] [Job 15] %%BoundingBox: 0 0 612 792 >D [04/Jun/2012:07:42:09 -0500] [Job 15] %%For: root >D [04/Jun/2012:07:42:09 -0500] [Job 15] %%Orientation: Portrait >D [04/Jun/2012:07:42:09 -0500] [Job 15] %%EndComments >D [04/Jun/2012:07:42:09 -0500] [Job 15] Before copy_prolog - %%BeginProlog >D [04/Jun/2012:07:42:09 -0500] [Job 15] Before copy_setup - %%BeginSetup >D [04/Jun/2012:07:42:09 -0500] [Job 15] Filetype: PostScript >D [04/Jun/2012:07:42:09 -0500] [Job 15] Before page loop - %%Page: 1 1 >D [04/Jun/2012:07:42:09 -0500] [Job 15] Copying page 1... >D [04/Jun/2012:07:42:09 -0500] [Job 15] Reading PostScript input ... >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> This document is DSC-conforming! >D [04/Jun/2012:07:42:09 -0500] [Job 15] pagew = 588.0, pagel = 768.0 >D [04/Jun/2012:07:42:09 -0500] [Job 15] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792 >D [04/Jun/2012:07:42:09 -0500] [Job 15] PageLeft = 12.0, PageRight = 600.0 >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found %RBINumCopies: 1 >D [04/Jun/2012:07:42:09 -0500] [Job 15] PageTop = 780.0, PageBottom = 12.0 >D [04/Jun/2012:07:42:09 -0500] [Job 15] PageWidth = 612.0, PageLength = 792.0 >D [04/Jun/2012:07:42:09 -0500] [Job 15] >D [04/Jun/2012:07:42:09 -0500] [Job 15] ----------- >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginProlog >D [04/Jun/2012:07:42:09 -0500] [Job 15] Wrote 1 pages... >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%EndProlog >D [04/Jun/2012:07:42:09 -0500] [Job 15] >D [04/Jun/2012:07:42:09 -0500] [Job 15] ----------- >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginSetup >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *Option1 None >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: Option1=None >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *Option5 False >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: Option5=False >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RIPaperPolicy PromptUser >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RIPaperPolicy=PromptUser >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *PageSize Letter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: PageSize=Letter >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RPSBitsPerPixel 1BitsPerPixel >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RPSBitsPerPixel=1BitsPerPixel >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RIPrintMode 0rhit >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RIPrintMode=0rhit >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *Duplex None >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: Duplex=None >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *ColorModel CMYK >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: ColorModel=CMYK >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RPSRGBcorrect DetailBright >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RPSRGBcorrect=DetailBright >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RPSColorRendDict Auto >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RPSColorRendDict=Auto >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RPSDitherType Auto >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RPSDitherType=Auto >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RPSBlackMode GRAY >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RPSBlackMode=GRAY >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RPSBlackOverPrint False >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RPSBlackOverPrint=False >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *Rcmyksimulation Off >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: Rcmyksimulation=Off >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *Collate False >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: Collate=False >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *MediaType Plain >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: MediaType=Plain >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *LockedPrintPassword None >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: LockedPrintPassword=None >D [04/Jun/2012:07:42:09 -0500] [Job 15] Setting option >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *JobType Normal >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: JobType=Normal >D [04/Jun/2012:07:42:09 -0500] [Job 15] Setting option >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %% FoomaticRIPOptionSetting: JobType=Normal >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: JobType=Normal >D [04/Jun/2012:07:42:09 -0500] [Job 15] Setting option >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *UserCode None >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: UserCode=None >D [04/Jun/2012:07:42:09 -0500] [Job 15] Setting option >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RIWatermark Off >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RIWatermark=Off >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RIWMText Confidential >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RIWMText=Confidential >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RIwmFont HelveticaB >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RIwmFont=HelveticaB >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RIwmSize 36 >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RIwmSize=36 >d [04/Jun/2012:07:42:09 -0500] process_children() >d [04/Jun/2012:07:42:09 -0500] cupsdFinishProcess(pid=22026, name=0x7fffed204e50, namelen=1024, job_id=0x7fffed204e4c(15)) = "/usr/lib/cups/filter/pstops" >D [04/Jun/2012:07:42:09 -0500] PID 22026 (/usr/lib/cups/filter/pstops) exited with no errors. >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RIwmAngle 45Deg >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RIwmAngle=45Deg >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginFeature: *RIwmTextStyle Gray >D [04/Jun/2012:07:42:09 -0500] [Job 15] Option: RIwmTextStyle=Gray >D [04/Jun/2012:07:42:09 -0500] [Job 15] --> Option will be set by PostScript interpreter >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%EndSetup >D [04/Jun/2012:07:42:09 -0500] [Job 15] Inserting PostScript code for CUPS' page accounting >D [04/Jun/2012:07:42:09 -0500] [Job 15] >D [04/Jun/2012:07:42:09 -0500] [Job 15] ----------- >D [04/Jun/2012:07:42:09 -0500] [Job 15] New page: %%Page: 1 1 >D [04/Jun/2012:07:42:09 -0500] [Job 15] >D [04/Jun/2012:07:42:09 -0500] [Job 15] Found: %%BeginPageSetup >D [04/Jun/2012:07:42:09 -0500] [Job 15] Inserting option code into "PageSetup" section. >D [04/Jun/2012:07:42:09 -0500] [Job 15] Flushing FIFO. >D [04/Jun/2012:07:42:10 -0500] [Job 15] Resolved as "socket://192.168.1.9:9100"... >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -connecting-to-device,offline-report >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-connecting-to-device,offline-report" >D [04/Jun/2012:07:42:10 -0500] [Job 15] Executing backend "/usr/lib/cups/backend/socket"... >d [04/Jun/2012:07:42:10 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="Printer "%s" state changed.", ...) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313cdda50(printer-state-changed)) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313ce7330(printer-state-changed)) >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: +connecting-to-device >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="+connecting-to-device" >D [04/Jun/2012:07:42:10 -0500] [Job 15] Looking up "192.168.1.9"... >d [04/Jun/2012:07:42:10 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="Printer "%s" state changed.", ...) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313ce76b0(printer-state-changed)) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313ce7a30(printer-state-changed)) >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] >D [04/Jun/2012:07:42:10 -0500] [Job 15] Starting renderer with command: "printf "%%!PS-Adobe-3.0\n%%%% %%%%\nmark\n() () (20`date +%y%m%d%R | sed 's/://'`) {setuserinfo} stopped\ncleartomark\n"%%%%; cat;" >D [04/Jun/2012:07:42:10 -0500] [Job 15] Starting process "kid3" (generation 1) >d [04/Jun/2012:07:42:10 -0500] [Job 15] hrDeviceDesc="RICOH Aficio CL1000N" >D [04/Jun/2012:07:42:10 -0500] [Job 15] >D [04/Jun/2012:07:42:10 -0500] [Job 15] Closing renderer >D [04/Jun/2012:07:42:10 -0500] [Job 15] Starting process "kid4" (generation 2) >D [04/Jun/2012:07:42:10 -0500] [Job 15] Starting process "renderer" (generation 2) >D [04/Jun/2012:07:42:10 -0500] [Job 15] JCL: %-12345X@PJL >D [04/Jun/2012:07:42:10 -0500] [Job 15] <job data> >D [04/Jun/2012:07:42:10 -0500] [Job 15] >d [04/Jun/2012:07:42:10 -0500] [Job 15] prtMarkerColorantValue.1.1 = "black" >D [04/Jun/2012:07:42:10 -0500] [Job 15] renderer exited with status 0 >D [04/Jun/2012:07:42:10 -0500] [Job 15] kid4 exited with status 0 >D [04/Jun/2012:07:42:10 -0500] [Job 15] kid3 finished >D [04/Jun/2012:07:42:10 -0500] [Job 15] kid3 exited with status 0 >D [04/Jun/2012:07:42:10 -0500] [Job 15] >D [04/Jun/2012:07:42:10 -0500] [Job 15] Closing foomatic-rip. >d [04/Jun/2012:07:42:10 -0500] [Job 15] prtMarkerColorantValue.1.2 = "other" >d [04/Jun/2012:07:42:10 -0500] process_children() >d [04/Jun/2012:07:42:10 -0500] cupsdFinishProcess(pid=22027, name=0x7fffed204e50, namelen=1024, job_id=0x7fffed204e4c(15)) = "/usr/lib/cups/filter/foomatic-rip" >D [04/Jun/2012:07:42:10 -0500] PID 22027 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. >d [04/Jun/2012:07:42:10 -0500] [Job 15] prtMarkerColorantValue.1.3 = "cyan" >d [04/Jun/2012:07:42:10 -0500] [Job 15] prtMarkerColorantValue.1.4 = "magenta" >d [04/Jun/2012:07:42:10 -0500] [Job 15] prtMarkerColorantValue.1.5 = "yellow" >D [04/Jun/2012:07:42:10 -0500] [Job 15] ATTR: marker-colors=#000000,none,#00FFFF,#FF00FF,#FFFF00 >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(P-----) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] ATTR: marker-names="Black Toner","Waste Toner","Cyan Toner","Magenta Toner","Yellow Toner" >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(P-----) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] ATTR: marker-types=toner,wasteToner,toner,toner,toner >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(P-----) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] ATTR: marker-levels=-1,-1,-1,-1,-1 >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(P-----) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] new_supply_state=0, change_state=ffff >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -developer-low-report >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-developer-low-report" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -developer-empty-warning >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-developer-empty-warning" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -marker-supply-low-report >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-marker-supply-low-report" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -marker-supply-empty-warning >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-marker-supply-empty-warning" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -opc-near-eol-report >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-opc-near-eol-report" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -opc-life-over-warning >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-opc-life-over-warning" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -toner-low-report >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-toner-low-report" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -toner-empty-warning >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-toner-empty-warning" >d [04/Jun/2012:07:42:10 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="Printer "%s" state changed.", ...) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313ce7ee0(printer-state-changed)) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313ce8220(printer-state-changed)) >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] new_state=0, change_state=ffff >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -media-low-report >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-media-low-report" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -media-empty-warning >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-media-empty-warning" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -door-open-report >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-door-open-report" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -media-jam-warning >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-media-jam-warning" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -input-tray-missing-warning >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-input-tray-missing-warning" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -output-tray-missing-warning >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-output-tray-missing-warning" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -marker-supply-missing-warning >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-marker-supply-missing-warning" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -output-area-almost-full-report >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-output-area-almost-full-report" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -output-area-full-warning >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-output-area-full-warning" >D [04/Jun/2012:07:42:10 -0500] [Job 15] backendWaitLoop(snmp_fd=5, addr=0x7fae9e9f99d8, side_cb=0x7fae9d96eef0) >D [04/Jun/2012:07:42:10 -0500] [Job 15] Connecting to 192.168.1.9:9100 >I [04/Jun/2012:07:42:10 -0500] [Job 15] Connecting to printer. >D [04/Jun/2012:07:42:10 -0500] [Job 15] Set job-printer-state-message to "Connecting to printer.", current level=INFO >d [04/Jun/2012:07:42:10 -0500] cupsdAddEvent(event=job-progress, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=0x7fa313ceb4c0(15), text="%s", ...) >D [04/Jun/2012:07:42:10 -0500] Discarding unused job-progress event... >d [04/Jun/2012:07:42:10 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="Printer "%s" state changed.", ...) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313ce85d0(printer-state-changed)) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313ce8950(printer-state-changed)) >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] STATE: -connecting-to-device >d [04/Jun/2012:07:42:10 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-connecting-to-device" >I [04/Jun/2012:07:42:10 -0500] [Job 15] Connected to printer. >D [04/Jun/2012:07:42:10 -0500] [Job 15] Set job-printer-state-message to "Connected to printer.", current level=INFO >D [04/Jun/2012:07:42:10 -0500] [Job 15] Connected to 192.168.1.9:9100... >d [04/Jun/2012:07:42:10 -0500] cupsdAddEvent(event=job-progress, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=0x7fa313ceb4c0(15), text="%s", ...) >D [04/Jun/2012:07:42:10 -0500] Discarding unused job-progress event... >d [04/Jun/2012:07:42:10 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="Printer "%s" state changed.", ...) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313ce8cd0(printer-state-changed)) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313ce9050(printer-state-changed)) >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] backendRunLoop(print_fd=0, device_fd=6, snmp_fd=5, addr=0x7fae9e9f99d8, use_bc=1, side_cb=0x7fae9d96eef0) >D [04/Jun/2012:07:42:10 -0500] [Job 15] Read 8192 bytes of print data... >d [04/Jun/2012:07:42:10 -0500] [Job 15] prtMarkerSuppliesLevel.1.1 = -3 >d [04/Jun/2012:07:42:10 -0500] [Job 15] prtMarkerSuppliesLevel.1.2 = -3 >d [04/Jun/2012:07:42:10 -0500] [Job 15] prtMarkerSuppliesLevel.1.3 = -3 >d [04/Jun/2012:07:42:10 -0500] [Job 15] prtMarkerSuppliesLevel.1.4 = -3 >d [04/Jun/2012:07:42:10 -0500] [Job 15] prtMarkerSuppliesLevel.1.5 = -3 >D [04/Jun/2012:07:42:10 -0500] [Job 15] ATTR: marker-levels=-1,-1,-1,-1,-1 >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(P-----) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] new_supply_state=0, change_state=0 >d [04/Jun/2012:07:42:10 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="Printer "%s" state changed.", ...) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313ce93d0(printer-state-changed)) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313ce9750(printer-state-changed)) >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >D [04/Jun/2012:07:42:10 -0500] [Job 15] new_state=0, change_state=0 >D [04/Jun/2012:07:42:10 -0500] [Job 15] Wrote 8192 bytes of print data... >D [04/Jun/2012:07:42:10 -0500] [Job 15] Read 7773 bytes of print data... >D [04/Jun/2012:07:42:10 -0500] [Job 15] Wrote 7773 bytes of print data... >I [04/Jun/2012:07:42:10 -0500] [Job 15] Waiting for printer to finish. >D [04/Jun/2012:07:42:10 -0500] [Job 15] Set job-printer-state-message to "Waiting for printer to finish.", current level=INFO >d [04/Jun/2012:07:42:10 -0500] cupsdAddEvent(event=job-progress, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=0x7fa313ceb4c0(15), text="%s", ...) >D [04/Jun/2012:07:42:10 -0500] Discarding unused job-progress event... >d [04/Jun/2012:07:42:10 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="Printer "%s" state changed.", ...) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313ce9ad0(printer-state-changed)) >d [04/Jun/2012:07:42:10 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313ce9e50(printer-state-changed)) >D [04/Jun/2012:07:42:10 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:10 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >d [04/Jun/2012:07:42:11 -0500] select_timeout(0): 29 seconds to write dirty config/state files >D [04/Jun/2012:07:42:11 -0500] [Job 15] Received 10 bytes of back-channel data >d [04/Jun/2012:07:42:12 -0500] select_timeout(0): 28 seconds to write dirty config/state files >d [04/Jun/2012:07:42:14 -0500] [Job 15] prtMarkerSuppliesLevel.1.1 = -3 >d [04/Jun/2012:07:42:14 -0500] cupsdAddSelect(fd=16, read_cb=0x7fa311c8adf0, write_cb=(nil), data=0x7fa313ca5e30) >D [04/Jun/2012:07:42:14 -0500] Avahi client started >d [04/Jun/2012:07:42:14 -0500] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 >d [04/Jun/2012:07:42:14 -0500] [Job 15] prtMarkerSuppliesLevel.1.2 = -3 >d [04/Jun/2012:07:42:14 -0500] [Job 15] prtMarkerSuppliesLevel.1.3 = -3 >d [04/Jun/2012:07:42:14 -0500] [Job 15] prtMarkerSuppliesLevel.1.4 = -3 >d [04/Jun/2012:07:42:14 -0500] [Job 15] prtMarkerSuppliesLevel.1.5 = -3 >D [04/Jun/2012:07:42:14 -0500] [Job 15] ATTR: marker-levels=-1,-1,-1,-1,-1 >D [04/Jun/2012:07:42:14 -0500] cupsdMarkDirty(P-----) >D [04/Jun/2012:07:42:14 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >D [04/Jun/2012:07:42:14 -0500] [Job 15] new_supply_state=0, change_state=0 >d [04/Jun/2012:07:42:14 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="Printer "%s" state changed.", ...) >d [04/Jun/2012:07:42:14 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313cdff60(printer-state-changed)) >d [04/Jun/2012:07:42:14 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313cdff30(printer-state-changed)) >D [04/Jun/2012:07:42:14 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:14 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >D [04/Jun/2012:07:42:14 -0500] [Job 15] new_state=0, change_state=0 >I [04/Jun/2012:07:42:14 -0500] [Job 15] Ready to print. >D [04/Jun/2012:07:42:14 -0500] [Job 15] Set job-printer-state-message to "Ready to print.", current level=INFO >d [04/Jun/2012:07:42:14 -0500] cupsdAddEvent(event=job-progress, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=0x7fa313ceb4c0(15), text="%s", ...) >D [04/Jun/2012:07:42:14 -0500] Discarding unused job-progress event... >d [04/Jun/2012:07:42:14 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="Printer "%s" state changed.", ...) >d [04/Jun/2012:07:42:14 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313ce85a0(printer-state-changed)) >d [04/Jun/2012:07:42:14 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313cdff90(printer-state-changed)) >D [04/Jun/2012:07:42:14 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:14 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >d [04/Jun/2012:07:42:14 -0500] process_children() >d [04/Jun/2012:07:42:14 -0500] cupsdFinishProcess(pid=22028, name=0x7fffed204e50, namelen=1024, job_id=0x7fffed204e4c(15)) = "/usr/lib/cups/backend/dnssd" >D [04/Jun/2012:07:42:14 -0500] PID 22028 (/usr/lib/cups/backend/dnssd) exited with no errors. >d [04/Jun/2012:07:42:14 -0500] finalize_job(job=0x7fa313ceb4c0(15)) >d [04/Jun/2012:07:42:14 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-connecting-to-device,cups-remote-pending,cups-remote-pending-held,cups-remote-processing,cups-remote-stopped,cups-remote-canceled,cups-remote-aborted,cups-remote-completed" >d [04/Jun/2012:07:42:14 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-offline-report" >d [04/Jun/2012:07:42:14 -0500] cupsdDeleteProfile(profile="(null)") >d [04/Jun/2012:07:42:14 -0500] cupsdRemoveSelect(fd=17) >d [04/Jun/2012:07:42:14 -0500] cupsdSetJobState(job=0x7fa313ceb4c0(15), state=5, newstate=9, action=0, message="%s") >d [04/Jun/2012:07:42:14 -0500] stop_job(job=0x7fa313ceb4c0(15), action=0) >d [04/Jun/2012:07:42:14 -0500] cupsdAddEvent(event=job-completed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=0x7fa313ceb4c0(15), text="%s", ...) >D [04/Jun/2012:07:42:14 -0500] Discarding unused job-completed event... >I [04/Jun/2012:07:42:14 -0500] [Job 15] Job completed. >D [04/Jun/2012:07:42:14 -0500] cupsdMarkDirty(----J-) >D [04/Jun/2012:07:42:14 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >D [04/Jun/2012:07:42:14 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >d [04/Jun/2012:07:42:14 -0500] cupsdAddEvent(event=printer-state-changed, dest=0x7fa313c9dcc0(RICOH-Aficio-CL1000N), job=(nil)(0), text="%s "%s" state changed to %s.", ...) >d [04/Jun/2012:07:42:14 -0500] cupsd_send_notification(sub=0x7fa313cf7140(15), event=0x7fa313ced710(printer-state-changed)) >d [04/Jun/2012:07:42:14 -0500] cupsd_send_notification(sub=0x7fa313cf7280(20), event=0x7fa313ce02e0(printer-state-changed)) >D [04/Jun/2012:07:42:14 -0500] cupsdMarkDirty(-----S) >D [04/Jun/2012:07:42:14 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >d [04/Jun/2012:07:42:14 -0500] cupsdSetPrinterReasons(p=0x7fa313c9dcc0(RICOH-Aficio-CL1000N),s="-paused" >d [04/Jun/2012:07:42:14 -0500] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0 >d [04/Jun/2012:07:42:14 -0500] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0 >d [04/Jun/2012:07:42:15 -0500] cupsdSaveJob(job=0x7fa313ceb4c0(15)): job->attrs=0x7fa313ceb3f0 >D [04/Jun/2012:07:42:15 -0500] [Job 15] Unloading... >d [04/Jun/2012:07:42:15 -0500] select_timeout(0): 25 seconds to write dirty config/state files
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 827632
:
588593
|
589139
| 589141 |
589142
|
589177
|
589178
|
593265