Cancel job from web interface fails (1.4.4)

Matti Laakso matti.laakso at aalto.fi
Tue Oct 4 00:24:50 PDT 2011


That was it, perfect! Undoubtedly some leftovers of the upgrade from Debian 5.0.

> You appear to have old web interface template files - the "GET" interface was deprecated in 1.4 for security reasons.
>
> Sent from my iPhone
>
> On Oct 3, 2011, at 12:32 AM, Matti Laakso <matti.laakso at aalto.fi> wrote:
>
> > 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?
> > _______________________________________________
> > cups-bugs mailing list
> > cups-bugs at easysw.com
> > http://lists.easysw.com/mailman/listinfo/cups-bugs
>





More information about the cups-devel mailing list