Bug 475913 - large file sent to cups disappears without a trace
Summary: large file sent to cups disappears without a trace
Keywords:
Status: CLOSED RAWHIDE
Alias: None
Product: Fedora
Classification: Fedora
Component: cups
Version: 11
Hardware: All
OS: Linux
low
medium
Target Milestone: ---
Assignee: Tim Waugh
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks: F11Target
TreeView+ depends on / blocked
 
Reported: 2008-12-11 02:39 UTC by Jonathan Kamens
Modified: 2009-07-29 02:21 UTC (History)
1 user (show)

Fixed In Version:
Clone Of:
Environment:
Last Closed: 2009-07-29 02:21:07 UTC
Type: ---
Embargoed:


Attachments (Terms of Use)
cupsd error_log file with debug2 enabled, with noise stripped out (249.51 KB, text/plain)
2008-12-11 02:39 UTC, Jonathan Kamens
no flags Details

Description Jonathan Kamens 2008-12-11 02:39:26 UTC
Created attachment 326579 [details]
cupsd error_log file with debug2 enabled, with noise stripped out

With current packages from rawhide including cups-1.4-0.b1.4.fc11.i386...

I have a raw printer called "raw" set up to print to port 9100 on a remote printer.

I multiple printers with "real" drivers set up, all of whom feed into the raw printer queue over ipp (to serialize jobs to the actual printer).

One of the printers that feeds into the raw printer, "color2", is configured with the HP Color LaserJet 5550 Foomatic/hpijs driver.

When I print a 100MB PostScript file to color2 from the Linux version of picasa, it disappears into the ether -- Picasa thinks it printed, the CUPS logs don't show any errors, and yet it never prints.

If I disable the color2 printer with cupsdisable before printing from picasa, and then reenable it afterwards with cupsenable, the file *does* print, i.e., it goes from the color2 queue into the raw queue and from there to the actual printer.

I enabled debug2 logging when the problem manifests.  Attached is the result, with all of the cupsdDoSelect and cupsdReadClient lines removed to make the log file a reasonable size.  I can't figure out from this log file what's going wrong.

Comment 1 Tim Waugh 2008-12-11 09:52:54 UTC
This seems to be the trouble:

E [10/Dec/2008:21:23:51 -0500] [Job 16046] No files, canceling job!

Looking at the cupsdReadClient lines, is the file actually transferred to the server?

You could set "PreserveJobFiles Yes" in /etc/cups/cupsd.conf in order to keep all of the job files in /var/spool/cups; that might give us clues.

Comment 2 Jonathan Kamens 2008-12-12 04:04:17 UTC
The "No files, canceling job" message is clearly mistaken.

I just set PreserveJobFiles to yes and then printed the big job from picasa again, and again it disappeared into the ether.  When I look at /var/spool/cups with "ls -l --si", here's what i see at the end:

-rw-r----- 1 root lp 168M 2008-12-10 17:28 d16032-001
-rw-r----- 1 root lp 168M 2008-12-10 17:41 d16033-001
-rw-r----- 1 root lp 168M 2008-12-10 17:50 d16034-001
-rw-r----- 1 root lp 168M 2008-12-10 18:02 d16035-001
-rw-r----- 1 root lp 168M 2008-12-10 18:17 d16036-001
-rw-r----- 1 root lp  67M 2008-12-10 18:20 d16037-001
-rw-r----- 1 root lp 101M 2008-12-10 18:33 d16042-001
-rw-r----- 1 root lp 101M 2008-12-10 18:57 d16043-001
-rw-r----- 1 root lp 168M 2008-12-10 21:24 d16046-001
-rw-r----- 1 root lp 242M 2008-12-11 22:59 d16054-001

The last file is the one I just printed.  So yes, it is getting sent to cups.  And like I said, if I disable the color2 printer before printing, and then enable afterwards, it prints fine (I didn't do that in this case, so it didn't print).

Interesting: When I open the print status applet and tell it to view completed jobs, it says that the job I just tried to print was "Aborted".

When right-click on it and select "Reprint", it works.

Comment 3 Tim Waugh 2008-12-19 17:29:24 UTC
I guess this probably still happens in 1.4b2..?

Comment 4 Jonathan Kamens 2009-01-14 22:41:33 UTC
Still seeing this with cups-1.4-0.b2.2.fc11.i386.

Comment 5 Bug Zapper 2009-06-09 10:13:50 UTC
This bug appears to have been reported against 'rawhide' during the Fedora 11 development cycle.
Changing version to '11'.

More information and reason for this action is here:
http://fedoraproject.org/wiki/BugZappers/HouseKeeping

Comment 6 Tim Waugh 2009-07-28 17:18:32 UTC
Are you still seeing this with the current version, cups-1.4-0.rc1.10.fc11?  If so I'm hoping that the error_log might have more useful information in this time.

Comment 7 Jonathan Kamens 2009-07-29 02:21:07 UTC
I can no longer reproduce this issue.


Note You need to log in before you can comment on or make changes to this bug.