Attachment 589141 Details for Bug 827632 – error log from lpr text file to printer setup in S-C-P - printing successful

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:

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

Actions: View

Attachments on bug 827632: 588593 | 589139 | 589141 | 589142 | 589177 | 589178 | 593265