HP DesignJet Printing Failure

Originator:cmconnelly
Number:rdar://7259633 Date Originated:28-Sep-2009 05:01 PM
Status:Open Resolved:
Product:Mac OS X Product Version:10.6
Classification:Severe Reproducible:Always
 
28-Sep-2009 05:01 PM Claire Connelly:
Summary:

A new Snow Leopard machine can print to an HP DesignJet 1055cm printer just fine.  Installing Apple's HP Printer Driver update package (version 2.1 or 2.2) causes printing to fail.

Steps to Reproduce:

1. Get a fresh Snow Leopard machine without the printer driver updates package installed.  
2. Install the printer-driver updates package.  
3. Failures happen.

Expected Results:

Documents should be printed.

Actual Results:

Documents fail to print.  From Preview (printing a large PDF), a dialog appears reading

   Print
   Error while printing.

Console messages log has messages similar to

9/28/09 4:47:03 PM	Preview[209]	Printing failed because PMSessionEndDocumentNoDialog() returned -30870.

In the CUPS error log, messages similar to those in the attached log file.

Regression:

Printing worked before the update package came out; printing on a new machine works until the update package is installed.

Notes:

Discovered on my MacBook Pro, upgraded to Snow Leopard, then later upgraded with the HP printer driver package (2.1).  Replicated on a brand-new iMac with Snow Leopard preinstalled; printing worked until version 2.2 of the HP printer driver update package was installed.

Attached are a System Profile report for the MacBook Pro and the iMac, CUPS error_log files from both machines, and a sample poster (in both PDF and PPT) that printed before the update and does not print after the update.

(Note that I had also tried printing this document using its original PowerPoint file, which failed with similar messages.)




'logs-sample-etc.zip' and 'system-profiles.zip' were successfully uploaded

Comments

Relevant log extract

As the logs attached to Apple's bug aren't viewable here, the following are the relevant lines from the CUPS error_log file:

D [28/Sep/2009:15:07:19 -0700] [Job 55] The following messages were recorded from 15:07:01 to 15:07:19
D [28/Sep/2009:15:07:19 -0700] [Job 55] Adding start banner page "none".
D [28/Sep/2009:15:07:19 -0700] [Job 55] Queued on "scraps__DesignJet_1055CM_" by "cmc".
D [28/Sep/2009:15:07:19 -0700] [Job 55] File of type application/vnd.cups-command queued by "cmc".
D [28/Sep/2009:15:07:19 -0700] [Job 55] Adding end banner page "none".
D [28/Sep/2009:15:07:19 -0700] [Job 55] job-sheets=none,none
D [28/Sep/2009:15:07:19 -0700] [Job 55] argv[0]="scraps__DesignJet_1055CM_"
D [28/Sep/2009:15:07:19 -0700] [Job 55] argv[1]="55"
D [28/Sep/2009:15:07:19 -0700] [Job 55] argv[2]="cmc"
D [28/Sep/2009:15:07:19 -0700] [Job 55] argv[3]="Supplies Levels"
D [28/Sep/2009:15:07:19 -0700] [Job 55] argv[4]="1"
D [28/Sep/2009:15:07:19 -0700] [Job 55] argv[5]="job-uuid=urn:uuid:c2eb6aa9-cc01-3f2c-4452-e0153ccd5991 job-originating-host-name=localhost"
D [28/Sep/2009:15:07:19 -0700] [Job 55] argv[6]="/private/var/spool/cups/d00055-001"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[0]=""
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[1]="CUPS_CACHEDIR=/private/var/spool/cups/cache"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[2]="CUPS_DATADIR=/usr/share/cups"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[3]="CUPS_DOCROOT=/usr/share/doc/cups"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[4]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[5]="CUPS_REQUESTROOT=/private/var/spool/cups"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[6]="CUPS_SERVERBIN=/usr/libexec/cups"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[7]="CUPS_SERVERROOT=/private/etc/cups"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[8]="CUPS_STATEDIR=/private/etc/cups"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[9]="HOME=/private/var/spool/cups/tmp"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[10]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[11]="SERVER_ADMIN=root@perosteck"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[12]="SOFTWARE=CUPS/1.4.1"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[13]="TMPDIR=/private/var/spool/cups/tmp"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[14]="USER=root"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[15]="CUPS_SERVER=/private/var/run/cupsd"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[17]="IPP_PORT=631"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[18]="CHARSET=utf-8"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[19]="LANG=en_US.UTF-8"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[20]="APPLE_LANGUAGE=en-US"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[21]="PPD=/private/etc/cups/ppd/scraps__DesignJet_1055CM_.ppd"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[22]="RIP_MAX_CACHE=8m"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[23]="CONTENT_TYPE=application/vnd.cups-command"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[24]="DEVICE_URI=dnssd://DesignJet%201055CM%20%28C6075A%29%20%280001E653463B%29._printer._tcp.local."
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[25]="PRINTER_INFO=scraps (DesignJet 1055CM)"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[26]="PRINTER_LOCATION=Math Clinic Workroom (Claire Connelly, system@math.hmc.edu)"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[27]="PRINTER=scraps__DesignJet_1055CM_"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[28]="CUPS_FILETYPE=document"
D [28/Sep/2009:15:07:19 -0700] [Job 55] envp[29]="FINAL_CONTENT_TYPE=printer/scraps__DesignJet_1055CM_"
D [28/Sep/2009:15:07:19 -0700] [Job 55] Started filter /Library/Printers/hp/cups/filters/commandtohp.filter/Contents/MacOS/commandtohp (PID 6143)
D [28/Sep/2009:15:07:19 -0700] [Job 55] Started backend /usr/libexec/cups/backend/dnssd (PID 6144)
D [28/Sep/2009:15:07:19 -0700] [Job 55] Resolving "DesignJet 1055CM (C6075A) (0001E653463B)._printer._tcp.local."...
D [28/Sep/2009:15:07:19 -0700] [Job 55] STATE: +connecting-to-device
D [28/Sep/2009:15:07:19 -0700] [Job 55] Resolving "DesignJet 1055CM (C6075A) (0001E653463B)", regtype="_printer._tcp", domain="local."...
D [28/Sep/2009:15:07:19 -0700] [Job 55] Looking for printer...
D [28/Sep/2009:15:07:19 -0700] [Job 55] Resolved as "lpd://scraps.local.:515/BINPS"...
D [28/Sep/2009:15:07:19 -0700] [Job 55] STATE: -connecting-to-device
D [28/Sep/2009:15:07:19 -0700] [Job 55] Executing backend "/usr/libexec/cups/backend/lpd"...
D [28/Sep/2009:15:07:19 -0700] [Job 55] STATE: +connecting-to-device
D [28/Sep/2009:15:07:19 -0700] [Job 55] Looking up "scraps.local."...
D [28/Sep/2009:15:07:19 -0700] [Job 55] Copying print data...
D [28/Sep/2009:15:07:19 -0700] [Job 55] backendRunLoop(print_fd=-1, device_fd=7, snmp_fd=5, addr=0x100109478, use_bc=0, side_cb=0x144921e94)
D [28/Sep/2009:15:07:19 -0700] [Job 55] Job canceled by "cmc"
D [28/Sep/2009:15:07:19 -0700] [Job 55] Received an interrupt before any bytes were written, aborting!
D [28/Sep/2009:15:07:19 -0700] [Job 55] STATE: +connecting-to-device
D [28/Sep/2009:15:07:19 -0700] [Job 55] Looking up "scraps.local."...
D [28/Sep/2009:15:07:19 -0700] [Job 55] Backend returned status 1 (failed)
D [28/Sep/2009:15:07:19 -0700] [Job 55] End of messages
D [28/Sep/2009:15:07:19 -0700] [Job 55] printer-state=3(idle)
D [28/Sep/2009:15:07:19 -0700] [Job 55] printer-state-message="Copying print data..."
D [28/Sep/2009:15:07:19 -0700] [Job 55] printer-state-reasons=none
E [28/Sep/2009:15:07:25 -0700] Returning IPP client-error-document-format-not-supported for Send-Document (ipp://localhost:631/printers/scraps__DesignJet_1055CM_) from localhost
E [28/Sep/2009:15:08:58 -0700] Ignoring empty "marker-names" attribute
E [28/Sep/2009:15:09:12 -0700] Returning IPP client-error-document-format-not-supported for Send-Document (ipp://localhost:631/printers/scraps_math_hmc_edu) from localhost

Apple's response

Engineering has requested the following information in order to further investigate this issue:

You may have a mismatch of old HP SW installed and new HP drivers placed on top of it. Please delete and re-add it. Please let us know if that works?

Continued failure

Alas, no.

I wiped out the iMac I was using for testing (erased disk and restored from the DVDs it came with), giving me a clean Snow Leopard install with iWork '09.

I then created the printer using the standard browser from the preference pane by picking it out from the list of printers in the Default tab (i.e., over Bonjour).

I then started Pages, created a blank document, then chose Page Setup and created a custom page size of 36" x 2" (for use with the DesignJet).

I put a tiny bit of text and an arrow from Pages's Shapes menu in the document.

I printed the document to the DesignJet. Success!

I then ran Software Update, which installed the HP Printer Update package, version 2.2.

I tried printing the document again, and it failed, with a not-so-helpful dialog box saying Print. Error while printing.''

In Console, I see the message

10/1/09 5:52:27 PM Pages[209] Printing failed because PMSessionEndDocumentNoDialog() returned -30870.

in Console Messages.

Attached are the CUPS logs from that machine.

[Essentially the same messages as before.]

07-Oct-2009 12:10 PM

Engineering is requesting the following information to investigate this issue further.

The printer queue URI (ipp://localhost:631/printers/scraps__DesignJet_1055CM_) is quite strange. Can you please attach the information below:

  1. Before installing the HP Printer Update 2.2: the output of "lpstat -s" command and the files in /etc/cups/ppd.

  2. After you install the HP 2.2 update, and print fails: the output of "lpstat -s" command and the files in /etc/cups/ppd, plus the /var/log/install.log, /var/log/system.log, plus the screenshot of the error dialog.

Apple asked whether restarting the application being printed from made a difference. I responded negatively, pointed at the commandtohp filter, and included logs from a working machine (without the update) and one from a machine where the update is installed and printing fails. The key difference seems to be that on a machine with the update, the print system tries to send a PDF, which another part of the print system rejects. On a working machine (sans update), the print job is converted to PostScript and sent to the printer successfully.


Please note: Reports posted here will not necessarily be seen by Apple. All problems should be submitted at bugreport.apple.com before they are posted here. Please only post information for Radars that you have filed yourself, and please do not include Apple confidential information in your posts. Thank you!