Bug 222552

Summary: cups i/o error
Product: [openSUSE] openSUSE 10.2 Reporter: Markus Kriewald <markus.kriewald>
Component: PrintingAssignee: Johannes Meixner <jsmeix>
Status: RESOLVED DUPLICATE QA Contact: Johannes Meixner <jsmeix>
Severity: Normal    
Priority: P5 - None    
Version: Beta 2   
Target Milestone: ---   
Hardware: x86-64   
OS: Other   
Whiteboard:
Found By: Other Services Priority:
Business Priority: Blocker: ---
Marketing QA Status: --- IT Deployment: ---
Attachments: lpt1.ppd
error_log

Description Markus Kriewald 2006-11-19 21:21:48 UTC
Hardware: Minolta PagePro1300W on SMC 7004ABR switch with parallel printer port

The printer does not work anymore in new opensuse10.2.

my way of Installation:
Yast/Hardware/Printer/Add/Network Printer/Print via LPD-Style Network Server

After pressing Look Up Scan for LPD servers none will be found?!

In Hostname of Printer Server I fill in 192.168.2.1, Remoute Queue Name lpt1. After pressing Test Route LPD Access a window is opened with “The print server is accessible.“ In the next step i fill in the Printer Description and Location, disable Share Printer (the option Share Printer and it´s description isn´t translated into the choosen standard language!) and enable Do Local Filtering.On the next a window is opend „printer drivers are not installed. Install packages cups-drivers-stp, filters, manufacturer-PPD´s?“ After continung: Error installing packages failed. But on OK all printer modells will be shown and i choose my Minolta 1300w. But my printer isn´t working.

My descripted install method is working fine for me on Suse10.0 and 10.1.

/var/log/warn
Nov 19 20:05:08 linux-mcr5 hp: unable to open /var/run/hpiod.port: No such file or directory: prnt/hpijs/hplip_api.c 84
Nov 19 20:05:08 linux-mcr5 hp: unable to connect hpiod socket 50000: Connection refused: prnt/hpijs/hplip_api.c 702
Nov 19 20:05:08 linux-mcr5 hp: unable to send ProbeDevices: Broken pipe
Nov 19 20:06:28 linux-mcr5 hp: unable to open /var/run/hpiod.port: No such file or directory: prnt/hpijs/hplip_api.c 84
Nov 19 20:06:28 linux-mcr5 hp: unable to connect hpiod socket 50000: Connection refused: prnt/hpijs/hplip_api.c 702
Nov 19 20:06:28 linux-mcr5 hp: unable to send ProbeDevices: Broken pipe
Nov 19 20:07:49 linux-mcr5 hp: unable to open /var/run/hpiod.port: No such file or directory: prnt/hpijs/hplip_api.c 84
Nov 19 20:07:49 linux-mcr5 hp: unable to connect hpiod socket 50000: Connection refused: prnt/hpijs/hplip_api.c 702
Nov 19 20:07:49 linux-mcr5 hp: unable to send ProbeDevices: Broken pipe
Nov 19 20:11:28 linux-mcr5 syslog-ng[2497]: Changing permissions on special file /dev/xconsole
Nov 19 20:11:28 linux-mcr5 syslog-ng[2497]: Changing permissions on special file /dev/tty10
Nov 19 20:11:28 linux-mcr5 kernel: sda: assuming drive cache: write through
Nov 19 20:11:28 linux-mcr5 kernel: sda: assuming drive cache: write through
Nov 19 20:50:24 linux-mcr5 kdm: :1[15569]: IO Error in XOpenDisplay
Nov 19 20:50:24 linux-mcr5 kdm[3189]: Display :1 cannot be opened
Nov 19 20:50:24 linux-mcr5 kdm[3189]: Unable to fire up local display :1; disabling.
Nov 19 20:50:58 linux-mcr5 kernel: mtrr: MTRR 3 not used
Nov 19 20:50:58 linux-mcr5 kernel: mtrr: MTRR 4 not used
Nov 19 20:50:58 linux-mcr5 kernel: mtrr: MTRR 5 not used
Nov 19 20:50:58 linux-mcr5 kernel: mtrr: MTRR 6 not used
Nov 19 20:50:58 linux-mcr5 kernel: mtrr: MTRR 7 not used

