You are not logged in.

#1 2009-10-15 22:29:53

owain
Member
Registered: 2009-08-24
Posts: 251

CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

Followed the instructions, blacklisted usblp.  No printout even though CUPS reports success in the logs.

However, if I do the following:

$ sudo modprobe usblp
Password: 
$ lp randomfile
$ sudo modprobe -r usblp

this file then prints.  The following, from /var/log/everything.log, suggests that only once usblp is loaded is the firmware uploading to the printer:

Oct 15 23:26:31 owain-laptop kernel: usb 6-2: new full speed USB device using uhci_hcd and address 8
Oct 15 23:26:31 owain-laptop kernel: usb 6-2: configuration #1 chosen from 1 choice
Oct 15 23:26:31 owain-laptop load-modules.sh: Not loading module 'usblp' for alias 'usb:v03F0p1317d0120dc00dsc00dp00ic07isc01ip02' because it is blacklisted
Oct 15 23:26:47 owain-laptop kernel: usblp1: USB Bidirectional printer dev 8 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1317
Oct 15 23:26:47 owain-laptop kernel: usbcore: registered new interface driver usblp
Oct 15 23:26:47 owain-laptop /etc/hotplug/usb/hplj1005: foo2zjs: loading HP LaserJet 1005 firmware /var/lib/foo2zjs/firmware/sihp1005.dl to /dev/usb/lp1 ...
Oct 15 23:26:47 owain-laptop /etc/hotplug/usb/hplj1005: foo2zjs: ... download successful.
Oct 15 23:26:49 owain-laptop kernel: usb 6-2: usbfs: interface 0 claimed by usblp while 'usb' sets config #1
Oct 15 23:26:49 owain-laptop foo2zjs-wrapper: foo2zjs-wrapper -P -r600x600 -p9 -m1 -s7
Oct 15 23:26:49 owain-laptop foo2zjs-wrapper: gs -sPAPERSIZE=a4 -g4960x7016 -r600x600 -sDEVICE=pbmraw -dCOLORSCREEN -dMaxBitmap=500000000  
Oct 15 23:26:49 owain-laptop foo2zjs-wrapper: foo2zjs -r600x600 -g4960x7016 -p9 -m1 -n1 -d1 -s7 -z0  -u 112x100 -l 112x100      -P 
Oct 15 23:26:53 owain-laptop kernel: usbcore: deregistering interface driver usblp
Oct 15 23:26:53 owain-laptop kernel: usblp1: removed

Where do I go from here?

Last edited by owain (2009-10-16 10:30:38)

Offline

#2 2009-10-16 05:11:40

AndyRTR
Developer
From: Magdeburg/Germany
Registered: 2005-10-07
Posts: 1,641

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

check the printer permissions settings. it has to belong to the "lp" group.

Offline

#3 2009-10-16 08:55:42

owain
Member
Registered: 2009-08-24
Posts: 251

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

AndyRTR wrote:

check the printer permissions settings. it has to belong to the "lp" group.

You mean the permissions of my user?  Yes, I'm in lp, and this was a working setup prior to the CUPS upgrade.  Or do you mean something else, by 'printer permissions'?

$ cat /etc/group | grep lp
lp::7:daemon,owain

Offline

#4 2009-10-16 10:28:03

bds
Member
Registered: 2004-02-27
Posts: 47

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

owain,

I got my HP P1005 to work in CUPS 1.4 (same driver as yours, except that firmware is p1005 instead of 1005).

Steps:

1. Delete existing CUPS 1.3 printer
2. Stop cupsd
3. Copy cupsd.conf.pacnew to cupsd.conf
4. Blacklist usblp in modprobe.conf
5. Reboot
6. Create new CUPS 1.4 printer, select Foomatic/foo2xqx driver
7. Print test page - FAILS silently
8. modprobe usblp
9. Print test page - SUCCESS
10. rmmod usblp

From this point, the printer works normally, including after CUPS restart and reboot, without the need for usblp.

Offline

#5 2009-10-16 10:30:07

owain
Member
Registered: 2009-08-24
Posts: 251

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

Ahh, it's about the 664 permissions, because this gets the printer working:

$ lsusb | grep LaserJet
Bus 006 Device 003: ID 03f0:1317 Hewlett-Packard LaserJet 1005

$ ls -al /dev/bus/usb/006/
total 0
drwxr-xr-x  2 root root           100 2009-10-16 10:37 .
drwxr-xr-x 10 root root           200 2009-10-16 10:37 ..
crw-rw-r--  1 root vboxusers 189, 640 2009-10-16 10:37 001
crw-rw-r--  1 root vboxusers 189, 641 2009-10-16 10:37 002
crw-rw----  1 root lp        189, 642 2009-10-16 10:37 003

$ sudo chmod 664 /dev/bus/usb/006/003
Password: 

$ lp randomfile

How do I make the permissions permanent, for when I reboot or unplug/replug the printer?

Offline

#6 2009-10-16 10:32:32

AndyRTR
Developer
From: Magdeburg/Germany
Registered: 2005-10-07
Posts: 1,641

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

you need a custom udev rule. see the opther cups threads and mailing lists. http://reactivated.net/writing_udev_rules.html

Offline

#7 2009-10-16 14:24:00

owain
Member
Registered: 2009-08-24
Posts: 251

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

OK, I've read through that, but I'm nonetheless still not quite sure what I'm trying to do.  I've tried this:

/etc/udev/rules.d/10-custom.rules:

SUBSYSTEMS=="usb", ATTRS{product}=="hp LaserJet 1005 series", MODE="0664"

to no avail, and I presume I'm missing something.

