Cancel job from web interface fails (1.4.4)

Matti Laakso matti.laakso at aalto.fi
Mon Oct 3 00:32:19 PDT 2011


We're running a CUPS server on Debian 6.0, using cups 1.4.4. It seems that the 'Cancel job' button on the 'Jobs' tab in the web interface does nothing. Here is a debug log when the button is pressed in a browser running locally on the server:

D [30/Sep/2011:16:15:58 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [30/Sep/2011:16:15:59 +0300] cupsdAcceptClient: 29 from localhost:631 (IPv4)
D [30/Sep/2011:16:15:59 +0300] cupsdReadClient: 29 GET /jobs/?op=cancel-job&job_id=262002&job_printer_uri=/printers/agricola HTTP/1.1
D [30/Sep/2011:16:15:59 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [30/Sep/2011:16:15:59 +0300] cupsdAuthorize: No authentication data provided.
D [30/Sep/2011:16:15:59 +0300] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/jobs.cgi"
D [30/Sep/2011:16:15:59 +0300] [CGI] argv[1] = "op=cancel-job&job_id=262002&job_printer_uri=/printers/agricola"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[10] = "SERVER_ADMIN=wizards at ltl.tkk.fi"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[13] = "TZ=Europe/Helsinki"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[14] = "USER=root"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[17] = "IPP_PORT=631"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[18] = "LANG=en_US.UTF8"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[21] = "SERVER_NAME=localhost"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[22] = "SERVER_PORT=631"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[25] = "SCRIPT_NAME=/jobs/"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/jobs/"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[27] = "PATH_INFO=/"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[29] = "HTTP_COOKIE=org.cups.sid=28071f10690d370a31f16fb6daf20015"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[30] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.9.1.16) Gecko/20110929 Iceweasel/3.5.16 (like Firefox/3.5.16)"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[31] = "HTTP_REFERER=http://localhost:631/jobs/?op=cancel-job&job_id=262002&job_printer_uri=/printers/agricola"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[32] = "REQUEST_METHOD=GET"
D [30/Sep/2011:16:15:59 +0300] [CGI] envp[33] = "QUERY_STRING=op=cancel-job&job_id=262002&job_printer_uri=/printers/agricola"
D [30/Sep/2011:16:15:59 +0300] [CGI] Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 26212)
I [30/Sep/2011:16:15:59 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=26212)
D [30/Sep/2011:16:15:59 +0300] cupsdSendCommand: 29 file=33
D [30/Sep/2011:16:15:59 +0300] [CGI] org.cups.sid cookie is "28071f10690d370a31f16fb6daf20015"
D [30/Sep/2011:16:15:59 +0300] cupsdAcceptClient: 34 from localhost (Domain)
D [30/Sep/2011:16:15:59 +0300] Script header: Content-Type: text/html;charset=utf-8
D [30/Sep/2011:16:15:59 +0300] Script header:
D [30/Sep/2011:16:15:59 +0300] cupsdReadClient: 34 POST / HTTP/1.1
D [30/Sep/2011:16:15:59 +0300] cupsdAuthorize: No authentication data provided.
D [30/Sep/2011:16:15:59 +0300] cupsdReadClient: 34 1.1 Get-Jobs 1
D [30/Sep/2011:16:15:59 +0300] Get-Jobs ipp://localhost/
D [30/Sep/2011:16:15:59 +0300] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
D [30/Sep/2011:16:15:59 +0300] cupsdReadClient: 34 WAITING Closing on EOF
D [30/Sep/2011:16:15:59 +0300] cupsdCloseClient: 34
D [30/Sep/2011:16:15:59 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [30/Sep/2011:16:15:59 +0300] PID 26212 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors.

The script exits with no errors, but the job is not canceled. Here is the relevant part of cupsd.conf:

  <Limit Cancel-Job CUPS-Authenticate-Job>
    Require user @OWNER @SYSTEM
    Order deny,allow
  </Limit>

We've also tried commenting out the Require user line, but it doesn't help.

Canceling all jobs for a printer works from the 'Maintenance' menu though (after authenticating with a password):

D [30/Sep/2011:16:16:10 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [30/Sep/2011:16:16:11 +0300] cupsdAcceptClient: 13 from localhost:631 (IPv4)
D [30/Sep/2011:16:16:11 +0300] cupsdReadClient: 13 POST /printers/agricola HTTP/1.1
D [30/Sep/2011:16:16:11 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [30/Sep/2011:16:16:11 +0300] cupsdAuthorize: Authorized as malaakso using Basic
D [30/Sep/2011:16:16:11 +0300] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[10] = "SERVER_ADMIN=wizards at ltl.tkk.fi"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[13] = "TZ=Europe/Helsinki"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[14] = "USER=root"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[17] = "IPP_PORT=631"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[18] = "CUPSD_AUTH_TYPE=Basic"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[19] = "LANG=en_US.UTF8"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[22] = "SERVER_NAME=localhost"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[23] = "SERVER_PORT=631"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[26] = "SCRIPT_NAME=/printers/agricola"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/agricola"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[28] = "PATH_INFO=/agricola"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[29] = "REMOTE_USER=malaakso"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=28071f10690d370a31f16fb6daf20015"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.9.1.16) Gecko/20110929 Iceweasel/3.5.16 (like Firefox/3.5.16)"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[33] = "HTTP_REFERER=http://localhost:631/printers/agricola"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[34] = "REQUEST_METHOD=POST"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[35] = "CONTENT_LENGTH=59"
D [30/Sep/2011:16:16:11 +0300] [CGI] envp[36] = "CONTENT_TYPE=application/x-www-form-urlencoded"
D [30/Sep/2011:16:16:11 +0300] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 26217)
I [30/Sep/2011:16:16:11 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=26217)
D [30/Sep/2011:16:16:11 +0300] cupsdSendCommand: 13 file=29
D [30/Sep/2011:16:16:11 +0300] [CGI] org.cups.sid cookie is "28071f10690d370a31f16fb6daf20015"
D [30/Sep/2011:16:16:11 +0300] cupsdAcceptClient: 26 from localhost (Domain)
D [30/Sep/2011:16:16:11 +0300] cupsdReadClient: 26 POST /printers/agricola HTTP/1.1
D [30/Sep/2011:16:16:11 +0300] cupsdAuthorize: No authentication data provided.
D [30/Sep/2011:16:16:11 +0300] cupsdReadClient: 26 1.1 Purge-Jobs 1
D [30/Sep/2011:16:16:11 +0300] Purge-Jobs ipp://localhost/printers/agricola
D [30/Sep/2011:16:16:11 +0300] Returning HTTP Unauthorized for Purge-Jobs (ipp://localhost/printers/agricola) from localhost
D [30/Sep/2011:16:16:11 +0300] cupsdSendHeader: 26 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [30/Sep/2011:16:16:11 +0300] cupsdReadClient: 26 WAITING Closing on EOF
D [30/Sep/2011:16:16:11 +0300] cupsdCloseClient: 26
D [30/Sep/2011:16:16:11 +0300] cupsdAcceptClient: 26 from localhost (Domain)
D [30/Sep/2011:16:16:11 +0300] cupsdAcceptClient: 33 from localhost (Domain)
D [30/Sep/2011:16:16:11 +0300] cupsdReadClient: 26 WAITING Closing on EOF
D [30/Sep/2011:16:16:11 +0300] cupsdCloseClient: 26
D [30/Sep/2011:16:16:11 +0300] cupsdReadClient: 33 POST /printers/agricola HTTP/1.1
D [30/Sep/2011:16:16:11 +0300] cupsdAuthorize: Authorized as malaakso using Local
D [30/Sep/2011:16:16:11 +0300] cupsdReadClient: 33 1.1 Purge-Jobs 1
D [30/Sep/2011:16:16:11 +0300] Purge-Jobs ipp://localhost/printers/agricola
D [30/Sep/2011:16:16:11 +0300] cupsdIsAuthorized: username="malaakso"
D [30/Sep/2011:16:16:11 +0300] Discarding unused job-completed event...
I [30/Sep/2011:16:16:11 +0300] [Job 262001] Job purged by user.
D [30/Sep/2011:16:16:11 +0300] [Job 262001] Unloading...
D [30/Sep/2011:16:16:11 +0300] Discarding unused job-completed event...
I [30/Sep/2011:16:16:11 +0300] [Job 262002] Job purged by user.
D [30/Sep/2011:16:16:11 +0300] Discarding unused printer-state-changed event...
D [30/Sep/2011:16:16:11 +0300] cupsdSetBusyState: Active clients and dirty files
I [30/Sep/2011:16:16:11 +0300] All jobs on "agricola" were purged by "malaakso".
D [30/Sep/2011:16:16:11 +0300] Returning IPP successful-ok for Purge-Jobs (ipp://localhost/printers/agricola) from localhost
D [30/Sep/2011:16:16:11 +0300] PID 26142 (/usr/lib/cups/filter/cpdftocps) was terminated normally with signal 9.
D [30/Sep/2011:16:16:11 +0300] PID 26143 (/usr/lib/cups/backend/socket) was terminated normally with signal 9.
D [30/Sep/2011:16:16:11 +0300] Script header: Content-Type: text/html;charset=utf-8
D [30/Sep/2011:16:16:11 +0300] Script header:
D [30/Sep/2011:16:16:11 +0300] cupsdReadClient: 33 WAITING Closing on EOF
D [30/Sep/2011:16:16:11 +0300] cupsdCloseClient: 33
D [30/Sep/2011:16:16:11 +0300] PID 26217 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.

Is there something I'm missing, or is this a bug?




More information about the cups-devel mailing list