Strange messages in kernel log for printing (same email just more info from other logs)

[Anthony Gardner]

This is my sister's computer hence the 'sexysue' ;)

After recently upgrading to Jaunty I've noticed strange behaviour esp with
in */var/log*.

Switching on the printer (HP Officejet 6310) gives me this in ....
*
kern.log*

Jun 15 09:34:41 sexysue kernel: [ 2176.060051] usb 1-5: new high speed USB
device using ehci_hcd and address 3
Jun 15 09:34:41 sexysue kernel: [ 2176.219858] usb 1-5: configuration #1
chosen from 1 choice
Jun 15 09:34:41 sexysue kernel: [ 2176.245616] Initializing USB Mass Storage
driver...
Jun 15 09:34:41 sexysue kernel: [ 2176.247216] scsi2 : SCSI emulation for
USB Mass Storage devices
Jun 15 09:34:41 sexysue kernel: [ 2176.247476] usbcore: registered new
interface driver usb-storage
Jun 15 09:34:41 sexysue kernel: [ 2176.247481] USB Mass Storage support
registered.
Jun 15 09:34:41 sexysue kernel: [ 2176.248981] usb-storage: device found at
3
Jun 15 09:34:41 sexysue kernel: [ 2176.248984] usb-storage: waiting for
device to settle before scanning
Jun 15 09:34:41 sexysue kernel: [ 2176.307738] usblp0: USB Bidirectional
printer dev 3 if 1 alt 0 proto 2 vid 0x03F0 pid 0x5311
Jun 15 09:34:41 sexysue kernel: [ 2176.307756] usbcore: registered new
interface driver usblp
Jun 15 09:34:46 sexysue kernel: [ 2181.248237] usb-storage: device scan
complete
Jun 15 09:34:46 sexysue kernel: [ 2181.248730] scsi 2:0:0:0:
Direct-Access     HP       Officejet 6310   1.00 PQ: 0 ANSI: 2
Jun 15 09:34:46 sexysue kernel: [ 2181.251460] sd 2:0:0:0: [sdb] Attached
SCSI removable disk
Jun 15 09:34:46 sexysue kernel: [ 2181.251546] sd 2:0:0:0: Attached scsi
generic sg2 type 0
Jun 15 09:35:35 sexysue kernel: [ 2229.368709] usblp0: removed
Jun 15 09:36:05 sexysue kernel: [ 2260.112060] usb 1-5: reset high speed USB
device using ehci_hcd and address 3
Jun 15 09:36:05 sexysue kernel: [ 2260.248976] usblp0: USB Bidirectional
printer dev 3 if 1 alt 0 proto 2 vid 0x03F0 pid 0x5311
Jun 15 09:36:05 sexysue kernel: [ 2260.249071] usb 1-5: usbfs: process 6063
(hp) did not claim interface 1 before use

.... and then trying to print something from FF gives me ....

line: *1414* of kernel.log ..... Jun *15 09:36:05* sexysue kernel: [
2260.249393] usb 1-5: usbfs: process 5958 (hp) did not claim interface 1
before use
[snippet]
line *45003* of kernel.log .... Jun *15 09:36:31* sexysue kernel: [
2286.337969] usb 1-5: usbfs: process 27877 (hp) did not claim interface 1
before use

giving me nearly 44,000 lines printed to the log file in 30 seconds before I
cancelled the print.

and the other log files ...

*syslog*