Here's the info from udevadm info (not udevinfo as in that link):

owain: { ~ } $ udevadm info -a -p /sys/class/usb_device/usbdev6.3

Udevadm info starts with the device specified by the devpath and then
walks up the chain of parent devices. It prints for every device
found, all possible attributes in the udev rules key format.
A rule to match, can be composed by the attributes of the device
and the attributes from one single parent device.

  looking at device '/devices/pci0000:00/0000:00:1d.0/usb6/6-2/usb_device/usbdev6.3':
    KERNEL=="usbdev6.3"
    SUBSYSTEM=="usb_device"
    DRIVER==""

  looking at parent device '/devices/pci0000:00/0000:00:1d.0/usb6/6-2':
    KERNELS=="6-2"
    SUBSYSTEMS=="usb"
    DRIVERS=="usb"
    ATTRS{configuration}==""
    ATTRS{bNumInterfaces}==" 1"
    ATTRS{bConfigurationValue}=="1"
    ATTRS{bmAttributes}=="c0"
    ATTRS{bMaxPower}==" 98mA"
    ATTRS{urbnum}=="9"
    ATTRS{idVendor}=="03f0"
    ATTRS{idProduct}=="1317"
    ATTRS{bcdDevice}=="0120"
    ATTRS{bDeviceClass}=="00"
    ATTRS{bDeviceSubClass}=="00"
    ATTRS{bDeviceProtocol}=="00"
    ATTRS{bNumConfigurations}=="1"
    ATTRS{bMaxPacketSize0}=="8"
    ATTRS{speed}=="12"
    ATTRS{busnum}=="6"
    ATTRS{devnum}=="3"
    ATTRS{version}==" 1.10"
    ATTRS{maxchild}=="0"
    ATTRS{quirks}=="0x0"
    ATTRS{authorized}=="1"
    ATTRS{manufacturer}=="Hewlett-Packard"
    ATTRS{product}=="hp LaserJet 1005 series"

  looking at parent device '/devices/pci0000:00/0000:00:1d.0/usb6':
    KERNELS=="usb6"
    SUBSYSTEMS=="usb"
    DRIVERS=="usb"
    ATTRS{configuration}==""
    ATTRS{bNumInterfaces}==" 1"
    ATTRS{bConfigurationValue}=="1"
    ATTRS{bmAttributes}=="e0"
    ATTRS{bMaxPower}=="  0mA"
    ATTRS{urbnum}=="48"
    ATTRS{idVendor}=="1d6b"
    ATTRS{idProduct}=="0001"
    ATTRS{bcdDevice}=="0206"
    ATTRS{bDeviceClass}=="09"
    ATTRS{bDeviceSubClass}=="00"
    ATTRS{bDeviceProtocol}=="00"
    ATTRS{bNumConfigurations}=="1"
    ATTRS{bMaxPacketSize0}=="64"
    ATTRS{speed}=="12"
    ATTRS{busnum}=="6"
    ATTRS{devnum}=="1"
    ATTRS{version}==" 1.10"
    ATTRS{maxchild}=="2"
    ATTRS{quirks}=="0x0"
    ATTRS{authorized}=="1"
    ATTRS{manufacturer}=="Linux 2.6.31-ARCH uhci_hcd"
    ATTRS{product}=="UHCI Host Controller"
    ATTRS{serial}=="0000:00:1d.0"
    ATTRS{authorized_default}=="1"

  looking at parent device '/devices/pci0000:00/0000:00:1d.0':
    KERNELS=="0000:00:1d.0"
    SUBSYSTEMS=="pci"
    DRIVERS=="uhci_hcd"
    ATTRS{vendor}=="0x8086"
    ATTRS{device}=="0x2934"
    ATTRS{subsystem_vendor}=="0x1025"
    ATTRS{subsystem_device}=="0x0142"
    ATTRS{class}=="0x0c0300"
    ATTRS{irq}=="23"
    ATTRS{local_cpus}=="ffff"
    ATTRS{local_cpulist}=="0-15"
    ATTRS{modalias}=="pci:v00008086d00002934sv00001025sd00000142bc0Csc03i00"
    ATTRS{broken_parity_status}=="0"
    ATTRS{msi_bus}==""

  looking at parent device '/devices/pci0000:00':
    KERNELS=="pci0000:00"
    SUBSYSTEMS==""
    DRIVERS==""

Offline

#8 2009-10-16 14:46:52

AndyRTR
Developer
From: Magdeburg/Germany
Registered: 2005-10-07
Posts: 1,641

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

mine:

SYSFS{idVendor}=="043d", SYSFS{idProduct}=="000c", GROUP="lp", MODE="0664"

yours for the first one should be:

SYSFS{idVendor}=="03f0", SYSFS{idProduct}=="1317", GROUP="lp", MODE="0664"  - but is this your printer???

Last edited by AndyRTR (2009-10-16 14:48:41)

Offline

#9 2009-10-16 16:50:10

owain
Member
Registered: 2009-08-24
Posts: 251

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

AndyRTR wrote:

mine:

SYSFS{idVendor}=="043d", SYSFS{idProduct}=="000c", GROUP="lp", MODE="0664"

yours for the first one should be:

SYSFS{idVendor}=="03f0", SYSFS{idProduct}=="1317", GROUP="lp", MODE="0664"  - but is this your printer???

I've given that a go, and no joy.  I don't know how to troubleshoot further, because I still don't understand exactly what this is intended to do.

Offline

#10 2009-10-16 18:11:26

owain
Member
Registered: 2009-08-24
Posts: 251

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

