Bug 548219 - after CUPS sends an IPP request to a Xerox WorkCentre Pro C2128, it waits forever
Summary: after CUPS sends an IPP request to a Xerox WorkCentre Pro C2128, it waits for...
Keywords:
Status: CLOSED ERRATA
Alias: None
Product: Fedora
Classification: Fedora
Component: cups
Version: 12
Hardware: All
OS: Linux
low
low
Target Milestone: ---
Assignee: Tim Waugh
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks: 555732
TreeView+ depends on / blocked
 
Reported: 2009-12-16 23:46 UTC by Aram Agajanian
Modified: 2010-03-13 02:29 UTC (History)
2 users (show)

Fixed In Version: cups-1.4.2-26.fc11
Doc Type: Bug Fix
Doc Text:
Clone Of:
: 555732 (view as bug list)
Environment:
Last Closed: 2010-03-12 04:20:26 UTC
Type: ---
Embargoed:


Attachments (Terms of Use)
error_log file generated when submitting an IPP print job to Xerox WorkCentre Pro C2128 (77.12 KB, text/plain)
2009-12-22 17:24 UTC, Aram Agajanian
no flags Details


Links
System ID Private Priority Status Summary Last Updated
CUPS Bugs and Features 3460 0 None None None Never

Description Aram Agajanian 2009-12-16 23:46:30 UTC
Description of problem:
After an IPP request is sent to a Xerox WorkCentre Pro C2128, the queue hangs because CUPS doesn't think that the printer is available.


Version-Release number of selected component (if applicable):
cups-1.4.2-7.fc12.x86_64

How reproducible:
Happens every time.


Steps to Reproduce:
1. Send a print job to an IPP queue.
2. Run the command "lpstat -t" to check the print queues.
3. 
  
Actual results:
The IPP queue has a status of "Waiting for job to complete..."

Expected results:
The IPP queue should be clear after the print job has been processed.


Additional info:

Using a socket connection (instead of IPP) is working OK.

The following entries are in the error_log file:

D [16/Dec/2009:18:31:41 -0500] cupsdReadClient: 16 1.1 Get-Printer-Attributes 1
D [16/Dec/2009:18:31:41 -0500] Get-Printer-Attributes ipp://localhost/printers/xrxc2128-ipp
D [16/Dec/2009:18:31:41 -0500] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/xrxc2128-ipp) from localhost
D [16/Dec/2009:18:31:41 -0500] cupsdSetBusyState: Dirty files
D [16/Dec/2009:18:31:45 -0500] cupsdReadClient: 16 POST / HTTP/1.1
D [16/Dec/2009:18:31:45 -0500] cupsdSetBusyState: Active clients and dirty files

This bug seems similar to bug #476424.

Comment 1 Tim Waugh 2009-12-22 16:35:15 UTC
Please show me a full error_log showing the job being processed, starting from rest.

Here is how:

1. "service cups restartlog" (as root)
2. Submit a print job
3. Wait a reasonable amount of time for the job to complete
4. Attach /var/log/cups/error_log here

Thanks.

Comment 2 Aram Agajanian 2009-12-22 17:24:11 UTC
Created attachment 379867 [details]
error_log file generated when submitting an IPP print job to Xerox WorkCentre Pro C2128

Comment 3 Aram Agajanian 2009-12-22 17:32:06 UTC
I noticed something today in testing is that different from what I reported.  

After:  = one job is processed AND
        = the "Waiting for job to complete..." message 
          is in the output of lpstat -t

THEN, a subsequent job was processed after a delay.  The "Waiting for job to complete..." message persists the entire time.

Comment 4 Tim Waugh 2009-12-23 12:01:48 UTC
I'd really like to see the IPP conversation between the CUPS server and the printer.  Please run this command before you submit a job:

tcpdump -s0 -U -w ipp.pcap host 137.140.7.52

Then submit the job and wait for it to complete, and press Control-C to stop the tcpdump command.  Attach the ipp.pcap file here.

Comment 5 Aram Agajanian 2009-12-23 15:56:01 UTC
I sent the ipp.pcap file via a private email message.

Comment 6 Tim Waugh 2009-12-23 16:32:09 UTC
Thanks.  The conversation shows a successful job submission.

The problem was just that the ipp backend was not clearing that message.  Reported upstream with patch.

Also affects Fedora 11.

