Stats 3 with latest ESP

[Anonymous]

I have a Canon i560 which has been printing fine for weeks with
GS 7.07
Cups 1.1.23-15
gimp-print 4.2.7-10

Yesterday I did a yum update and is had me update ghostscript to 8.15rc3 (and possibly cups to 1.1.23-15).  Since then nothign has printed, OpenOffice has quit working, etc.

Best I can figure is that the GS 8.15rc3 is causing the problem.
Anyway, here is the output when I try to print a testpage:

d [07/Jun/2005:23:26:30 -0500] AcceptClient(lis=0x83ff360) 0 NumClients = 0
D [07/Jun/2005:23:26:30 -0500] AcceptClient: 6 from localhost:631.
d [07/Jun/2005:23:26:30 -0500] AcceptClient: Adding fd 6 to InputSet...
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6, used=0, file=-1
D [07/Jun/2005:23:26:30 -0500] ReadClient: 6 POST / HTTP/1.1
d [07/Jun/2005:23:26:30 -0500] decode_auth(0xb7c66008): Authorization string = ""
d [07/Jun/2005:23:26:30 -0500] decode_auth: 6 username=""
d [07/Jun/2005:23:26:30 -0500] IsAuthorized: con->uri = "/"
d [07/Jun/2005:23:26:30 -0500] FindBest: uri = "/"...
d [07/Jun/2005:23:26:30 -0500] FindBest: Location / Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /admin Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /printers/printer Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /printers/canon Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [07/Jun/2005:23:26:30 -0500] FindBest: best = "/"
d [07/Jun/2005:23:26:30 -0500] IsAuthorized: auth = 0, satisfy=0...
d [07/Jun/2005:23:26:30 -0500] POST /
d [07/Jun/2005:23:26:30 -0500] CONTENT_TYPE = application/ipp
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6 con->data_encoding = length, con->data_remaining = 135, con->file = -1
d [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest(0xb7c66008[6]): operation_id = 4002
d [07/Jun/2005:23:26:30 -0500] get_printers(0xb7c66008[6], 0)
d [07/Jun/2005:23:26:30 -0500] add_printer_state_reasons(0xb7c66008[6], 0x84080e8[canon])
d [07/Jun/2005:23:26:30 -0500] add_queued_job_count(0xb7c66008[6], 0x84080e8[canon])
d [07/Jun/2005:23:26:30 -0500] copy_attrs(0x83e8b40, 0x840f4b8, 0x83e8a60, 0)
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8b40, 0x840f3d8[printer-name,4,42])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8b40, 0x82679f0[job-sheets-default,4,42])
d [07/Jun/2005:23:26:30 -0500] copy_attrs(0x83e8b40, 0x82742f8, 0x83e8a60, 0)
D [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest: 6 status_code=1
d [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest: Adding fd 6 to OutputSet...
d [07/Jun/2005:23:26:30 -0500] WriteClient: Removing fd 6 from OutputSet...
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6, used=0, file=-1
D [07/Jun/2005:23:26:30 -0500] ReadClient: 6 POST / HTTP/1.1
d [07/Jun/2005:23:26:30 -0500] decode_auth(0xb7c66008): Authorization string = ""
d [07/Jun/2005:23:26:30 -0500] decode_auth: 6 username=""
d [07/Jun/2005:23:26:30 -0500] IsAuthorized: con->uri = "/"
d [07/Jun/2005:23:26:30 -0500] FindBest: uri = "/"...
d [07/Jun/2005:23:26:30 -0500] FindBest: Location / Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /admin Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /printers/printer Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /printers/canon Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [07/Jun/2005:23:26:30 -0500] FindBest: best = "/"
d [07/Jun/2005:23:26:30 -0500] IsAuthorized: auth = 0, satisfy=0...
d [07/Jun/2005:23:26:30 -0500] POST /
d [07/Jun/2005:23:26:30 -0500] CONTENT_TYPE = application/ipp
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6 con->data_encoding = length, con->data_remaining = 135, con->file = -1
d [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest(0xb7c66008[6]): operation_id = 4005
d [07/Jun/2005:23:26:30 -0500] get_printers(0xb7c66008[6], 1)
D [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest: 6 status_code=1
d [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest: Adding fd 6 to OutputSet...
d [07/Jun/2005:23:26:30 -0500] WriteClient: Removing fd 6 from OutputSet...
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6, used=0, file=-1
D [07/Jun/2005:23:26:30 -0500] ReadClient: 6 POST / HTTP/1.1
d [07/Jun/2005:23:26:30 -0500] decode_auth(0xb7c66008): Authorization string = ""
d [07/Jun/2005:23:26:30 -0500] decode_auth: 6 username=""
d [07/Jun/2005:23:26:30 -0500] IsAuthorized: con->uri = "/"
d [07/Jun/2005:23:26:30 -0500] FindBest: uri = "/"...
d [07/Jun/2005:23:26:30 -0500] FindBest: Location / Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /admin Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /printers/printer Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /printers/canon Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [07/Jun/2005:23:26:30 -0500] FindBest: best = "/"
d [07/Jun/2005:23:26:30 -0500] IsAuthorized: auth = 0, satisfy=0...
d [07/Jun/2005:23:26:30 -0500] POST /
d [07/Jun/2005:23:26:30 -0500] CONTENT_TYPE = application/ipp
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6 con->data_encoding = length, con->data_remaining = 75, con->file = -1
d [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest(0xb7c66008[6]): operation_id = 4001
d [07/Jun/2005:23:26:30 -0500] get_default(0xb7c66008[6])
d [07/Jun/2005:23:26:30 -0500] copy_attrs(0x83e8dc8, 0x840f4b8, (nil), 0)
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x840f4e0[printer-uri-supported,4,45])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x82667b8[uri-authentication-supported,4,44])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8273f90[uri-security-supported,4,44])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x840f3d8[printer-name,4,42])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x84085b8[printer-location,4,41])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x84085f0[printer-info,4,41])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8408628[printer-more-info,4,45])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8408690[job-quota-period,4,21])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x84086d0[job-k-limit,4,21])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x82679b0[job-page-limit,4,21])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x82679f0[job-sheets-default,4,42])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8267a58[device-uri,4,45])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x83ff278[color-supported,4,22])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8404ce8[pages-per-minute,4,21])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x83fe398[printer-make-and-model,4,41])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8267cf8[media-supported,4,44])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x829a5c0[media-default,4,44])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8270b10[finishings-supported,4,23])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x83ff168[finishings-default,4,23])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x83fee08[printer-type,4,23])
d [07/Jun/2005:23:26:30 -0500] copy_attrs(0x83e8dc8, 0x82742f8, (nil), 0)
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8274480[pdl-override-supported,4,44])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8408058[ipp-versions-supported,4,44])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x840f1f0[operations-supported,4,23])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8273ff0[multiple-document-jobs-supported,4,22])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8404d80[multiple-operation-time-out,4,21])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x826d650[multiple-document-handling-supported,4,44])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x82682f0[charset-configured,4,47])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x840f6d8[charset-supported,4,47])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x840f118[natural-language-configured,4,48])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x840f170[generated-natural-language-supported,4,48])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x840f600[document-format-default,4,49])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x84093f0[document-format-supported,4,80000049])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8409588[compression-supported,4,44])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x84095f8[job-priority-supported,4,21])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x840f688[job-priority-default,4,21])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8273e10[copies-supported,4,33])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8273e38[copies-default,4,21])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8273e78[page-ranges-supported,4,22])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8408700[number-up-supported,4,21])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x82739b8[number-up-default,4,21])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x82739e0[orientation-requested-supported,4,23])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x84087d0[orientation-requested-default,4,23])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x8273930[job-hold-until-supported,4,44])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x840f920[job-hold-until-default,4,44])
d [07/Jun/2005:23:26:30 -0500] copy_attribute(0x83e8dc8, 0x840f978[job-sheets-supported,4,42])
D [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest: 6 status_code=0
d [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest: Adding fd 6 to OutputSet...
d [07/Jun/2005:23:26:30 -0500] WriteClient: Removing fd 6 from OutputSet...
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6, used=0, file=-1
d [07/Jun/2005:23:26:30 -0500] ReadClient: httpGets returned EOF...
D [07/Jun/2005:23:26:30 -0500] CloseClient: 6
d [07/Jun/2005:23:26:30 -0500] CloseClient: Removing fd 6 from InputSet and OutputSet...
d [07/Jun/2005:23:26:30 -0500] AcceptClient(lis=0x83ff360) 0 NumClients = 0
D [07/Jun/2005:23:26:30 -0500] AcceptClient: 6 from localhost:631.
d [07/Jun/2005:23:26:30 -0500] AcceptClient: Adding fd 6 to InputSet...
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6, used=0, file=-1
D [07/Jun/2005:23:26:30 -0500] ReadClient: 6 POST /printers/canon HTTP/1.1
d [07/Jun/2005:23:26:30 -0500] decode_auth(0xb7c66008): Authorization string = ""
d [07/Jun/2005:23:26:30 -0500] decode_auth: 6 username=""
d [07/Jun/2005:23:26:30 -0500] IsAuthorized: con->uri = "/printers/canon"
d [07/Jun/2005:23:26:30 -0500] FindBest: uri = "/printers/canon"...
d [07/Jun/2005:23:26:30 -0500] FindBest: Location / Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /admin Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /printers/printer Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location /printers/canon Limit 7f
d [07/Jun/2005:23:26:30 -0500] FindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [07/Jun/2005:23:26:30 -0500] FindBest: best = "/printers/canon"
d [07/Jun/2005:23:26:30 -0500] IsAuthorized: auth = 0, satisfy=0...
d [07/Jun/2005:23:26:30 -0500] POST /printers/canon
d [07/Jun/2005:23:26:30 -0500] CONTENT_TYPE = application/ipp
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6 con->data_encoding = length, con->data_remaining = 14926, con->file = -1
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6, used=313, file=-1
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6 con->data_encoding = length, con->data_remaining = 14918, con->file = -1
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6 REQUEST /var/spool/cups/00000006=8
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6 writing 1985 bytes to 8
d [07/Jun/2005:23:26:30 -0500] select_timeout: 29 seconds to send browse update
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6, used=0, file=8
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6 con->data_encoding = length, con->data_remaining = 12557, con->file = 8
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6 writing 12557 bytes to 8
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6 Closing data file 8, size = 14542.
d [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest(0xb7c66008[6]): operation_id = 0002
d [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest: URI="ipp://localhost:631/printers/canon"
d [07/Jun/2005:23:26:30 -0500] print_job(0xb7c66008[6], ipp://localhost:631/printers/canon)
D [07/Jun/2005:23:26:30 -0500] print_job: auto-typing file...
D [07/Jun/2005:23:26:30 -0500] print_job: request file type is application/postscript.
d [07/Jun/2005:23:26:30 -0500] check_quotas(0xb7c66008[6], 0x84080e8[canon])
D [07/Jun/2005:23:26:30 -0500] check_quotas: requesting-user-name = 'root'
D [07/Jun/2005:23:26:30 -0500] print_job: requesting-user-name = 'root'
I [07/Jun/2005:23:26:30 -0500] Adding start banner page "none" to job 32.
d [07/Jun/2005:23:26:30 -0500] copy_banner(0xb7c66008[6], 0x83e97a8[32], none)
d [07/Jun/2005:23:26:30 -0500] add_file(con=0xb7c66008[6], job=32, filetype=application/postscript, compression=0)
I [07/Jun/2005:23:26:30 -0500] Adding end banner page "none" to job 32.
d [07/Jun/2005:23:26:30 -0500] copy_banner(0xb7c66008[6], 0x83e97a8[32], none)
I [07/Jun/2005:23:26:30 -0500] Job 32 queued on 'canon' by 'root'.
D [07/Jun/2005:23:26:30 -0500] Job 32 hold_until = 0
d [07/Jun/2005:23:26:30 -0500] SaveJob: Closing file 8...
D [07/Jun/2005:23:26:30 -0500] StartJob(32, 0x84080e8)
D [07/Jun/2005:23:26:30 -0500] StartJob() id = 32, file = 0/1
D [07/Jun/2005:23:26:30 -0500] job-sheets=none,none
D [07/Jun/2005:23:26:30 -0500] banner_page = 0
D [07/Jun/2005:23:26:30 -0500] StartJob: argv = "canon","32","root","testprint.ps","1","cpi=12 fitplot lpi=7 page-bottom=86 page-left=57 page-right=57 page-top=72 scaling=100 wrap","/var/spool/cups/d00032-001"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[2]="USER=root"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[3]="CHARSET=utf-8"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[4]="LANG=en_US"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[5]="PPD=/etc/cups/ppd/canon.ppd"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[10]="DEVICE_URI=lpd://192.168.1.2/L1"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[11]="PRINTER=canon"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[14]="CUPS_SERVER=localhost"
D [07/Jun/2005:23:26:30 -0500] StartJob: envp[15]="IPP_PORT=631"
d [07/Jun/2005:23:26:30 -0500] StartJob: Allocating status buffer...
D [07/Jun/2005:23:26:30 -0500] StartJob: statusfds = [ 8 9 ]
D [07/Jun/2005:23:26:30 -0500] StartJob: filterfds[1] = [ 10 -1 ]
D [07/Jun/2005:23:26:30 -0500] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [07/Jun/2005:23:26:30 -0500] StartJob: filterfds[0] = [ 11 12 ]
D [07/Jun/2005:23:26:30 -0500] start_process("/usr/lib/cups/filter/pstops", 0xbfd8bd20, 0xbfd8b298, 10, 12, 9)
I [07/Jun/2005:23:26:30 -0500] Started filter /usr/lib/cups/filter/pstops (PID 6798) for job 32.
D [07/Jun/2005:23:26:30 -0500] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip"
D [07/Jun/2005:23:26:30 -0500] StartJob: filterfds[1] = [ 10 13 ]
D [07/Jun/2005:23:26:30 -0500] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbfd8bd20, 0xbfd8b298, 11, 13, 9)
I [07/Jun/2005:23:26:30 -0500] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6799) for job 32.
D [07/Jun/2005:23:26:30 -0500] StartJob: backend = "/usr/lib/cups/backend/lpd"
D [07/Jun/2005:23:26:30 -0500] StartJob: filterfds[0] = [ -1 11 ]
D [07/Jun/2005:23:26:30 -0500] start_process("/usr/lib/cups/backend/lpd", 0xbfd8bd20, 0xbfd8b298, 10, 11, 9)
I [07/Jun/2005:23:26:30 -0500] Started backend /usr/lib/cups/backend/lpd (PID 6800) for job 32.
d [07/Jun/2005:23:26:30 -0500] StartJob: Adding fd 8 to InputSet...
d [07/Jun/2005:23:26:30 -0500] add_job_state_reasons(0xb7c66008[6], 32)
D [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest: 6 status_code=0
d [07/Jun/2005:23:26:30 -0500] ProcessIPPRequest: Adding fd 6 to OutputSet...
d [07/Jun/2005:23:26:30 -0500] PID 6798 exited with no errors.
D [07/Jun/2005:23:26:30 -0500] [Job 32] Page = 612x792; 36,36 to 576,756
D [07/Jun/2005:23:26:30 -0500] [Job 32] slowcollate=0, slowduplex=0, sloworder=0
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%BoundingBox: 0 0 612 792
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%Pages: 1
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%LanguageLevel: 1
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%DocumentData: Clean7Bit
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%DocumentSuppliedResources: procset testprint/1.1
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%Creator: Michael Sweet, Easy Software Products
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%CreationDate: May 11, 1999
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%Title: Test Page
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%EndComments
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%BeginProlog
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%BeginResource procset testprint 1.1 0
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%EndResource
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%EndProlog
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%Page: 1 1
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%Page: 1 1
D [07/Jun/2005:23:26:30 -0500] [Job 32] pw = 498.0, pl = 634.0
D [07/Jun/2005:23:26:30 -0500] [Job 32] PageLeft = 57.0, PageRight = 555.0
D [07/Jun/2005:23:26:30 -0500] [Job 32] PageTop = 720.0, PageBottom = 86.0
D [07/Jun/2005:23:26:30 -0500] [Job 32] PageWidth = 612.0, PageLength = 792.0
D [07/Jun/2005:23:26:30 -0500] [Job 32] 0 %%EOF
D [07/Jun/2005:23:26:30 -0500] [Job 32] Saw EOF!
d [07/Jun/2005:23:26:30 -0500] WriteClient: Removing fd 6 from OutputSet...
d [07/Jun/2005:23:26:30 -0500] ReadClient: 6, used=0, file=-1
d [07/Jun/2005:23:26:30 -0500] ReadClient: httpGets returned EOF...
D [07/Jun/2005:23:26:30 -0500] CloseClient: 6
d [07/Jun/2005:23:26:30 -0500] CloseClient: Removing fd 6 from InputSet and OutputSet...
D [07/Jun/2005:23:26:31 -0500] [Job 32] foomatic-rip version $Revision: 3.43.2.7 $ running...
D [07/Jun/2005:23:26:31 -0500] [Job 32] Parsing PPD file ...
D [07/Jun/2005:23:26:31 -0500] [Job 32] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option ColorSpace
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option PageSize
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option PageRegion
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option ImageableArea
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option PaperDimension
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option Manual
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option MediaType
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option MediaWeight
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option Resolution
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option PrintQuality
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option BitsPerPixel
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option ProcessColorModel
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option PrintColors
D [07/Jun/2005:23:26:31 -0500] [Job 32] Added option Font
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] Parameter Summary
D [07/Jun/2005:23:26:31 -0500] [Job 32] -----------------
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] Spooler: cups
D [07/Jun/2005:23:26:31 -0500] [Job 32] Printer: canon
D [07/Jun/2005:23:26:31 -0500] [Job 32] PPD file: /etc/cups/ppd/canon.ppd
D [07/Jun/2005:23:26:31 -0500] [Job 32] Printer model: Canon BJC-7000 Foomatic/bjc800
D [07/Jun/2005:23:26:31 -0500] [Job 32] Job title: testprint.ps
D [07/Jun/2005:23:26:31 -0500] [Job 32] File(s) to be printed:
D [07/Jun/2005:23:26:31 -0500] [Job 32] <STDIN>
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] Pondering option 'cpi=12'
D [07/Jun/2005:23:26:31 -0500] [Job 32] Unknown option cpi=12.
D [07/Jun/2005:23:26:31 -0500] [Job 32] Pondering option 'fitplot'
D [07/Jun/2005:23:26:31 -0500] [Job 32] Unknown boolean option "fitplot".
D [07/Jun/2005:23:26:31 -0500] [Job 32] Pondering option 'lpi=7'
D [07/Jun/2005:23:26:31 -0500] [Job 32] Unknown option lpi=7.
D [07/Jun/2005:23:26:31 -0500] [Job 32] Pondering option 'page-bottom=86'
D [07/Jun/2005:23:26:31 -0500] [Job 32] Unknown option page-bottom=86.
D [07/Jun/2005:23:26:31 -0500] [Job 32] Pondering option 'page-left=57'
D [07/Jun/2005:23:26:31 -0500] [Job 32] Unknown option page-left=57.
D [07/Jun/2005:23:26:31 -0500] [Job 32] Pondering option 'page-right=57'
D [07/Jun/2005:23:26:31 -0500] [Job 32] Unknown option page-right=57.
D [07/Jun/2005:23:26:31 -0500] [Job 32] Pondering option 'page-top=72'
D [07/Jun/2005:23:26:31 -0500] [Job 32] Unknown option page-top=72.
D [07/Jun/2005:23:26:31 -0500] [Job 32] Pondering option 'scaling=100'
D [07/Jun/2005:23:26:31 -0500] [Job 32] Unknown option scaling=100.
D [07/Jun/2005:23:26:31 -0500] [Job 32] Pondering option 'wrap'
D [07/Jun/2005:23:26:31 -0500] [Job 32] Unknown boolean option "wrap".
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] ================================================
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] File: <STDIN>
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] ================================================
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] Reading PostScript input ...
D [07/Jun/2005:23:26:31 -0500] [Job 32] --> This document is DSC-conforming!
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] -----------
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginProlog
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%EndProlog
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] -----------
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginSetup
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginFeature: *ProcessColorModel CMYK
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: ProcessColorModel=CMYK --> Setting option
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %% FoomaticRIPOptionSetting: ProcessColorModel=CMYK
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: ProcessColorModel=CMYK --> Setting option
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginFeature: *BitsPerPixel 24
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: BitsPerPixel=24 --> Setting option
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %% FoomaticRIPOptionSetting: BitsPerPixel=24
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: BitsPerPixel=24 --> Setting option
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginFeature: *MediaType plain
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: MediaType=plain --> Option will be set by PostScript interpreter
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginFeature: *MediaWeight Normal
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: MediaWeight=Normal --> Option will be set by PostScript interpreter
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginFeature: *PageSize Letter
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: PageSize=Letter --> Option will be set by PostScript interpreter
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginFeature: *PrintQuality Normal
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: PrintQuality=Normal --> Option will be set by PostScript interpreter
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginFeature: *Manual False
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: Manual=False --> Correcting numerical/string option to Manual=0 (Command line argument)
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginFeature: *PrintColors CMYK
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: PrintColors=CMYK --> Option will be set by PostScript interpreter
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%BeginFeature: *Resolution 300x300dpi
D [07/Jun/2005:23:26:31 -0500] [Job 32] Option: Resolution=300x300dpi --> Option will be set by PostScript interpreter
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found: %%EndSetup
D [07/Jun/2005:23:26:31 -0500] [Job 32] Inserting PostScript code for CUPS' page accounting
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] -----------
D [07/Jun/2005:23:26:31 -0500] [Job 32] New page:  1 1
D [07/Jun/2005:23:26:31 -0500] [Job 32] Inserting option code into "PageSetup" section.
D [07/Jun/2005:23:26:31 -0500] [Job 32] No page header or page header not DSC-conforming
D [07/Jun/2005:23:26:31 -0500] [Job 32] Stopping search for page header options
D [07/Jun/2005:23:26:31 -0500] [Job 32] Found:
D [07/Jun/2005:23:26:31 -0500] [Job 32] pageHeight sub			% Move down...
D [07/Jun/2005:23:26:31 -0500] [Job 32] --> Output goes directly to the renderer now.
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] Starting renderer
D [07/Jun/2005:23:26:31 -0500] [Job 32] JCL: <job data>
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] renderer PID kid4=6802
D [07/Jun/2005:23:26:31 -0500] [Job 32] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=bjc800 -dBitsPerPixel=24 -dProcessColorModel=/DeviceCMYK -sOutputFile=- -
D [07/Jun/2005:23:26:31 -0500] [Job 32]
D [07/Jun/2005:23:26:31 -0500] [Job 32] Closing renderer
D [07/Jun/2005:23:26:31 -0500] [Job 32] foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=bjc800' '-dBitsPerPixel=24' '-dProcessColorModel=/DeviceCMYK' '-sOutputFile=| cat >&3' '/dev/fd/0' 3>&1 1>&2
D [07/Jun/2005:23:26:31 -0500] [Job 32] GNU Ghostscript 7.07: Can't find initialization file gs_init.ps.
D [07/Jun/2005:23:26:31 -0500] [Job 32] renderer return value: 1
D [07/Jun/2005:23:26:31 -0500] [Job 32] renderer received signal: 1
D [07/Jun/2005:23:26:31 -0500] [Job 32] KID3 exited with status 3
D [07/Jun/2005:23:26:31 -0500] [Job 32] Renderer exit stat: 3
D [07/Jun/2005:23:26:31 -0500] [Job 32] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3
D [07/Jun/2005:23:26:31 -0500] [Job 32] error: Illegal seek (29)
D [07/Jun/2005:23:26:31 -0500] [Job 32] Possible error on renderer command line or PostScript error. Check options.
D [07/Jun/2005:23:26:31 -0500] [Job 32] Renderer process finished
D [07/Jun/2005:23:26:31 -0500] [Job 32] Killing process 6801 (KID3)
D [07/Jun/2005:23:26:31 -0500] [Job 32] Process dying with "Error closing renderer", exit stat: 3
D [07/Jun/2005:23:26:31 -0500] [Job 32] error: Bad file descriptor (9)
D [07/Jun/2005:23:26:31 -0500] [Job 32] Error closing renderer
D [07/Jun/2005:23:26:31 -0500] [Job 32] tail process done writing data to STDOUT
D [07/Jun/2005:23:26:31 -0500] [Job 32] KID4 finished
I [07/Jun/2005:23:26:31 -0500] [Job 32] Attempting to connect to host 192.168.1.2 for printer L1
E [07/Jun/2005:23:26:31 -0500] PID 6799 stopped with status 3!
I [07/Jun/2005:23:26:31 -0500] [Job 32] Connected to 192.168.1.2...
D [07/Jun/2005:23:26:31 -0500] [Job 32] Connected on ports 515 (local 1023)...
D [07/Jun/2005:23:26:31 -0500] [Job 32] lpd_command 02 L1
D [07/Jun/2005:23:26:31 -0500] [Job 32] Sending command string (4 bytes)...
D [07/Jun/2005:23:26:31 -0500] [Job 32] Reading command status...
D [07/Jun/2005:23:26:31 -0500] [Job 32] lpd_command returning 0
D [07/Jun/2005:23:26:31 -0500] [Job 32] Control file is:
D [07/Jun/2005:23:26:31 -0500] [Job 32] Hdharma
D [07/Jun/2005:23:26:31 -0500] [Job 32] Proot
D [07/Jun/2005:23:26:31 -0500] [Job 32] Jtestprint_ps
D [07/Jun/2005:23:26:31 -0500] [Job 32] ldfA800dharma
D [07/Jun/2005:23:26:31 -0500] [Job 32] UdfA800dharma
D [07/Jun/2005:23:26:31 -0500] [Job 32] Ntestprint_ps
D [07/Jun/2005:23:26:31 -0500] [Job 32] lpd_command 02 70 cfA800dharma
D [07/Jun/2005:23:26:31 -0500] [Job 32] Sending command string (17 bytes)...
D [07/Jun/2005:23:26:31 -0500] [Job 32] Reading command status...
D [07/Jun/2005:23:26:31 -0500] [Job 32] lpd_command returning 0
I [07/Jun/2005:23:26:31 -0500] [Job 32] Sending control file (70 bytes)
I [07/Jun/2005:23:26:31 -0500] [Job 32] Control file sent successfully
D [07/Jun/2005:23:26:31 -0500] [Job 32] lpd_command 03 0 dfA800dharma
D [07/Jun/2005:23:26:31 -0500] [Job 32] Sending command string (16 bytes)...
D [07/Jun/2005:23:26:31 -0500] [Job 32] Reading command status...
D [07/Jun/2005:23:26:31 -0500] [Job 32] lpd_command returning 0
I [07/Jun/2005:23:26:31 -0500] [Job 32] Sending data file (0 bytes)
I [07/Jun/2005:23:26:31 -0500] [Job 32] Data file sent successfully
d [07/Jun/2005:23:26:31 -0500] PID 6800 exited with no errors.
D [07/Jun/2005:23:26:31 -0500] UpdateJob: job 32, file 0 is complete.
d [07/Jun/2005:23:26:31 -0500] UpdateJob: Removing fd 8 from InputSet...
D [07/Jun/2005:23:26:31 -0500] CancelJob: id = 32
D [07/Jun/2005:23:26:31 -0500] StopJob: id = 32, force = 0
D [07/Jun/2005:23:26:31 -0500] StopJob: printer state is 3
d [07/Jun/2005:23:26:31 -0500] StopJob: Freeing status buffer...
d [07/Jun/2005:23:26:31 -0500] SaveJob: Closing file 6...
d [07/Jun/2005:23:26:31 -0500] SaveJob: Closing file 6...