Attachment 501518 Details for Bug 770880

cups debug mode log file

error_log (text/plain), 122.26 KB, created by Stanley Miller on 2012-08-08 04:42:00 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Stanley Miller

Created: 2012-08-08 04:42:00 UTC

Size: 122.26 KB

patch

obsolete

>I [07/Aug/2012:21:31:27 -0700] Remote access is disabled.
>D [07/Aug/2012:21:31:27 -0700] Added auto ServerAlias t3400-1
>I [07/Aug/2012:21:31:27 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
>D [07/Aug/2012:21:31:27 -0700] Creating missing directory "/var/spool/cups"
>D [07/Aug/2012:21:31:27 -0700] Repairing ownership of "/var/spool/cups"
>D [07/Aug/2012:21:31:27 -0700] Repairing access permissions of "/var/spool/cups"
>D [07/Aug/2012:21:31:27 -0700] Repairing ownership of "/var/run/cups"
>D [07/Aug/2012:21:31:27 -0700] Creating missing directory "/var/run/cups/certs"
>D [07/Aug/2012:21:31:27 -0700] Repairing ownership of "/var/run/cups/certs"
>D [07/Aug/2012:21:31:27 -0700] Repairing access permissions of "/var/run/cups/certs"
>I [07/Aug/2012:21:31:27 -0700] Using default TempDir of /var/spool/cups/tmp...
>D [07/Aug/2012:21:31:27 -0700] Creating missing directory "/var/spool/cups/tmp"
>D [07/Aug/2012:21:31:27 -0700] Repairing ownership of "/var/spool/cups/tmp"
>D [07/Aug/2012:21:31:27 -0700] Repairing access permissions of "/var/spool/cups/tmp"
>I [07/Aug/2012:21:31:27 -0700] Configured for up to 100 clients.
>I [07/Aug/2012:21:31:27 -0700] Allowing up to 100 client connections per host.
>I [07/Aug/2012:21:31:27 -0700] Full reload is required.
>I [07/Aug/2012:21:31:27 -0700] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 35 types, 46 filters...
>D [07/Aug/2012:21:31:27 -0700] Loading printer Brother...
>D [07/Aug/2012:21:31:27 -0700] load_ppd: Loading /var/cache/cups/Brother.data...
>D [07/Aug/2012:21:31:27 -0700] cupsdRegisterPrinter(p=0x7fcc463ac910(Brother))
>D [07/Aug/2012:21:31:27 -0700] load_ppd: Loading /var/cache/cups/Brother.data...
>D [07/Aug/2012:21:31:27 -0700] cupsdRegisterPrinter(p=0x7fcc463ac910(Brother))
>D [07/Aug/2012:21:31:27 -0700] Scanning /var/spool/cups for jobs...
>I [07/Aug/2012:21:31:27 -0700] Loading NextJobId from job cache file "/var/cache/cups/job.cache"...
>I [07/Aug/2012:21:31:27 -0700] Full reload complete.
>D [07/Aug/2012:21:31:27 -0700] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
>I [07/Aug/2012:21:31:27 -0700] Cleaning out old files in "/var/spool/cups/tmp"...
>D [07/Aug/2012:21:31:27 -0700] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
>I [07/Aug/2012:21:31:27 -0700] Cleaning out old files in "/var/cache/cups"...
>D [07/Aug/2012:21:31:27 -0700] systemd_checkin: Matched existing listener /var/run/cups/cups.sock with fd 3...
>D [07/Aug/2012:21:31:27 -0700] systemd_checkin: Adding new listener [v1.::] with fd 4...
>E [07/Aug/2012:21:31:27 -0700] Browsing=1
>E [07/Aug/2012:21:31:27 -0700] BrowseLocalProtocols=0
>E [07/Aug/2012:21:31:27 -0700] BrowseRemoteProtocols=1
>E [07/Aug/2012:21:31:27 -0700] BROWSE_CUPS=1
>D [07/Aug/2012:21:31:27 -0700] systemd_checkin: Matched browse (port 631) with fd 5:0.0.0.0...
>E [07/Aug/2012:21:31:27 -0700] Unable to bind socket for address [v1.::1]:631 - Cannot assign requested address.
>I [07/Aug/2012:21:31:27 -0700] Listening to 127.0.0.1:631 on fd 10...
>I [07/Aug/2012:21:31:27 -0700] Listening to /var/run/cups/cups.sock:631 on fd 3...
>I [07/Aug/2012:21:31:27 -0700] Listening to [v1.::]:631 on fd 4...
>I [07/Aug/2012:21:31:27 -0700] Resuming new connection processing...
>D [07/Aug/2012:21:31:27 -0700] cupsdRegisterPrinter(p=0x7fcc463ac910(Brother))
>D [07/Aug/2012:21:31:27 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [07/Aug/2012:21:31:27 -0700] Discarding unused server-started event...
>D [07/Aug/2012:21:31:28 -0700] Report: clients=0
>D [07/Aug/2012:21:31:28 -0700] Report: jobs=0
>D [07/Aug/2012:21:31:28 -0700] Report: jobs-active=0
>D [07/Aug/2012:21:31:28 -0700] Report: printers=1
>D [07/Aug/2012:21:31:28 -0700] Report: printers-implicit=0
>D [07/Aug/2012:21:31:28 -0700] Report: stringpool-string-count=1364
>D [07/Aug/2012:21:31:28 -0700] Report: stringpool-alloc-bytes=9048
>D [07/Aug/2012:21:31:28 -0700] Report: stringpool-total-bytes=25968
>D [07/Aug/2012:21:31:58 -0700] cupsdAcceptClient: 13 from localhost (Domain)
>D [07/Aug/2012:21:31:58 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:31:58 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:31:58 -0700] cupsdReadClient: 13 1.1 Get-Printer-Attributes 1
>D [07/Aug/2012:21:31:58 -0700] Get-Printer-Attributes ipp://localhost:631/printers/Brother
>D [07/Aug/2012:21:31:58 -0700] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/Brother) from localhost
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:31:58 -0700] cupsdReadClient: 13 POST /printers/Brother HTTP/1.1
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:31:58 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:31:58 -0700] cupsdReadClient: 13 1.1 Create-Job 1
>D [07/Aug/2012:21:31:58 -0700] Create-Job ipp://localhost:631/printers/Brother
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(----J-)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
>D [07/Aug/2012:21:31:58 -0700] add_job: requesting-user-name="root"
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Adding start banner page "none".
>D [07/Aug/2012:21:31:58 -0700] Discarding unused job-created event...
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Queued on "Brother" by "root".
>D [07/Aug/2012:21:31:58 -0700] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/Brother) from localhost
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [07/Aug/2012:21:31:58 -0700] cupsdReadClient: 13 POST /printers/Brother HTTP/1.1
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [07/Aug/2012:21:31:58 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:31:58 -0700] cupsdReadClient: 13 1.1 Send-Document 1
>D [07/Aug/2012:21:31:58 -0700] Send-Document ipp://localhost:631/printers/Brother
>D [07/Aug/2012:21:31:58 -0700] cupsdIsAuthorized: requesting-user-name="root"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Auto-typing file...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Request file type is text/plain.
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(----J-)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>I [07/Aug/2012:21:31:58 -0700] [Job 47] File of type text/plain queued by "root".
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Adding end banner page "none".
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(----J-)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(----J-)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] job-sheets=none,none
>D [07/Aug/2012:21:31:58 -0700] [Job 47] argv[0]="Brother"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] argv[1]="47"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] argv[2]="root"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] argv[3]="(stdin)"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] argv[4]="1"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:1f34be7b-981c-3cab-499f-d82dbb95f7fa job-originating-host-name=localhost time-at-creation=1344400318 time-at-processing=1344400318"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] argv[6]="/var/spool/cups/d00047-001"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[1]="CUPS_DATADIR=/usr/share/cups"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[2]="CUPS_DOCROOT=/usr/share/cups/webcontent"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[6]="CUPS_SERVERROOT=/etc/cups"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[7]="CUPS_STATEDIR=/var/run/cups"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[8]="HOME=/var/spool/cups/tmp"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[10]="SERVER_ADMIN=root@t3400-1"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[11]="SOFTWARE=CUPS/1.5.3"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[12]="TMPDIR=/var/spool/cups/tmp"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[13]="USER=root"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[15]="CUPS_ENCRYPTION=IfRequested"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[16]="IPP_PORT=631"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[17]="CHARSET=utf-8"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[18]="LANG=en_US.UTF-8"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[19]="PPD=/etc/cups/ppd/Brother.ppd"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[20]="RIP_MAX_CACHE=128m"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[21]="CONTENT_TYPE=text/plain"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[22]="DEVICE_URI=socket://192.168.2.253:9100"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[23]="PRINTER_INFO=Brother HL-5250DN with driver Brother HL-5250DN BR-Script3"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[24]="PRINTER_LOCATION=Computer Room"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[25]="PRINTER=Brother"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[26]="PRINTER_STATE_REASONS=none"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[27]="CUPS_FILETYPE=document"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[28]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] envp[29]="AUTH_I****"
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Started filter /usr/lib/cups/filter/texttops (PID 3550)
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Started filter /usr/lib/cups/filter/pstops (PID 3551)
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Started backend /usr/lib/cups/backend/socket (PID 3552)
>D [07/Aug/2012:21:31:58 -0700] Discarding unused job-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/Brother) from localhost
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
>D [07/Aug/2012:21:31:58 -0700] cupsdReadClient: 13 WAITING Closing on EOF
>D [07/Aug/2012:21:31:58 -0700] cupsdCloseClient: 13
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: +connecting-to-device
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Looking up "192.168.2.253"...
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Page = 612x792; 12,12 to 600,780
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->num_fonts = 66
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[0] = AvantGarde-Book
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[1] = AvantGarde-BookOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[2] = AvantGarde-Demi
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[3] = AvantGarde-DemiOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[4] = Bookman-Demi
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[5] = Bookman-DemiItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[6] = Bookman-Light
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[7] = Bookman-LightItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[8] = Courier
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[9] = Courier-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[10] = Courier-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[11] = Courier-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[12] = Helvetica
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[13] = Helvetica-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[14] = Helvetica-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[15] = Helvetica-Narrow
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[16] = Helvetica-Narrow-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[17] = Helvetica-Narrow-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[18] = Helvetica-Narrow-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[19] = Helvetica-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[20] = NewCenturySchlbk-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[21] = NewCenturySchlbk-BoldItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[22] = NewCenturySchlbk-Italic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[23] = NewCenturySchlbk-Roman
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[24] = Palatino-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[25] = Palatino-BoldItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[26] = Palatino-Italic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[27] = Palatino-Roman
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[28] = Times-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[29] = Times-BoldItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[30] = Times-Italic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[31] = Times-Roman
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[32] = ZapfChancery-MediumItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[33] = ZapfDingbats
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[34] = Symbol
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[35] = Alaska
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[36] = AlaskaExtrabold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[37] = AntiqueOakland
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[38] = AntiqueOakland-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[39] = AntiqueOakland-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[40] = ClevelandCondensed
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[41] = Connecticut
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[42] = Guatemala-Antique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[43] = Guatemala-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[44] = Guatemala-Italic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[45] = Guatemala-BoldItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[46] = LetterGothic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[47] = LetterGothic-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[48] = LetterGothic-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[49] = Maryland
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[50] = Oklahoma
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[51] = Oklahoma-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[52] = Oklahoma-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[53] = Oklahoma-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[54] = Utah
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[55] = Utah-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[56] = Utah-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[57] = Utah-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[58] = UtahCondensed
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[59] = UtahCondensed-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[60] = UtahCondensed-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[61] = UtahCondensed-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[62] = BermudaScript
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[63] = Germany
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[64] = SanDiego
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[65] = US-Roman
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->num_fonts = 66
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[0] = AvantGarde-Book
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[1] = AvantGarde-BookOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[2] = AvantGarde-Demi
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[3] = AvantGarde-DemiOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[4] = Bookman-Demi
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[5] = Bookman-DemiItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[6] = Bookman-Light
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[7] = Bookman-LightItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[8] = Courier
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[9] = Courier-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[10] = Courier-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[11] = Courier-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[12] = Helvetica
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[13] = Helvetica-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[14] = Helvetica-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[15] = Helvetica-Narrow
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[16] = Helvetica-Narrow-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[17] = Helvetica-Narrow-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[18] = Helvetica-Narrow-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[19] = Helvetica-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[20] = NewCenturySchlbk-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[21] = NewCenturySchlbk-BoldItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[22] = NewCenturySchlbk-Italic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[23] = NewCenturySchlbk-Roman
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[24] = Palatino-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[25] = Palatino-BoldItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[26] = Palatino-Italic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[27] = Palatino-Roman
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[28] = Times-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[29] = Times-BoldItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[30] = Times-Italic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[31] = Times-Roman
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[32] = ZapfChancery-MediumItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[33] = ZapfDingbats
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[34] = Symbol
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[35] = Alaska
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[36] = AlaskaExtrabold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[37] = AntiqueOakland
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[38] = AntiqueOakland-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[39] = AntiqueOakland-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[40] = ClevelandCondensed
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[41] = Connecticut
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[42] = Guatemala-Antique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[43] = Guatemala-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[44] = Guatemala-Italic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[45] = Guatemala-BoldItalic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[46] = LetterGothic
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[47] = LetterGothic-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[48] = LetterGothic-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[49] = Maryland
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[50] = Oklahoma
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[51] = Oklahoma-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[52] = Oklahoma-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[53] = Oklahoma-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[54] = Utah
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[55] = Utah-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[56] = Utah-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[57] = Utah-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[58] = UtahCondensed
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[59] = UtahCondensed-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[60] = UtahCondensed-Oblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[61] = UtahCondensed-BoldOblique
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[62] = BermudaScript
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[63] = Germany
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[64] = SanDiego
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ppd->fonts[65] = US-Roman
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Page = 612x792; 12,12 to 600,780
>D [07/Aug/2012:21:31:58 -0700] [Job 47] slow_collate=0, slow_duplex=0, slow_order=0
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Before copy_comments - %!PS-Adobe-3.0
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %!PS-Adobe-3.0
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%BoundingBox: 0 0 612 792
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %cupsRotation: 0
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%Creator: texttops/CUPS v1.5.3
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%CreationDate: Tue 07 Aug 2012 09:31:58 PM MST
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%Title: ((stdin))
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%For: (root)
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%Pages: (atend)
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%DocumentNeededResources: font Monospace-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%+ font Monospace
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%DocumentSuppliedResources: procset texttops 1.1 0
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%+ font Monospace-Bold
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%+ font Monospace
>D [07/Aug/2012:21:31:58 -0700] [Job 47] %%EndComments
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Before copy_prolog - %%BeginProlog
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Before copy_setup - %%Page: 1 1
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Before page loop - %%Page: 1 1
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Copying page 1...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] PAGE: 1 1
>D [07/Aug/2012:21:31:58 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] pagew = 587.9, pagel = 767.9
>D [07/Aug/2012:21:31:58 -0700] [Job 47] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
>D [07/Aug/2012:21:31:58 -0700] [Job 47] PageLeft = 12.0, PageRight = 599.9
>D [07/Aug/2012:21:31:58 -0700] [Job 47] PageTop = 780.0, PageBottom = 12.1
>D [07/Aug/2012:21:31:58 -0700] [Job 47] PageWidth = 612.0, PageLength = 792.0
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Wrote 1 pages...
>D [07/Aug/2012:21:31:58 -0700] PID 3550 (/usr/lib/cups/filter/texttops) exited with no errors.
>D [07/Aug/2012:21:31:58 -0700] PID 3551 (/usr/lib/cups/filter/pstops) exited with no errors.
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ATTR: marker-colors=#000000,#000000,#000000
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ATTR: marker-names='"Toner Cartridge Brother TN-550/TN-3130/TN-3145/TN-35J/TN-3135"','"Toner Cartridge Brother TN-580/TN-3170/TN-3185/TN-37J/TN-3175"','"Drum Unit Brother DR-520/DR-3100/DR-3115/DR-31J/DR-3150/DR-3117"'
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ATTR: marker-types=toner,toner,opc
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ATTR: marker-levels=-1,-1,85
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] new_supply_state=0, change_state=ffff
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -developer-low-report
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -developer-empty-warning
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -marker-supply-low-report
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -marker-supply-empty-warning
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -opc-near-eol-report
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -opc-life-over-warning
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -toner-low-report
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -toner-empty-warning
>D [07/Aug/2012:21:31:58 -0700] [Job 47] new_state=0, change_state=ffff
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -media-low-report
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -media-empty-warning
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -door-open-report
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -media-jam-warning
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -input-tray-missing-warning
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -output-tray-missing-warning
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -marker-supply-missing-warning
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -output-area-almost-full-report
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -output-area-full-warning
>D [07/Aug/2012:21:31:58 -0700] [Job 47] backendWaitLoop(snmp_fd=5, addr=0x7f673e241068, side_cb=0x7f673c9a04e0)
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Connecting to 192.168.2.253:9100
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Connecting to printer.
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Set job-printer-state-message to "Connecting to printer.", current level=INFO
>D [07/Aug/2012:21:31:58 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] STATE: -connecting-to-device
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Connected to printer.
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Set job-printer-state-message to "Connected to printer.", current level=INFO
>D [07/Aug/2012:21:31:58 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Connected to 192.168.2.253:9100...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] backendRunLoop(print_fd=0, device_fd=7, snmp_fd=5, addr=0x7f673e241068, use_bc=1, side_cb=0x7f673c9a04e0)
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Read 8192 bytes of print data...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ATTR: marker-levels=-1,-1,85
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] new_supply_state=0, change_state=0
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] new_state=0, change_state=0
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Read 8192 bytes of print data...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Read 8192 bytes of print data...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Read 6232 bytes of print data...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Wrote 6232 bytes of print data...
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Waiting for printer to finish.
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Set job-printer-state-message to "Waiting for printer to finish.", current level=INFO
>D [07/Aug/2012:21:31:58 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] ATTR: marker-levels=-1,-1,85
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:31:58 -0700] [Job 47] new_supply_state=0, change_state=0
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] [Job 47] new_state=0, change_state=0
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Ready to print.
>D [07/Aug/2012:21:31:58 -0700] [Job 47] Set job-printer-state-message to "Ready to print.", current level=INFO
>D [07/Aug/2012:21:31:58 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:58 -0700] PID 3552 (/usr/lib/cups/backend/socket) exited with no errors.
>D [07/Aug/2012:21:31:58 -0700] Discarding unused job-completed event...
>I [07/Aug/2012:21:31:58 -0700] [Job 47] Job completed.
>D [07/Aug/2012:21:31:58 -0700] cupsdMarkDirty(----J-)
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:31:58 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:31:58 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:31:59 -0700] [Job 47] Unloading...
>D [07/Aug/2012:21:32:29 -0700] cupsdAcceptClient: 13 from localhost (Domain)
>I [07/Aug/2012:21:32:29 -0700] Saving printers.conf...
>I [07/Aug/2012:21:32:29 -0700] Saving job.cache...
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:32:29 -0700] Report: clients=1
>D [07/Aug/2012:21:32:29 -0700] Report: jobs=1
>D [07/Aug/2012:21:32:29 -0700] Report: jobs-active=0
>D [07/Aug/2012:21:32:29 -0700] Report: printers=1
>D [07/Aug/2012:21:32:29 -0700] Report: printers-implicit=0
>D [07/Aug/2012:21:32:29 -0700] Report: stringpool-string-count=1367
>D [07/Aug/2012:21:32:29 -0700] Report: stringpool-alloc-bytes=9088
>D [07/Aug/2012:21:32:29 -0700] Report: stringpool-total-bytes=26016
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:29 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:29 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:29 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:29 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:29 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Printers
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST / HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
>D [07/Aug/2012:21:32:31 -0700] CUPS-Get-Default
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST /printers/Brother HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 Create-Job 1
>D [07/Aug/2012:21:32:31 -0700] Create-Job ipp://localhost:631/printers/Brother
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(----J-)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
>D [07/Aug/2012:21:32:31 -0700] add_job: requesting-user-name="stan"
>D [07/Aug/2012:21:32:31 -0700] Adding default job-sheets values "none,none"...
>I [07/Aug/2012:21:32:31 -0700] [Job 48] Adding start banner page "none".
>D [07/Aug/2012:21:32:31 -0700] Discarding unused job-created event...
>I [07/Aug/2012:21:32:31 -0700] [Job 48] Queued on "Brother" by "stan".
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/Brother) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 POST /printers/Brother HTTP/1.1
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [07/Aug/2012:21:32:31 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:32:31 -0700] cupsdReadClient: 13 1.1 Send-Document 1
>D [07/Aug/2012:21:32:31 -0700] Send-Document ipp://localhost:631/printers/Brother
>D [07/Aug/2012:21:32:31 -0700] cupsdIsAuthorized: requesting-user-name="stan"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Auto-typing file...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Request file type is application/pdf.
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(----J-)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>I [07/Aug/2012:21:32:31 -0700] [Job 48] File of type application/pdf queued by "stan".
>I [07/Aug/2012:21:32:31 -0700] [Job 48] Adding end banner page "none".
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(----J-)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(----J-)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [07/Aug/2012:21:32:31 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] job-sheets=none,none
>D [07/Aug/2012:21:32:31 -0700] [Job 48] argv[0]="Brother"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] argv[1]="48"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] argv[2]="stan"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] argv[3]="SSD Optimizations"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] argv[4]="1"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] argv[5]="media=Letter job-uuid=urn:uuid:12ca52e5-9e78-3c21-5c9f-c463c22d50f2 job-originating-host-name=localhost time-at-creation=1344400351 time-at-processing=1344400351 PageSize=Letter"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] argv[6]="/var/spool/cups/d00048-001"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[1]="CUPS_DATADIR=/usr/share/cups"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[2]="CUPS_DOCROOT=/usr/share/cups/webcontent"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[6]="CUPS_SERVERROOT=/etc/cups"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[7]="CUPS_STATEDIR=/var/run/cups"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[8]="HOME=/var/spool/cups/tmp"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[10]="SERVER_ADMIN=root@t3400-1"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[11]="SOFTWARE=CUPS/1.5.3"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[12]="TMPDIR=/var/spool/cups/tmp"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[13]="USER=root"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[15]="CUPS_ENCRYPTION=IfRequested"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[16]="IPP_PORT=631"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[17]="CHARSET=utf-8"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[18]="LANG=en_US.UTF-8"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[19]="PPD=/etc/cups/ppd/Brother.ppd"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[20]="RIP_MAX_CACHE=128m"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[21]="CONTENT_TYPE=application/pdf"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[22]="DEVICE_URI=socket://192.168.2.253:9100"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[23]="PRINTER_INFO=Brother HL-5250DN with driver Brother HL-5250DN BR-Script3"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[24]="PRINTER_LOCATION=Computer Room"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[25]="PRINTER=Brother"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[26]="PRINTER_STATE_REASONS=none"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[27]="CUPS_FILETYPE=document"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[28]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] envp[29]="AUTH_I****"
>I [07/Aug/2012:21:32:31 -0700] [Job 48] Started filter /usr/lib/cups/filter/pdftops (PID 3555)
>I [07/Aug/2012:21:32:31 -0700] [Job 48] Started backend /usr/lib/cups/backend/socket (PID 3556)
>D [07/Aug/2012:21:32:31 -0700] Discarding unused job-state-changed event...
>D [07/Aug/2012:21:32:31 -0700] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/Brother) from localhost
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: +connecting-to-device
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Looking up "192.168.2.253"...
>D [07/Aug/2012:21:32:31 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Started filter pdftops (PID 3557)
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Started filter pstops (PID 3558)
>D [07/Aug/2012:21:32:31 -0700] [Job 48] ATTR: marker-colors=#000000,#000000,#000000
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] ATTR: marker-names='"Toner Cartridge Brother TN-550/TN-3130/TN-3145/TN-35J/TN-3135"','"Toner Cartridge Brother TN-580/TN-3170/TN-3185/TN-37J/TN-3175"','"Drum Unit Brother DR-520/DR-3100/DR-3115/DR-31J/DR-3150/DR-3117"'
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] ATTR: marker-types=toner,toner,opc
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] ATTR: marker-levels=-1,-1,85
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] new_supply_state=0, change_state=ffff
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -developer-low-report
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -developer-empty-warning
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -marker-supply-low-report
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -marker-supply-empty-warning
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -opc-near-eol-report
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -opc-life-over-warning
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -toner-low-report
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -toner-empty-warning
>D [07/Aug/2012:21:32:31 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] new_state=0, change_state=ffff
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -media-low-report
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -media-empty-warning
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -door-open-report
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -media-jam-warning
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -input-tray-missing-warning
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -output-tray-missing-warning
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -marker-supply-missing-warning
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -output-area-almost-full-report
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -output-area-full-warning
>D [07/Aug/2012:21:32:31 -0700] [Job 48] backendWaitLoop(snmp_fd=5, addr=0x7f61ceed1068, side_cb=0x7f61cd4214e0)
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Page = 612x792; 12,12 to 600,780
>D [07/Aug/2012:21:32:31 -0700] [Job 48] slow_collate=0, slow_duplex=0, slow_order=0
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Before copy_comments - %!PS-Adobe-3.0
>D [07/Aug/2012:21:32:31 -0700] [Job 48] %!PS-Adobe-3.0
>D [07/Aug/2012:21:32:31 -0700] [Job 48] %Produced by poppler pdftops version: 0.20.0 (http://poppler.freedesktop.org)
>D [07/Aug/2012:21:32:31 -0700] [Job 48] %%Creator:
>D [07/Aug/2012:21:32:31 -0700] [Job 48] %%LanguageLevel: 3
>D [07/Aug/2012:21:32:31 -0700] [Job 48] %%DocumentSuppliedResources: (atend)
>D [07/Aug/2012:21:32:31 -0700] [Job 48] %%DocumentMedia: 612x792 612 792 0 () ()
>D [07/Aug/2012:21:32:31 -0700] [Job 48] %%BoundingBox: 0 0 612 792
>D [07/Aug/2012:21:32:31 -0700] [Job 48] %%Pages: 2
>D [07/Aug/2012:21:32:31 -0700] [Job 48] %%EndComments
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Before copy_prolog - %%BeginProlog
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Connecting to 192.168.2.253:9100
>I [07/Aug/2012:21:32:31 -0700] [Job 48] Connecting to printer.
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Set job-printer-state-message to "Connecting to printer.", current level=INFO
>D [07/Aug/2012:21:32:31 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:32:31 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] STATE: -connecting-to-device
>I [07/Aug/2012:21:32:31 -0700] [Job 48] Connected to printer.
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Set job-printer-state-message to "Connected to printer.", current level=INFO
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Connected to 192.168.2.253:9100...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] backendRunLoop(print_fd=0, device_fd=7, snmp_fd=5, addr=0x7f61ceed1068, use_bc=1, side_cb=0x7f61cd4214e0)
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 7168 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:32:31 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Before copy_setup - %%BeginSetup
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Before page loop - %%Page: 1 1
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Copying page 1...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] PAGE: 1 1
>D [07/Aug/2012:21:32:31 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] pagew = 587.9, pagel = 767.9
>D [07/Aug/2012:21:32:31 -0700] [Job 48] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
>D [07/Aug/2012:21:32:31 -0700] [Job 48] PageLeft = 12.0, PageRight = 599.9
>D [07/Aug/2012:21:32:31 -0700] [Job 48] PageTop = 780.0, PageBottom = 12.1
>D [07/Aug/2012:21:32:31 -0700] [Job 48] PageWidth = 612.0, PageLength = 792.0
>D [07/Aug/2012:21:32:31 -0700] [Job 48] ATTR: marker-levels=-1,-1,85
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] new_supply_state=0, change_state=0
>D [07/Aug/2012:21:32:31 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] new_state=0, change_state=0
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 7168 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Copying page 2...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] PAGE: 2 1
>D [07/Aug/2012:21:32:31 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] pagew = 587.9, pagel = 767.9
>D [07/Aug/2012:21:32:31 -0700] [Job 48] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
>D [07/Aug/2012:21:32:31 -0700] [Job 48] PageLeft = 12.0, PageRight = 599.9
>D [07/Aug/2012:21:32:31 -0700] [Job 48] PageTop = 780.0, PageBottom = 12.1
>D [07/Aug/2012:21:32:31 -0700] [Job 48] PageWidth = 612.0, PageLength = 792.0
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] PID 3557 (pdftops) exited with no errors.
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 2 pages...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] PID 3558 (pstops) exited with no errors.
>D [07/Aug/2012:21:32:31 -0700] PID 3555 (/usr/lib/cups/filter/pdftops) exited with no errors.
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 8192 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Read 4121 bytes of print data...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Wrote 4121 bytes of print data...
>I [07/Aug/2012:21:32:31 -0700] [Job 48] Waiting for printer to finish.
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Set job-printer-state-message to "Waiting for printer to finish.", current level=INFO
>D [07/Aug/2012:21:32:31 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:32:31 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] ATTR: marker-levels=-1,-1,85
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(P-----)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:32:31 -0700] [Job 48] new_supply_state=0, change_state=0
>D [07/Aug/2012:21:32:31 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:32:31 -0700] [Job 48] new_state=0, change_state=0
>I [07/Aug/2012:21:32:31 -0700] [Job 48] Ready to print.
>D [07/Aug/2012:21:32:31 -0700] [Job 48] Set job-printer-state-message to "Ready to print.", current level=INFO
>D [07/Aug/2012:21:32:31 -0700] Discarding unused job-progress event...
>D [07/Aug/2012:21:32:31 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:32:31 -0700] PID 3556 (/usr/lib/cups/backend/socket) exited with no errors.
>D [07/Aug/2012:21:32:31 -0700] Discarding unused job-completed event...
>I [07/Aug/2012:21:32:31 -0700] [Job 48] Job completed.
>D [07/Aug/2012:21:32:31 -0700] cupsdMarkDirty(----J-)
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:32:31 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:32:31 -0700] Discarding unused printer-state-changed event...
>D [07/Aug/2012:21:32:32 -0700] [Job 48] Unloading...
>I [07/Aug/2012:21:33:02 -0700] Saving printers.conf...
>I [07/Aug/2012:21:33:02 -0700] Saving job.cache...
>D [07/Aug/2012:21:33:02 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"
>D [07/Aug/2012:21:36:02 -0700] cupsdAcceptClient: 15 from localhost:631 (IPv4)
>D [07/Aug/2012:21:36:02 -0700] Report: clients=2
>D [07/Aug/2012:21:36:02 -0700] Report: jobs=2
>D [07/Aug/2012:21:36:02 -0700] Report: jobs-active=0
>D [07/Aug/2012:21:36:02 -0700] Report: printers=1
>D [07/Aug/2012:21:36:02 -0700] Report: printers-implicit=0
>D [07/Aug/2012:21:36:02 -0700] Report: stringpool-string-count=1369
>D [07/Aug/2012:21:36:02 -0700] Report: stringpool-alloc-bytes=9104
>D [07/Aug/2012:21:36:02 -0700] Report: stringpool-total-bytes=26032
>D [07/Aug/2012:21:36:02 -0700] cupsdReadClient: 15 GET /admin HTTP/1.1
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:36:02 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:02 -0700] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[10] = "SERVER_ADMIN=root@t3400-1"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.3"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[13] = "USER=root"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[16] = "IPP_PORT=631"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[17] = "LANG=en_US.UTF8"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[18] = "REDIRECT_STATUS=1"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[20] = "SERVER_NAME=localhost"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[21] = "SERVER_PORT=631"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[23] = "REMOTE_HOST=localhost"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[24] = "SCRIPT_NAME=/admin"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/admin"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[27] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:14.0) Gecko/20100101 Firefox/14.0.1"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[28] = "REQUEST_METHOD=GET"
>D [07/Aug/2012:21:36:02 -0700] [CGI] envp[29] = "QUERY_STRING="
>D [07/Aug/2012:21:36:02 -0700] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 3626)
>I [07/Aug/2012:21:36:02 -0700] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=3626)
>D [07/Aug/2012:21:36:02 -0700] cupsdSendCommand: 15 file=16
>D [07/Aug/2012:21:36:02 -0700] [CGI] admin.cgi started...
>D [07/Aug/2012:21:36:02 -0700] cupsdAcceptClient: 17 from localhost (Domain)
>D [07/Aug/2012:21:36:02 -0700] [CGI] http=0x7f7d7d6384c0
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: SECTION="admin"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: REFRESH_PAGE=""
>D [07/Aug/2012:21:36:02 -0700] [CGI] org.cups.sid cookie not found, initializing!
>D [07/Aug/2012:21:36:02 -0700] [CGI] org.cups.sid cookie is "388c683b1c3fce6c7c7ec561d21832de"
>D [07/Aug/2012:21:36:02 -0700] [CGI] No form data, showing main menu...
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: DEBUG_LOGGING="CHECKED"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: HAVE_GSSAPI="1"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: KERBEROS=""
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: HAVE_LDAP="1"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: HAVE_LIBSLP="1"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: BROWSE_REMOTE_CUPS="CHECKED"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: BROWSE_LOCAL_CUPS="CHECKED"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: PRESERVE_JOB_HISTORY="CHECKED"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: MAX_CLIENTS="100"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: MAX_JOBS="500"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: MAX_LOG_SIZE="1m"
>D [07/Aug/2012:21:36:02 -0700] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
>D [07/Aug/2012:21:36:02 -0700] cupsdReadClient: 17 POST / HTTP/1.1
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:02 -0700] cupsdReadClient: 17 1.1 Get-Subscriptions 1
>D [07/Aug/2012:21:36:02 -0700] Get-Subscriptions ipp://localhost/
>D [07/Aug/2012:21:36:02 -0700] Get-Subscriptions client-error-not-found: No subscriptions found.
>D [07/Aug/2012:21:36:02 -0700] Returning IPP client-error-not-found for Get-Subscriptions (ipp://localhost/) from localhost
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: TITLE="Administration"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
>D [07/Aug/2012:21:36:02 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
>D [07/Aug/2012:21:36:02 -0700] Script header: Set-Cookie: org.cups.sid=388c683b1c3fce6c7c7ec561d21832de; path=/;
>D [07/Aug/2012:21:36:02 -0700] Script header: Content-Type: text/html;charset=utf-8
>D [07/Aug/2012:21:36:02 -0700] Script header: 
>D [07/Aug/2012:21:36:02 -0700] cupsdReadClient: 17 WAITING Closing on EOF
>D [07/Aug/2012:21:36:02 -0700] cupsdCloseClient: 17
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] PID 3626 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
>D [07/Aug/2012:21:36:02 -0700] cupsdReadClient: 15 GET /cups.css HTTP/1.1
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:36:02 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:02 -0700] cupsdAcceptClient: 17 from localhost:631 (IPv4)
>D [07/Aug/2012:21:36:02 -0700] cupsdAcceptClient: 18 from localhost:631 (IPv4)
>D [07/Aug/2012:21:36:02 -0700] cupsdReadClient: 17 GET /images/left.gif HTTP/1.1
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:02 -0700] cupsdReadClient: 18 GET /images/right.gif HTTP/1.1
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] cupsdReadClient: 15 GET /images/unsel.gif HTTP/1.1
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:36:02 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:02 -0700] cupsdReadClient: 17 GET /images/sel.gif HTTP/1.1
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:36:02 -0700] cupsdReadClient: 15 GET /images/cups-icon.png HTTP/1.1
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:36:02 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:02 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:36:04 -0700] cupsdReadClient: 15 GET /jobs/ HTTP/1.1
>D [07/Aug/2012:21:36:04 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:36:04 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:04 -0700] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/jobs.cgi"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[10] = "SERVER_ADMIN=root@t3400-1"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.3"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[13] = "USER=root"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[16] = "IPP_PORT=631"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[17] = "LANG=en_US.UTF8"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[18] = "REDIRECT_STATUS=1"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[20] = "SERVER_NAME=localhost"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[21] = "SERVER_PORT=631"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[23] = "REMOTE_HOST=localhost"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[24] = "SCRIPT_NAME=/jobs/"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/jobs/"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=388c683b1c3fce6c7c7ec561d21832de"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:14.0) Gecko/20100101 Firefox/14.0.1"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[29] = "HTTP_REFERER=http://localhost:631/admin"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[30] = "REQUEST_METHOD=GET"
>D [07/Aug/2012:21:36:04 -0700] [CGI] envp[31] = "QUERY_STRING="
>D [07/Aug/2012:21:36:04 -0700] [CGI] Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 3627)
>I [07/Aug/2012:21:36:04 -0700] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=3627)
>D [07/Aug/2012:21:36:04 -0700] cupsdSendCommand: 15 file=16
>D [07/Aug/2012:21:36:04 -0700] [CGI] org.cups.sid cookie is "388c683b1c3fce6c7c7ec561d21832de"
>D [07/Aug/2012:21:36:04 -0700] [CGI] cgiSetVariable: SECTION="jobs"
>D [07/Aug/2012:21:36:04 -0700] [CGI] cgiSetVariable: REFRESH_PAGE=""
>D [07/Aug/2012:21:36:04 -0700] cupsdAcceptClient: 19 from localhost (Domain)
>D [07/Aug/2012:21:36:04 -0700] [CGI] cgiSetVariable: TITLE="Jobs"
>D [07/Aug/2012:21:36:04 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
>D [07/Aug/2012:21:36:04 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
>D [07/Aug/2012:21:36:04 -0700] Script header: Content-Type: text/html;charset=utf-8
>D [07/Aug/2012:21:36:04 -0700] Script header: 
>D [07/Aug/2012:21:36:04 -0700] cupsdReadClient: 19 POST / HTTP/1.1
>D [07/Aug/2012:21:36:04 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:04 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:04 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
>D [07/Aug/2012:21:36:04 -0700] Get-Jobs ipp://localhost/
>D [07/Aug/2012:21:36:04 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
>D [07/Aug/2012:21:36:04 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:04 -0700] [CGI] cgiClearVariables called.
>D [07/Aug/2012:21:36:04 -0700] [CGI] cgiSetVariable: ORDER="asc"
>D [07/Aug/2012:21:36:04 -0700] [CGI] cgiSetVariable: SECTION="jobs"
>D [07/Aug/2012:21:36:04 -0700] [CGI] cgiSetVariable: TOTAL="0"
>D [07/Aug/2012:21:36:04 -0700] [CGI] cgiSetVariable: THISURL="/jobs/"
>D [07/Aug/2012:21:36:04 -0700] cupsdReadClient: 19 WAITING Closing on EOF
>D [07/Aug/2012:21:36:04 -0700] cupsdCloseClient: 19
>D [07/Aug/2012:21:36:04 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:04 -0700] PID 3627 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors.
>D [07/Aug/2012:21:36:04 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:36:06 -0700] cupsdReadClient: 15 GET /jobs?which_jobs=all HTTP/1.1
>D [07/Aug/2012:21:36:06 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:36:06 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:06 -0700] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/jobs.cgi"
>D [07/Aug/2012:21:36:06 -0700] [CGI] argv[1] = "which_jobs=all"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[10] = "SERVER_ADMIN=root@t3400-1"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.3"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[13] = "USER=root"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[16] = "IPP_PORT=631"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[17] = "LANG=en_US.UTF8"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[18] = "REDIRECT_STATUS=1"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[20] = "SERVER_NAME=localhost"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[21] = "SERVER_PORT=631"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[23] = "REMOTE_HOST=localhost"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[24] = "SCRIPT_NAME=/jobs"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/jobs"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=388c683b1c3fce6c7c7ec561d21832de"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:14.0) Gecko/20100101 Firefox/14.0.1"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[29] = "HTTP_REFERER=http://localhost:631/jobs/"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[30] = "REQUEST_METHOD=GET"
>D [07/Aug/2012:21:36:06 -0700] [CGI] envp[31] = "QUERY_STRING=which_jobs=all"
>D [07/Aug/2012:21:36:06 -0700] [CGI] Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 3628)
>I [07/Aug/2012:21:36:06 -0700] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=3628)
>D [07/Aug/2012:21:36:06 -0700] cupsdSendCommand: 15 file=16
>D [07/Aug/2012:21:36:06 -0700] [CGI] org.cups.sid cookie is "388c683b1c3fce6c7c7ec561d21832de"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: which_jobs="all"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: SECTION="jobs"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: REFRESH_PAGE=""
>D [07/Aug/2012:21:36:06 -0700] cupsdAcceptClient: 19 from localhost (Domain)
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: TITLE="Jobs"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
>D [07/Aug/2012:21:36:06 -0700] Script header: Content-Type: text/html;charset=utf-8
>D [07/Aug/2012:21:36:06 -0700] Script header: 
>D [07/Aug/2012:21:36:06 -0700] cupsdReadClient: 19 POST / HTTP/1.1
>D [07/Aug/2012:21:36:06 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:06 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:06 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
>D [07/Aug/2012:21:36:06 -0700] Get-Jobs ipp://localhost/
>D [07/Aug/2012:21:36:06 -0700] [Job 47] Loading attributes...
>D [07/Aug/2012:21:36:06 -0700] [Job 48] Loading attributes...
>D [07/Aug/2012:21:36:06 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
>D [07/Aug/2012:21:36:06 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiClearVariables called.
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: ORDER="dec"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: SECTION="jobs"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: TOTAL="2"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: WHICH_JOBS="all"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_preserved[0]="0"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_printer_name[0]="Brother"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_printer_uri[0]="/printers/Brother"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: time_at_creation[0]="Tue 07 Aug 2012 09:32:31 PM MST"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: time_at_processing[0]="Tue 07 Aug 2012 09:32:31 PM MST"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: time_at_completed[0]="Tue 07 Aug 2012 09:32:31 PM MST"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_id[0]="48"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_state[0]="9"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_media_sheets_completed[0]="2"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_k_octets[0]="18"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_printer_state_message[0]=""
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_preserved[1]="0"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_printer_name[1]="Brother"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_printer_uri[1]="/printers/Brother"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: time_at_creation[1]="Tue 07 Aug 2012 09:31:58 PM MST"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: time_at_processing[1]="Tue 07 Aug 2012 09:31:58 PM MST"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: time_at_completed[1]="Tue 07 Aug 2012 09:31:58 PM MST"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_id[1]="47"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_state[1]="9"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_media_sheets_completed[1]="1"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_k_octets[1]="1"
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetArray: job_printer_state_message[1]=""
>D [07/Aug/2012:21:36:06 -0700] [CGI] cgiSetVariable: THISURL="/jobs/"
>D [07/Aug/2012:21:36:06 -0700] cupsdReadClient: 19 WAITING Closing on EOF
>D [07/Aug/2012:21:36:06 -0700] cupsdCloseClient: 19
>D [07/Aug/2012:21:36:06 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:06 -0700] PID 3628 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors.
>D [07/Aug/2012:21:36:06 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:36:14 -0700] cupsdReadClient: 15 GET /admin HTTP/1.1
>D [07/Aug/2012:21:36:14 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:36:14 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:14 -0700] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[10] = "SERVER_ADMIN=root@t3400-1"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.3"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[13] = "USER=root"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[16] = "IPP_PORT=631"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[17] = "LANG=en_US.UTF8"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[18] = "REDIRECT_STATUS=1"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[20] = "SERVER_NAME=localhost"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[21] = "SERVER_PORT=631"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[23] = "REMOTE_HOST=localhost"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[24] = "SCRIPT_NAME=/admin"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/admin"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=388c683b1c3fce6c7c7ec561d21832de"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:14.0) Gecko/20100101 Firefox/14.0.1"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[29] = "HTTP_REFERER=http://localhost:631/jobs?which_jobs=all"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[30] = "REQUEST_METHOD=GET"
>D [07/Aug/2012:21:36:14 -0700] [CGI] envp[31] = "QUERY_STRING="
>D [07/Aug/2012:21:36:14 -0700] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 3629)
>I [07/Aug/2012:21:36:14 -0700] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=3629)
>D [07/Aug/2012:21:36:14 -0700] cupsdSendCommand: 15 file=16
>D [07/Aug/2012:21:36:14 -0700] [CGI] admin.cgi started...
>D [07/Aug/2012:21:36:14 -0700] cupsdAcceptClient: 19 from localhost (Domain)
>D [07/Aug/2012:21:36:14 -0700] [CGI] http=0x7fef90a2e4c0
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: SECTION="admin"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: REFRESH_PAGE=""
>D [07/Aug/2012:21:36:14 -0700] [CGI] org.cups.sid cookie is "388c683b1c3fce6c7c7ec561d21832de"
>D [07/Aug/2012:21:36:14 -0700] [CGI] No form data, showing main menu...
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: DEBUG_LOGGING="CHECKED"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: HAVE_GSSAPI="1"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: KERBEROS=""
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: HAVE_LDAP="1"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: HAVE_LIBSLP="1"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: BROWSE_REMOTE_CUPS="CHECKED"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: BROWSE_LOCAL_CUPS="CHECKED"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: PRESERVE_JOB_HISTORY="CHECKED"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: MAX_CLIENTS="100"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: MAX_JOBS="500"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: MAX_LOG_SIZE="1m"
>D [07/Aug/2012:21:36:14 -0700] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
>D [07/Aug/2012:21:36:14 -0700] cupsdReadClient: 19 POST / HTTP/1.1
>D [07/Aug/2012:21:36:14 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:14 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:14 -0700] cupsdReadClient: 19 1.1 Get-Subscriptions 1
>D [07/Aug/2012:21:36:14 -0700] Get-Subscriptions ipp://localhost/
>D [07/Aug/2012:21:36:14 -0700] Get-Subscriptions client-error-not-found: No subscriptions found.
>D [07/Aug/2012:21:36:14 -0700] Returning IPP client-error-not-found for Get-Subscriptions (ipp://localhost/) from localhost
>D [07/Aug/2012:21:36:14 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: TITLE="Administration"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
>D [07/Aug/2012:21:36:14 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
>D [07/Aug/2012:21:36:14 -0700] Script header: Content-Type: text/html;charset=utf-8
>D [07/Aug/2012:21:36:14 -0700] Script header: 
>D [07/Aug/2012:21:36:14 -0700] PID 3629 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
>D [07/Aug/2012:21:36:14 -0700] cupsdReadClient: 19 WAITING Closing on EOF
>D [07/Aug/2012:21:36:14 -0700] cupsdCloseClient: 19
>D [07/Aug/2012:21:36:14 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>D [07/Aug/2012:21:36:14 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:36:15 -0700] cupsdReadClient: 15 GET /admin/log/error_log HTTP/1.1
>D [07/Aug/2012:21:36:15 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:36:15 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:15 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:36:16 -0700] cupsdReadClient: 15 GET /favicon.ico HTTP/1.1
>D [07/Aug/2012:21:36:16 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:36:16 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:16 -0700] cupsdReadClient: 15 Closing because Keep-Alive disabled
>D [07/Aug/2012:21:36:16 -0700] cupsdCloseClient: 15
>D [07/Aug/2012:21:36:16 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:36:16 -0700] cupsdReadClient: 17 GET /favicon.ico HTTP/1.1
>D [07/Aug/2012:21:36:16 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [07/Aug/2012:21:36:16 -0700] cupsdAuthorize: No authentication data provided.
>D [07/Aug/2012:21:36:16 -0700] cupsdReadClient: 17 Closing because Keep-Alive disabled
>D [07/Aug/2012:21:36:16 -0700] cupsdCloseClient: 17
>D [07/Aug/2012:21:36:16 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [07/Aug/2012:21:36:32 -0700] cupsdReadClient: 18 WAITING Closing on EOF
>D [07/Aug/2012:21:36:32 -0700] cupsdCloseClient: 18
>D [07/Aug/2012:21:36:32 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [07/Aug/2012:21:37:32 -0700] [Job 47] Unloading...
>D [07/Aug/2012:21:37:32 -0700] [Job 48] Unloading...
>D [07/Aug/2012:21:37:32 -0700] Closing client 13 after 300 seconds of inactivity...
>D [07/Aug/2012:21:37:32 -0700] cupsdCloseClient: 13
>D [07/Aug/2012:21:37:32 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [07/Aug/2012:21:37:32 -0700] Report: clients=0
>D [07/Aug/2012:21:37:32 -0700] Report: jobs=2
>D [07/Aug/2012:21:37:32 -0700] Report: jobs-active=0
>D [07/Aug/2012:21:37:32 -0700] Report: printers=1
>D [07/Aug/2012:21:37:32 -0700] Report: printers-implicit=0
>D [07/Aug/2012:21:37:32 -0700] Report: stringpool-string-count=1369
>D [07/Aug/2012:21:37:32 -0700] Report: stringpool-alloc-bytes=9104
>D [07/Aug/2012:21:37:32 -0700] Report: stringpool-total-bytes=26032

Actions: View

Attachments on bug 770880: 501518