Comment 7 Fedora Update System 2010-02-23 12:47:25 UTC
cups-1.4.2-23.fc11 has been submitted as an update for Fedora 11.
http://admin.fedoraproject.org/updates/cups-1.4.2-23.fc11

Comment 8 Fedora Update System 2010-02-23 12:48:57 UTC
cups-1.4.2-23.fc11 has been submitted as an update for Fedora 11.
http://admin.fedoraproject.org/updates/cups-1.4.2-23.fc11

Comment 9 Fedora Update System 2010-02-23 12:51:17 UTC
cups-1.4.2-25.fc12 has been submitted as an update for Fedora 12.
http://admin.fedoraproject.org/updates/cups-1.4.2-25.fc12

Comment 10 Fedora Update System 2010-02-23 12:52:41 UTC
cups-1.4.2-31.fc13 has been submitted as an update for Fedora 13.
http://admin.fedoraproject.org/updates/cups-1.4.2-31.fc13

Comment 11 Fedora Update System 2010-02-23 12:58:02 UTC
cups-1.4.2-25.fc12 has been submitted as an update for Fedora 12.
http://admin.fedoraproject.org/updates/cups-1.4.2-25.fc12

Comment 12 Fedora Update System 2010-02-23 16:08:18 UTC
cups-1.4.2-31.fc13 has been pushed to the Fedora 13 testing repository.  If problems still persist, please make note of it in this bug report.
 If you want to test the update, you can install it with 
 su -c 'yum --enablerepo=updates-testing update cups'.  You can provide feedback for this update here: http://admin.fedoraproject.org/updates/F13/FEDORA-2010-2681

Comment 13 Fedora Update System 2010-02-24 06:11:41 UTC
cups-1.4.2-23.fc11 has been pushed to the Fedora 11 testing repository.  If problems still persist, please make note of it in this bug report.
 If you want to test the update, you can install it with 
 su -c 'yum --enablerepo=updates-testing update cups'.  You can provide feedback for this update here: http://admin.fedoraproject.org/updates/F11/FEDORA-2010-2743

Comment 14 Fedora Update System 2010-02-24 06:12:12 UTC
cups-1.4.2-25.fc12 has been pushed to the Fedora 12 testing repository.  If problems still persist, please make note of it in this bug report.
 If you want to test the update, you can install it with 
 su -c 'yum --enablerepo=updates-testing update cups'.  You can provide feedback for this update here: http://admin.fedoraproject.org/updates/F12/FEDORA-2010-2748

Comment 15 Fedora Update System 2010-02-28 14:47:11 UTC
cups-1.4.2-31.fc13 has been pushed to the Fedora 13 stable repository.  If problems still persist, please make note of it in this bug report.

Comment 16 Fedora Update System 2010-03-05 11:12:28 UTC
cups-1.4.2-28.fc12 has been submitted as an update for Fedora 12.
http://admin.fedoraproject.org/updates/cups-1.4.2-28.fc12

Comment 17 Fedora Update System 2010-03-05 11:16:01 UTC
cups-1.4.2-28.fc12 has been submitted as an update for Fedora 12.
http://admin.fedoraproject.org/updates/cups-1.4.2-28.fc12

Comment 18 Fedora Update System 2010-03-06 03:46:40 UTC
cups-1.4.2-28.fc12 has been pushed to the Fedora 12 testing repository.  If problems still persist, please make note of it in this bug report.
 If you want to test the update, you can install it with 
 su -c 'yum --enablerepo=updates-testing update cups'.  You can provide feedback for this update here: http://admin.fedoraproject.org/updates/cups-1.4.2-28.fc12

Comment 19 Fedora Update System 2010-03-06 03:54:18 UTC
cups-1.4.2-26.fc11 has been pushed to the Fedora 11 testing repository.  If problems still persist, please make note of it in this bug report.
 If you want to test the update, you can install it with 
 su -c 'yum --enablerepo=updates-testing update cups'.  You can provide feedback for this update here: http://admin.fedoraproject.org/updates/cups-1.4.2-26.fc11

Comment 20 Fedora Update System 2010-03-12 04:19:45 UTC
cups-1.4.2-28.fc12 has been pushed to the Fedora 12 stable repository.  If problems still persist, please make note of it in this bug report.

Comment 21 Fedora Update System 2010-03-13 02:28:39 UTC
cups-1.4.2-26.fc11 has been pushed to the Fedora 11 stable repository.  If problems still persist, please make note of it in this bug report.


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