[Bug 691027] Re: USB Printer not re-enabled after printer power off

NoOp 691027 at bugs.launchpad.net
Sat Mar 31 19:27:10 UTC 2012 

Not printer related as I have the same issue with an Epson Workforce
635:

Bus 001 Device 005: ID 04b8:085d Seiko Epson Corp.
$ apt-cache policy udev
udev:
  Installed: 162-2.2
  Candidate: 162-2.2
$ apt-cache policy udev
udev:
  Installed: 162-2.2
  Candidate: 162-2.2
$ apt-cache policy cups
cups:
  Installed: 1.4.4-6ubuntu2.4
  Candidate: 1.4.4-6ubuntu2.4

Found something of interest in the Cups access log - the following was from boot/startup:
localhost - - [31/Mar/2012:10:32:57 -0700] "POST /admin/ HTTP/1.1" 401 180 Pause-Printer successful-ok
localhost - root [31/Mar/2012:10:32:57 -0700] "POST /admin/ HTTP/1.1" 200 180 Pause-Printer successful-ok
localhost - - [31/Mar/2012:10:32:57 -0700] "POST /admin/ HTTP/1.1" 401 186 Pause-Printer successful-ok
localhost - root [31/Mar/2012:10:32:57 -0700] "POST /admin/ HTTP/1.1" 200 186 Pause-Printer successful-ok
localhost - - [31/Mar/2012:10:32:57 -0700] "POST /admin/ HTTP/1.1" 401 183 Pause-Printer successful-ok
localhost - root [31/Mar/2012:10:32:57 -0700] "POST /admin/ HTTP/1.1" 200 183 Pause-Printer successful-ok
localhost - - [31/Mar/2012:10:32:57 -0700] "POST /admin/ HTTP/1.1" 401 183 Pause-Printer successful-ok
localhost - root [31/Mar/2012:10:32:57 -0700] "POST /admin/ HTTP/1.1" 200 183 Pause-Printer successful-ok
localhost - - [31/Mar/2012:10:32:57 -0700] "POST /admin/ HTTP/1.1" 401 186 Pause-Printer successful-ok
localhost - root [31/Mar/2012:10:32:57 -0700] "POST /admin/ HTTP/1.1" 200 186 Pause-Printer successful-ok

and after discovering that I can't print, so manually reenabling the printers via System|Administration|Printers:
localhost - - [31/Mar/2012:11:33:15 -0700] "POST /admin/ HTTP/1.1" 401 127 Resume-Printer successful-ok
localhost - gg [31/Mar/2012:11:33:15 -0700] "POST /admin/ HTTP/1.1" 200 127 Resume-Printer successful-ok
localhost - gg [31/Mar/2012:11:33:24 -0700] "POST /admin/ HTTP/1.1" 200 130 Resume-Printer successful-ok
localhost - gg [31/Mar/2012:11:33:27 -0700] "POST /admin/ HTTP/1.1" 200 133 Resume-Printer successful-ok
localhost - gg [31/Mar/2012:11:33:32 -0700] "POST /admin/ HTTP/1.1" 200 130 Resume-Printer successful-ok
localhost - gg [31/Mar/2012:11:33:35 -0700] "POST /admin/ HTTP/1.1" 200 133 Resume-Printer successful-ok

I think the key is figuring out what is causing 'Pause-Printer
successful-ok'

-- 
You received this bug notification because you are a member of Ubuntu
Foundations Bugs, which is subscribed to udev in Ubuntu.
https://bugs.launchpad.net/bugs/691027

Title:
  USB Printer not re-enabled after printer power off

Status in “udev” package in Ubuntu:
  New