Out of desperation, I've tried copying /lib/udev/rules.d/50-udev-default.rules to /etc/udev/rules.d/50-udev-default.rules, and modifying

SUBSYSTEM=="usb", ENV{DEVTYPE}=="usb_device", ENV{ID_USB_INTERFACES}==":0701*:", GROUP="lp", MODE="660"

to

SUBSYSTEM=="usb", ENV{DEVTYPE}=="usb_device", ENV{ID_USB_INTERFACES}==":0701*:", GROUP="lp", MODE="664"

This gives the correct permissions on /dev/bus/usb/00X/00X, but I still had to do 'modprobe usblp' / 'modprobe -r usblp' to load the firmware.  I'm close to giving up and downgrading.

Offline

#11 2009-10-17 13:24:29

owain
Member
Registered: 2009-08-24
Posts: 251

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

I've now got the rule AndyRTR suggested working instead, thanks to a pointer in another thread about using 'GROUP:="lp", MODE:="0664"' to override other rules.  Still stuck on the firmware - is anyone able to explain how it's supposed to be loaded?  The responsibility of CUPS, presumably?  Is there a log I should be looking in to find clues about why it's not happening?

Offline

#12 2009-10-18 00:02:49

kjon
Member
From: Temuco, Chile
Registered: 2008-04-16
Posts: 398

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

owain, I decided to use hplip instead. It solves all the firmware issues, and the driver is pretty good too.


They say that if you play a Win cd backward you hear satanic messages. That's nothing! 'cause if you play it forwards, it installs windows.

Offline

#13 2009-10-18 00:51:29

owain
Member
Registered: 2009-08-24
Posts: 251

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

hplip causes other problems - foo2zjs is the only driver that I've ever had working with this printer.  These are the errors when trying to print with the drivers offered by hplip:

E [18/Oct/2009:01:43:15 +0100] [Job 157] Job stopped due to filter errors; please consult the error_log file for details.
D [18/Oct/2009:01:43:15 +0100] [Job 157] The following messages were recorded from 01:43:10 to 01:43:15
D [18/Oct/2009:01:43:15 +0100] [Job 157] Adding start banner page "none".
D [18/Oct/2009:01:43:15 +0100] [Job 157] Adding end banner page "none".
D [18/Oct/2009:01:43:15 +0100] [Job 157] File of type application/vnd.cups-banner queued by "root".
D [18/Oct/2009:01:43:15 +0100] [Job 157] hold_until=0
D [18/Oct/2009:01:43:15 +0100] [Job 157] Queued on "HP-LaserJet-1005-USB" by "root".
D [18/Oct/2009:01:43:15 +0100] [Job 157] job-sheets=none,none
D [18/Oct/2009:01:43:15 +0100] [Job 157] argv[0]="HP-LaserJet-1005-USB"
D [18/Oct/2009:01:43:15 +0100] [Job 157] argv[1]="157"
D [18/Oct/2009:01:43:15 +0100] [Job 157] argv[2]="root"
D [18/Oct/2009:01:43:15 +0100] [Job 157] argv[3]="Test Page"
D [18/Oct/2009:01:43:15 +0100] [Job 157] argv[4]="1"
D [18/Oct/2009:01:43:15 +0100] [Job 157] argv[5]="job-uuid=urn:uuid:dd8e9d38-ec89-3b03-6a42-673ae14fafd7 job-originating-host-name=localhost"
D [18/Oct/2009:01:43:15 +0100] [Job 157] argv[6]="/var/spool/cups/d00157-001"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[8]="HOME=/var/spool/cups/tmp"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[10]="SERVER_ADMIN=root@owain-laptop"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[11]="SOFTWARE=CUPS/1.4.1"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[13]="USER=root"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[16]="IPP_PORT=631"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[17]="CHARSET=utf-8"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[18]="LANG=en_US.UTF-8"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[19]="PPD=/etc/cups/ppd/HP-LaserJet-1005-USB.ppd"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[20]="RIP_MAX_CACHE=8m"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[21]="CONTENT_TYPE=application/vnd.cups-banner"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[22]="DEVICE_URI=usb://HP/hp%20LaserJet%201005%20series?serial=?"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[23]="PRINTER_INFO=HP LaserJet 1005 series"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[24]="PRINTER_LOCATION="
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[25]="PRINTER=HP-LaserJet-1005-USB"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[26]="CUPS_FILETYPE=document"
D [18/Oct/2009:01:43:15 +0100] [Job 157] envp[27]="FINAL_CONTENT_TYPE=printer/HP-LaserJet-1005-USB"
D [18/Oct/2009:01:43:15 +0100] [Job 157] Started filter /usr/lib/cups/filter/bannertops (PID 23404)
D [18/Oct/2009:01:43:15 +0100] [Job 157] Started filter /usr/lib/cups/filter/pstops (PID 23405)
D [18/Oct/2009:01:43:15 +0100] [Job 157] Started filter /usr/lib/cups/filter/foomatic-rip-hplip (PID 23406)
D [18/Oct/2009:01:43:15 +0100] [Job 157] Started backend /usr/lib/cups/backend/usb (PID 23407)
D [18/Oct/2009:01:43:15 +0100] [Job 157] perl: warning: Setting locale failed.
D [18/Oct/2009:01:43:15 +0100] [Job 157] perl: warning: Please check that your locale settings:
D [18/Oct/2009:01:43:15 +0100] [Job 157] LANGUAGE = (unset),
D [18/Oct/2009:01:43:15 +0100] [Job 157] LC_ALL = (unset),
D [18/Oct/2009:01:43:15 +0100] [Job 157] LANG = "en_US.UTF-8"
D [18/Oct/2009:01:43:15 +0100] [Job 157] are supported and installed on your system.
D [18/Oct/2009:01:43:15 +0100] [Job 157] perl: warning: Falling back to the standard locale ("C").
D [18/Oct/2009:01:43:15 +0100] [Job 157] print_device
D [18/Oct/2009:01:43:15 +0100] [Job 157] usb_find_busses=8
D [18/Oct/2009:01:43:15 +0100] [Job 157] load_banner(filename="/var/spool/cups/d00157-001")
D [18/Oct/2009:01:43:15 +0100] [Job 157] Page = 595x842; 18,14 to 577,828
D [18/Oct/2009:01:43:15 +0100] [Job 157] Page = 595x842; 18,14 to 577,828
D [18/Oct/2009:01:43:15 +0100] [Job 157] slow_collate=0, slow_duplex=0, slow_order=0
D [18/Oct/2009:01:43:15 +0100] [Job 157] Before copy_comments - %!PS-Adobe-3.0
D [18/Oct/2009:01:43:15 +0100] [Job 157] %!PS-Adobe-3.0
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%BoundingBox: 18 14 577 828
D [18/Oct/2009:01:43:15 +0100] [Job 157] %cupsRotation: 0
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%Creator: bannertops/CUPS v1.4.1
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%CreationDate: Sun Oct 18 01:43:10 2009
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%LanguageLevel: 2
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%DocumentData: Clean7Bit
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%Title: (Test Page)
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%For: (root)
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%Pages: 1
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%DocumentSuppliedResources: font Monospace
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%+ font Monospace-Bold
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%+ font Monospace-BoldOblique
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%+ font Monospace-Oblique
D [18/Oct/2009:01:43:15 +0100] [Job 157] %%EndComments
D [18/Oct/2009:01:43:15 +0100] [Job 157] Before copy_prolog - %%BeginProlog
D [18/Oct/2009:01:43:15 +0100] [Job 157] foomatic-rip version $Revision=3.0.2.131$ running...
D [18/Oct/2009:01:43:15 +0100] [Job 157] Parsing PPD file ...
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option ColorSpace
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option Resolution
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option PageSize
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option Model
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option PrintoutMode
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option MediaType
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option InputSlot
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option Quality
D [18/Oct/2009:01:43:15 +0100] [Job 157] *cupsFilter: "application/vnd.cups-postscript 100 foomatic-rip-hplip"
D [18/Oct/2009:01:43:15 +0100] [Job 157] *cupsFilter: "application/vnd.cups-pdf 0 foomatic-rip-hplip"
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option PageRegion
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option ImageableArea
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option PaperDimension
D [18/Oct/2009:01:43:15 +0100] [Job 157] Added option Font
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] Parameter Summary
D [18/Oct/2009:01:43:15 +0100] [Job 157] -----------------
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] Spooler: cups
D [18/Oct/2009:01:43:15 +0100] [Job 157] Printer: HP-LaserJet-1005-USB
D [18/Oct/2009:01:43:15 +0100] [Job 157] Shell: /bin/sh
D [18/Oct/2009:01:43:15 +0100] [Job 157] PPD file: /etc/cups/ppd/HP-LaserJet-1005-USB.ppd
D [18/Oct/2009:01:43:15 +0100] [Job 157] ATTR file: 
D [18/Oct/2009:01:43:15 +0100] [Job 157] Printer model: HP LaserJet 1005 Series hpijs, 3.9.8
D [18/Oct/2009:01:43:15 +0100] [Job 157] Job title: Test Page
D [18/Oct/2009:01:43:15 +0100] [Job 157] File(s) to be printed: 
D [18/Oct/2009:01:43:15 +0100] [Job 157] <STDIN>
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [18/Oct/2009:01:43:15 +0100] [Job 157] Pondering option 'job-uuid=urn:uuid:dd8e9d38-ec89-3b03-6a42-673ae14fafd7'
D [18/Oct/2009:01:43:15 +0100] [Job 157] Unknown option job-uuid=urn:uuid:dd8e9d38-ec89-3b03-6a42-673ae14fafd7.
D [18/Oct/2009:01:43:15 +0100] [Job 157] Pondering option 'job-originating-host-name=localhost'
D [18/Oct/2009:01:43:15 +0100] [Job 157] Unknown option job-originating-host-name=localhost.
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] ================================================
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] File: <STDIN>
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] ================================================
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] Reading PostScript input ...
D [18/Oct/2009:01:43:15 +0100] [Job 157] --> This document is DSC-conforming!
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %RBINumCopies: 1
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] -----------
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%BeginProlog
D [18/Oct/2009:01:43:15 +0100] [Job 157] PNG image: 128x128x8, color_type=6 (RGB+ALPHA)
D [18/Oct/2009:01:43:15 +0100] [Job 157] PNG image: 192x128x8, color_type=2 (RGB)
D [18/Oct/2009:01:43:15 +0100] [Job 157] Before copy_setup - %%Page: coverpage 1
D [18/Oct/2009:01:43:15 +0100] [Job 157] Before page loop - %%Page: coverpage 1
D [18/Oct/2009:01:43:15 +0100] [Job 157] Copying page 1...
D [18/Oct/2009:01:43:15 +0100] [Job 157] pagew = 559.0, pagel = 813.2
D [18/Oct/2009:01:43:15 +0100] [Job 157] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [18/Oct/2009:01:43:15 +0100] [Job 157] PageLeft = 18.0, PageRight = 577.0
D [18/Oct/2009:01:43:15 +0100] [Job 157] PageTop = 827.6, PageBottom = 14.4
D [18/Oct/2009:01:43:15 +0100] [Job 157] PageWidth = 595.0, PageLength = 842.0
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%EndProlog
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] -----------
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%BeginSetup
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%BeginFeature: *MediaType Plain
D [18/Oct/2009:01:43:15 +0100] [Job 157] Option: MediaType=Plain --> Setting option
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %% FoomaticRIPOptionSetting: MediaType=Plain
D [18/Oct/2009:01:43:15 +0100] [Job 157] Option: MediaType=Plain --> Setting option
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%BeginFeature: *PageSize A4
D [18/Oct/2009:01:43:15 +0100] [Job 157] Option: PageSize=A4 --> Setting option
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %% FoomaticRIPOptionSetting: PageSize=A4
D [18/Oct/2009:01:43:15 +0100] [Job 157] Option: PageSize=A4 --> Setting option
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%BeginFeature: *PrintoutMode Normal
D [18/Oct/2009:01:43:15 +0100] [Job 157] Option: PrintoutMode=Normal --> Setting option
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
D [18/Oct/2009:01:43:15 +0100] [Job 157] Option: PrintoutMode=Normal --> Setting option
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%BeginFeature: *InputSlot Default
D [18/Oct/2009:01:43:15 +0100] [Job 157] Option: InputSlot=Default --> Setting option
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %% FoomaticRIPOptionSetting: InputSlot=Default
D [18/Oct/2009:01:43:15 +0100] [Job 157] Option: InputSlot=Default --> Setting option
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%BeginFeature: *Quality FromPrintoutMode
D [18/Oct/2009:01:43:15 +0100] [Job 157] Option: Quality=FromPrintoutMode --> Setting option
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [18/Oct/2009:01:43:15 +0100] [Job 157] Option: Quality=FromPrintoutMode --> Setting option
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%EndSetup
D [18/Oct/2009:01:43:15 +0100] [Job 157] Inserting PostScript code for CUPS' page accounting
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] -----------
D [18/Oct/2009:01:43:15 +0100] [Job 157] New page:  coverpage 1
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%BeginPageSetup
D [18/Oct/2009:01:43:15 +0100] [Job 157] Inserting option code into "PageSetup" section.
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: %%EndPageSetup
D [18/Oct/2009:01:43:15 +0100] [Job 157] End of page header
D [18/Oct/2009:01:43:15 +0100] [Job 157] Stopping search for page header options
D [18/Oct/2009:01:43:15 +0100] [Job 157] Found: s8W-!s8W-!s8W-!s8W-!s8W-!s8W-!s8W-!s8W-!s8W-!s8W*!zzzzzzzzzzzzz!<<*!s8W-!s8W-!
D [18/Oct/2009:01:43:15 +0100] [Job 157] --> Output goes directly to the renderer now.
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] Starting renderer
D [18/Oct/2009:01:43:15 +0100] [Job 157] usb_find_devices=13
D [18/Oct/2009:01:43:15 +0100] [Job 157] STATE: +connecting-to-device
D [18/Oct/2009:01:43:15 +0100] [Job 157] JCL: <job data> 
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] renderer PID kid4=23412
D [18/Oct/2009:01:43:15 +0100] [Job 157] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="HP LaserJet 1018" -r600 -sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:PenSet=0Plain,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=- -
D [18/Oct/2009:01:43:15 +0100] [Job 157] STATE: -connecting-to-device
D [18/Oct/2009:01:43:15 +0100] [Job 157] Starting process 23413: "gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=5..."
D [18/Oct/2009:01:43:15 +0100] [Job 157] prnt/hpijs/hpijs.cpp 268: unable to set device=HP LaserJet 1018, err=48
D [18/Oct/2009:01:43:15 +0100] [Job 157] prnt/hpijs/hpijs.cpp 289: unable to set device=HP LaserJet 1018, err=48
D [18/Oct/2009:01:43:15 +0100] [Job 157] prnt/hpijs/hpijs.cpp 663: unable to read client data err=-2
D [18/Oct/2009:01:43:15 +0100] [Job 157] Process 23413 ending: "gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=5..."
D [18/Oct/2009:01:43:15 +0100] [Job 157] renderer return value: 1
D [18/Oct/2009:01:43:15 +0100] [Job 157] renderer received signal: 1
D [18/Oct/2009:01:43:15 +0100] [Job 157] tail process done writing data to STDOUT
D [18/Oct/2009:01:43:15 +0100] [Job 157] KID4 finished
D [18/Oct/2009:01:43:15 +0100] [Job 157] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3
D [18/Oct/2009:01:43:15 +0100] [Job 157] error: No such file or directory (2)
D [18/Oct/2009:01:43:15 +0100] [Job 157] Cleaning up ...
D [18/Oct/2009:01:43:15 +0100] [Job 157] Killing process 23412 (KID4) with signal 15
D [18/Oct/2009:01:43:15 +0100] [Job 157] Maximal count of pending signals (120) exceeded at /usr/lib/cups/filter/foomatic-rip-hplip line 3639, <STDIN> line 11238.
D [18/Oct/2009:01:43:15 +0100] [Job 157] Wrote 1 pages...
D [18/Oct/2009:01:43:15 +0100] [Job 157] Killing process 23412 (KID4) with signal 9
D [18/Oct/2009:01:43:15 +0100] [Job 157] 
D [18/Oct/2009:01:43:15 +0100] [Job 157] Closing foomatic-rip.
D [18/Oct/2009:01:43:15 +0100] [Job 157] End of messages
D [18/Oct/2009:01:43:15 +0100] [Job 157] printer-state=3(idle)
D [18/Oct/2009:01:43:15 +0100] [Job 157] printer-state-message="/usr/lib/cups/filter/foomatic-rip-hplip failed"
D [18/Oct/2009:01:43:15 +0100] [Job 157] printer-state-reasons=none
D [18/Oct/2009:01:44:57 +0100] [Job 158] The following messages were recorded from 01:44:57 to 01:44:57
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -mark-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -mark-
D [18/Oct/2009:01:44:57 +0100] [Job 158] Installing.
D [18/Oct/2009:01:44:57 +0100] [Job 158] false
D [18/Oct/2009:01:44:57 +0100] [Job 158] -device-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -mark-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -mark-
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b63c0)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b6370)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_params(0x19a76a8, 0x7fff991b6380)
D [18/Oct/2009:01:44:57 +0100] [Job 158] before gdev_prn_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] after gdev_prn_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] Leaving cups_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b6400)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_params(0x19a76a8, 0x7fff991b6380)
D [18/Oct/2009:01:44:57 +0100] [Job 158] before gdev_prn_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] after gdev_prn_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] Leaving cups_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b63f0)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_params(0x19a76a8, 0x7fff991b6380)
D [18/Oct/2009:01:44:57 +0100] [Job 158] before gdev_prn_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] after gdev_prn_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] Leaving cups_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b6030)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b6030)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b6030)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b6030)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b63a0)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] Finishing.
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -mark-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -dict-
D [18/Oct/2009:01:44:57 +0100] [Job 158] -mark-
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b6110)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] Wrote 1 pages...
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_params(0x19a76a8, 0x7fff991b6380)
D [18/Oct/2009:01:44:57 +0100] [Job 158] before gdev_prn_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] after gdev_prn_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] Leaving cups_get_params()
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_print_pages(0x19a76a8, 0x7ff69ef72780, 1)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsBitsPerPixel = 1, cupsWidth = 4662, cupsBytesPerLine = 583, srcbytes = 583
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsWidth = 4662, cupsHeight = 6756, cupsBytesPerLine = 583
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_print_chunked - flip = 0, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] Processing page 2...
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_get_matrix(0x19a76a8, 0x7fff991b63a0)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->header.Duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups->page = 2
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsPPD->flip_duplex = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595 841 ], HWResolution = [ 600 600 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] matrix = [ 8.333 0.000 0.000 -8.333 -150.000 6879.167 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] **** DSC comment: /Pages
D [18/Oct/2009:01:44:57 +0100] [Job 158] << /NumPages 1 /DSC_struct -dsc_data_struct- >>
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_put_params(0x19a76a8, 0x7fff991b6380)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_set_color_info(0x19a76a8)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsEncodeLUT[0] = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsEncodeLUT[65535] = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] num_components = 1, depth = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsColorSpace = 3, cupsColorOrder = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsBitsPerPixel = 1, cupsBitsPerColor = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] max_gray = 1, dither_grays = 2
D [18/Oct/2009:01:44:57 +0100] [Job 158] max_color = 0, dither_colors = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] ppd = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595.440 841.680 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] margins = [ 0.000 0.000 -0.000 0.000 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWResolution = [ 600.000 600.000 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] **** DSC comment: /BoundingBox
D [18/Oct/2009:01:44:57 +0100] [Job 158] << /DSC_struct -dsc_data_struct- /BoundingBox [18 16 577 826] >>
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_put_params(0x19a76a8, 0x7fff991b6380)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_set_color_info(0x19a76a8)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsEncodeLUT[0] = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsEncodeLUT[65535] = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] num_components = 1, depth = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsColorSpace = 3, cupsColorOrder = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsBitsPerPixel = 1, cupsBitsPerColor = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] max_gray = 1, dither_grays = 2
D [18/Oct/2009:01:44:57 +0100] [Job 158] max_color = 0, dither_colors = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] ppd = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595.440 841.680 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] margins = [ 0.000 0.000 -0.000 0.000 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWResolution = [ 600.000 600.000 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] **** DSC comment: /EOF
D [18/Oct/2009:01:44:57 +0100] [Job 158] << /DSC_struct -dsc_data_struct- >>
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_put_params(0x19a76a8, 0x7fff991b6380)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_set_color_info(0x19a76a8)
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsEncodeLUT[0] = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsEncodeLUT[65535] = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] num_components = 1, depth = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsColorSpace = 3, cupsColorOrder = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] cupsBitsPerPixel = 1, cupsBitsPerColor = 1
D [18/Oct/2009:01:44:57 +0100] [Job 158] max_gray = 1, dither_grays = 2
D [18/Oct/2009:01:44:57 +0100] [Job 158] max_color = 0, dither_colors = 0
D [18/Oct/2009:01:44:57 +0100] [Job 158] ppd = 0x1b70fb0
D [18/Oct/2009:01:44:57 +0100] [Job 158] PageSize = [ 595.440 841.680 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] margins = [ 0.000 0.000 -0.000 0.000 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWResolution = [ 600.000 600.000 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] width = 4662, height = 6756
D [18/Oct/2009:01:44:57 +0100] [Job 158] HWMargins = [ 18.000 15.500 18.000 15.500 ]
D [18/Oct/2009:01:44:57 +0100] [Job 158] cups_close(0x19a76a8)
D [18/Oct/2009:01:44:57 +0100] [Job 158] Backend returned status 1 (failed)
D [18/Oct/2009:01:44:57 +0100] [Job 158] Printer stopped due to backend errors; please consult the error_log file for details.
D [18/Oct/2009:01:44:57 +0100] [Job 158] End of messages
D [18/Oct/2009:01:44:57 +0100] [Job 158] printer-state=5(stopped)
D [18/Oct/2009:01:44:57 +0100] [Job 158] printer-state-message="Processing page 2..."
D [18/Oct/2009:01:44:57 +0100] [Job 158] printer-state-reasons=paused

Offline

#14 2009-10-18 11:04:26

kjon
Member
From: Temuco, Chile
Registered: 2008-04-16
Posts: 398

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

D [18/Oct/2009:01:43:15 +0100] [Job 157] prnt/hpijs/hpijs.cpp 268: unable to set device=HP LaserJet 1018, err=48
D [18/Oct/2009:01:43:15 +0100] [Job 157] prnt/hpijs/hpijs.cpp 289: unable to set device=HP LaserJet 1018, err=48
D [18/Oct/2009:01:43:15 +0100] [Job 157] prnt/hpijs/hpijs.cpp 663: unable to read client data err=-2

I've a hp 1018 and it works perfectly to me. I don't know why your printer looks to be detected like a 1018 when it's a 1005... that's fishy.

I don't know if this might help, but I've added my printer through the hp-toolbox assistant, not by cups.

Last edited by kjon (2009-10-18 11:08:03)


They say that if you play a Win cd backward you hear satanic messages. That's nothing! 'cause if you play it forwards, it installs windows.

Offline

#15 2009-10-18 12:28:01

owain
Member
Registered: 2009-08-24
Posts: 251

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

kjon wrote:

I don't know why your printer looks to be detected like a 1018 when it's a 1005... that's fishy.

Yeah!

I don't know if this might help, but I've added my printer through the hp-toolbox assistant, not by cups.

Thanks, I didn't know about this option...however, I just tried it, and got the error 'Printer queue setup failed' after clicking 'add printer'.

Offline

#16 2009-10-18 18:33:27

kjon
Member
From: Temuco, Chile
Registered: 2008-04-16
Posts: 398

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

What I did was to wipe all the foo2zjs (I suppose you were using this driver...) with pacman -Rscn foo2zjs. It should kill the foomatic subsystem too.

After that, pull hplip with pacman. It will install the missing dependencies. Sorry for the 'windows-quality' of the approach, but I'm running out of ideas. I hope this works for you.

p.s. Once done try running

sudo hp-toolbox

cheers.

p.s.2. you can always close the good-for-nothing hp trayicon.

p.s.3. Your printer queue error is caused due wrong permissions. Create a custom udev rule like

ACTION=="add", SUBSYSTEM=="usb", ATTR{idVendor}=="04b8", ATTR{idProduct}=="082f", GROUP:="lp", MODE:="0664"

That one is for my epson printer. Name it like '10-goddamned-printer.rules' and reload the set with

udevadm control --reload-rules

change the idvendor and idproduct with the info supplied by lsusb.

And a last detail, once you've set succesfully your printer with hplip, unplug and plug it back to allow the firmware to be uploaded to your device.

I had a very hard time with this crap. I hope this time you can make it work.

Last edited by kjon (2009-10-18 18:38:28)


They say that if you play a Win cd backward you hear satanic messages. That's nothing! 'cause if you play it forwards, it installs windows.

Offline

#17 2009-10-18 20:03:09

owain
Member
Registered: 2009-08-24
Posts: 251

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

kjon wrote:

Sorry for the 'windows-quality' of the approach, but I'm running out of ideas.

I sympathise!  Thanks for the help.

Unfortunately, hp-toolbox refuses to run as su or with sudo.  It does ask for a root password at the relevant stage of adding a device.  Still the print queue error, though, and I've already got

SUBSYSTEM=="usb", SYSFS{idVendor}=="03f0", SYSFS{idProduct}=="1317", GROUP:="lp", MODE:="0664"

in my udev rules, and the relevant location at /dev/bus/usb/00x/00y definitely has 0664 permissions, so this doesn't appear to be the cause of that.

I had a very hard time with this crap. I hope this time you can make it work.

Thanks!  (FWIW, the support for this printer in Ubuntu, and not in Vista, was one of the main reasons I finally abandoned Windows for good...I REALLY want to get it working again! smile )

Offline

#18 2009-10-19 12:13:07

bds
Member
Registered: 2004-02-27
Posts: 47

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

There is a fundamental problem loading firmware to the printer with foo2zjs/foo2xqx and CUPS 1.4.

The script /etc/hotplug/usb/hplj1000 (part of foo2xqx) transfers firmware from
/usr/share/foo2zjs/firmware to the printer. The script looks for a printer device ( "/dev/usb/lp*" or "/dev/usblp*") then "cats" the firmware to the device.

This is a classical Catch-22 situation: CUPS 1.4 requires that usblp be blacklisted (or else nothing is printed), but foo2xqx requires an lp-device created by usblp in order to load firmware (without which nothing is printed).

A simple workaround (until the foo* script is rewritten with CUPS 1.4 in mind) is

a: do NOT blacklist usblp
b: add "rmmod usblp" to /etc/rc.local

udev will run the hotplug script, the device lp* will be found, and the firmware will be loaded. After that, rc.local wil remove usblp, and the printer will be up and running by the time you log in.

Offline

#19 2009-10-19 13:51:18

owain
Member
Registered: 2009-08-24
Posts: 251

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

Thanks for the explanation, bds - I think I'm just going to sit this one out, as I know how I can get the firmware loaded manually when I need to, because it's a laptop and so I'm typically hotplugging rather than booting with the printer connected.  (Unless anyone has any other ideas of how to diagnose the failure of hplip? smile )

Offline

#20 2009-10-19 18:50:32

jt512
Member
Registered: 2009-02-19
Posts: 262

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

bds wrote:

There is a fundamental problem loading firmware to the printer with foo2zjs/foo2xqx and CUPS 1.4.

The script /etc/hotplug/usb/hplj1000 (part of foo2xqx) transfers firmware from
/usr/share/foo2zjs/firmware to the printer. The script looks for a printer device ( "/dev/usb/lp*" or "/dev/usblp*") then "cats" the firmware to the device.

This is a classical Catch-22 situation: CUPS 1.4 requires that usblp be blacklisted (or else nothing is printed), but foo2xqx requires an lp-device created by usblp in order to load firmware (without which nothing is printed).

A simple workaround (until the foo* script is rewritten with CUPS 1.4 in mind) is

a: do NOT blacklist usblp
b: add "rmmod usblp" to /etc/rc.local

udev will run the hotplug script, the device lp* will be found, and the firmware will be loaded. After that, rc.local wil remove usblp, and the printer will be up and running by the time you log in.

If all usblp is needed for is to create the device nodes, then couldn't you blacklist usblp and just write a udev rule to create the device nodes?  Alternatively, if usblp is needed for more than just creating device nodes, couldn't you write a udev rule that loads and/or unloads usblp when the printer is hotplugged?

Jay

Offline

#21 2009-10-20 11:12:48

kjon
Member
From: Temuco, Chile
Registered: 2008-04-16
Posts: 398

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

jt512 wrote:
bds wrote:

There is a fundamental problem loading firmware to the printer with foo2zjs/foo2xqx and CUPS 1.4.

The script /etc/hotplug/usb/hplj1000 (part of foo2xqx) transfers firmware from
/usr/share/foo2zjs/firmware to the printer. The script looks for a printer device ( "/dev/usb/lp*" or "/dev/usblp*") then "cats" the firmware to the device.

This is a classical Catch-22 situation: CUPS 1.4 requires that usblp be blacklisted (or else nothing is printed), but foo2xqx requires an lp-device created by usblp in order to load firmware (without which nothing is printed).

A simple workaround (until the foo* script is rewritten with CUPS 1.4 in mind) is

a: do NOT blacklist usblp
b: add "rmmod usblp" to /etc/rc.local

udev will run the hotplug script, the device lp* will be found, and the firmware will be loaded. After that, rc.local wil remove usblp, and the printer will be up and running by the time you log in.

If all usblp is needed for is to create the device nodes, then couldn't you blacklist usblp and just write a udev rule to create the device nodes?  Alternatively, if usblp is needed for more than just creating device nodes, couldn't you write a udev rule that loads and/or unloads usblp when the printer is hotplugged?

Jay

I already tryied that, but it didn't work for me. A ugly workaround, but still functional might be to blacklist usblp and, when you connect your printer, issue:

modprobe usblp && sleep 5 && modprobe -r usblp

The printer will recieve its firmware and the module will be unloaded in order to free resources for the usb backend of cups. This thing works, but... it's just too ugly for me (that's why I'm using hplip instead...)


They say that if you play a Win cd backward you hear satanic messages. That's nothing! 'cause if you play it forwards, it installs windows.

Offline

#22 2009-10-20 11:17:19

bds
Member
Registered: 2004-02-27
Posts: 47

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

jt512 asked:

If all usblp is needed for is to create the device nodes, then couldn't you blacklist usblp and just write a udev rule to create the device nodes?

A driver that provides write-to-device functionality is needed, not just the device node itself. usblp provides this. The script hplj1000 uses this functionality to copy firmware to the device.

Alternatively, if usblp is needed for more than just creating device nodes, couldn't you write a udev rule that loads and/or unloads usblp when the printer is hotplugged?

You would have to guarantee the sequence load usblp/call hplj1000 script/unload usblp. IMHO to avoid race conditions, it would be better to incorporate load/unload usblp into the hplj1000 script itself: load usblp before the script looks for an lp* device, and unload usblp after the firmware has been successfully copied.

As for me, I'll wait for the foo2xqx/foo2zjs author to update the package for CUPS 1.4x compatiblilty, and in the meantime will use the workaround given in my previous post.

Offline

#23 2010-02-01 22:38:41

brendan
Member
From: UK
Registered: 2009-05-16
Posts: 130
Website

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

bds wrote:

A simple workaround (until the foo* script is rewritten with CUPS 1.4 in mind) is

a: do NOT blacklist usblp
b: add "rmmod usblp" to /etc/rc.local

udev will run the hotplug script, the device lp* will be found, and the firmware will be loaded. After that, rc.local wil remove usblp, and the printer will be up and running by the time you log in.

Thanks, this is working for me. or at least it is for the time being!

Cheers
Brendan

Offline

#24 2012-06-22 15:53:02

mich41
Member
Registered: 2012-06-22
Posts: 796

Re: CUPS 1.4.1, foo2zjs, HP LaserJet 1005 [664 permissions]

Hi.
I'm digging this thread because I've had similar problems with my P1005 today and I've found a much cleaner workaround than playing tricks with usblp, risking race conditions and possibly disturbing other hardware.

The solution is to disable CUPS's raw USB driver and use device file provided by usblp instead. After CUPS configured my P1005, I've shut it down, opened printers.conf and changed DeviceUri to "file:/dev/hpljP1005-0", which is a symlink to /dev/usb/lpSomething created dynamically by udev scripts.

Now everything works like a breeze, even hotplugging after rc.local has been processed smile

Offline

Board footer

Powered by FluxBB