Bug 161511

Summary:	HP840C printer does not work when system is restarted
Product:	[Unmaintained] kdeprint	Reporter:	wilhelmgallegos
Component:	kjobviewer	Assignee:	KDEPrint Devel Mailinglist <kde-print-devel>
Status:	CLOSED NOT A BUG
Severity:	normal	CC:	finex, jlayt
Priority:	NOR
Version:	0.1
Target Milestone:	---
Platform:	unspecified
OS:	Linux
Latest Commit:		Version Fixed In:
Sentry Crash Report:

Description wilhelmgallegos 2008-05-02 01:30:42 UTC

Version:           0.1 (using 3.5.7 "release 72.6" , openSUSE 10.3)
Compiler:          Target: i586-suse-linux
OS:                Linux (i686) release 2.6.22.17-0.1-default

I am new to Linux, so please be patient.
I have installed an HP840C printer by using the GUI interface of the YaST2 Control Center, then the printer has been detected automatically (though the driver of an HP842C has been assigned) and everything works fine. Then I try to print a job in Open Office and everything is still fine.

The problem is that, when I restart the computer, it is not possible to print something out again... When checking the status of the job in the KjobViewer an state of error appears.  

Then, if I start the Control Center, the printer is listed there as:

Queue name    Device   Model       Default Queue
deskjet840C  dev/lp0   deskjet842C Default

When selected such entry and pressing the Edit button, and then pressing the Test button, a test page is printed correctly. Then, if I go and try to print something else, then printing is successful.

However, when I restart the computer the problem persists.

Thanks for your attention.

Comment 1 FiNeX 2008-05-02 11:44:45 UTC

You should check the error log of cups. Check the /var/log/cups/error_log file for more informations about the error.

Comment 2 wilhelmgallegos 2008-05-15 04:46:09 UTC

Thanks for your answer. I've had a look at cup's log error file, but yet do not know what can I do to solve the problem, so I am stuck in a vicious circle that I describe in the following lines, where, together with particular actions taken by the user (in YaST), the reflected changes in the cups log file are transcribed. 

I thank you very much again for your patience and help.

Regards.

Let me take the following starting point (cups error log file), when I try to print (with no success):

I [14/May/2008:17:32:54 +0100] Adding start banner page "none" to job 23.
I [14/May/2008:17:32:54 +0100] Adding end banner page "none" to job 23.
I [14/May/2008:17:32:54 +0100] Job 23 queued on "deskjet840c" by "guillo".

Then I select the job in Kviewer and try to move it to printer:
E [14/May/2008:17:38:23 +0100] CUPS-Move-Job: Unauthorized
E [14/May/2008:17:38:33 +0100] CUPS-Move-Job: Unauthorized
E [14/May/2008:17:38:33 +0100] CUPS-Move-Job: Unauthorized

After that I cancel the job:
I [14/May/2008:17:39:56 +0100] Job 23 was canceled by "guillo".

In the meantime: lpstat -p -d   gives:
printer deskjet840c disabled since Wed May 14 13:14:28 2008 -
        /usr/lib/cups/backend/parallel failed
system default destination: deskjet840c

Then I go to YAST2.- Printer configuration.- then select the printer .-  then press Edit button.- then press Test.- Nothing is printed. And cups log generates:

I [14/May/2008:17:50:21 +0100] Scheduler shutting down normally.
I [14/May/2008:17:50:21 +0100] Saving remote.cache...
I [14/May/2008:17:50:21 +0100] Saving job cache file "/var/cache/cups/job.cache"...
I [14/May/2008:17:50:22 +0100] Listening to 127.0.0.1:631 (IPv4)
I [14/May/2008:17:50:22 +0100] Listening to ::1:631 (IPv6)
I [14/May/2008:17:50:22 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [14/May/2008:17:50:22 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [14/May/2008:17:50:22 +0100] Using default TempDir of /var/spool/cups/tmp...
I [14/May/2008:17:50:22 +0100] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [14/May/2008:17:50:22 +0100] Configured for up to 100 clients.
I [14/May/2008:17:50:22 +0100] Allowing up to 100 client connections per host.
I [14/May/2008:17:50:22 +0100] Using policy "default" as the default!
I [14/May/2008:17:50:22 +0100] Full reload is required.
I [14/May/2008:17:50:22 +0100] Loaded MIME database from '/etc/cups': 36 types, 40 filters...
I [14/May/2008:17:50:22 +0100] Loading job cache file "/var/cache/cups/job.cache"...
I [14/May/2008:17:50:22 +0100] Full reload complete.
I [14/May/2008:17:50:22 +0100] Listening to 127.0.0.1:631 on fd 0...
I [14/May/2008:17:50:22 +0100] Listening to ::1:631 on fd 2...
I [14/May/2008:17:50:22 +0100] Listening to /var/run/cups/cups.sock on fd 3...
E [14/May/2008:17:50:23 +0100] CUPS-Add-Modify-Printer: Unauthorized
I [14/May/2008:17:50:23 +0100] Setting y2test device-uri to "parallel:/dev/lp0" (was "file:/dev/null".)
I [14/May/2008:17:50:23 +0100] Setting y2test printer-is-accepting-jobs to 1 (was 0.)
I [14/May/2008:17:50:23 +0100] Setting y2test printer-is-shared to 1 (was 1.)
I [14/May/2008:17:50:23 +0100] Setting y2test printer-state to 5 (was 5.)
I [14/May/2008:17:50:23 +0100] Saving printers.conf...
I [14/May/2008:17:50:23 +0100] New printer "y2test" added by "root".
I [14/May/2008:17:50:23 +0100] Adding start banner page "none" to job 24.
I [14/May/2008:17:50:23 +0100] Adding end banner page "none" to job 24.
I [14/May/2008:17:50:23 +0100] Job 24 queued on "y2test" by "root".

then I press Stop button (the dialog ask whether or not printing was successful)....:
E [14/May/2008:17:53:31 +0100] CUPS-Delete-Printer: Unauthorized
I [14/May/2008:17:53:31 +0100] Printer "y2test" deleted by "root".
I [14/May/2008:17:53:31 +0100] Saving printers.conf...

then I press Abort in printer configuration:
(nothing is shown in cups log error)

Then I go to YAST2.- hardware.- printer:
(nothing yet). then Printer configuration.- then select the printer .-  then press Delete button:
(nothing yet). Then press Edit button.- then press Test.- but now, printing is done:

I [14/May/2008:18:02:31 +0100] Scheduler shutting down normally.
I [14/May/2008:18:02:31 +0100] Saving remote.cache...
I [14/May/2008:18:02:31 +0100] Saving job cache file "/var/cache/cups/job.cache"...
I [14/May/2008:18:02:32 +0100] Listening to 127.0.0.1:631 (IPv4)
I [14/May/2008:18:02:32 +0100] Listening to ::1:631 (IPv6)
I [14/May/2008:18:02:32 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [14/May/2008:18:02:32 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [14/May/2008:18:02:32 +0100] Using default TempDir of /var/spool/cups/tmp...
I [14/May/2008:18:02:32 +0100] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [14/May/2008:18:02:32 +0100] Configured for up to 100 clients.
I [14/May/2008:18:02:32 +0100] Allowing up to 100 client connections per host.
I [14/May/2008:18:02:32 +0100] Using policy "default" as the default!
I [14/May/2008:18:02:32 +0100] Full reload is required.
I [14/May/2008:18:02:32 +0100] Loaded MIME database from '/etc/cups': 36 types, 40 filters...
I [14/May/2008:18:02:32 +0100] Loading job cache file "/var/cache/cups/job.cache"...
I [14/May/2008:18:02:32 +0100] Full reload complete.
I [14/May/2008:18:02:32 +0100] Listening to 127.0.0.1:631 on fd 0...
I [14/May/2008:18:02:32 +0100] Listening to ::1:631 on fd 2...
I [14/May/2008:18:02:32 +0100] Listening to /var/run/cups/cups.sock on fd 3...
E [14/May/2008:18:02:32 +0100] CUPS-Add-Modify-Printer: Unauthorized
I [14/May/2008:18:02:32 +0100] Setting y2test device-uri to "parallel:/dev/lp0" (was "file:/dev/null".)
I [14/May/2008:18:02:32 +0100] Setting y2test printer-is-accepting-jobs to 1 (was 0.)
I [14/May/2008:18:02:32 +0100] Setting y2test printer-state to 3 (was 5.)
I [14/May/2008:18:02:32 +0100] Saving printers.conf...
I [14/May/2008:18:02:32 +0100] New printer "y2test" added by "root".
I [14/May/2008:18:02:32 +0100] Adding start banner page "none" to job 25.
I [14/May/2008:18:02:32 +0100] Adding end banner page "none" to job 25.
I [14/May/2008:18:02:32 +0100] Job 25 queued on "y2test" by "root".
I [14/May/2008:18:02:32 +0100] Started filter /usr/lib/cups/filter/texttops (PID 26104) for job 25.
I [14/May/2008:18:02:32 +0100] Started filter /usr/lib/cups/filter/pstops (PID 26105) for job 25.
I [14/May/2008:18:02:32 +0100] Started filter /usr/lib/cups/filter/foomatic-rip (PID 26106) for job 25.
I [14/May/2008:18:02:32 +0100] Started backend /usr/lib/cups/backend/parallel (PID 26107) for job 25.

Then press button Printout finished:
E [14/May/2008:18:08:10 +0100] CUPS-Delete-Printer: Unauthorized
I [14/May/2008:18:08:10 +0100] Printer "y2test" deleted by "root".
I [14/May/2008:18:08:10 +0100] Saving printers.conf...

Then OK.- then finished:
I [14/May/2008:18:10:32 +0100] Scheduler shutting down normally.
I [14/May/2008:18:10:32 +0100] Saving remote.cache...
I [14/May/2008:18:10:32 +0100] Saving job cache file "/var/cache/cups/job.cache"...
I [14/May/2008:18:10:32 +0100] Listening to 127.0.0.1:631 (IPv4)
I [14/May/2008:18:10:32 +0100] Listening to ::1:631 (IPv6)
I [14/May/2008:18:10:32 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [14/May/2008:18:10:32 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [14/May/2008:18:10:32 +0100] Using default TempDir of /var/spool/cups/tmp...
I [14/May/2008:18:10:32 +0100] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [14/May/2008:18:10:32 +0100] Configured for up to 100 clients.
I [14/May/2008:18:10:32 +0100] Allowing up to 100 client connections per host.
I [14/May/2008:18:10:32 +0100] Using policy "default" as the default!
I [14/May/2008:18:10:32 +0100] Full reload is required.
I [14/May/2008:18:10:32 +0100] Loaded MIME database from '/etc/cups': 36 types, 40 filters...
I [14/May/2008:18:10:32 +0100] Loading job cache file "/var/cache/cups/job.cache"...
I [14/May/2008:18:10:32 +0100] Full reload complete.
I [14/May/2008:18:10:32 +0100] Listening to 127.0.0.1:631 on fd 0...
I [14/May/2008:18:10:32 +0100] Listening to ::1:631 on fd 2...
I [14/May/2008:18:10:32 +0100] Listening to /var/run/cups/cups.sock on fd 3...
E [14/May/2008:18:10:33 +0100] CUPS-Delete-Printer: Unauthorized
I [14/May/2008:18:10:33 +0100] Printer "deskjet840c" deleted by "root".
I [14/May/2008:18:10:33 +0100] Saving printers.conf...
E [14/May/2008:18:10:33 +0100] CUPS-Add-Modify-Printer: Unauthorized
I [14/May/2008:18:10:33 +0100] Setting deskjet840c device-uri to "parallel:/dev/lp0" (was "file:/dev/null".)
I [14/May/2008:18:10:33 +0100] Setting deskjet840c printer-is-accepting-jobs to 1 (was 0.)
I [14/May/2008:18:10:33 +0100] Setting deskjet840c printer-state to 3 (was 5.)
I [14/May/2008:18:10:33 +0100] Saving printers.conf...
I [14/May/2008:18:10:33 +0100] New printer "deskjet840c" added by "root".
E [14/May/2008:18:10:33 +0100] CUPS-Set-Default: Unauthorized
I [14/May/2008:18:10:33 +0100] Saving printers.conf...
I [14/May/2008:18:10:33 +0100] Saving classes.conf...
I [14/May/2008:18:10:33 +0100] Default destination set to "deskjet840c" by "root".

Then I try to print something (successfully):
I [14/May/2008:18:17:16 +0100] Adding start banner page "none" to job 26.
I [14/May/2008:18:17:16 +0100] Adding end banner page "none" to job 26.
I [14/May/2008:18:17:16 +0100] Job 26 queued on "deskjet840c" by "guillo".
I [14/May/2008:18:17:16 +0100] Started filter /usr/lib/cups/filter/pstops (PID 26349) for job 26.
I [14/May/2008:18:17:16 +0100] Started filter /usr/lib/cups/filter/foomatic-rip (PID 26350) for job 26.
I [14/May/2008:18:17:16 +0100] Started backend /usr/lib/cups/backend/parallel (PID 26351) for job 26.

I [14/May/2008:18:31:08 +0100] Adding start banner page "none" to job 28.
I [14/May/2008:18:31:08 +0100] Adding end banner page "none" to job 28.
I [14/May/2008:18:31:08 +0100] Job 28 queued on "deskjet840c" by "guillo".
I [14/May/2008:18:31:08 +0100] Started filter /usr/lib/cups/filter/pstops (PID 26522) for job 28.
I [14/May/2008:18:31:08 +0100] Started filter /usr/lib/cups/filter/foomatic-rip (PID 26523) for job 28.
I [14/May/2008:18:31:08 +0100] Started backend /usr/lib/cups/backend/parallel (PID 26524) for job 28.

In the meantime: lpstat -p -d   gives:
printer deskjet840c is idle.  enabled since Wed May 14 18:17:20 2008
system default destination: deskjet840c

Then I restart the computer, and 
lpstat -p -d   gives:
printer deskjet840c is idle.  enabled since Wed May 14 18:17:20 2008
system default destination: deskjet840c

And cups error log also has registered:
while shutting down:
I [14/May/2008:18:39:02 +0100] Scheduler shutting down normally.
I [14/May/2008:18:39:02 +0100] Saving remote.cache...
I [14/May/2008:18:39:02 +0100] Saving job cache file "/var/cache/cups/job.cache"...

and after restarting:
I [14/May/2008:18:43:07 +0100] Listening to 127.0.0.1:631 (IPv4)
I [14/May/2008:18:43:07 +0100] Listening to ::1:631 (IPv6)
I [14/May/2008:18:43:07 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [14/May/2008:18:43:07 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [14/May/2008:18:43:07 +0100] Using default TempDir of /var/spool/cups/tmp...
I [14/May/2008:18:43:07 +0100] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [14/May/2008:18:43:07 +0100] Configured for up to 100 clients.
I [14/May/2008:18:43:07 +0100] Allowing up to 100 client connections per host.
I [14/May/2008:18:43:07 +0100] Using policy "default" as the default!
I [14/May/2008:18:43:07 +0100] Full reload is required.
I [14/May/2008:18:43:08 +0100] Loaded MIME database from '/etc/cups': 36 types, 40 filters...
I [14/May/2008:18:43:09 +0100] Loading job cache file "/var/cache/cups/job.cache"...
I [14/May/2008:18:43:09 +0100] Full reload complete.
I [14/May/2008:18:43:09 +0100] Listening to 127.0.0.1:631 on fd 0...
I [14/May/2008:18:43:09 +0100] Listening to ::1:631 on fd 2...
I [14/May/2008:18:43:09 +0100] Listening to /var/run/cups/cups.sock on fd 3...

Then I try to print (with no success):
I [14/May/2008:18:49:42 +0100] Adding start banner page "none" to job 29.
I [14/May/2008:18:49:42 +0100] Adding end banner page "none" to job 29.
I [14/May/2008:18:49:42 +0100] Job 29 queued on "deskjet840c" by "guillo".
I [14/May/2008:18:49:42 +0100] Started filter /usr/lib/cups/filter/pstops (PID 3438) for job 29.
I [14/May/2008:18:49:42 +0100] Started filter /usr/lib/cups/filter/foomatic-rip (PID 3439) for job 29.
I [14/May/2008:18:49:42 +0100] Started backend /usr/lib/cups/backend/parallel (PID 3440) for job 29.
E [14/May/2008:18:49:42 +0100] [Job 29] Unable to open parallel port device file "/dev/lp0": Permission denied
E [14/May/2008:18:49:42 +0100] PID 3440 (/usr/lib/cups/backend/parallel) stopped with status 1!
I [14/May/2008:18:49:42 +0100] Hint: Try setting the LogLevel to "debug" to find out more.
E [14/May/2008:18:49:47 +0100] PID 3439 (/usr/lib/cups/filter/foomatic-rip) stopped with status 9!
I [14/May/2008:18:49:47 +0100] Hint: Try setting the LogLevel to "debug" to find out more.
I [14/May/2008:18:49:47 +0100] [Job 29] Backend returned status 1 (failed)
I [14/May/2008:18:49:47 +0100] Saving printers.conf...

Then I remove job from queue (using Kviewer):
I [14/May/2008:18:53:09 +0100] Job 29 was canceled by "guillo".

In the meantime: lpstat -p -d   gives:
printer deskjet840c disabled since Wed May 14 18:49:47 2008 -
        /usr/lib/cups/backend/parallel failed
system default destination: deskjet840c

Then, when trying to print out again GO BACK TO START.

Then I restart the computer, and 
lpstat -p -d   gives:
printer deskjet840c disabled since Wed May 14 18:49:47 2008 -
        /usr/lib/cups/backend/parallel failed
system default destination: deskjet840c

And cups error log also has registered:
while shutting down:
I [14/May/2008:19:13:13 +0100] Scheduler shutting down normally.
I [14/May/2008:19:13:13 +0100] Saving remote.cache...
I [14/May/2008:19:13:13 +0100] Saving job cache file "/var/cache/cups/job.cache"...

and after restarting:
I [14/May/2008:19:17:28 +0100] Listening to 127.0.0.1:631 (IPv4)
I [14/May/2008:19:17:28 +0100] Listening to ::1:631 (IPv6)
I [14/May/2008:19:17:28 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [14/May/2008:19:17:28 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [14/May/2008:19:17:28 +0100] Using default TempDir of /var/spool/cups/tmp...
I [14/May/2008:19:17:28 +0100] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [14/May/2008:19:17:28 +0100] Configured for up to 100 clients.
I [14/May/2008:19:17:28 +0100] Allowing up to 100 client connections per host.
I [14/May/2008:19:17:28 +0100] Using policy "default" as the default!
I [14/May/2008:19:17:28 +0100] Full reload is required.
I [14/May/2008:19:17:28 +0100] Loaded MIME database from '/etc/cups': 36 types, 40 filters...
I [14/May/2008:19:17:29 +0100] Loading job cache file "/var/cache/cups/job.cache"...
I [14/May/2008:19:17:29 +0100] Full reload complete.
I [14/May/2008:19:17:29 +0100] Listening to 127.0.0.1:631 on fd 0...
I [14/May/2008:19:17:29 +0100] Listening to ::1:631 on fd 2...
I [14/May/2008:19:17:29 +0100] Listening to /var/run/cups/cups.sock on fd 3...

Then I try to print (with no success):

I [14/May/2008:19:25:19 +0100] Adding start banner page "none" to job 31.
I [14/May/2008:19:25:19 +0100] Adding end banner page "none" to job 31.
I [14/May/2008:19:25:19 +0100] Job 31 queued on "deskjet840c" by "guillo".

Then I remove job from queue (using Kviewer). Then, when trying to print out again GO BACK TO START.

----------------------------------------
[bugs.kde.org quoted mail]

_________________________________________________________________
Express yourself instantly with MSN Messenger! Download today it's FREE!
http://messenger.msn.click-url.com/go/onm00200471ave/direct/01/

Comment 3 John Layt 2009-01-02 17:32:57 UTC

Closing due to age and not an issue with KDEPrint.  Recommend contacting openSUSE forum or mailing list for further help if needed.