HPLIP

Printjobs disappear on Laserjet P1005 after it's switched off and on again.

Asked by Elmar Krieger

Dear HP support team,

I sucessfully installed the Laserjet P1005 using HPLIP 2.8.5 on my Fedora Core 3 machine.
I know FC3 is outdated, but printing the testpage, printing from Firefox etc all work fine. Unfortunately only until I turn the printer off. After it's turned on again, the print jobs simply don't arrive at the printer, but disappear into nowhere. There are no different error messages etc in /var/log/cups/error_log.
- Rebooting doesn't help
- Reinstalling a new queue with hp-setup doesn't help.
- One thing helps however, I found this hint on the web: If I first do

cd /usr/share/hplip/data/firmware
rm *
cd /usr/share/hplip/data/plugins
rm *
cd /usr/share/hplip/prnt/plugins
rm *

..and then reinstall the queue with hp-setup, it downloads the firmware again, and the printer works again. Until it's switched off...
Leaving the printer on forever is not an option ;-)

Do you have any idea? Does the printer forget its firmware? It's brand new..

Here's the output from hp-check

[root@yasara1 tools]# hp-check -t

HP Linux Imaging and Printing System (ver. 2.8.5)
Dependency/Version Check Utility ver. 14.0

Copyright (c) 2001-8 Hewlett-Packard Development Company, LP
This software comes with ABSOLUTELY NO WARRANTY.
This is free software, and you are welcome to distribute it
under certain conditions. See COPYING file for more details.

Note: hp-check can be run in three modes:
1. Compile-time check mode (-c or --compile): Use this mode before compiling the
HPLIP supplied tarball (.tar.gz or .run) to determine if the proper dependencies
are installed to successfully compile HPLIP.
2. Run-time check mode (-r or --run): Use this mode to determine if a distro
supplied package (.deb, .rpm, etc) or an already built HPLIP supplied tarball
has the proper dependencies installed to successfully run.
3. Both compile- and run-time check mode (-b or --both) (Default): This mode
will check both of the above cases (both compile- and run-time dependencies).

Saving output in log file: hp-check.log

Initializing. Please wait...
warning: Invalid ppd_dir value: None

---------------
| SYSTEM INFO |
---------------

Basic system information:
Linux yasara1 2.6.9-1.667 #1 Tue Nov 2 14:41:25 EST 2004 i686 i686 i386 GNU/Linu
x

Distribution:
fedora 3

HPOJ running?
No, HPOJ is not running (OK).

Checking Python version...
OK, version 2.3.4 installed

Checking PyQt version...
error: HPLIP may not function properly with the version of PyQt that is installe
d (3.13.0).
error: Ver. 3.14 or greater required.

Checking SIP version...
OK, Version 4.1 installed

Checking for CUPS...
Status: scheduler is running
Version: 1.1.22rc1
error_log is set to level: debug

Checking for dbus/python-dbus...
dbus daemon is running.
warning: python-dbus imported OK, but unknown version.

------------------------------------
| COMPILE AND RUNTIME DEPENDENCIES |
------------------------------------

note: To check for compile-time only dependencies, re-run hp-check with the -c p
arameter (ie, hp-check -c).
note: To check for run-time only dependencies, re-run hp-check with the -r param
eter (ie, hp-check -r).

Checking for dependency: cups - Common Unix Printing System...
OK, found.

Checking for dependency: cups-ddk - CUPS driver development kit...
warning: NOT FOUND! This is an OPTIONAL/RUNTIME ONLY dependency. Some HPLIP func
tionality may not function properly.

Checking for dependency: cups-devel- Common Unix Printing System development fil
es...
OK, found.

Checking for dependency: dbus - Message bus system...
OK, found.

Checking for dependency: gcc - GNU Project C and C++ Compiler...
OK, found.

Checking for dependency: GhostScript - PostScript and PDF language interpreter a
nd previewer...
OK, found.

Checking for dependency: libcrypto - OpenSSL cryptographic library...
OK, found.