I [19/Nov/2006:20:00:19 +0100] Job 2 queued on "lpt1" by "markes".
I [19/Nov/2006:20:00:19 +0100] Started filter /usr/lib64/cups/filter/pstops (PID 4943) for job 2.
I [19/Nov/2006:20:00:19 +0100] Started filter /usr/lib64/cups/filter/foomatic-rip (PID 4944) for job 2.
I [19/Nov/2006:20:00:19 +0100] Started backend /usr/lib64/cups/backend/lpd (PID 4945) for job 2.
E [19/Nov/2006:20:00:22 +0100] [Job 2] /ioerror in --.outputpage--
E [19/Nov/2006:20:00:22 +0100] PID 4944 (/usr/lib64/cups/filter/foomatic-rip) stopped with status 1!
I [19/Nov/2006:20:00:22 +0100] Hint: Try setting the LogLevel to "debug" to find out more.
I [19/Nov/2006:20:00:49 +0100] Job 2 was held by "markes".
I [19/Nov/2006:20:02:32 +0100] Started filter /usr/lib64/cups/filter/pstops (PID 13271) for job 2.
I [19/Nov/2006:20:02:32 +0100] Started filter /usr/lib64/cups/filter/foomatic-rip (PID 13272) for job 2.
I [19/Nov/2006:20:02:32 +0100] Started backend /usr/lib64/cups/backend/lpd (PID 13273) for job 2.
I [19/Nov/2006:20:02:32 +0100] Job 2 was released by "root".
E [19/Nov/2006:20:02:33 +0100] [Job 2] /ioerror in --.outputpage--
E [19/Nov/2006:20:02:33 +0100] PID 13272 (/usr/lib64/cups/filter/foomatic-rip) stopped with status 1!
I [19/Nov/2006:20:02:33 +0100] Hint: Try setting the LogLevel to "debug" to find out more.
I [19/Nov/2006:20:04:16 +0100] Started "/usr/lib64/cups/cgi-bin/printers.cgi" (pid=13300)
I [19/Nov/2006:20:04:31 +0100] Started "/usr/lib64/cups/cgi-bin/printers.cgi" (pid=13301)
I [19/Nov/2006:20:04:33 +0100] Adding start banner page "none" to job 3.
I [19/Nov/2006:20:04:33 +0100] Adding end banner page "none" to job 3.
I [19/Nov/2006:20:04:33 +0100] Job 3 queued on "lpt1" by "guest".
I [19/Nov/2006:20:04:33 +0100] Started filter /usr/lib64/cups/filter/pstops (PID 13302) for job 3.
I [19/Nov/2006:20:04:33 +0100] Started filter /usr/lib64/cups/filter/foomatic-rip (PID 13303) for job 3.
I [19/Nov/2006:20:04:33 +0100] Started backend /usr/lib64/cups/backend/lpd (PID 13304) for job 3.
E [19/Nov/2006:20:04:34 +0100] [Job 3] /ioerror in --.outputpage--
E [19/Nov/2006:20:04:34 +0100] PID 13303 (/usr/lib64/cups/filter/foomatic-rip) stopped with status 1!
I [19/Nov/2006:20:04:34 +0100] Hint: Try setting the LogLevel to "debug" to find out more.
I [19/Nov/2006:20:04:35 +0100] Started "/usr/lib64/cups/cgi-bin/printers.cgi" (pid=13315)
I [19/Nov/2006:20:04:51 +0100] Started "/usr/lib64/cups/cgi-bin/admin.cgi" (pid=13316)
I [19/Nov/2006:20:05:05 +0100] Started "/usr/lib64/cups/cgi-bin/admin.cgi" (pid=13317)
I [19/Nov/2006:20:05:05 +0100] Started "/usr/lib64/cups/daemon/cups-deviced" (pid=13318)
E [19/Nov/2006:20:05:11 +0100] PID 13317 (/usr/lib64/cups/cgi-bin/admin.cgi) crashed on signal 9!
I [19/Nov/2006:20:05:11 +0100] Hint: Try setting the LogLevel to "debug" to find out more.
I [19/Nov/2006:20:05:23 +0100] Started "/usr/lib64/cups/cgi-bin/classes.cgi" (pid=13348)
I [19/Nov/2006:20:05:29 +0100] Started "/usr/lib64/cups/cgi-bin/jobs.cgi" (pid=13349)
I [19/Nov/2006:20:05:40 +0100] Started "/usr/lib64/cups/cgi-bin/jobs.cgi" (pid=13350)
E [19/Nov/2006:20:05:40 +0100] Restart-Job: Unauthorized
I [19/Nov/2006:20:05:56 +0100] Started "/usr/lib64/cups/cgi-bin/jobs.cgi" (pid=13351)
I [19/Nov/2006:20:05:56 +0100] Started filter /usr/lib64/cups/filter/pstops (PID 13352) for job 2.
I [19/Nov/2006:20:05:56 +0100] Started filter /usr/lib64/cups/filter/foomatic-rip (PID 13353) for job 2.
I [19/Nov/2006:20:05:56 +0100] Started backend /usr/lib64/cups/backend/lpd (PID 13354) for job 2.
I [19/Nov/2006:20:05:56 +0100] Job 2 was restarted by "root".
E [19/Nov/2006:20:05:57 +0100] [Job 2] /ioerror in --.outputpage--
E [19/Nov/2006:20:05:57 +0100] PID 13353 (/usr/lib64/cups/filter/foomatic-rip) stopped with status 1!
I [19/Nov/2006:20:05:57 +0100] Hint: Try setting the LogLevel to "debug" to find out more.
I [19/Nov/2006:20:06:02 +0100] Started "/usr/lib64/cups/cgi-bin/printers.cgi" (pid=13365)
I [19/Nov/2006:20:06:15 +0100] Started "/usr/lib64/cups/cgi-bin/admin.cgi" (pid=13366)
I [19/Nov/2006:20:06:26 +0100] Started "/usr/lib64/cups/cgi-bin/admin.cgi" (pid=13369)
I [19/Nov/2006:20:06:27 +0100] Started "/usr/lib64/cups/daemon/cups-deviced" (pid=13370)
I [19/Nov/2006:20:06:48 +0100] Started "/usr/lib64/cups/cgi-bin/help.cgi" (pid=13402)
I [19/Nov/2006:20:06:50 +0100] Started "/usr/lib64/cups/cgi-bin/printers.cgi" (pid=13403)
I [19/Nov/2006:20:07:05 +0100] Started "/usr/lib64/cups/cgi-bin/help.cgi" (pid=13404)
I [19/Nov/2006:20:07:12 +0100] Started "/usr/lib64/cups/cgi-bin/classes.cgi" (pid=13405)
I [19/Nov/2006:20:07:37 +0100] Started "/usr/lib64/cups/cgi-bin/printers.cgi" (pid=13406)
I [19/Nov/2006:20:07:40 +0100] Started "/usr/lib64/cups/cgi-bin/admin.cgi" (pid=13407)
E [19/Nov/2006:20:07:40 +0100] Pause-Printer: Unauthorized
I [19/Nov/2006:20:07:42 +0100] Started "/usr/lib64/cups/cgi-bin/admin.cgi" (pid=13408)
E [19/Nov/2006:20:07:42 +0100] Pause-Printer: Unauthorized
I [19/Nov/2006:20:07:42 +0100] Saving printers.conf...
I [19/Nov/2006:20:07:42 +0100] Printer "lpt1" stopped by "root".
I [19/Nov/2006:20:07:46 +0100] Started "/usr/lib64/cups/cgi-bin/admin.cgi" (pid=13409)
I [19/Nov/2006:20:07:48 +0100] Started "/usr/lib64/cups/daemon/cups-deviced" (pid=13410)
Comment 1 Johannes Meixner 2006-11-20 09:08:00 UTC
The crucial error is this:

E [19/Nov/2006:20:00:22 +0100] PID 4944 (/usr/lib64/cups/filter/foomatic-rip)
stopped with status 1!

To find out why this filter fails, follow the
"Hint: Try setting the LogLevel to "debug" to´ find out more."
or see http://en.opensuse.org/SDB:CUPS_in_a_Nutshell
"If problems are encountered".)

1.
Set the "LogLevel debug" in /etc/cups/cupsd.conf.
2.
Stop cupsd.
3.
Move /var/log/cups/error_log* to another location (or delete it)
in order to avoid having to search through gigantic log files.
4.
Start cupsd.
4a)
Re-enable the disabled print queue:
cupsenable lpt1
5.
Retry the action leading to the problem:
I.e. print via the queue:
echo Hello | lp -d lpt1
6.
Now /var/log/cups/error_log contains many messages
that are useful for troubleshooting. 

Attach /var/log/cups/error_log as attachment
with mime type "text plain" to this bug.


Furthermore provide the PPD file which you actually use:
Attach /etc/cups/ppd/lpt1.ppd as attachment
with mime type "text plain" to this bug.

Comment 2 Markus Kriewald 2006-11-20 17:34:14 UTC
Created attachment 106280 [details]
lpt1.ppd
Comment 3 Markus Kriewald 2006-11-20 17:36:00 UTC
Created attachment 106281 [details]
error_log
Comment 4 Johannes Meixner 2006-11-21 07:38:37 UTC
The crucial line in your error_log is:
------------------------------------------------------------------------
D [20/Nov/2006:17:42:09 +0100] [Job 6] sh: min12xxw: command not found
------------------------------------------------------------------------

Are you sure you have /usr/bin/min12xxw package filters installed?
I think it failed (as you wrote in comment #0) as a side effect
of bug #220979 and bug #220278 and I close it as duplicate.


For me it works without errors (openSUSE 10.2 beta2plus on x86_64)
when I have the filters package installed:

lpadmin -p foo -v file:/dev/null -E \
 -P /usr/share/cups/model/Minolta/PagePro_1300W-min12xxw.ppd.gz

and then

echo bla | lp -d foo

rsults no errors or other suspicious messages in error_log.

I cannot test the driver with a real printer because we don't have
such "min12xxw" printers (the "W" means "for Windows").

It would be nice when you report whether or not the min12xxw driver
actually works for your printer after you installed the filters package.

*** This bug has been marked as a duplicate of bug 220278 ***
Comment 5 Markus Kriewald 2006-11-21 21:47:17 UTC
Thanks! I have updated to yast2-printer-2.14.12 and have installed my printer a second time. Now it works. Text and graphic of the Yast test page are OK, but the 6,25% field is shown withe.