[Kde-print-devel] [Bug 161511] HP840C printer does not work when system is restarted

[wilhelmgallegos at hotmail.com]

------- You are receiving this mail because: -------
You are the assignee for the bug, or are watching the assignee.
         
http://bugs.kde.org/show_bug.cgi?id=161511         




------- Additional Comments From wilhelmgallegos hotmail com  2008-05-15 04:46 -------
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/