Checking for dependency: libjpeg - JPEG library...
OK, found.

Checking for dependency: libnetsnmp-devel - SNMP networking library developmentf
iles...
error: NOT FOUND! This is a REQUIRED dependency. Please make sure that this depe
ndency is installed before installing or running HPLIP.

Checking for dependency: libpthread - POSIX threads library...
OK, found.

Checking for dependency: libtool - Library building support services...
OK, found.

Checking for dependency: libusb - USB library...
OK, found.

Checking for dependency: make - GNU make utility to maintain groups of programs.
..
OK, found.

Checking for dependency: PIL - Python Imaging Library (required for commandlines
canning with hp-scan)...
warning: NOT FOUND! This is an OPTIONAL/RUNTIME ONLY dependency. Some HPLIP func
tionality may not function properly.

Checking for dependency: ppdev - Parallel port support kernel module....
OK, found.

Checking for dependency: PyQt - Qt interface for Python...
OK, found.

Checking for dependency: python-ctypes - A foreign function library for Python..
.
error: NOT FOUND! This is a REQUIRED/RUNTIME ONLY dependency. Please make sure t
hat this dependency is installed before installing or running HPLIP.

Checking for dependency: python-dbus - Python bindings for dbus...
error: NOT FOUND! This is a REQUIRED/RUNTIME ONLY dependency. Please make sure t
hat this dependency is installed before installing or running HPLIP.

Checking for dependency: python-devel - Python development files...
OK, found.

Checking for dependency: Python 2.3 or greater - Required for fax functionality.
..
OK, found.

Checking for dependency: Python 2.2 or greater - Python programming language...
OK, found.

Checking for dependency: Reportlab - PDF library for Python...
warning: NOT FOUND! This is an OPTIONAL/RUNTIME ONLY dependency. Some HPLIP func
tionality may not function properly.

Checking for dependency: SANE - Scanning library...
OK, found.

Checking for dependency: SANE - Scanning library development files...
OK, found.

Checking for dependency: scanimage - Shell scanning program...
OK, found.

Checking for dependency: xsane - Graphical scanner frontend for SANE...
OK, found.

----------------------
| HPLIP INSTALLATION |
----------------------

Currently installed HPLIP version...
HPLIP 2.8.5 currently installed in '/usr/share/hplip'.

Current contents of '/etc/hp/hplip.conf' file:
# hplip.conf. Generated from hplip.conf.in by configure.

[hpssd]
# Note: hpssd does not support dynamic ports
# Port 2207 is the IANA assigned port for hpssd
port=2207

[hplip]
version=2.8.5

[dirs]
home=/usr/share/hplip
run=/var/run
ppd=/usr/share/cups/model/HP
ppdbase=/usr/share/cups/model
doc=/usr/share/doc/hplip-2.8.5
icon=/usr/share/applications
cupsbackend=/usr/lib/cups/backend
cupsfilter=/usr/lib/cups/filter
drv=/usr/share/cups/drv/hp

# Following values are determined at configure time and cannot be changed.
[configure]
network-build=no
pp-build=no
gui-build=yes
scanner-build=yes
fax-build=no
dbus-build=no
cups11-build=no
doc-build=yes
shadow-build=no
foomatic-drv-install=no
foomatic-ppd-install=yes
foomatic-rip-hplip-install=yes
internal-tag=2.8.5.23

--------------------------
| DISCOVERED USB DEVICES |
--------------------------

  Device URI Model
  -------------------------------- -----------------
  hp:/usb/HP_LaserJet_P1005?serial HP LaserJet P1005
  =BC04BK2

---------------------------------
| INSTALLED CUPS PRINTER QUEUES |
---------------------------------

