Printer stops mid-job; thereafter, USB mouse fails

[Scott Cowles Jacobs]

I am not sure, but I believe I have not printed anything since my install of Lubuntuy 16.10 last week.

I printed something from LibreOffice Calc, and it got only partway, and the printer stopped printing. I then noticed that the mouse (USB) had stopped working - I thought the batteries had co-incidentally failed, but fresh batteries accomplished nothing.
I had to shut down.

The next time, feeling that the print function of Calc was faulty, I exported the file as .pdf, and printed from Document Viewer (Evince). It got just about as far, and stopped, and the mouse failed again.

The next time, I was printing something from Firefox, and it got most of the way through that, but it stopped, and the mouse failed again.

By now, I had "installed" and activated PointerKeys, and with it, I was (slowly and painfully) able to have the terminal do lsusb and cut-n-paste the output to leafpad and save.
Some website suggested one could "reboot" the USB system, but I apparently failed to understand or apply the knowledge to success (or the procedure did not somehow apply to my current OS).

Rebooting sometimes (always?) does NOT fix the problem - Shutdown so far has.
When things are good, there are a lot more items in lsusb than when the mouse was not working.

When the mouse did not work:

scott@scott-ASUS-M2N68-AM-PLUS:~$ lsusb
Bus 001 Device 006: ID 7392:7622 Edimax Technology Co., Ltd
Bus 001 Device 005: ID 058f:6366 Alcor Micro Corp. Multi Flash Reader
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub

When the mouse works:

scott@scott-ASUS-M2N68-AM-PLUS:~$ lsusb
Bus 001 Device 006: ID 7392:7622 Edimax Technology Co., Ltd
Bus 001 Device 005: ID 058f:6366 Alcor Micro Corp. Multi Flash Reader
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 004: ID 0a12:1004 Cambridge Silicon Radio, Ltd
Bus 002 Device 003: ID 046d:c501 Logitech, Inc. Cordless Mouse Receiver
Bus 002 Device 007: ID 046d:c52b Logitech, Inc. Unifying Receiver
Bus 002 Device 006: ID 046d:c52b Logitech, Inc. Unifying Receiver
Bus 002 Device 005: ID 046d:0825 Logitech, Inc. Webcam C270
Bus 002 Device 002: ID 0a05:7211 Unknown Manufacturer hub
Bus 002 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub

Syslog shows some stuff that may show the problem happening:

Oct 26 16:55:32 scott-ASUS-M2N68-AM-PLUS kernel: [ 2798.923885] usb 2-1.4: new full-speed USB device number 8 using ohci-pci
Oct 26 16:55:32 scott-ASUS-M2N68-AM-PLUS kernel: [ 2799.064011] usb 2-1.4: New USB device found, idVendor=03f0, idProduct=5511
Oct 26 16:55:32 scott-ASUS-M2N68-AM-PLUS kernel: [ 2799.064014] usb 2-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Oct 26 16:55:32 scott-ASUS-M2N68-AM-PLUS kernel: [ 2799.064016] usb 2-1.4: Product: Deskjet F300 series
Oct 26 16:55:32 scott-ASUS-M2N68-AM-PLUS kernel: [ 2799.064017] usb 2-1.4: Manufacturer: HP
Oct 26 16:55:32 scott-ASUS-M2N68-AM-PLUS kernel: [ 2799.064018] usb 2-1.4: SerialNumber: CN647B623804KH
Oct 26 16:55:32 scott-ASUS-M2N68-AM-PLUS root: loading HP Device 002 008
Oct 26 16:55:33 scott-ASUS-M2N68-AM-PLUS /hp-config_usb_printer: [2039]: error: Failed to create /root/.hplip
Oct 26 16:55:33 scott-ASUS-M2N68-AM-PLUS /hp-config_usb_printer: io/hpmud/musb.c 2183: [2039] hpmud_make_usb_uri() bus=002 dev=008
Oct 26 16:55:33 scott-ASUS-M2N68-AM-PLUS /hp-config_usb_printer: io/hpmud/musb.c 2281: hpmud_make_usb_uri() uri=hp:/usb/Deskjet_F300_series?serial=CN647B623804KH bytes_read=49
Oct 26 16:55:33 scott-ASUS-M2N68-AM-PLUS kernel: [ 2799.793120] usblp 2-1.4:1.1: usblp2: USB Bidirectional printer dev 8 if 1 alt 0 proto 2 vid 0x03F0 pid 0x5511
Oct 26 16:55:33 scott-ASUS-M2N68-AM-PLUS kernel: [ 2799.793150] usbcore: registered new interface driver usblp

Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hpcups[2108]: prnt/hpcups/Lidil.cpp 586: CupeInteger1 = [3]
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hpcups[2108]: prnt/hpcups/Lidil.cpp 587: CupeInteger2 = [1]
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hpcups[2108]: prnt/hpcups/Lidil.cpp 588: PrintMode Index = [6]
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hpcups[2108]: prnt/hpcups/Lidil.cpp 600: Print Mode = [PlainNormalGrayK]
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hp[2109]: io/hpmud/musb.c 427: Found interface conf=0, iface=1, altset=0, index=1
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hp[2109]: io/hpmud/musb.c 389: Active kernel driver on interface=1 ret=1
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hp[2109]: io/hpmud/musb.c 393: Detaching kernel driver on interface=1 ret=0
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hp[2109]: io/hpmud/musb.c 535: claimed 7/1/2 interface
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hp[2109]: io/hpmud/musb.c 780: read actual device_id successfully fd=1 len=142
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS kernel: [ 2939.750878] usblp2: removed
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS kernel: [ 2939.750945] Did not find alt setting 1 for intf 0, config 1
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hp[2109]: io/hpmud/musb.c 561: released 7/1/2 interface
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hp[2109]: io/hpmud/musb.c 960: new PRINT channel=2 clientCnt=1 channelCnt=1
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hp[2109]: io/hpmud/musb.c 427: Found interface conf=0, iface=1, altset=0, index=1
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hp[2109]: io/hpmud/musb.c 389: Active kernel driver on interface=1 ret=0
Oct 26 16:57:53 scott-ASUS-M2N68-AM-PLUS hp[2109]: io/hpmud/musb.c 535: claimed 7/1/2 interface
Oct 26 16:58:30 scott-ASUS-M2N68-AM-PLUS kernel: [ 2976.394360] ohci-pci 0000:00:02.0: HcDoneHead not written back; disabled
Oct 26 16:58:30 scott-ASUS-M2N68-AM-PLUS kernel: [ 2976.394367] ohci-pci 0000:00:02.0: HC died; cleaning up
Oct 26 16:58:30 scott-ASUS-M2N68-AM-PLUS kernel: [ 2976.394433] usb 2-1: USB disconnect, device number 2
Oct 26 16:58:30 scott-ASUS-M2N68-AM-PLUS kernel: [ 2976.394435] usb 2-1.1: USB disconnect, device number 5
Oct 26 16:58:30 scott-ASUS-M2N68-AM-PLUS kernel: [ 2976.443511] usb 2-1.2: USB disconnect, device number 6
Oct 26 16:58:30 scott-ASUS-M2N68-AM-PLUS acpid: input device has been disconnected, fd 10
Oct 26 16:58:30 scott-ASUS-M2N68-AM-PLUS kernel: [ 2976.578625] usb 2-1.3: USB disconnect, device number 7
Oct 26 16:58:30 scott-ASUS-M2N68-AM-PLUS kernel: [ 2976.615770] usb 2-1.4: USB disconnect, device number 8
Oct 26 16:58:30 scott-ASUS-M2N68-AM-PLUS kernel: [ 2976.616167] usb 2-2: USB disconnect, device number 3
Oct 26 16:58:30 scott-ASUS-M2N68-AM-PLUS kernel: [ 2976.653429] usb 2-3: USB disconnect, device number 4

"HC died; cleaning up" doesn't sound good, and all those USB devices disconnecting, makes sense, although I don't actually see a device 8 in my list from after shutting down (and not trying to print something).

1. Does anyone know why this is happening, and how to stop it?
2. I would not like to shutdown every time I lose the mouse - how should I re-boot the USB?