Jun 15 09:34:41 sexysue kernel: [ 2176.060051] usb 1-5: new high speed USB
device using ehci_hcd and address 3
Jun 15 09:34:41 sexysue kernel: [ 2176.219858] usb 1-5: configuration #1
chosen from 1 choice
Jun 15 09:34:41 sexysue kernel: [ 2176.245616] Initializing USB Mass Storage
driver...
Jun 15 09:34:41 sexysue kernel: [ 2176.247216] scsi2 : SCSI emulation for
USB Mass Storage devices
Jun 15 09:34:41 sexysue kernel: [ 2176.247476] usbcore: registered new
interface driver usb-storage
Jun 15 09:34:41 sexysue kernel: [ 2176.247481] USB Mass Storage support
registered.
Jun 15 09:34:41 sexysue kernel: [ 2176.248981] usb-storage: device found at
3
Jun 15 09:34:41 sexysue kernel: [ 2176.248984] usb-storage: waiting for
device to settle before scanning
Jun 15 09:34:41 sexysue kernel: [ 2176.307738] usblp0: USB Bidirectional
printer dev 3 if 1 alt 0 proto 2 vid 0x03F0 pid 0x5311
Jun 15 09:34:41 sexysue kernel: [ 2176.307756] usbcore: registered new
interface driver usblp
Jun 15 09:34:41 sexysue hal_lpadmin: Running hal_lpadmin
Jun 15 09:34:42 sexysue hal_lpadmin: hal_lpadmin triggered by low-level USB
device
Jun 15 09:34:43 sexysue hal_lpadmin: Getting device ID from the usblp HAL
entry ...
Jun 15 09:34:43 sexysue hal_lpadmin: Device ID for /dev/usb/lp0:
MFG:HP;MDL:Officejet 6300
series;DES:Q8061B;CMD:MLC,PCL,PML,DW-PCL,DESKJET,DYN;
Jun 15 09:34:43 sexysue hal_lpadmin: Written device ID into HAL database
entry: MFG:HP;MDL:Officejet 6300
series;DES:Q8061B;CMD:MLC,PCL,PML,DW-PCL,DESKJET,DYN;
Jun 15 09:34:43 sexysue hal_lpadmin: add
Jun 15 09:34:43 sexysue hal_lpadmin: Printer reported by HAL: HP Officejet
6300 series CN78HFW3MP04M4
Jun 15 09:34:43 sexysue hal_lpadmin: Found configured printer:
Officejet_6300_series_fax; URI:
hpfax:/usb/Officejet_6300_series?serial=CN78HFW3MP04M4
Jun 15 09:34:43 sexysue hal_lpadmin: Not adding printer:
Officejet_6300_series_fax already exists
Jun 15 09:34:43 sexysue hal_lpadmin: Found configured printer:
Officejet_6300_series; URI:
hp:/usb/Officejet_6300_series?serial=CN78HFW3MP04M4
Jun 15 09:34:43 sexysue hal_lpadmin: Not adding printer:
Officejet_6300_series already exists
Jun 15 09:34:43 sexysue hal_lpadmin: No print queue setup needed for printer
with UDI
/org/freedesktop/Hal/devices/usb_device_3f0_5311_CN78HFW3MP04M4_if1, as
queues are already in place.
Jun 15 09:34:46 sexysue kernel: [ 2181.248237] usb-storage: device scan
complete
Jun 15 09:34:46 sexysue kernel: [ 2181.248730] scsi 2:0:0:0:
Direct-Access     HP       Officejet 6310   1.00 PQ: 0 ANSI: 2
Jun 15 09:34:46 sexysue kernel: [ 2181.251460] sd 2:0:0:0: [sdb] Attached
SCSI removable disk
Jun 15 09:34:46 sexysue kernel: [ 2181.251546] sd 2:0:0:0: Attached scsi
generic sg2 type 0
Jun 15 09:35:35 sexysue kernel: [ 2229.368709] usblp0: removed
Jun 15 09:36:05 sexysue kernel: [ 2260.112060] usb 1-5: reset high speed USB
device using ehci_hcd and address 3
Jun 15 09:36:05 sexysue Officejet_6300_series?serial=CN78HFW3MP04M4:
io/hpmud/musb.c 1022: bulk_write failed buf=0xbffe138c size=7680 len=-16: No
data available
Jun 15 09:36:05 sexysue Officejet_6300_series?serial=CN78HFW3MP04M4:
io/hpmud/musb.c 1384: unable to write data
hp:/usb/Officejet_6300_series?serial=CN78HFW3MP04M4: No data available
Jun 15 09:36:05 sexysue Officejet_6300_series?serial=CN78HFW3MP04M4:
io/hpmud/musb.c 725: invalid deviceid wIndex=1, retrying wIndex=100: Device
or resource busy
Jun 15 09:36:05 sexysue kernel: [ 2260.248976] usblp0: USB Bidirectional
printer dev 3 if 1 alt 0 proto 2 vid 0x03F0 pid 0x5311
Jun 15 09:36:05 sexysue kernel: [ 2260.249071] usb 1-5: usbfs: process 6063
(hp) did not claim interface 1 before use
Jun 15 09:36:05 sexysue kernel: [ 2260.249393] usb 1-5: usbfs: process 5958
(hp) did not claim interface 1 before use
Jun 15 09:36:05 sexysue kernel: [ 2260.249447] usb 1-5: usbfs: process 5958
(hp) did not claim interface 0 before use