HP_LaserJet_P1005
-----------------
Type: Printer
Installed in HPLIP?: Yes, using the hp: CUPS backend.
Device URI: hp:/usb/HP_LaserJet_P1005?serial=BC04BK2
PPD: /etc/cups/ppd/HP_LaserJet_P1005.ppd
PPD Description: HP LaserJet P1005 Foomatic/hpijs-ZJS (recommended)
Printer ready to printr HP_LaserJet_P1005 is idle. enabled since Jan 01 00:00
Required plug-in status: Installed
Communication status: Good

----------------------
| SANE CONFIGURATION |
----------------------

'hpaio' in '/etc/sane.d/dll.conf'...
OK, found. SANE backend 'hpaio' is properly set up.

Checking output of 'scanimage -L'...

No scanners were identified. If you were expecting something different,
check that the scanner is plugged in, turned on and detected by the
sane-find-scanner tool (if appropriate). Please read the documentation
which came with this software (README, FAQ, manpages).

---------------------
| PYTHON EXTENSIONS |
---------------------

Checking 'cupsext' CUPS extension...
OK, found.

Checking 'pcardext' Photocard extension...
OK, found.

Checking 'hpmudext' I/O extension...
OK, found.

Checking 'scanext' SANE scanning extension...
OK, found.

-----------------
| USB I/O SETUP |
-----------------

Checking for permissions of USB attached printers...

-----------
| SUMMARY |
-----------

error: 7 errors and/or warnings.

Please refer to the installation instructions at:
http://hplip.sourceforge.net/install/index.html

Done.

Here's the CUPS error_log for a print job that gets lost:

D [23/Jul/2008:16:45:17 +0200] AcceptClient: 7 from localhost:631.
D [23/Jul/2008:16:45:17 +0200] ReadClient: 7 POST / HTTP/1.1
D [23/Jul/2008:16:45:17 +0200] ProcessIPPRequest: 7 status_code=1
D [23/Jul/2008:16:45:17 +0200] ReadClient: 7 POST / HTTP/1.1
D [23/Jul/2008:16:45:17 +0200] ProcessIPPRequest: 7 status_code=1
D [23/Jul/2008:16:45:17 +0200] ReadClient: 7 POST / HTTP/1.1
D [23/Jul/2008:16:45:17 +0200] ProcessIPPRequest: 7 status_code=0
D [23/Jul/2008:16:45:17 +0200] CloseClient: 7
D [23/Jul/2008:16:45:17 +0200] AcceptClient: 7 from localhost:631.
D [23/Jul/2008:16:45:17 +0200] ReadClient: 7 POST / HTTP/1.1
D [23/Jul/2008:16:45:17 +0200] ProcessIPPRequest: 7 status_code=1
D [23/Jul/2008:16:45:17 +0200] ReadClient: 7 POST / HTTP/1.1
D [23/Jul/2008:16:45:17 +0200] ProcessIPPRequest: 7 status_code=1
D [23/Jul/2008:16:45:17 +0200] ReadClient: 7 POST / HTTP/1.1
D [23/Jul/2008:16:45:17 +0200] ProcessIPPRequest: 7 status_code=0
D [23/Jul/2008:16:45:17 +0200] CloseClient: 7
D [23/Jul/2008:16:45:17 +0200] CloseClient: 5
D [23/Jul/2008:16:45:20 +0200] AcceptClient: 5 from localhost:631.
D [23/Jul/2008:16:45:20 +0200] ReadClient: 5 POST / HTTP/1.1
D [23/Jul/2008:16:45:20 +0200] ProcessIPPRequest: 5 status_code=1
D [23/Jul/2008:16:45:20 +0200] ReadClient: 5 POST / HTTP/1.1
D [23/Jul/2008:16:45:21 +0200] ProcessIPPRequest: 5 status_code=1
D [23/Jul/2008:16:45:21 +0200] ReadClient: 5 POST / HTTP/1.1
D [23/Jul/2008:16:45:21 +0200] ProcessIPPRequest: 5 status_code=0
D [23/Jul/2008:16:45:21 +0200] CloseClient: 5
D [23/Jul/2008:16:45:21 +0200] AcceptClient: 5 from localhost:631.
D [23/Jul/2008:16:45:21 +0200] ReadClient: 5 POST / HTTP/1.1
D [23/Jul/2008:16:45:21 +0200] ProcessIPPRequest: 5 status_code=1
D [23/Jul/2008:16:45:21 +0200] ReadClient: 5 POST / HTTP/1.1
D [23/Jul/2008:16:45:21 +0200] ProcessIPPRequest: 5 status_code=1
D [23/Jul/2008:16:45:21 +0200] ReadClient: 5 POST / HTTP/1.1
D [23/Jul/2008:16:45:21 +0200] ProcessIPPRequest: 5 status_code=0
D [23/Jul/2008:16:45:21 +0200] CloseClient: 5
D [23/Jul/2008:16:45:21 +0200] AcceptClient: 5 from localhost:631.
D [23/Jul/2008:16:45:21 +0200] ReadClient: 5 POST /printers/HP_LaserJet_P1005 HTTP/1.1
D [23/Jul/2008:16:45:21 +0200] print_job: auto-typing file...
D [23/Jul/2008:16:45:21 +0200] print_job: request file type is application/postscript.
D [23/Jul/2008:16:45:21 +0200] check_quotas: requesting-user-name = 'elmar'
D [23/Jul/2008:16:45:21 +0200] print_job: requesting-user-name = 'elmar'
I [23/Jul/2008:16:45:21 +0200] Adding start banner page "none" to job 596.
I [23/Jul/2008:16:45:21 +0200] Adding end banner page "none" to job 596.
I [23/Jul/2008:16:45:21 +0200] Job 596 queued on 'HP_LaserJet_P1005' by 'elmar'.
D [23/Jul/2008:16:45:21 +0200] Job 596 hold_until = 0
D [23/Jul/2008:16:45:21 +0200] StartJob(596, 0xf7e880a0)
D [23/Jul/2008:16:45:21 +0200] StartJob() id = 596, file = 0/1
D [23/Jul/2008:16:45:21 +0200] job-sheets=none,none
D [23/Jul/2008:16:45:21 +0200] banner_page = 0
D [23/Jul/2008:16:45:21 +0200] StartJob: argv = "HP_LaserJet_P1005","596","elmar","(stdin)","1","","/var/spool/cups/d00596-001"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[2]="USER=root"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[3]="CHARSET=utf-8"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[4]="LANG=en_US"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[5]="PPD=/etc/cups/ppd/HP_LaserJet_P1005.ppd"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[10]="DEVICE_URI=hp:/usb/HP_LaserJet_P1005?serial=BC04BK2"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[11]="PRINTER=HP_LaserJet_P1005"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[14]="CUPS_SERVER=localhost"
D [23/Jul/2008:16:45:21 +0200] StartJob: envp[15]="IPP_PORT=631"
D [23/Jul/2008:16:45:21 +0200] StartJob: statusfds = [ 7 8 ]
D [23/Jul/2008:16:45:21 +0200] StartJob: filterfds[1] = [ 9 -1 ]
D [23/Jul/2008:16:45:21 +0200] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [23/Jul/2008:16:45:21 +0200] StartJob: filterfds[0] = [ 11 12 ]
D [23/Jul/2008:16:45:21 +0200] start_process("/usr/lib/cups/filter/pstops", 0xfef214c0, 0xfef20830, 9, 12, 8)
I [23/Jul/2008:16:45:21 +0200] Started filter /usr/lib/cups/filter/pstops (PID 5628) for job 596.
D [23/Jul/2008:16:45:21 +0200] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip"
D [23/Jul/2008:16:45:21 +0200] StartJob: filterfds[1] = [ 9 13 ]
D [23/Jul/2008:16:45:21 +0200] start_process("/usr/lib/cups/filter/foomatic-rip", 0xfef214c0, 0xfef20830, 11, 13, 8)
I [23/Jul/2008:16:45:21 +0200] Started filter /usr/lib/cups/filter/foomatic-rip (PID 5629) for job 596.
D [23/Jul/2008:16:45:21 +0200] StartJob: backend = "/usr/lib/cups/backend/hp"
D [23/Jul/2008:16:45:21 +0200] StartJob: filterfds[0] = [ -1 11 ]
D [23/Jul/2008:16:45:21 +0200] start_process("/usr/lib/cups/backend/hp", 0xfef214c0, 0xfef20830, 9, 11, 8)
I [23/Jul/2008:16:45:21 +0200] Started backend /usr/lib/cups/backend/hp (PID 5630) for job 596.
D [23/Jul/2008:16:45:21 +0200] ProcessIPPRequest: 5 status_code=0
D [23/Jul/2008:16:45:22 +0200] CloseClient: 5
D [23/Jul/2008:16:45:22 +0200] [Job 596] Page = 612x792; 18,14 to 594,778
D [23/Jul/2008:16:45:22 +0200] [Job 596] slowcollate=0, slowduplex=0, sloworder=0
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%BoundingBox: 0 0 612 792
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%Creator: Mozilla PostScript module (rv:1.7.3/2004102003)
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%DocumentData: Clean8Bit
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%DocumentPaperSizes: Letter
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%Orientation: Portrait
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%Pages: (atend)
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%PageOrder: Ascend
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%EndComments
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%BeginProlog
D [23/Jul/2008:16:45:22 +0200] [Job 596] foomatic-rip version $Revision: 3.43.2.6 $ running...
D [23/Jul/2008:16:45:22 +0200] [Job 596] Parsing PPD file ...
D [23/Jul/2008:16:45:22 +0200] [Job 596] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option ColorSpace
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option Resolution
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option PageSize
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option PageRegion
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option Model
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option PrintoutMode
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option MediaType
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option InputSlot
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option ImageableArea
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option PaperDimension
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option Quality
D [23/Jul/2008:16:45:22 +0200] [Job 596] Added option Font
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] Parameter Summary
D [23/Jul/2008:16:45:22 +0200] [Job 596] -----------------
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] Spooler: cups
D [23/Jul/2008:16:45:22 +0200] [Job 596] Printer: HP_LaserJet_P1005
D [23/Jul/2008:16:45:22 +0200] [Job 596] PPD file: /etc/cups/ppd/HP_LaserJet_P1005.ppd
D [23/Jul/2008:16:45:22 +0200] [Job 596] Printer model: HP LaserJet P1005 Foomatic/hpijs-ZJS (recommended)
D [23/Jul/2008:16:45:22 +0200] [Job 596] Job title: stdin
D [23/Jul/2008:16:45:22 +0200] [Job 596] File(s) to be printed:
D [23/Jul/2008:16:45:22 +0200] [Job 596] <STDIN>
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] ================================================
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] File: <STDIN>
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] ================================================
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] Reading PostScript input ...
D [23/Jul/2008:16:45:22 +0200] [Job 596] --> This document is DSC-conforming!
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] -----------
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%BeginProlog
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%EndProlog
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%Page: 1 1
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%Page: 1 1
D [23/Jul/2008:16:45:22 +0200] [Job 596] pw = 576.0, pl = 763.2
D [23/Jul/2008:16:45:22 +0200] [Job 596] PageLeft = 18.0, PageRight = 594.0
D [23/Jul/2008:16:45:22 +0200] [Job 596] PageTop = 777.6, PageBottom = 14.4
D [23/Jul/2008:16:45:22 +0200] [Job 596] PageWidth = 612.0, PageLength = 792.0
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%BeginPageSetup
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%EndPageSetup
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%EndProlog
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] -----------
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%BeginSetup
D [23/Jul/2008:16:45:22 +0200] [Job 596] Inserting PostScript code for CUPS' page accounting
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%BeginFeature: *MediaType Plain
D [23/Jul/2008:16:45:22 +0200] [Job 596] Option: MediaType=Plain --> Setting option
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %% FoomaticRIPOptionSetting: MediaType=Plain
D [23/Jul/2008:16:45:22 +0200] [Job 596] Option: MediaType=Plain --> Setting option
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%BeginFeature: *PrintoutMode Normal
D [23/Jul/2008:16:45:22 +0200] [Job 596] Option: PrintoutMode=Normal --> Setting option
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
D [23/Jul/2008:16:45:22 +0200] [Job 596] Option: PrintoutMode=Normal --> Setting option
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%BeginFeature: *InputSlot Default
D [23/Jul/2008:16:45:22 +0200] [Job 596] Option: InputSlot=Default --> Setting option
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %% FoomaticRIPOptionSetting: InputSlot=Default
D [23/Jul/2008:16:45:22 +0200] [Job 596] Option: InputSlot=Default --> Setting option
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%BeginFeature: *Quality FromPrintoutMode
D [23/Jul/2008:16:45:22 +0200] [Job 596] Option: Quality=FromPrintoutMode --> Setting option
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [23/Jul/2008:16:45:22 +0200] [Job 596] Option: Quality=FromPrintoutMode --> Setting option
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%BeginFeature: *PageRegion Letter
D [23/Jul/2008:16:45:22 +0200] [Job 596] Option: PageRegion=Letter --> Option will be set by PostScript interpreter
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
D [23/Jul/2008:16:45:22 +0200] [Job 596] Option: PageSize=Letter --> Setting option
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%EndSetup
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] -----------
D [23/Jul/2008:16:45:22 +0200] [Job 596] New page: 1 1
D [23/Jul/2008:16:45:22 +0200] [Job 596] Inserting option code into "PageSetup" section.
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%BeginPageSetup
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found: %%EndPageSetup
D [23/Jul/2008:16:45:22 +0200] [Job 596] End of page header
D [23/Jul/2008:16:45:22 +0200] [Job 596] Stopping search for page header options
D [23/Jul/2008:16:45:22 +0200] [Job 596] Found:
D [23/Jul/2008:16:45:22 +0200] [Job 596] ( | ) show
D [23/Jul/2008:16:45:22 +0200] [Job 596] --> Output goes directly to the renderer now.
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] Starting renderer
D [23/Jul/2008:16:45:22 +0200] [Job 596] JCL: <job data>
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] renderer PID kid4=5632
D [23/Jul/2008:16:45:22 +0200] [Job 596] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="HP LaserJet P1005" -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -r600 -sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:PenSet=0Plain,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=- -
D [23/Jul/2008:16:45:22 +0200] [Job 596] foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' '-sDeviceModel=HP LaserJet P1005' '-dDEVICEWIDTHPOINTS=612' '-dDEVICEHEIGHTPOINTS=792' '-r600' '-sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:PenSet=0Plain,PS:MediaPosition=7' '-dIjsUseOutputFD' '-sOutputFile=| cat >&3' '/dev/fd/0' 3>&1 1>&2
D [23/Jul/2008:16:45:22 +0200] [Job 596] 0 %%Trailer
D [23/Jul/2008:16:45:22 +0200] [Job 596] Saw Trailer!
D [23/Jul/2008:16:45:22 +0200] [Job 596] Saw EOF!
D [23/Jul/2008:16:45:22 +0200] [Job 596]
D [23/Jul/2008:16:45:22 +0200] [Job 596] Closing renderer
D [23/Jul/2008:16:45:26 +0200] [Job 596] tail process done writing data to STDOUT
D [23/Jul/2008:16:45:26 +0200] [Job 596] KID4 finished
D [23/Jul/2008:16:45:26 +0200] [Job 596] KID4 exited with status 0
D [23/Jul/2008:16:45:26 +0200] [Job 596] KID3 exited with status 0
D [23/Jul/2008:16:45:26 +0200] [Job 596] Renderer exit stat: 0
D [23/Jul/2008:16:45:26 +0200] [Job 596] KID3 finished
D [23/Jul/2008:16:45:26 +0200] [Job 596] Renderer process finished
D [23/Jul/2008:16:45:26 +0200] [Job 596]
D [23/Jul/2008:16:45:26 +0200] [Job 596] Closing foomatic-rip.
D [23/Jul/2008:16:45:34 +0200] AcceptClient: 8 from localhost:631.
D [23/Jul/2008:16:45:34 +0200] AcceptClient: 9 from localhost:631.
D [23/Jul/2008:16:45:34 +0200] ReadClient: 9 POST / HTTP/1.1
D [23/Jul/2008:16:45:34 +0200] ProcessIPPRequest: 9 status_code=1
D [23/Jul/2008:16:45:34 +0200] ReadClient: 9 POST / HTTP/1.1
D [23/Jul/2008:16:45:34 +0200] ProcessIPPRequest: 9 status_code=1
D [23/Jul/2008:16:45:34 +0200] ReadClient: 9 POST / HTTP/1.1
D [23/Jul/2008:16:45:34 +0200] ProcessIPPRequest: 9 status_code=0
D [23/Jul/2008:16:45:34 +0200] ReadClient: 8 POST / HTTP/1.1
D [23/Jul/2008:16:45:34 +0200] ProcessIPPRequest: 8 status_code=0
D [23/Jul/2008:16:45:34 +0200] CloseClient: 9
D [23/Jul/2008:16:45:34 +0200] CloseClient: 8
D [23/Jul/2008:16:45:46 +0200] UpdateJob: job 596, file 0 is complete.
D [23/Jul/2008:16:45:46 +0200] CancelJob: id = 596
D [23/Jul/2008:16:45:46 +0200] StopJob: id = 596, force = 0
D [23/Jul/2008:16:45:46 +0200] StopJob: printer state is 3
D [23/Jul/2008:16:45:52 +0200] AcceptClient: 7 from localhost:631.
D [23/Jul/2008:16:45:52 +0200] AcceptClient: 8 from localhost:631.
D [23/Jul/2008:16:45:52 +0200] ReadClient: 8 POST / HTTP/1.1
D [23/Jul/2008:16:45:52 +0200] ProcessIPPRequest: 8 status_code=1
D [23/Jul/2008:16:45:52 +0200] ReadClient: 8 POST / HTTP/1.1
D [23/Jul/2008:16:45:52 +0200] ProcessIPPRequest: 8 status_code=1
D [23/Jul/2008:16:45:52 +0200] ReadClient: 8 POST / HTTP/1.1
D [23/Jul/2008:16:45:53 +0200] ProcessIPPRequest: 8 status_code=0
D [23/Jul/2008:16:45:53 +0200] ReadClient: 7 POST / HTTP/1.1
D [23/Jul/2008:16:45:53 +0200] ProcessIPPRequest: 7 status_code=0
D [23/Jul/2008:16:45:53 +0200] CloseClient: 8
D [23/Jul/2008:16:45:53 +0200] CloseClient: 7