-Scott Jacobs

---------------------------------------------------
scott@scott-ASUS-M2N68-AM-PLUS:~$ uname -a
Linux scott-ASUS-M2N68-AM-PLUS 4.8.0-26-generic #28-Ubuntu SMP Tue Oct 18 14:39:52 UTC 2016 x86_64 x86_64 x86_64 GNU/Linux
scott@scott-ASUS-M2N68-AM-PLUS:~$ lsb_release -dsc
Ubuntu 16.10
yakkety
scott@scott-ASUS-M2N68-AM-PLUS:~$ echo $DESKTOP_SESSION
Lubuntu

cups (et al.): 2.2.0-2
cups-filters : 1.11.4-0ubuntu2
hplip(et al.): 3.16.7+repack0-1ubuntu1
gtklp(et al.): 1.11.4-0ubuntu2

ProblemType: Bug
DistroRelease: Ubuntu 16.10
Package: cups 2.2.0-2
ProcVersionSignature: Ubuntu 4.8.0-26.28-generic 4.8.0
Uname: Linux 4.8.0-26-generic x86_64
ApportVersion: 2.20.3-0ubuntu8
Architecture: amd64
CurrentDesktop: LXDE
Date: Wed Oct 26 21:18:45 2016
InstallationDate: Installed on 2016-10-20 (7 days ago)
InstallationMedia: Lubuntu 16.10 "Yakkety Yak" - Release amd64 (20161012.1)
Lpstat: device for HP-Deskjet-F300-series: hp:/usb/Deskjet_F300_series?serial=CN647B623804KH
MachineType: System manufacturer System Product Name
Papersize: letter
PpdFiles: Error: command ['fgrep', '-H', '*NickName', '/etc/cups/ppd/HP-Deskjet-F300-series.ppd'] failed with exit code 2: grep: /etc/cups/ppd/HP-Deskjet-F300-series.ppd: Permission denied
ProcKernelCmdLine: BOOT_IMAGE=/boot/vmlinuz-4.8.0-26-generic root=UUID=5a40c58b-cb06-4221-8625-ef04379be2be ro quiet splash vt.handoff=7
SourcePackage: cups
UpgradeStatus: No upgrade log present (probably fresh install)
dmi.bios.date: 06/10/2010
dmi.bios.vendor: American Megatrends Inc.
dmi.bios.version: 1802
dmi.board.asset.tag: To Be Filled By O.E.M.
dmi.board.name: M2N68-AM Plus
dmi.board.vendor: ASUSTeK Computer INC.
dmi.board.version: Rev X.0x
dmi.chassis.asset.tag: Asset-1234567890
dmi.chassis.type: 3
dmi.chassis.vendor: Chassis Manufacture
dmi.chassis.version: Chassis Version
dmi.modalias: dmi:bvnAmericanMegatrendsInc.:bvr1802:bd06/10/2010:svnSystemmanufacturer:pnSystemProductName:pvrSystemVersion:rvnASUSTeKComputerINC.:rnM2N68-AMPlus:rvrRevX.0x:cvnChassisManufacture:ct3:cvrChassisVersion:
dmi.product.name: System Product Name
dmi.product.version: System Version
dmi.sys.vendor: System manufacturer

[Scott Cowles Jacobs (scott092707)]

I still have not been able to complete a print job without the USB devices disconnecting.

Comment #22 of the "Duplicate bug" states:

"I was able to fix the issue by changing the IO_WATCHDOG_DELAY in ohci-hcd.c from 250 msec to 275 msec. "

Only once has this bug manifested when I was NOT trying to print something,
so I guess my question is: why does cups somehow generate an excessively long IO_WATCHDOG_DELAY
such that it always at some point in a print job trips the bug?

I know nothing about USB, or what IO_WATCHDOG_DELAY is, but I have 8 or 9 devices hooked up with USB, and (with the one exception noted above) only the printer has tripped this bug.

What is this delay used for, and why is cups' so large so often?

(In other words: the kernel may have a bug in how it handles IO_WATCHDOG_DELAY, but if cups did not generate a delay that tripped the bug, there would be no issue for printing. )