*user.log*

Jun 15 09:36:05 sexysue Officejet_6300_series?serial=CN78HFW3MP04M4:
io/hpmud/musb.c 1022: bulk_write failed buf=0xbffe138c size=7680 len=-16: No
data available
Jun 15 09:36:05 sexysue Officejet_6300_series?serial=CN78HFW3MP04M4:
io/hpmud/musb.c 1384: unable to write data
hp:/usb/Officejet_6300_series?serial=CN78HFW3MP04M4: No data available
Jun 15 09:36:05 sexysue Officejet_6300_series?serial=CN78HFW3MP04M4:
io/hpmud/musb.c 725: invalid deviceid wIndex=1, retrying wIndex=100: Device
or resource busy
Jun 15 09:36:05 sexysue Officejet_6300_series?serial=CN78HFW3MP04M4:
io/hpmud/musb.c 1022: bulk_write failed buf=0xbffe138c size=7680 len=-16:
Device or resource busy

*messages*

Jun 15 09:34:41 sexysue kernel: [ 2176.060051] usb 1-5: new high speed USB
device using ehci_hcd and address 3
Jun 15 09:34:41 sexysue kernel: [ 2176.219858] usb 1-5: configuration #1
chosen from 1 choice
Jun 15 09:34:41 sexysue kernel: [ 2176.245616] Initializing USB Mass Storage
driver...
Jun 15 09:34:41 sexysue kernel: [ 2176.247216] scsi2 : SCSI emulation for
USB Mass Storage devices
Jun 15 09:34:41 sexysue kernel: [ 2176.247476] usbcore: registered new
interface driver usb-storage
Jun 15 09:34:41 sexysue kernel: [ 2176.247481] USB Mass Storage support
registered.
Jun 15 09:34:41 sexysue kernel: [ 2176.307738] usblp0: USB Bidirectional
printer dev 3 if 1 alt 0 proto 2 vid 0x03F0 pid 0x5311
Jun 15 09:34:41 sexysue kernel: [ 2176.307756] usbcore: registered new
interface driver usblp
Jun 15 09:34:46 sexysue kernel: [ 2181.248730] scsi 2:0:0:0:
Direct-Access     HP       Officejet 6310   1.00 PQ: 0 ANSI: 2
Jun 15 09:34:46 sexysue kernel: [ 2181.251460] sd 2:0:0:0: [sdb] Attached
SCSI removable disk
Jun 15 09:34:46 sexysue kernel: [ 2181.251546] sd 2:0:0:0: Attached scsi
generic sg2 type 0
Jun 15 09:35:35 sexysue kernel: [ 2229.368709] usblp0: removed
Jun 15 09:36:05 sexysue kernel: [ 2260.112060] usb 1-5: reset high speed USB
device using ehci_hcd and address 3
Jun 15 09:36:05 sexysue kernel: [ 2260.248976] usblp0: USB Bidirectional
printer dev 3 if 1 alt 0 proto 2 vid 0x03F0 pid 0x5311
Jun 15 09:36:05 sexysue kernel: [ 2260.249071] usb 1-5: usbfs: process 6063
(hp) did not claim interface 1 before use

The thing is, the printer starts to print, it prints the headers from the
email (that's what I tried to print) but then just stops wihout printing the
body of the mail; and then the log file gets hit.

Any ideas from anyone? Google throws up nothing.

Printing from OO is fine and have just succesfully printed the orignal email
without the errors. This is very strange and I'm scared she'll run out of
partition space if this happens when I'm not here .... which will likely be
the case.
*
*CIA

-Ants

-- 
100% naturally selected. 0% designed.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.ubuntu.com/archives/ubuntu-users/attachments/20090615/be78900f/attachment.html>