Question information

Language:
English Edit question
Status:
Answered
For:
HPLIP Edit question
Assignee:
No assignee Edit question
Last query:
Last reply:
Revision history for this message
Aaron Albright (albrigha-deactivatedaccount) said : 		#1

The firmware should be loaded automaticlly. To manually load the firmware run:

hp-firmware

This will load the firmware to the printer.

Although it should load it automatically. Try running

su -c "tail -f /var/log/messages"

Then power off the printer and power it on again and send the log.

A

Revision history for this message
Elmar Krieger (hp-yasara) said : 		#2

Dear Aaron,

> The firmware should be loaded automaticlly. To manually load the
> firmware run:
>
> hp-firmware
>
> This will load the firmware to the printer.

Many thanks, that's brilliant and "solved" the problem: I simple added hp-firmware to the /etc/init.d/cups startup script, now the printer is initialized whenever I start the computer. Things work perfectly now.

In case you are still interested in the /var/log/messages

Printer powered off:

Jul 23 18:45:48 yasara1 kernel: usb 1-2: USB disconnect, address 6

Printer powered on again [now jobs will disappear]:

Jul 23 18:46:08 yasara1 kernel: usb 1-2: new full speed USB device using address 7
Jul 23 18:46:08 yasara1 kernel: drivers/usb/class/usblp.c: usblp0: USB Bidirectional printer dev 7 if 0 alt 0 proto 2 vid 0x03F0 pid 0x3D17

