[cups] Only root can print

Bernd Eggink monoped at sudrala.de
Mon Mar 10 15:09:50 PDT 2014


Hi experts,

I got a brand new PC, installed my Linux system (Crux 3.0, nearly 
evertything self-compiled), and installed CUPS. I did this a dozen times 
before. However, now the result is that only root can print. If normal 
users try to print, nothing happens - no prompt, no message, no error 
log entry (at least with debug level = "warn"), nothing left in the 
print queue.
OTOH, on my notebook, which has exactly the same system, same CUPS 
version, same cupsd.conf, same users, same groups, all users can print 
without problems!
I tried everything, even copied cupsd.conf from the notebook to the PC, 
but without any effect. I'm at my wit's end.

Here are some facts:

Kernel 3.13.6
CUPS 1.7.1
WLAN printer "HP LaserJet Pro P1102w"
Driver: Foomatic/foo2zjs-z2
Browser: Firefox 27.0.1 (not self-compiled)

Printing via lp works for normal users, so the problem must be CUPS 
specific. I guess that I'm missing something simple and fundamental, but 
I can't figure it out.

Below is the content of error_log after a user's try to print a test 
page, with LogLevel=debug. Looks quite normal to me. Could somebody have 
a look at it? Any help is appreciated!

Bernd

----------------------------------------------------------------------
D [10/Mar/2014:22:01:46 +0000] [Client 12] Accepted from localhost:631 
(IPv4)
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for request.
D [10/Mar/2014:22:01:46 +0000] Report: clients=1
D [10/Mar/2014:22:01:46 +0000] Report: jobs=1
D [10/Mar/2014:22:01:46 +0000] Report: jobs-active=0
D [10/Mar/2014:22:01:46 +0000] Report: printers=1
D [10/Mar/2014:22:01:46 +0000] Report: stringpool-string-count=17528
D [10/Mar/2014:22:01:46 +0000] Report: stringpool-alloc-bytes=9064
D [10/Mar/2014:22:01:46 +0000] Report: stringpool-total-bytes=317224
D [10/Mar/2014:22:01:46 +0000] [Client 12] POST /printers/hp HTTP/1.1
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Not busy"
D [10/Mar/2014:22:01:46 +0000] [Client 12] No authentication data provided.
D [10/Mar/2014:22:01:46 +0000] [CGI] argv[0] = 
"/usr/lib/cups/cgi-bin/printers.cgi"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[0] = 
"CUPS_CACHEDIR=/var/cache/cups"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[1] = 
"CUPS_DATADIR=/usr/share/cups"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[2] = 
"CUPS_DOCROOT=/usr/share/cups/doc"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[3] = 
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[4] = 
"CUPS_REQUESTROOT=/var/spool/cups"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[5] = 
"CUPS_SERVERBIN=/usr/lib/cups"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[9] = 
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[10] = "SERVER_ADMIN=root at Black"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/1.7.1"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[13] = "USER=root"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[15] = 
"CUPS_SERVER=/var/run/cups/cups.sock"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[16] = 
"CUPS_ENCRYPTION=IfRequested"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[18] = "LANG=en_US.UTF8"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[21] = "SERVER_NAME=localhost"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[22] = "SERVER_PORT=631"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[25] = "SCRIPT_NAME=/printers/hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[26] = 
"SCRIPT_FILENAME=/usr/share/cups/doc/printers/hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[27] = "PATH_INFO=/hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[29] = 
"HTTP_COOKIE=TiddlyWiki=chkRegExpSearch:"false" 
chkCaseSensitiveSearch:"false" chkIncrementalSearch:"true" 
chkAnimate:"true" chkSaveBackups:"true" chkAutoSave:"true" 
chkGenerateAnRssFeed:"false" chkSaveEmptyTemplate:"false" 
chkOpenInNewWindow:"true" chkToggleLinks:"false" chkHttpReadOnly:"true" 
chkForceMinorUpdate:"false" chkConfirmDelete:"true" 
chkInsertTabs:"false" chkUsePreForStorage:"true" 
chkDisplayInstrumentation:"false" txtBackupFolder:"" 
txtEditorFocus:"text" txtMainTab:"Timeline" txtMoreTab:"moreTabAll" 
txtMaxEditRows:"30" txtFileSystemCharSet:"UTF-8" txtTheme:"" 
txtUserName:"Bernd" chkSliderOptionsPanel:"true"; 
org.cups.sid=b40cf560d74ba1239b2ef3a8f8cbe832"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[30] = 
"HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:27.0) Gecko/20100101 
Firefox/27.0"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[31] = 
"HTTP_REFERER=http://localhost:631/printers/hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[32] = "REQUEST_METHOD=POST"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[33] = "CONTENT_LENGTH=64"
D [10/Mar/2014:22:01:46 +0000] [CGI] envp[34] = 
"CONTENT_TYPE=application/x-www-form-urlencoded"
D [10/Mar/2014:22:01:46 +0000] [CGI] Started 
/usr/lib/cups/cgi-bin/printers.cgi (PID 2226)
I [10/Mar/2014:22:01:46 +0000] [Client 12] Started 
"/usr/lib/cups/cgi-bin/printers.cgi" (pid=2226)
D [10/Mar/2014:22:01:46 +0000] [Client 12] file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [CGI] org.cups.sid cookie not found, 
initializing!
D [10/Mar/2014:22:01:46 +0000] [CGI] org.cups.sid cookie is 
"826161c6281eb7b4cb7d53875cacb842"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: 
org.cups.sid="b40cf560d74ba1239b2ef3a8f8cbe832"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: OP="print-test-page"
D [10/Mar/2014:22:01:46 +0000] [CGI] org.cups.sid form variable is 
"b40cf560d74ba1239b2ef3a8f8cbe832"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiClearVariables called.
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: SECTION="printers"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: PRINTER_NAME="hp"
D [10/Mar/2014:22:01:46 +0000] [Client 13] Accepted from localhost (Domain)
D [10/Mar/2014:22:01:46 +0000] [Client 13] Waiting for request.
D [10/Mar/2014:22:01:46 +0000] [Client 13] POST / HTTP/1.1
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [Client 13] No authentication data provided.
D [10/Mar/2014:22:01:46 +0000] [Client 13] 2.0 CUPS-Get-Default 1
D [10/Mar/2014:22:01:46 +0000] CUPS-Get-Default
D [10/Mar/2014:22:01:46 +0000] [Client 13] Returning IPP successful-ok 
for CUPS-Get-Default (no URI) from localhost
D [10/Mar/2014:22:01:46 +0000] [Client 13] Content-Length: 152
D [10/Mar/2014:22:01:46 +0000] [Client 13] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=152, response=0x7f7bbc8a8260(IPP_IDLE), pipe_pid=0, file=-1
D [10/Mar/2014:22:01:46 +0000] [Client 13] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [10/Mar/2014:22:01:46 +0000] [Client 13] bytes=0, http_state=0, 
data_remaining=0
D [10/Mar/2014:22:01:46 +0000] [Client 13] Waiting for request.
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: DEFAULT_NAME="hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: 
DEFAULT_URI="/printers/hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] show_printer(http=0x7f3cd2fce4e0, 
printer="hp")
D [10/Mar/2014:22:01:46 +0000] [Client 13] POST / HTTP/1.1
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [Client 13] No authentication data provided.
D [10/Mar/2014:22:01:46 +0000] [Client 13] 2.0 Get-Printer-Attributes 2
D [10/Mar/2014:22:01:46 +0000] Get-Printer-Attributes 
ipp://localhost/printers/hp
D [10/Mar/2014:22:01:46 +0000] [Client 13] Returning IPP successful-ok 
for Get-Printer-Attributes (ipp://localhost/printers/hp) from localhost
D [10/Mar/2014:22:01:46 +0000] [Client 13] Content-Length: 631
D [10/Mar/2014:22:01:46 +0000] [Client 13] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=631, response=0x7f7bbc8a7fc0(IPP_IDLE), pipe_pid=0, file=-1
D [10/Mar/2014:22:01:46 +0000] [Client 13] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [10/Mar/2014:22:01:46 +0000] [Client 13] bytes=0, http_state=0, 
data_remaining=0
D [10/Mar/2014:22:01:46 +0000] [Client 13] Waiting for request.
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: CUPS_VERSION="CUPS 
v1.7.1"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: CUPS_VERSION="CUPS 
v1.7.1"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: 
printer_is_accepting_jobs[0]="1"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: printer_is_shared[0]="0"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: printer_state[0]="3"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: admin_uri[0]="/admin/"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: 
printer_uri_supported[0]="/printers/hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: printer_name[0]="hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: printer_location[0]=""
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: printer_info[0]="HP 
LaserJet"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: 
job_sheets_default[0]="none, none"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: 
device_uri[0]="socket://hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: color_supported[0]="0"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: 
printer_make_and_model[0]="HP LaserJet Pro P1102w Foomatic/foo2zjs-z2 
(recommended)"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: 
media_default[0]="iso_a4_210x297mm"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: 
sides_supported[0]="one-sided"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: 
sides_default[0]="one-sided"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: 
printer_commands[0]="AutoConfigure, Clean, PrintSelfTestPage"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetArray: 
server_is_sharing_printers[0]="0"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: TITLE="hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: CUPS_VERSION="CUPS 
v1.7.1"
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] Script header: Set-Cookie: 
org.cups.sid=826161c6281eb7b4cb7d53875cacb842; path=/;
D [10/Mar/2014:22:01:46 +0000] Script header: Content-Type: 
text/html;charset=utf-8
D [10/Mar/2014:22:01:46 +0000] Script header:
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [CGI] Regular expression ".*Clean.*"
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [CGI] matches[0].rm_so=0
D [10/Mar/2014:22:01:46 +0000] [CGI] matches[1].rm_so=-1
D [10/Mar/2014:22:01:46 +0000] [CGI] Regular expression 
".*PrintSelfTestPage.*"
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [CGI] matches[0].rm_so=0
D [10/Mar/2014:22:01:46 +0000] [CGI] matches[1].rm_so=-1
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 13] POST / HTTP/1.1
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [Client 13] No authentication data provided.
D [10/Mar/2014:22:01:46 +0000] [Client 13] 2.0 Get-Jobs 3
D [10/Mar/2014:22:01:46 +0000] Get-Jobs ipp://localhost:631/printers/hp
D [10/Mar/2014:22:01:46 +0000] [Client 13] Returning IPP successful-ok 
for Get-Jobs (ipp://localhost:631/printers/hp) from localhost
D [10/Mar/2014:22:01:46 +0000] [Client 13] Content-Length: 75
D [10/Mar/2014:22:01:46 +0000] [Client 13] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
data_remaining=75, response=0x7f7bbc8a8260(IPP_IDLE), pipe_pid=0, file=-1
D [10/Mar/2014:22:01:46 +0000] [Client 13] Writing IPP response, 
ipp_state=DATA, old wused=0, new wused=0
D [10/Mar/2014:22:01:46 +0000] [Client 13] bytes=0, http_state=0, 
data_remaining=0
D [10/Mar/2014:22:01:46 +0000] [Client 13] Waiting for request.
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiClearVariables called.
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: ORDER="asc"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: SECTION="printers"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: TOTAL="0"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: PRINTER_NAME="hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: 
PRINTER_URI_SUPPORTED="/printers/hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: THISURL="/printers/hp"
D [10/Mar/2014:22:01:46 +0000] [CGI] cgiSetVariable: SEARCH_DEST="hp"
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 13] HTTP_STATE_WAITING Closing on EOF
D [10/Mar/2014:22:01:46 +0000] [Client 13] Closing connection.
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] CGI data ready to be sent.
D [10/Mar/2014:22:01:46 +0000] [Client 12] cupsdWriteClient error=0, 
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, 
data_remaining=0, response=(nil)(), pipe_pid=2226, file=14
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for CGI data.
D [10/Mar/2014:22:01:46 +0000] [Client 12] Waiting for request.
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Not busy", 
busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] PID 2226 
(/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [10/Mar/2014:22:01:46 +0000] [Client 12] GET /cups.css HTTP/1.1
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Not busy"
D [10/Mar/2014:22:01:46 +0000] [Client 12] No authentication data provided.
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Not busy", 
busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [Client 12] GET /images/left.gif HTTP/1.1
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Not busy"
D [10/Mar/2014:22:01:46 +0000] [Client 12] No authentication data provided.
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Not busy", 
busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [Client 13] Accepted from localhost:631 
(IPv4)
D [10/Mar/2014:22:01:46 +0000] [Client 13] Waiting for request.
D [10/Mar/2014:22:01:46 +0000] [Client 13] GET /images/right.gif HTTP/1.1
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Not busy"
D [10/Mar/2014:22:01:46 +0000] [Client 13] No authentication data provided.
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Not busy", 
busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [Client 12] GET /images/unsel.gif HTTP/1.1
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Not busy"
D [10/Mar/2014:22:01:46 +0000] [Client 12] No authentication data provided.
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Not busy", 
busy="Active clients"
D [10/Mar/2014:22:01:46 +0000] [Client 13] GET /images/sel.gif HTTP/1.1
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Active 
clients", busy="Not busy"
D [10/Mar/2014:22:01:46 +0000] [Client 13] No authentication data provided.
D [10/Mar/2014:22:01:46 +0000] cupsdSetBusyState: newbusy="Not busy", 
busy="Active clients"
D [10/Mar/2014:22:02:17 +0000] [Client 13] HTTP_STATE_WAITING Closing on EOF
D [10/Mar/2014:22:02:17 +0000] [Client 13] Closing connection.
D [10/Mar/2014:22:02:17 +0000] cupsdSetBusyState: newbusy="Not busy", 
busy="Not busy"
D [10/Mar/2014:22:02:17 +0000] [Client 12] HTTP_STATE_WAITING Closing on EOF
D [10/Mar/2014:22:02:17 +0000] [Client 12] Closing connection.
D [10/Mar/2014:22:02:17 +0000] cupsdSetBusyState: newbusy="Not busy", 
busy="Not busy"



More information about the cups mailing list