Printer Getting Disabled
Helge Blischke
h.blischke at acm.org
Sun Jul 3 03:36:21 PDT 2011
Dinesh Acharya wrote:
> Hi Helge,
> thanks for the reply.
> Here is the error log entry for one of the failed jobs,
>
> 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 data file entry which was in /var/spool/cups is,
> ^[E^[&l1o2a5.45C^[(s0p16.66H^[&k6.75H^[&k3GGippsland Health Alliance
> Journal Import Execution Report
> Date: 01-JUL-11 09:05
> Concurrent Request ID: 20855084
> Page: 1
>
> Total Total Total
> Total Unbalanced Total
> Unbalanced Total Flex
> Total Non-Flex
> Journal Entry Source Name Group Id Status Lines Batches Headers
> Batches Headers Errors Errors
> ---------------------------- ----------- ------- ------- ------- -------
> ---------------- ---------------- ---------- --------------
> Spreadsheet 24395245574 Success 2 1 1
> 0 0 0 0
> ------- ------- -------
> ----------------
> ----------------
> ---------- --------------
> *** TOTALS *** 2 1 1
> 0 0 0 0
>
>
> ======================================================= Batches Created
> ========================================================
>
>
>
Total
>
>
>
Total
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
Total
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
Total
> Warning Batch Name Period
> Name Lines Headers Accounted Debits Accounted Credits -------
> -------------------------------------------------------- -----------
> ------- ------- ------------------- -------------------
> Spreadsheet 20855084: A 243952455743 JUN-11
> 2 1 9504.6 9504.6
>
>
> ================================================= Unbalanced Journal
> Entries** =================================================
>
>
> Error
> Total
> Code Journal Entry Name Batch Name
> Lines Period Name Total Debits Total Credits -----
> ------------------------------------- ------------------------------------
> ----- ----------- ---------------- ----------------
>
>
> ========================================================= Error Lines
> ==========================================================
>
>
> Accounting
> Error Code Source Date Currency
> Entered Debit Entered Credit Accounting Flexfield/CCID
> ------------------------------ ------------- ----------- --------
> ------------------ ------------------ ----------------------------
>
>
> ========================================================== Error Key
> ===========================================================
>
> Period Error Codes
> ------------------
> EP01 This date is not in any open or future enterable period.
> EP02 This set of books does not have any open or future enterable
> periods.
> EP03 This date is not within any period in an open encumbrance year.
> EP04 This date is not a business day.
> EP05 There are no business days in this period.
>
> Unbalanced Journal Error Codes
> ------------------------------
> WU01 Warning: This journal entry is unbalanced. It is accepted because
> suspense posting is allowed in this set of books.
> EU02 This journal entry is unbalanced and suspense posting is not
> allowed in this set of books.
> EU03 This encumbrance journal entry is unbalanced and the Reserve for
> Encumbrance account is not defined.
>
> Flexfield Error Codes
> ---------------------
> EF01 This Accounting Flexfield is inactive for this accounting date.
> EF02 Detail posting not allowed for this Accounting Flexfield.
> EF03 Disabled Accounting Flexfield.
> ^L
> Gippsland Health Alliance Journal Import Execution
> Report Date: 01-JUL-11 09:05
>
> Thanks again for your response,
> 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
>>
Dinesh,
the printer in question responds immediately to the forst command (0x02,
receive print job) with return code 104, which means "connection reset by
peer".
I suspect that the printer needs to be configured to accept connections from
the host running cups to its (privileged) port 515. So it seems to be a
printer, not cups issue.
Helge
More information about the cups
mailing list