Printer Getting Disabled

Sun Jul 3 02:07:53 PDT 2011

Hi Helge,
Thanks for that, the error log entry is ,

D [01/Jul/2011:09:03:03 +1000] ReadClient: 7 POST /printers/ERH_AP_CHQRPT HTTP/1.1
d [01/Jul/2011:09:03:03 +1000] decode_auth(0xf6cd0008): Authorization string = ""
d [01/Jul/2011:09:03:03 +1000] decode_auth: 7 username=""
d [01/Jul/2011:09:03:03 +1000] IsAuthorized: con->uri = "/printers/ERH_AP_CHQRPT"
d [01/Jul/2011:09:03:03 +1000] FindBest: uri = "/printers/ERH_AP_CHQRPT"...
d [01/Jul/2011:09:03:03 +1000] FindBest: Location / Limit 7f
d [01/Jul/2011:09:03:03 +1000] FindBest: Location /admin Limit 7f
d [01/Jul/2011:09:03:03 +1000] FindBest: best = "/"
d [01/Jul/2011:09:03:03 +1000] IsAuthorized: auth = 0, satisfy=0...
d [01/Jul/2011:09:03:03 +1000] POST /printers/ERH_AP_CHQRPT
d [01/Jul/2011:09:03:03 +1000] CONTENT_TYPE = application/ipp
d [01/Jul/2011:09:03:03 +1000] ReadClient: 7 con->data_encoding = length, con->data_remaining = 8585, con->file = -1
d [01/Jul/2011:09:03:03 +1000] ReadClient: 7 REQUEST /var/spool/cups/000000ae=8
d [01/Jul/2011:09:03:03 +1000] ReadClient: 7 writing 8294 bytes to 8
d [01/Jul/2011:09:03:03 +1000] ReadClient: 7 Closing data file 8, size = 8294.
d [01/Jul/2011:09:03:03 +1000] ProcessIPPRequest(0xf6cd0008[7]): operation_id = 0002
d [01/Jul/2011:09:03:03 +1000] ProcessIPPRequest: URI="ipp://localhost:631/printers/ERH_AP_CHQRPT"
d [01/Jul/2011:09:03:03 +1000] print_job(0xf6cd0008[7], ipp://localhost:631/printers/ERH_AP_CHQRPT)
D [01/Jul/2011:09:03:03 +1000] print_job: auto-typing file...
D [01/Jul/2011:09:03:03 +1000] print_job: request file type is application/vnd.cups-raw.
d [01/Jul/2011:09:03:03 +1000] check_quotas(0xf6cd0008[7], 0x98846e8[ERH_AP_CHQRPT])
D [01/Jul/2011:09:03:03 +1000] check_quotas: requesting-user-name = 'oracle'
D [01/Jul/2011:09:03:03 +1000] print_job: requesting-user-name = 'oracle'
I [01/Jul/2011:09:03:03 +1000] Adding start banner page "none" to job 175.
d [01/Jul/2011:09:03:03 +1000] copy_banner(0xf6cd0008[7], 0x9be4170[175], none)
d [01/Jul/2011:09:03:03 +1000] add_file(con=0xf6cd0008[7], job=175, filetype=application/vnd.cups-raw, compression=0)
I [01/Jul/2011:09:03:03 +1000] Adding end banner page "none" to job 175.
d [01/Jul/2011:09:03:03 +1000] copy_banner(0xf6cd0008[7], 0x9be4170[175], none)
I [01/Jul/2011:09:03:03 +1000] Job 175 queued on 'ERH_AP_CHQRPT' by 'oracle'.
D [01/Jul/2011:09:03:03 +1000] Job 175 hold_until = 0
d [01/Jul/2011:09:03:03 +1000] SaveJob: Closing file 8...
D [01/Jul/2011:09:03:03 +1000] StartJob(175, 0x98846e8)
D [01/Jul/2011:09:03:03 +1000] StartJob() id = 175, file = 0/1
D [01/Jul/2011:09:03:03 +1000] StartJob: Sending job to queue tagged as raw...
D [01/Jul/2011:09:03:03 +1000] job-sheets=none,none
D [01/Jul/2011:09:03:03 +1000] banner_page = 0
D [01/Jul/2011:09:03:03 +1000] StartJob: argv = "ERH_AP_CHQRPT","175","oracle","REPRINT.20854909","1","nb","/var/spool/cups/d00175-001"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[2]="USER=root"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[3]="CHARSET=iso-8859-1"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[4]="LANG=c-"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[5]="PPD=/etc/cups/ppd/ERH_AP_CHQRPT.ppd"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[9]="CONTENT_TYPE=application/vnd.cups-raw"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[10]="DEVICE_URI=lpd://10.49.129.22/ERH_AP_CHQRPT"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[11]="PRINTER=ERH_AP_CHQRPT"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[14]="CUPS_SERVER=localhost"
D [01/Jul/2011:09:03:03 +1000] StartJob: envp[15]="IPP_PORT=631"
d [01/Jul/2011:09:03:03 +1000] StartJob: Allocating status buffer...
D [01/Jul/2011:09:03:03 +1000] StartJob: statusfds = [ 8 9 ]
D [01/Jul/2011:09:03:03 +1000] StartJob: filterfds[1] = [ 10 -1 ]
D [01/Jul/2011:09:03:03 +1000] StartJob: backend = "/usr/lib/cups/backend/lpd"
D [01/Jul/2011:09:03:03 +1000] StartJob: filterfds[0] = [ -1 11 ]
D [01/Jul/2011:09:03:03 +1000] start_process("/usr/lib/cups/backend/lpd", 0xfee12930, 0xfee11ca0, 10, 11, 9)
D [01/Jul/2011:09:03:03 +1000] start_process("/usr/lib/cups/backend/lpd", 0xfee12930, 0xfee11ca0, 10, 11, 9)
I [01/Jul/2011:09:03:03 +1000] Started backend /usr/lib/cups/backend/lpd (PID 25927) for job 175.
d [01/Jul/2011:09:03:03 +1000] StartJob: Adding fd 8 to InputSet...
d [01/Jul/2011:09:03:03 +1000] add_job_state_reasons(0xf6cd0008[7], 175)
D [01/Jul/2011:09:03:03 +1000] ProcessIPPRequest: 7 status_code=0
d [01/Jul/2011:09:03:03 +1000] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [01/Jul/2011:09:03:03 +1000] WriteClient: Removing fd 7 from OutputSet...
I [01/Jul/2011:09:03:03 +1000] [Job 175] Attempting to connect to host 10.49.129.22 for printer ERH_AP_CHQRPT
d [01/Jul/2011:09:03:03 +1000] ReadClient: 7, used=0, file=-1
d [01/Jul/2011:09:03:03 +1000] ReadClient: httpGets returned EOF...
D [01/Jul/2011:09:03:03 +1000] CloseClient: 7
d [01/Jul/2011:09:03:03 +1000] CloseClient: Removing fd 7 from InputSet and OutputSet...
I [01/Jul/2011:09:03:03 +1000] [Job 175] Connected to 10.49.129.22...
D [01/Jul/2011:09:03:03 +1000] [Job 175] Connected on ports 515 (local 1021)...
D [01/Jul/2011:09:03:03 +1000] [Job 175] lpd_command 02 ERH_AP_CHQRPT
D [01/Jul/2011:09:03:03 +1000] [Job 175] Sending command string (15 bytes)...
D [01/Jul/2011:09:03:03 +1000] [Job 175] Reading command status...
W [01/Jul/2011:09:03:03 +1000] [Job 175] Remote host did not respond with command status byte after 300 seconds!
D [01/Jul/2011:09:03:03 +1000] [Job 175] lpd_command returning 104
E [01/Jul/2011:09:03:03 +1000] PID 25927 stopped with status 1!
D [01/Jul/2011:09:03:03 +1000] UpdateJob: job 175, file 0 is complete.
d [01/Jul/2011:09:03:03 +1000] UpdateJob: Removing fd 8 from InputSet...
D [01/Jul/2011:09:03:03 +1000] StopJob: id = 175, force = 0
I [01/Jul/2011:09:03:03 +1000] Saving printers.conf...
D [01/Jul/2011:09:03:03 +1000] StopJob: printer state is 5
d [01/Jul/2011:09:03:03 +1000] StopJob: Freeing status buffer...
d [01/Jul/2011:09:03:03 +1000] SaveJob: Closing file 7...

The datafile it mentions in the log is not there
D [01/Jul/2011:09:42:47 +1000] StartJob: argv = "ERH_AP_CHQRPT","175","oracle","REPRINT.20854909","1","nb","/var/spool/cups/d00175-001"

Regards
dinesh

> Dinesh Acharya wrote:
>
> > Hi,
> > I have installed CUPS and everything is fine except a printer which gets
> > disabled the moment user tries to print from Oracle E-Buisness. I have set
> > debugging to debug2
> >
> > When i print TEST page from the CUPS interface it prints ok. I am seeing
> > this info under the printer in the error log d [30/Jun/2011:22:33:49
> > +1000] pipe_command: envp[16] = "CUPS_SERVERROOT=/etc/cups" d
> > [30/Jun/2011:22:33:49 +1000] pipe_command: envp[17] =
> > "HTTP_USER_AGENT=Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1;
> > .NET CLR 1.1.4322; .NET CLR 2.0.50727)"
> >
> > I am just a begginer in CUPS please help
> >
> > Regards
> > Dinesh
>
> Please post the portion of your error_log that contains the messages
> generated by the job in question, and post the data file
> (/var/spool/cups/dxxxxx-001) of that job.
>
> Helge
>