cups PS

Thu Sep 28 08:17:53 PDT 2006

> Ricky wrote:
> > Hello,
> >
> > I am trying to print a cups testpage to a PS printer (Dell Laser Printer 3100cn), but nothing comes out of the printer.
> >
> > I have a HP Color LaserJet 3500 that is using (Foomatic/hpijs) drivers And a Samsung SCX-6220 using (Generic PCL 5e Printer Foomatic/ljet4d) driver.. I am able to print to both of them. All my printers are connected ethernet. So I am using AppsSocket/HP Jetdirect.
> >
> > I suspect I might be missing a filter to print ps files, but don't know which one. I actally Got another printe server up and running and it has no problems printing to the Dell. I actally copied the config files from working sever to other server.
> >
> >
> > My error log is turned on with debug mode enable so here is my long log file:
> >
> >
> >
> > I [27/Sep/2006:13:11:00 -0400] Adding end banner page "none" to job 15.
> > d [27/Sep/2006:13:11:00 -0400] copy_banner(0xb7cbfc3c[7], 0x81e9f10[15], none)
> > I [27/Sep/2006:13:11:00 -0400] Job 15 queued on 'BCHQ-Dell-Color' by 'root'.
> > D [27/Sep/2006:13:11:00 -0400] Job 15 hold_until = 0
> > d [27/Sep/2006:13:11:00 -0400] SaveJob: Closing file 9...
> > D [27/Sep/2006:13:11:00 -0400] StartJob(15, 0x8092448)
> > D [27/Sep/2006:13:11:00 -0400] StartJob() id = 15, file = 0/1
> > D [27/Sep/2006:13:11:00 -0400] job-sheets=none,none
> > D [27/Sep/2006:13:11:00 -0400] banner_page = 0
> > D [27/Sep/2006:13:11:00 -0400] StartJob: argv = "BCHQ-Dell-Color","15","root","Test Page","1","","/var/spool/cups/d00015-001"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[2]="USER=root"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[3]="CHARSET=utf-8"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[4]="LANG=en_US"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[5]="PPD=/etc/cups/ppd/BCHQ-Dell-Color.ppd"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[7]="RIP_MAX_CACHE=8m"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[10]="DEVICE_URI=socket://10.1.2.21:9100"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[11]="PRINTER=BCHQ-Dell-Color"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[14]="CUPS_SERVER=localhost"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: envp[15]="IPP_PORT=631"
> > d [27/Sep/2006:13:11:00 -0400] StartJob: Allocating status buffer...
> > D [27/Sep/2006:13:11:00 -0400] StartJob: statusfds = [ 9 10 ]
> > D [27/Sep/2006:13:11:00 -0400] StartJob: filterfds[1] = [ 11 -1 ]
> > D [27/Sep/2006:13:11:00 -0400] StartJob: filter = "/usr/lib/cups/filter/pstops"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: filterfds[0] = [ 12 13 ]
> > D [27/Sep/2006:13:11:00 -0400] start_process("/usr/lib/cups/filter/pstops", 0xbf82c880, 0xbf82bbf0, 11, 13, 10)
> > I [27/Sep/2006:13:11:00 -0400] Started filter /usr/lib/cups/filter/pstops (PID 15452) for job 15.
> > D [27/Sep/2006:13:11:00 -0400] StartJob: backend = "/usr/lib/cups/backend/socket"
> > D [27/Sep/2006:13:11:00 -0400] StartJob: filterfds[1] = [ -1 11 ]
> > D [27/Sep/2006:13:11:00 -0400] start_process("/usr/lib/cups/backend/socket", 0xbf82c880, 0xbf82bbf0, 12, 11, 10)
> > I [27/Sep/2006:13:11:00 -0400] Started backend /usr/lib/cups/backend/socket (PID 15453) for job 15.
> > d [27/Sep/2006:13:11:00 -0400] StartJob: Adding fd 9 to InputSet...
> > d [27/Sep/2006:13:11:00 -0400] add_job_state_reasons(0xb7cbfc3c[7], 15)
> > D [27/Sep/2006:13:11:00 -0400] ProcessIPPRequest: 7 status_code=0
> > d [27/Sep/2006:13:11:00 -0400] ProcessIPPRequest: Adding fd 7 to OutputSet...
> > I [27/Sep/2006:13:11:00 -0400] [Job 15] Attempting to connect to host 10.1.2.21 on port 9100
> > d [27/Sep/2006:13:11:00 -0400] WriteClient: Removing fd 7 from OutputSet...
> > d [27/Sep/2006:13:11:00 -0400] PID 15451 exited with no errors.
> > d [27/Sep/2006:13:11:00 -0400] DeleteCert: removing certificate for pid 15451
> > d [27/Sep/2006:13:11:00 -0400] ReadClient: 7, used=0, file=-1
> > d [27/Sep/2006:13:11:00 -0400] ReadClient: httpGets returned EOF...
> > D [27/Sep/2006:13:11:00 -0400] CloseClient: 7
> > d [27/Sep/2006:13:11:00 -0400] CloseClient: Removing fd 7 from InputSet and OutputSet...
> > d [27/Sep/2006:13:11:00 -0400] WriteClient: Removing fd 5 from OutputSet...
> > d [27/Sep/2006:13:11:00 -0400] WriteClient: Removing fd 8 from InputSet...
> > d [27/Sep/2006:13:11:00 -0400] WriteClient: 5 Closing data file 8.
> > I [27/Sep/2006:13:11:00 -0400] [Job 15] Connected to host, sending print job...
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] Page = 612x792; 12,12 to 600,780
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] slowcollate=0, slowduplex=0, sloworder=0
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%BoundingBox: 0 0 612 792
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%Pages: 1
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%LanguageLevel: 1
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%DocumentData: Clean7Bit
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%DocumentSuppliedResources: procset testprint/1.1
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%Creator: Michael Sweet, Easy Software Products
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%CreationDate: May 11, 1999
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%Title: Test Page
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%EndComments
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%BeginProlog
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%BeginResource procset testprint 1.1 0
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%EndResource
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%EndProlog
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%Page: 1 1
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%Page: 1 1
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] pw = 588.8, pl = 768.8
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] PageLeft = 11.6, PageRight = 600.4
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] PageTop = 780.4, PageBottom = 11.6
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] PageWidth = 612.0, PageLength = 792.0
> > E [27/Sep/2006:13:11:00 -0400] [Job 15] Unable to send print file to printer: Broken pipe
> > I [27/Sep/2006:13:11:00 -0400] [Job 15] Print file sent, waiting for printer to finish...
> > E [27/Sep/2006:13:11:00 -0400] PID 15453 stopped with status 1!
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] 0 %%EOF
> > D [27/Sep/2006:13:11:00 -0400] [Job 15] Saw EOF!
> > d [27/Sep/2006:13:11:00 -0400] PID 15452 exited with no errors.
> > D [27/Sep/2006:13:11:00 -0400] UpdateJob: job 15, file 0 is complete.
> > d [27/Sep/2006:13:11:00 -0400] UpdateJob: Removing fd 9 from InputSet...
> > D [27/Sep/2006:13:11:00 -0400] StopJob: id = 15, force = 0
> > I [27/Sep/2006:13:11:00 -0400] Saving printers.conf...
> > D [27/Sep/2006:13:11:00 -0400] StopJob: printer state is 5
> > d [27/Sep/2006:13:11:00 -0400] StopJob: Freeing status buffer...
> > d [27/Sep/2006:13:11:00 -0400] SaveJob: Closing file 8...
> > d [27/Sep/2006:13:11:01 -0400] select_timeout: 11 seconds to process active jobs
> > d [27/Sep/2006:13:11:12 -0400] select_timeout: 11 seconds to process active jobs
> > d [27/Sep/2006:13:11:23 -0400] select_timeout: 3 seconds to send browse update
> > d [27/Sep/2006:13:11:26 -0400] select_timeout: 1 seconds to send browse update
> > d [27/Sep/2006:13:11:27 -0400] select_timeout: 1 seconds to send browse update
> >
> >
> >
> >
> >
> >
> >
> > Thanks, For any help!
>
> If you look through your error log, you willfind that the socket backend
> exited with satus 1 and issued an error message ("...broken pipe").
> That probably is due to the fact that the respective printer closed
> the network connection before all of the job data had been transferred.
>
> Helge
>
> --
> Helge Blischke
> Softwareentwicklung
> SRZ Berlin | Firmengruppe besscom
> http://www.srz.de

