Comment 36 for bug 959676

Revision history for this message
Jason Radke (jer000) wrote :

I believe I am in a similar situation to Tero:

    Originally on 12.04 but upgraded to 12.10 to try and see if my issue was resolved with newer CUPS so here is the relevant info for a failure on 12.10

   Situation:
        1) LaserJet 6P connected to system by a USB<->LPT cable (Belkin brand cable)
             lsusb for the appropriate device
                Bus 001 Device 003: ID 050d:0002 Belkin Components
           and printing using Gutenprint
        2) before printing there is a /dev/usb/lp0 dev setup
        2) First print after a reboot of system works (I have not had a case where this fails)
After the first print
        3) at this point I see in the syslog
                  usblp0: removed
        4) the /dev/usb/lp0 is NO longer present ...
        5) If I try to print again the job hangs with Status "Processing"
        6) Looking at the cups error log with the logging set to "debug" I see the following:
                   [Job 1164] Got USB transaction timeout during write.
              (this message periodically repeats as it keeps trying to send the queued job)
        7) at this point the usblp seems to still be present
$ lsmod |egrep usblp
usblp 18140 0

        Breaking out of this bad state ...
            1) if I try to simply power off the printer ... no help ...
            2) if I disconnect the USB cable it does one of two things (both of which can lead to the job getting printed)
                    a) sometimes unplugging and plugging it back in causes the job to immediately start printing
                    b) sometimes during the unplug/replug the job gets put in a "held" state ... and then if I release the job it will
                         start printing
            3) If I reboot the system it will print the pending job upon reboot

      Generally it seems that 2) and 3) force the /dev/usb/lp0 to get re-insterted which allows printing of a job but it seems after printing a job the /dev/usb/lp0 gets lost

   Some other relevant info
D [18/Nov/2012:14:07:33 -0800] [Job 1167] Gutenprint: CUPS option 25 usb-no-reattach = true
D [18/Nov/2012:14:07:33 -0800] [Job 1167] Gutenprint: CUPS option 26 usb-unidir = true

   I tried to play with blacklist of the usblp but did not seem to impact ...
   I tried making the udev-configure-print not executable but was not able to seemingly get it to reliably work (although at one point of testing a combination of things it did seem for a while things were behaving somewhat good -multiple prints without having to disconnect the cable- but then I somehow lost the good state ...)

lpinfo -v (filtered to the line of interest)
direct usb://HP/LaserJet%206P

relevant unplug/replug syslog for situation where 2 jobs were in the queue ... but only one gets printed and the other gets
stuck:
Nov 18 14:15:53 golum kernel: [ 9429.761218] usb 1-1.2: USB disconnect, device number 10
Nov 18 14:15:53 golum kernel: [ 9429.761432] usblp0: removed
Nov 18 14:15:53 golum udev-configure-printer: remove /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2
Nov 18 14:15:53 golum udev-configure-printer: URI of detected printer: usb://HP/LaserJet%206P, normalized: laserjet 6p
Nov 18 14:15:53 golum udev-configure-printer: URI of print queue: usb://HP/LaserJet%206P, normalized: laserjet 6p
Nov 18 14:15:53 golum udev-configure-printer: Queue ipp://localhost:631/printers/HP_LaserJet_6P has matching device URI
Nov 18 14:15:53 golum udev-configure-printer: Disabled printer ipp://localhost:631/printers/HP_LaserJet_6P as the corresponding device was unplugged or turned off
Nov 18 14:16:14 golum kernel: [ 9450.954632] usb 1-1.2: new full-speed USB device number 11 using ehci_hcd
Nov 18 14:16:14 golum kernel: [ 9451.052738] usb 1-1.2: New USB device found, idVendor=050d, idProduct=0002
Nov 18 14:16:14 golum kernel: [ 9451.052743] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Nov 18 14:16:14 golum kernel: [ 9451.052746] usb 1-1.2: Product: IEEE-1284 Controller
Nov 18 14:16:14 golum kernel: [ 9451.052748] usb 1-1.2: Manufacturer: Belk USB Printing Support
Nov 18 14:16:14 golum mtp-probe: checking bus 1, device 11: "/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2"
Nov 18 14:16:14 golum kernel: [ 9451.059318] usblp 1-1.2:1.0: usblp0: USB Bidirectional printer dev 11 if 0 alt 1 proto 2 vid 0x050D pid 0x0002
Nov 18 14:16:14 golum mtp-probe: bus: 1, device: 11 was not an MTP device
Nov 18 14:16:14 golum udev-configure-printer: add /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0
Nov 18 14:16:14 golum udev-configure-printer: device devpath is /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2
Nov 18 14:16:14 golum udev-configure-printer: MFG:Hewlett-Packard MDL:HP LaserJet 6P SERN:- serial:-
Nov 18 14:16:15 golum kernel: [ 9452.128879] usblp0: removed
Nov 18 14:16:15 golum kernel: [ 9452.142659] usblp 1-1.2:1.0: usblp0: USB Bidirectional printer dev 11 if 0 alt 1 proto 2 vid 0x050D pid 0x0002
Nov 18 14:16:15 golum udev-configure-printer: URI matches without serial number: usb://HP/LaserJet%206P
Nov 18 14:16:15 golum udev-configure-printer: No serial number URI matches so using those without
Nov 18 14:16:15 golum udev-configure-printer: URI of detected printer: usb://HP/LaserJet%206P, normalized: laserjet 6p
Nov 18 14:16:15 golum udev-configure-printer: Consider also queues with "/usb/lp0" or "/usblp0" in their URIs as matching
Nov 18 14:16:15 golum udev-configure-printer: URI of print queue: usb://HP/LaserJet%206P, normalized: laserjet 6p
Nov 18 14:16:15 golum udev-configure-printer: Queue ipp://localhost:631/printers/HP_LaserJet_6P has matching device URI
Nov 18 14:16:15 golum udev-configure-printer: Re-enabled printer ipp://localhost:631/printers/HP_LaserJet_6P
Nov 18 14:16:15 golum kernel: [ 9452.210973] usblp0: removed