Created attachment 347642 [details]
a picture to illustrate the problem

Please update to the most recent system-config-printer test update:

yum --enablerepo=updates-testing update 'system-config-printer*'

Then, please follow the 'steps to reproduce', but before step 4 run the printing troubleshooter: System->Administration->Printing, then Help->Troubleshoot.  Follow the on-screen instructions and when it asks you to print a test page, print as normal.

Please attach the resulting troubleshoot.txt file to this bug report. (Please confirm that you got the 'Not connected?' pop-up for that print run.)

Apparently I must have the latest system-config-printer because there wasn't a new package in either updates or updates-testing.

Here is the information I requested.  I did get the Not Connected pop-up again.  Attaching another picture.

Created attachment 349434 [details]
Troubleshoot output

Created attachment 349435 [details]
screenshot of results from second test attempt

Thanks, that troubleshoot.txt file contains useful information.

Next, I'd like you to unplug the USB lead connecting the printer to the computer, then reconnect it.  After than, please run this command and show me the output:

getfacl /dev/bus/usb/007/002

(check that lsusb has this line:

Bus 007 Device 002: ID 03f0:2f11 Hewlett-Packard PSC 1200

and adjust the '007/002' accordingly if it is a different bus/device number).

Thanks.

Tim,

I just retested this issue with the latest cups from updates-testing that was apparently released in the last 24 hours and the issue appears to be resolved.

cups-1.4-0.rc1.4.fc11.x86_64
cups-libs-1.4-0.rc1.4.fc11.x86_64

Bryan

Really?  I'd be quite surprised by that.  As far as I can tell, the cause of the problem is that the 'lp' group is not granted access to the USB device node (i.e. /dev/bus/usb/.../...) at boot.  CUPS doesn't have any input in that: it's all down the hal, udev, and hal-cups-utils.

Looks like I called it too early.  I rebooted a second time and it did not work.  Strange that it worked that one time because I never did the unplug/plug thing.  I will test as requested in comment #6.

getfacl: Removing leading '/' from absolute path names
# file: dev/bus/usb/007/003
# owner: root
# group: root
user::rw-
user:bchrist:rw-
group::rw-
group:lp:rw-
mask::rw-
other::r--

Thanks.  For some reason the 'lp' group ACL is not getting added to the device at boot time.  Will investigate.

Tim,

I see that there are new hpijs and system-config-printer packages in "updates-testing".  Would this be a fix?

No.  I haven't gotten to hal-cups-utils yet.  Please be patient and I will get to it as soon as I can.

Just wanted to add a "me too" with a HP Photosmart C5580. Already tried installing system-config-printer anf hplip from updates-testing.

Tim,

Any update?  Is this still on your radar?

I am dealing with a great many bug reports; please be patient.  Since my last comment I have been making sure that this problem will not affect Fedora 12 by re-implementing the system cleanly on top of udev.

Please do not assume that I have forgotten about this bug.

So far I've discovered that the problem did not occur with the as-shipped kernel but does with the update.

GOOD: kernel-2.6.29.4-167.fc11
BAD:  kernel-2.6.29.6-213.fc11

Next I'll have to try to figure out which one broke it and what the change was.  Any help appreciated...

I don't know if this helps or not, but it looks like a USB problem to me because of a tangentially related problem I have with my USB mouse. With the "as shipped" kernel I didn't have any issues, but after the first kernel update my USB mouse is not detected on boot and I have to unplug and replug it in. 

Looking at lsusb from the console in the case of my mouse it is completely not detected before I 'replug' but the printer is so my mouse problem may have nothing to do with this bug but figured it is worth mentioning.

It certainly sounds similar.

Grr, I can't get it to go wrong with 2.6.29.6-213.fc11 any more. :-(

F-11 all updates applied, and I can't get it to go wrong again.  Not sure where to go from here other than trying to back-port the newer udev-based solution to Fedora 11.