Thanks for your quick response!

I went in and change my close connection to 1000 (for testing) in my cupsd.conf. Restarted cups and printed from the command line.

Gave this output:

d [28/Sep/2006:10:59:09 -0400] AcceptClient(lis=0x80866d0) 0 NumClients = 0
D [28/Sep/2006:10:59:09 -0400] AcceptClient: 5 from localhost:631.
d [28/Sep/2006:10:59:09 -0400] AcceptClient: Adding fd 5 to InputSet...
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5, used=0, file=-1
D [28/Sep/2006:10:59:09 -0400] ReadClient: 5 POST / HTTP/1.1
d [28/Sep/2006:10:59:09 -0400] decode_auth(0xb7d44008): Authorization string = ""
d [28/Sep/2006:10:59:09 -0400] decode_auth: 5 username=""
d [28/Sep/2006:10:59:09 -0400] IsAuthorized: con->uri = "/"
d [28/Sep/2006:10:59:09 -0400] FindBest: uri = "/"...
d [28/Sep/2006:10:59:09 -0400] FindBest: Location / Limit 7f
d [28/Sep/2006:10:59:09 -0400] FindBest: Location /admin Limit 7f
d [28/Sep/2006:10:59:09 -0400] FindBest: best = "/"
d [28/Sep/2006:10:59:09 -0400] IsAuthorized: auth = 0, satisfy=0...
d [28/Sep/2006:10:59:09 -0400] POST /
d [28/Sep/2006:10:59:09 -0400] CONTENT_TYPE = application/ipp
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 con->data_encoding = length, con->data_remaining = 137, con->file = -1
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5, used=0, file=-1
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 con->data_encoding = length, con->data_remaining = 129, con->file = -1
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5, used=0, file=-1
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 con->data_encoding = length, con->data_remaining = 95, con->file = -1
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5, used=0, file=-1
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 con->data_encoding = length, con->data_remaining = 61, con->file = -1
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5, used=23, file=-1
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 con->data_encoding = length, con->data_remaining = 24, con->file = -1
d [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest(0xb7d44008[5]): operation_id = 4002
d [28/Sep/2006:10:59:09 -0400] get_printers(0xb7d44008[5], 0)
d [28/Sep/2006:10:59:09 -0400] add_printer_state_reasons(0xb7d44008[5], 0x8092448[BCHQ-Dell-Color])
d [28/Sep/2006:10:59:09 -0400] add_queued_job_count(0xb7d44008[5], 0x8092448[BCHQ-Dell-Color])
d [28/Sep/2006:10:59:09 -0400] copy_attrs(0x81f0e38, 0x808f020, 0x81f0d58, 0)
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f0e38, 0x808f188[printer-name,4,42])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f0e38, 0x808f3a8[job-sheets-default,4,42])
d [28/Sep/2006:10:59:09 -0400] copy_attrs(0x81f0e38, 0x808ba98, 0x81f0d58, 0)
d [28/Sep/2006:10:59:09 -0400] add_printer_state_reasons(0xb7d44008[5], 0x80d0af8[BCHQ-HP-Color])
d [28/Sep/2006:10:59:09 -0400] add_queued_job_count(0xb7d44008[5], 0x80d0af8[BCHQ-HP-Color])
d [28/Sep/2006:10:59:09 -0400] copy_attrs(0x81f0e38, 0x80d1128, 0x81f0d58, 0)
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f0e38, 0x80d06d8[printer-name,4,42])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f0e38, 0x80d08c0[job-sheets-default,4,42])
d [28/Sep/2006:10:59:09 -0400] copy_attrs(0x81f0e38, 0x808ba98, 0x81f0d58, 0)
d [28/Sep/2006:10:59:09 -0400] add_printer_state_reasons(0xb7d44008[5], 0x80cfc68[BCHQ-Samsung-BW])
d [28/Sep/2006:10:59:09 -0400] add_queued_job_count(0xb7d44008[5], 0x80cfc68[BCHQ-Samsung-BW])
d [28/Sep/2006:10:59:09 -0400] copy_attrs(0x81f0e38, 0x80d0288, 0x81f0d58, 0)
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f0e38, 0x8096ac8[printer-name,4,42])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f0e38, 0x80d1238[job-sheets-default,4,42])
d [28/Sep/2006:10:59:09 -0400] copy_attrs(0x81f0e38, 0x808ba98, 0x81f0d58, 0)
d [28/Sep/2006:10:59:09 -0400] add_printer_state_reasons(0xb7d44008[5], 0x80d1610[BCHQ-Samsung-MFD-BW])
d [28/Sep/2006:10:59:09 -0400] add_queued_job_count(0xb7d44008[5], 0x80d1610[BCHQ-Samsung-MFD-BW])
d [28/Sep/2006:10:59:09 -0400] copy_attrs(0x81f0e38, 0x8090e58, 0x81f0d58, 0)
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f0e38, 0x8090fc8[printer-name,4,42])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f0e38, 0x80911e0[job-sheets-default,4,42])
d [28/Sep/2006:10:59:09 -0400] copy_attrs(0x81f0e38, 0x808ba98, 0x81f0d58, 0)
D [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest: 5 status_code=1
d [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [28/Sep/2006:10:59:09 -0400] select_timeout: 11 seconds to process active jobs
d [28/Sep/2006:10:59:09 -0400] WriteClient: Removing fd 5 from OutputSet...
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5, used=0, file=-1
D [28/Sep/2006:10:59:09 -0400] ReadClient: 5 POST / HTTP/1.1
d [28/Sep/2006:10:59:09 -0400] decode_auth(0xb7d44008): Authorization string = ""
d [28/Sep/2006:10:59:09 -0400] decode_auth: 5 username=""
d [28/Sep/2006:10:59:09 -0400] IsAuthorized: con->uri = "/"
d [28/Sep/2006:10:59:09 -0400] FindBest: uri = "/"...
d [28/Sep/2006:10:59:09 -0400] FindBest: Location / Limit 7f
d [28/Sep/2006:10:59:09 -0400] FindBest: Location /admin Limit 7f
d [28/Sep/2006:10:59:09 -0400] FindBest: best = "/"
d [28/Sep/2006:10:59:09 -0400] IsAuthorized: auth = 0, satisfy=0...
d [28/Sep/2006:10:59:09 -0400] POST /
d [28/Sep/2006:10:59:09 -0400] CONTENT_TYPE = application/ipp
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 con->data_encoding = length, con->data_remaining = 137, con->file = -1
d [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest(0xb7d44008[5]): operation_id = 4005
d [28/Sep/2006:10:59:09 -0400] get_printers(0xb7d44008[5], 1)
D [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest: 5 status_code=1
d [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [28/Sep/2006:10:59:09 -0400] WriteClient: Removing fd 5 from OutputSet...
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5, used=0, file=-1
D [28/Sep/2006:10:59:09 -0400] ReadClient: 5 POST / HTTP/1.1
d [28/Sep/2006:10:59:09 -0400] decode_auth(0xb7d44008): Authorization string = ""
d [28/Sep/2006:10:59:09 -0400] decode_auth: 5 username=""
d [28/Sep/2006:10:59:09 -0400] IsAuthorized: con->uri = "/"
d [28/Sep/2006:10:59:09 -0400] FindBest: uri = "/"...
d [28/Sep/2006:10:59:09 -0400] FindBest: Location / Limit 7f
d [28/Sep/2006:10:59:09 -0400] FindBest: Location /admin Limit 7f
d [28/Sep/2006:10:59:09 -0400] FindBest: best = "/"
d [28/Sep/2006:10:59:09 -0400] IsAuthorized: auth = 0, satisfy=0...
d [28/Sep/2006:10:59:09 -0400] POST /
d [28/Sep/2006:10:59:09 -0400] CONTENT_TYPE = application/ipp
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 con->data_encoding = length, con->data_remaining = 77, con->file = -1
d [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest(0xb7d44008[5]): operation_id = 4001
d [28/Sep/2006:10:59:09 -0400] get_default(0xb7d44008[5])
d [28/Sep/2006:10:59:09 -0400] copy_attrs(0x81f1940, 0x80d1128, (nil), 0)
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d1150[printer-uri-supported,4,45])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0610[uri-authentication-supported,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0680[uri-security-supported,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d06d8[printer-name,4,42])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0700[printer-location,4,41])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0738[printer-info,4,41])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0798[printer-more-info,4,45])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0808[job-quota-period,4,21])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0848[job-k-limit,4,21])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0880[job-page-limit,4,21])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d08c0[job-sheets-default,4,42])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0928[device-uri,4,45])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x809c220[color-supported,4,22])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x809b478[pages-per-minute,4,21])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8096cf8[printer-make-and-model,4,41])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8098fb8[media-supported,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8099328[media-default,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8096978[sides-supported,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8099ff0[sides-default,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0980[finishings-supported,4,23])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d09c8[finishings-default,4,23])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80d0a08[printer-type,4,23])
d [28/Sep/2006:10:59:09 -0400] copy_attrs(0x81f1940, 0x808ba98, (nil), 0)
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80934f8[pdl-override-supported,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8093098[ipp-versions-supported,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x808bb78[operations-supported,4,23])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8093388[multiple-document-jobs-supported,4,22])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8093360[multiple-operation-time-out,4,21])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8092e58[multiple-document-handling-supported,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8093218[charset-configured,4,47])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8092a68[charset-supported,4,47])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80931d8[natural-language-configured,4,48])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8093198[generated-natural-language-supported,4,48])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8093150[document-format-default,4,49])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8092f00[document-format-supported,4,80000049])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x808bb08[compression-supported,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8093108[job-priority-supported,4,21])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8092e98[job-priority-default,4,21])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8092e30[copies-supported,4,33])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8092dd8[copies-default,4,21])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8092a40[page-ranges-supported,4,22])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8093260[number-up-supported,4,21])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8092a18[number-up-default,4,21])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8093318[orientation-requested-supported,4,23])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x808bcf8[orientation-requested-default,4,23])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x80933d8[job-hold-until-supported,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x808bac0[job-hold-until-default,4,44])
d [28/Sep/2006:10:59:09 -0400] copy_attribute(0x81f1940, 0x8093550[job-sheets-supported,4,42])
D [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest: 5 status_code=0
d [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [28/Sep/2006:10:59:09 -0400] WriteClient: Removing fd 5 from OutputSet...
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5, used=0, file=-1
d [28/Sep/2006:10:59:09 -0400] ReadClient: httpGets returned EOF...
D [28/Sep/2006:10:59:09 -0400] CloseClient: 5
d [28/Sep/2006:10:59:09 -0400] CloseClient: Removing fd 5 from InputSet and OutputSet...
d [28/Sep/2006:10:59:09 -0400] AcceptClient(lis=0x80866d0) 0 NumClients = 0
D [28/Sep/2006:10:59:09 -0400] AcceptClient: 5 from localhost:631.
d [28/Sep/2006:10:59:09 -0400] AcceptClient: Adding fd 5 to InputSet...
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5, used=0, file=-1
D [28/Sep/2006:10:59:09 -0400] ReadClient: 5 POST /printers/BCHQ-Dell-Color HTTP/1.1
d [28/Sep/2006:10:59:09 -0400] decode_auth(0xb7d44008): Authorization string = ""
d [28/Sep/2006:10:59:09 -0400] decode_auth: 5 username=""
d [28/Sep/2006:10:59:09 -0400] IsAuthorized: con->uri = "/printers/BCHQ-Dell-Color"
d [28/Sep/2006:10:59:09 -0400] FindBest: uri = "/printers/BCHQ-Dell-Color"...
d [28/Sep/2006:10:59:09 -0400] FindBest: Location / Limit 7f
d [28/Sep/2006:10:59:09 -0400] FindBest: Location /admin Limit 7f
d [28/Sep/2006:10:59:09 -0400] FindBest: best = "/"
d [28/Sep/2006:10:59:09 -0400] IsAuthorized: auth = 0, satisfy=0...
d [28/Sep/2006:10:59:09 -0400] POST /printers/BCHQ-Dell-Color
d [28/Sep/2006:10:59:09 -0400] CONTENT_TYPE = application/ipp
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 con->data_encoding = length, con->data_remaining = 1174, con->file = -1
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 REQUEST /var/spool/cups/00000000=7
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 writing 909 bytes to 7
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5 Closing data file 7, size = 909.
d [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest(0xb7d44008[5]): operation_id = 0002
d [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest: URI="ipp://localhost:631/printers/BCHQ-Dell-Color"
d [28/Sep/2006:10:59:09 -0400] print_job(0xb7d44008[5], ipp://localhost:631/printers/BCHQ-Dell-Color)
D [28/Sep/2006:10:59:09 -0400] print_job: auto-typing file...
D [28/Sep/2006:10:59:09 -0400] print_job: request file type is text/plain.
d [28/Sep/2006:10:59:09 -0400] check_quotas(0xb7d44008[5], 0x8092448[BCHQ-Dell-Color])
D [28/Sep/2006:10:59:09 -0400] check_quotas: requesting-user-name = 'root'
D [28/Sep/2006:10:59:09 -0400] print_job: requesting-user-name = 'root'
I [28/Sep/2006:10:59:09 -0400] Adding start banner page "none" to job 19.
d [28/Sep/2006:10:59:09 -0400] copy_banner(0xb7d44008[5], 0x81f1968[19], none)
d [28/Sep/2006:10:59:09 -0400] add_file(con=0xb7d44008[5], job=19, filetype=text/plain, compression=0)
I [28/Sep/2006:10:59:09 -0400] Adding end banner page "none" to job 19.
d [28/Sep/2006:10:59:09 -0400] copy_banner(0xb7d44008[5], 0x81f1968[19], none)
I [28/Sep/2006:10:59:09 -0400] Job 19 queued on 'BCHQ-Dell-Color' by 'root'.
D [28/Sep/2006:10:59:09 -0400] Job 19 hold_until = 0
d [28/Sep/2006:10:59:09 -0400] SaveJob: Closing file 7...
d [28/Sep/2006:10:59:09 -0400] add_job_state_reasons(0xb7d44008[5], 19)
D [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest: 5 status_code=0
d [28/Sep/2006:10:59:09 -0400] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [28/Sep/2006:10:59:09 -0400] WriteClient: Removing fd 5 from OutputSet...
d [28/Sep/2006:10:59:09 -0400] ReadClient: 5, used=0, file=-1
d [28/Sep/2006:10:59:09 -0400] ReadClient: httpGets returned EOF...
D [28/Sep/2006:10:59:09 -0400] CloseClient: 5
d [28/Sep/2006:10:59:09 -0400] CloseClient: Removing fd 5 from InputSet and OutputSet...
d [28/Sep/2006:10:59:10 -0400] select_timeout: 11 seconds to process active jobs

Thanks