Move Job returns Forbidden, but works for Move All Jobs

Wed Nov 18 10:20:42 PST 2009

Attempting to move a single job from one queue to another results in a
Forbidden message from the CUPS pages, whereas using the "Move All Jobs" option
from the Maintenance menu succeeds.

Running cups-1.4.1-4 on Debian Squeeze.

>From the logs it seems to me that the authenticated username is not being
passed along with the single job move button.

The one that fails:
Nov 18 11:49:31 nero cupsd[2321]: cupsdReadClient: 71 WAITING Closing on EOF
Nov 18 11:49:31 nero cupsd[2321]: cupsdCloseClient: 71
Nov 18 11:49:31 nero cupsd[2321]: cupsdReadClient: 42 POST /jobs/ HTTP/1.1
Nov 18 11:49:31 nero cupsd[2321]: cupsdSetBusyState: Active clients, printing jobs, and dirty files
Nov 18 11:49:31 nero cupsd[2321]: cupsdAuthorize: No authentication data provided.
Nov 18 11:49:31 nero cupsd[2321]: cupsdIsAuthorized: username=""
Nov 18 11:49:31 nero cupsd[2321]: [CGI] argv[0] = "/usr/lib/cups/cgi-bin/jobs.cgi"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[8] = "HOME=/var/spool/cups-tmp"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[10] = "SERVER_ADMIN=helpdesk"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[11] = "SOFTWARE=CUPS/1.4.1"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[12] = "TMPDIR=/var/spool/cups-tmp"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[13] = "TZ=America/Chicago"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[14] = "USER=root"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[17] = "IPP_PORT=631"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[18] = "LANG=en_US.UTF8"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[19] = "REDIRECT_STATUS=1"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[21] = "SERVER_NAME=printserver"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[22] = "SERVER_PORT=443"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[23] = "REMOTE_ADDR=128.104.196.230"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[24] = "REMOTE_HOST=128.104.196.230"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[25] = "SCRIPT_NAME=/jobs/"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/jobs/"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux x86_64; en-US; rv:1.9.0.15) Gecko/2009102814 Iceweasel/3.0.6 (Debian-3.0.6-3)"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[29] = "HTTP_REFERER=https://printserver/jobs/"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[30] = "REQUEST_METHOD=POST"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[31] = "CONTENT_LENGTH=98"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[32] = "CONTENT_TYPE=application/x-www-form-urlencoded"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] envp[33] = "HTTPS=ON"
Nov 18 11:49:31 nero cupsd[2321]: [CGI] Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 25502)
Nov 18 11:49:31 nero cupsd[2321]: Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=25502)
Nov 18 11:49:31 nero cupsd[2321]: cupsdSendCommand: 42 file=74
Nov 18 11:49:31 nero cupsd[2321]: cupsdAcceptClient: 71 from localhost (Domain)
Nov 18 11:49:31 nero cupsd[2321]: cupsdReadClient: 71 POST /jobs/169381 HTTP/1.1
Nov 18 11:49:31 nero cupsd[2321]: cupsdAuthorize: No authentication data provided.
Nov 18 11:49:31 nero cupsd[2321]: cupsdIsAuthorized: username=""
Nov 18 11:49:31 nero cupsd[2321]: cupsdReadClient: 71 1.1 CUPS-Move-Job 1
Nov 18 11:49:31 nero cupsd[2321]: CUPS-Move-Job ipp://localhost/jobs/169381
Nov 18 11:49:31 nero cupsd[2321]: cupsdIsAuthorized: requesting-user-name=""
Nov 18 11:49:31 nero cupsd[2321]: Returning HTTP Forbidden for CUPS-Move-Job (ipp://localhost/jobs/169381) from localhost
Nov 18 11:49:31 nero cupsd[2321]: [CGI] lang="en_US.UTF8", locale="/en_US"...
Nov 18 11:49:31 nero cupsd[2321]: Script header: Content-Type: text/html;charset=utf-8
Nov 18 11:49:31 nero cupsd[2321]: Script header:
Nov 18 11:49:31 nero cupsd[2321]: [CGI] lang="en_US.UTF8", locale="/en_US"...
Nov 18 11:49:31 nero cupsd[2321]: [CGI] lang="en_US.UTF8", locale="/en_US"...
Nov 18 11:49:31 nero cupsd[2321]: cupsdReadClient: 71 WAITING Closing on EOF
Nov 18 11:49:31 nero cupsd[2321]: cupsdCloseClient: 71
Nov 18 11:49:31 nero cupsd[2321]: PID 25502 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors.

And the successful one:
Nov 18 12:03:13 nero cupsd[2321]: cupsdReadClient: 42 1.1 CUPS-Move-Job 1
Nov 18 12:03:13 nero cupsd[2321]: CUPS-Move-Job ipp://localhost:631/printers/building-170-duplex1
Nov 18 12:03:13 nero cupsd[2321]: cupsdIsAuthorized: requesting-user-name="bpkroth"
Nov 18 12:03:13 nero cupsd[2321]: cupsdIsAuthorized: requesting-user-name="bpkroth"
Nov 18 12:03:13 nero cupsd[2321]: Discarding unused job-config-changed event...
Nov 18 12:03:13 nero cupsd[2321]: Discarding unused job-stopped event...
Nov 18 12:03:13 nero cupsd[2321]: cupsdMarkDirty(----J-)
Nov 18 12:03:13 nero cupsd[2321]: cupsdMarkDirty(----J-)
Nov 18 12:03:13 nero cupsd[2321]: Discarding unused printer-state-changed event...
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] job-sheets=none,none
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] argv[0]="building-170-duplex2"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] argv[1]="169482"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] argv[2]="bpkroth"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] argv[3]="Test Page"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] argv[4]="1"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] argv[5]="job-uuid=urn:uuid:b81c4553-9131-3dd6-796b-d5325a51b3a7 job-originating-host-name=localhost"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] argv[6]="/var/spool/cups/d169482-001"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[1]="CUPS_DATADIR=/usr/share/cups"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[6]="CUPS_SERVERROOT=/etc/cups"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[7]="CUPS_STATEDIR=/var/run/cups"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[8]="HOME=/var/spool/cups-tmp"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[10]="SERVER_ADMIN=helpdesk"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[11]="SOFTWARE=CUPS/1.4.1"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[12]="TMPDIR=/var/spool/cups-tmp"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[13]="TZ=America/Chicago"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[14]="USER=root"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[16]="CUPS_ENCRYPTION=IfRequested"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[17]="IPP_PORT=631"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[18]="CHARSET=utf-8"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[19]="LANG=en_US.UTF-8"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[20]="PPD=/etc/cups/ppd/building-170-duplex2.ppd"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[21]="RIP_MAX_CACHE=974228k"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[22]="CONTENT_TYPE=application/vnd.cups-banner"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[23]="DEVICE_URI=tea4cups://socket://building-170-laser2:9100"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[24]="PRINTER_INFO=building 170 Duplex Printer 2"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[25]="PRINTER_LOCATION=building 170"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[26]="PRINTER=building-170-duplex2"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[27]="CUPS_FILETYPE=document"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] envp[28]="FINAL_CONTENT_TYPE=printer/building-170-duplex2"
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] Started filter /usr/lib/cups/filter/bannertops (PID 28697)
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] Started filter /usr/lib/cups/filter/pstopdf (PID 28698)
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] Started filter /usr/lib/cups/filter/pdftopdf (PID 28699)
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] Started filter /usr/lib/cups/filter/foomatic-rip (PID 28700)
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] Started backend /usr/lib/cups/backend/tea4cups (PID 28701)
Nov 18 12:03:13 nero cupsd[2321]: Discarding unused job-state-changed event...
Nov 18 12:03:13 nero cupsd[2321]: Returning IPP successful-ok for CUPS-Move-Job (ipp://localhost:631/printers/building-170-duplex1) from localhost
Nov 18 12:03:13 nero cupsd[2321]: [Job 169482] load_banner(filename="/var/spool/cups/d169482-001")
Nov 18 12:03:13 nero cupsd[2321]: PID 28696 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
Nov 18 12:03:13 nero cupsd[2321]: cupsdReadClient: 42 WAITING Closing on EOF
Nov 18 12:03:13 nero cupsd[2321]: cupsdCloseClient: 42