Bug description:
  Possibly udev bug.
  After last updates to Lucid (10.04 LTS).

  libusb-0.1-4 2:0.1.12-14ubuntu0.2
  libusb-1.0-0 2:1.0.6-1
  udev 151-12.2
  uname -a gives 
  Linux fitsserver 2.6.32-26-server #48-Ubuntu SMP Wed Nov 24 10:28:32 UTC 2010 x86_64 GNU/Linux

  I have a server running 24/7 which has a canon USB printer connected. The canon is on a switched mains supply so is turned off every day when not in use.
  Previously this worked fine - the server would see the canon turned on and the Print queue would be re-enabled.
  After the last round of updates this no longer works.  Replugging the printer will make it work.
  I have just tested and the syslog is below with interline comments at ***:

  Thanks

  David

  
  *** Turn printer off at mains - usb device removed OK
  Dec 16 09:57:41 fitsserver kernel: [521189.771560] usblp0: nonzero read bulk status received: -84
  Dec 16 09:57:41 fitsserver init: Handling class-device-removed event
  Dec 16 09:57:41 fitsserver init: Handling usb-device-removed event
  Dec 16 09:57:41 fitsserver init: Handling usb-device-removed event
  Dec 16 09:57:41 fitsserver kernel: [521189.790095] usb 3-2: USB disconnect, address 23
  Dec 16 09:57:41 fitsserver kernel: [521189.790446] usblp0: removed
  Dec 16 09:57:41 fitsserver udev-configure-printer: remove /devices/pci0000:00/0000:00:1d.1/usb3/3-2
  Dec 16 09:57:41 fitsserver udev-configure-printer: URI of print queue: lpd://10.55.12.101/PASSTHRU, normalized: lpd 10 55 12 101 passthru
  Dec 16 09:57:41 fitsserver udev-configure-printer: URI of detected printer: usb://Canon/i965, normalized: canon i965
  Dec 16 09:57:41 fitsserver udev-configure-printer: URI of print queue: lpd://10.55.12.101/PASSTHRU, normalized: lpd 10 55 12 101 passthru
  Dec 16 09:57:41 fitsserver udev-configure-printer: URI of detected printer: usb://Canon/i965, normalized: canon i965
  Dec 16 09:57:41 fitsserver udev-configure-printer: URI of print queue: usb://Canon/i965, normalized: canon i965
  Dec 16 09:57:41 fitsserver udev-configure-printer: URI of detected printer: usb://Canon/i965, normalized: canon i965
  Dec 16 09:57:41 fitsserver udev-configure-printer: Queue ipp://localhost:631/printers/i965 has matching device URI
  Dec 16 09:57:41 fitsserver udev-configure-printer: Disabled printer ipp://localhost:631/printers/i965 as the corresponding device was unplugged or turned off
  Dec 16 09:57:41 fitsserver init: Handling usb-device-removed event

  *** Now turn printer back on:
  Dec 16 09:57:55 fitsserver kernel: [521204.481335] usb 3-2: new full speed USB device using uhci_hcd and address 24
  Dec 16 09:57:55 fitsserver kernel: [521204.685303] usb 3-2: configuration #1 chosen from 1 choice
  Dec 16 09:57:55 fitsserver init: Handling usb-device-added event
  Dec 16 09:57:55 fitsserver init: Handling class-device-added event
  Dec 16 09:57:55 fitsserver kernel: [521204.706361] usblp0: USB Bidirectional printer dev 24 if 0 alt 0 proto 2 vid 0x04A9 pid 0x1088
  Dec 16 09:57:55 fitsserver udev-configure-printer: add /devices/pci0000:00/0000:00:1d.1/usb3/3-2/3-2:1.0
  Dec 16 09:57:55 fitsserver udev-configure-printer: parent devpath is /devices/pci0000:00/0000:00:1d.1/usb3/3-2
  Dec 16 09:57:55 fitsserver udev-configure-printer: Device vendor/product is 04A9:1088
  Dec 16 09:57:55 fitsserver init: Handling usb-device-added event
  Dec 16 09:57:55 fitsserver init: Handling usb-device-added event

  *** Ooops I think this is the problem...
  Dec 16 09:57:55 fitsserver udev-configure-printer: add /devices/pci0000:00/0000:00:1d.1/usb3/3-2/3-2:1.0/usb/lp0
  Dec 16 09:57:56 fitsserver udev-configure-printer: failed to claim interface
  Dec 16 09:57:56 fitsserver udev-configure-printer: invalid or missing IEEE 1284 Device ID
  Dec 16 09:57:56 fitsserver udev-configure-printer: parent devpath is /devices/pci0000:00/0000:00:1d.1/usb3/3-2
  Dec 16 09:57:56 fitsserver udev-configure-printer: MFG:Canon MDL:i965 SERN:- serial:210805
  Dec 16 09:57:57 fitsserver kernel: [521205.844652] usb 3-2: usbfs: interface 0 claimed by usblp while 'usb' sets config #1
  Dec 16 09:57:57 fitsserver hp[28556]: io/hpmud/pp.c 627: unable to read device-id ret=-1
  Dec 16 09:57:57 fitsserver python: io/hpmud/pp.c 627: unable to read device-id ret=-1

  *** Now UNPLUG printer
  Dec 16 10:00:24 fitsserver kernel: [521352.888121] usbcore: deregistering interface driver usblp
  Dec 16 10:00:24 fitsserver kernel: [521352.888549] usblp0: removed
  Dec 16 10:00:24 fitsserver init: Handling class-device-removed event
  Dec 16 10:00:24 fitsserver init: Handling usb-device-removed event
  Dec 16 10:00:24 fitsserver init: Handling module-device-removed event
  Dec 16 10:00:24 fitsserver init: Handling drivers-device-removed event

  Dec 16 10:00:51 fitsserver init: Handling usb-device-removed event
  Dec 16 10:00:51 fitsserver kernel: [521380.300088] usb 3-2: USB disconnect, address 24
  Dec 16 10:00:51 fitsserver udev-configure-printer: remove /devices/pci0000:00/0000:00:1d.1/usb3/3-2
  Dec 16 10:00:51 fitsserver init: Handling usb-device-removed event

  *** And plug it back in
  Dec 16 10:01:01 fitsserver kernel: [521390.661300] usb 3-2: new full speed USB device using uhci_hcd and address 25
  Dec 16 10:01:02 fitsserver kernel: [521390.838310] usb 3-2: configuration #1 chosen from 1 choice
  Dec 16 10:01:02 fitsserver init: Handling usb-device-added event
  Dec 16 10:01:02 fitsserver udev-configure-printer: add /devices/pci0000:00/0000:00:1d.1/usb3/3-2/3-2:1.0
  Dec 16 10:01:02 fitsserver udev-configure-printer: parent devpath is /devices/pci0000:00/0000:00:1d.1/usb3/3-2
  Dec 16 10:01:02 fitsserver udev-configure-printer: Device vendor/product is 04A9:1088
  Dec 16 10:01:02 fitsserver udev-configure-printer: MFG:Canon MDL:i965 SERN:- serial:210805
  Dec 16 10:01:02 fitsserver init: Handling module-device-added event
  Dec 16 10:01:02 fitsserver init: Handling class-device-added event
  Dec 16 10:01:02 fitsserver init: Handling usb-device-added event
  Dec 16 10:01:02 fitsserver init: Handling drivers-device-added event
  Dec 16 10:01:02 fitsserver kernel: [521390.993390] usblp0: USB Bidirectional printer dev 25 if 0 alt 0 proto 2 vid 0x04A9 pid 0x1088
  Dec 16 10:01:02 fitsserver kernel: [521390.993462] usbcore: registered new interface driver usblp
  Dec 16 10:01:02 fitsserver init: Handling usb-device-added event
  Dec 16 10:01:02 fitsserver udev-configure-printer: add /devices/pci0000:00/0000:00:1d.1/usb3/3-2/3-2:1.0/usb/lp0
  Dec 16 10:01:03 fitsserver kernel: [521392.020794] usb 3-2: usbfs: interface 0 claimed by usblp while 'usb' sets config #1
  Dec 16 10:01:03 fitsserver hp[28632]: io/hpmud/pp.c 627: unable to read device-id ret=-1
  Dec 16 10:01:03 fitsserver python: io/hpmud/pp.c 627: unable to read device-id ret=-1
  Dec 16 10:01:05 fitsserver udev-configure-printer: URI matches without serial number: usb://Canon/i965
  Dec 16 10:01:05 fitsserver udev-configure-printer: No serial number URI matches so using those without
  Dec 16 10:01:05 fitsserver udev-configure-printer: URI of print queue: lpd://10.55.12.101/PASSTHRU, normalized: lpd 10 55 12 101 passthru
  Dec 16 10:01:05 fitsserver udev-configure-printer: URI of detected printer: usb://Canon/i965, normalized: canon i965
  Dec 16 10:01:05 fitsserver udev-configure-printer: URI of print queue: lpd://10.55.12.101/PASSTHRU, normalized: lpd 10 55 12 101 passthru
  Dec 16 10:01:05 fitsserver udev-configure-printer: URI of detected printer: usb://Canon/i965, normalized: canon i965
  Dec 16 10:01:05 fitsserver udev-configure-printer: URI of print queue: usb://Canon/i965, normalized: canon i965
  Dec 16 10:01:05 fitsserver udev-configure-printer: URI of detected printer: usb://Canon/i965, normalized: canon i965

  *** and printer is recognised...
  Dec 16 10:01:05 fitsserver udev-configure-printer: Queue ipp://localhost:631/printers/i965 has matching device URI
  Dec 16 10:01:05 fitsserver udev-configure-printer: parent devpath is /devices/pci0000:00/0000:00:1d.1/usb3/3-2
  Dec 16 10:01:05 fitsserver udev-configure-printer: Re-enabled printer ipp://localhost:631/printers/i965

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/udev/+bug/691027/+subscriptions

More information about the foundations-bugs mailing list