I need to double check since the last kernel update but if I'm still having the problem (I know I'm still having the mouse usb problem) I'll do what I can to provide any info you want. Just let me know what logs/info you need.

I can only think that it's some other udev or hal rule that is causing problems.

So what does this say?:

ls -1 /etc/udev/rules.d /usr/share/hal/fdi/policy/10osvendor

/etc/udev/rules.d:
10-vboxdrv.rules
40-multipath.rules
60-libmtp.rules
60-pcmcia.rules
60-wacom.rules
70-persistent-cd.rules
70-persistent-net.rules
85-pcscd_ccid.rules
85-pcscd_egate.rules
90-alsa.rules
90-hal.rules
91-drm-modeset.rules
97-bluetooth-serial.rules
98-devkit.rules
99-fuse.rules

/usr/share/hal/fdi/policy/10osvendor:
05-olpc-detect.fdi
10-cpufreq.fdi
10-dockstation.fdi
10-hal_lpadmin.fdi
10-hplip.fdi
10-ibm-buttons.fdi
10-imac-backlight.fdi
10-input-policy.fdi
10-keymap.fdi
10-laptop-panel-mgmt-policy.fdi
10-leds.fdi
10-macbook-backlight.fdi
10-macbookpro-utils.fdi
10-power-mgmt-policy.fdi
10-rfkill-switch.fdi
10-tabletPCs.fdi
10-usbcsr-mice.fdi
10-x11-input.fdi
10-x11-keymap.fdi
15-storage-luks.fdi
20-acl-management.fdi
20-ntfs-config-write-policy.fdi
20-storage-methods.fdi
30-wol.fdi

Had a brainwave about this issue.

There are currently test updates for cups and for hplip.  I just built new packages for both of them, and they have not yet been re-pushed.  Here are the links to them:

https://admin.fedoraproject.org/updates/F11/FEDORA-2009-8133
https://admin.fedoraproject.org/updates/F11/FEDORA-2009-6959

The packages are cups-1.4-0.rc1.14.fc11 and hplip-3.9.8-2.fc11.

Can anyone confirm whether installing both of these updates together fixes the problem?

I got them (and their dependencies) installed remotely but will have to wait till I get home from work to reboot and see if it fixes it.

*** Bug 446678 has been marked as a duplicate of this bug. ***

confirm its fixed reboot problem

seems to work for me too.

Yes, the printer did work after a reboot but I got an AVC Denial, details below:


Summary:

SELinux is preventing python (hplip_t) "read" var_lib_t.

Detailed Description:

SELinux denied access requested by python. It is not expected that this access
is required by python and this access may signal an intrusion attempt. It is
also possible that the specific version or configuration of the application is
causing it to require additional access.

Allowing Access:

You can generate a local policy module to allow this access - see FAQ
(http://fedora.redhat.com/docs/selinux-faq-fc5/#id2961385) Or you can disable
SELinux protection altogether. Disabling SELinux protection is not recommended.
Please file a bug report (http://bugzilla.redhat.com/bugzilla/enter_bug.cgi)
against this package.

Additional Information:

Source Context                system_u:system_r:hplip_t:s0
Target Context                system_u:object_r:var_lib_t:s0
Target Objects                /var/lib/hp/hplip.state [ file ]
Source                        python
Source Path                   /usr/bin/python
Port                          <Unknown>
Host                          hobbes.localdomain
Source RPM Packages           python-2.6-9.fc11
Target RPM Packages           hplip-3.9.8-2.fc11
Policy RPM                    selinux-policy-3.6.12-69.fc11
Selinux Enabled               True
Policy Type                   targeted
MLS Enabled                   True
Enforcing Mode                Enforcing
Plugin Name                   catchall
Host Name                     hobbes.localdomain
Platform                      Linux hobbes.localdomain
                              2.6.29.6-217.2.3.fc11.x86_64 #1 SMP Wed Jul 29
                              16:02:42 EDT 2009 x86_64 x86_64
Alert Count                   7
First Seen                    Fri 07 Aug 2009 06:45:33 AM CDT
Last Seen                     Fri 07 Aug 2009 06:49:01 AM CDT
Local ID                      6778f7ac-5d16-4caa-94b4-a84372f2f2f9
Line Numbers                  

Raw Audit Messages            

node=hobbes.localdomain type=AVC msg=audit(1249645741.51:36): avc:  denied  { read } for  pid=2864 comm="python" name="hplip.state" dev=dm-0 ino=55892 scontext=system_u:system_r:hplip_t:s0 tcontext=system_u:object_r:var_lib_t:s0 tclass=file

node=hobbes.localdomain type=SYSCALL msg=audit(1249645741.51:36): arch=c000003e syscall=2 success=no exit=-13 a0=1bc5700 a1=0 a2=1b6 a3=238 items=0 ppid=2851 pid=2864 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="python" exe="/usr/bin/python" subj=system_u:system_r:hplip_t:s0 key=(null)

Yes, that's being tracked in bug #516078.

So, the problem was:

hplip's fdi policy was trying to do too much, both set the console user's ACL *and* the lp group ACL on the device node.  This doesn't seem to be the way hal-acl-tool is expected to be used.  I've removed the 'grant_group' line from the policy.

To grant the lp group the rw ACL we now do this via a udev rule shipped as part of CUPS.  This is done for all printer devices, as it would be required if we enabled libusb support in the CUPS usb backend anyway.

cups-1.4-0.rc1.15.fc11 has been pushed to the Fedora 11 testing repository.  If problems still persist, please make note of it in this bug report.
 If you want to test the update, you can install it with 
 su -c 'yum --enablerepo=updates-testing update cups'.  You can provide feedback for this update here: http://admin.fedoraproject.org/updates/F11/FEDORA-2009-8133

cups-1.4-0.rc1.15.fc11 has been pushed to the Fedora 11 stable repository.  If problems still persist, please make note of it in this bug report.

The only problem I've run into is a regression where even though I've setup a separate queue to use my dvd/cd printing tray it now has reverted to the default tray. I tried to find the bug in my gmail but didn't have any luck. I remember in that situation I had to update to a package out of updates-testing or koji to get it fixed. Not sure why whatever was fixed in that package no longer works. 

Not sure if it helps but my yum log shows the following:

$ sudo grep cups /var/log/yum.log 
Jun 11 20:30:27 Installed: 1:cups-libs-1.4-0.b2.18.fc11.i586
Jun 11 20:58:06 Updated: bluez-cups-4.37-2.fc11.x86_64
Jul 02 07:06:09 Updated: libgnomecups-0.2.3-6.fc11.x86_64
Jul 23 21:54:49 Updated: 1:cups-libs-1.4-0.rc1.10.fc11.x86_64
Jul 23 21:57:19 Updated: 1:cups-libs-1.4-0.rc1.10.fc11.i586
Jul 23 22:01:20 Updated: 1:cups-1.4-0.rc1.10.fc11.x86_64
Jul 29 21:45:19 Updated: bluez-cups-4.42-1.fc11.x86_64
Aug 06 10:38:25 Updated: 1:cups-libs-1.4-0.rc1.14.fc11.x86_64
Aug 06 10:38:31 Updated: 1:cups-1.4-0.rc1.14.fc11.x86_64
Aug 06 10:38:34 Updated: 1:cups-libs-1.4-0.rc1.14.fc11.i586
Aug 11 20:36:30 Updated: bluez-cups-4.42-3.fc11.x86_64
Aug 16 06:48:21 Updated: 1:cups-libs-1.4-0.rc1.15.fc11.x86_64
Aug 16 06:48:36 Updated: 1:cups-libs-1.4-0.rc1.15.fc11.i586
Aug 16 06:48:46 Updated: 1:cups-1.4-0.rc1.15.fc11.x86_64

Richard: sounds like you are talking about bug #495672.

Yup, but why would an update to CUPS revert a bug that was fixed in hplip/hpijs?