Printing a job that disappears:
[Note: the same messages about "bulk timeout on ep1in" appear with a successful job]

Jul 23 18:46:34 yasara1 kernel: drivers/usb/class/usblp.c: usblp0: removed
Jul 23 18:46:38 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:38 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:39 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:39 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:40 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:40 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:41 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:41 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:42 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:42 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:43 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:43 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:44 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:44 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:45 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:45 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:46 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:46 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:47 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:47 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:48 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:48 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:49 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:49 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:50 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:50 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:51 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:51 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:52 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:52 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:53 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:53 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:54 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:54 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:55 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:55 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:56 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:56 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:57 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:57 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:46:58 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:46:58 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110

Reinstalling the firmware using hp-firmware:

Jul 23 18:49:04 yasara1 python: hp-firmware[6628]: error: hp-firmware should not be run as root.

[I have to run hp-firmware as root, otherwise Python crashes with this error message (yep, I know, dbus is a problem in FC3):

HP Linux Imaging and Printing System (ver. 2.8.5)
Firmware Download Utility ver. 1.1

Copyright (c) 2001-8 Hewlett-Packard Development Company, LP
This software comes with ABSOLUTELY NO WARRANTY.
This is free software, and you are welcome to distribute it
under certain conditions. See COPYING file for more details.

Using device: hp:/usb/HP_LaserJet_P1005?serial=BC04BK2
Traceback (most recent call last):
  File "/usr/bin/hp-firmware", line 192, in ?
    d = device.Device(device_uri, printer_name)
  File "/usr/share/hplip/base/device.py", line 902, in __init__
    self.dbus_avail, self.service = init_dbus()
  File "/usr/share/hplip/base/device.py", line 150, in init_dbus
    except dbus.exceptions.DBusException, e:
AttributeError: 'module' object has no attribute 'exceptions'

and this in /var/log/messages:
Jul 23 18:51:24 yasara1 python: io/hpmud/musb.c 135: unable get_string_descriptor -1: Operation not permitted
Jul 23 18:51:24 yasara1 python: io/hpmud/musb.c 1949: invalid product id stringret=-1
Jul 23 18:51:24 yasara1 python: io/hpmud/musb.c 135: unable get_string_descriptor -1: Operation not permitted
Jul 23 18:51:24 yasara1 python: io/hpmud/musb.c 1954: invalid serial id string ret=-1
]

And finally printing a job after successful hp-firmware, which works fine:

Jul 23 18:52:13 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:13 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:14 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:14 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:15 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:15 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:16 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:16 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:17 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:17 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:18 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:18 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:19 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:19 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:20 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:20 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:21 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:21 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:22 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:22 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:23 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:23 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:24 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:24 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:25 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:25 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:26 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:26 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:27 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:27 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:28 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:28 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:29 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:29 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:30 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:30 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:31 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:31 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:32 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:32 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110
Jul 23 18:52:33 yasara1 kernel: usb 1-2: bulk timeout on ep1in
Jul 23 18:52:33 yasara1 kernel: usb 1-2: usbfs: USBDEVFS_BULK failed ep 0x81 len 1024 ret -110

>
> Although it should load it automatically. Try running
>
> su -c "tail -f /var/log/messages"
>
> Then power off the printer and power it on again and send the log.
>
> A

Revision history for this message
Aaron Albright (albrigha-deactivatedaccount) said : 		#3

Thanks for the update.

A

Can you help with this problem?

Provide an answer of your own, or ask Elmar Krieger for more information if necessary.

To post a message you must log in.