CUPS 1.4.6 & Solaris 10 - Print Problems

Jose Luis Gareli gareljos at sadesa.com
Tue Apr 5 05:17:44 PDT 2011


I compile and install CUPS-1.4.6 in Solaris 10 machine.
I use Web interface to setup a printer HP Laserjet 4100TN,
Wen I send a print jobs fail with the following messageas:

-bash-3.00# lp -d a23cupslpd test.txt
lp: Unsupported format 'text/plain'!

-bash-3.00# lp -d a23cupslpd r0006p.pdf
lp: Unsupported format 'application/pdf'!

-bash-3.00# lp -d a23cupslpd r0006p.ps
lp: Unsupported format 'application/postscript'!

what is wrong?

File access_log:

10.3.1.193 - - [31/Mar/2011:11:44:12 +0300] "POST /admin/ HTTP/1.1" 200 60 -

-
localhost - - [31/Mar/2011:11:44:12 +0300] "POST / HTTP/1.1" 401 91

CUPS-Get-Devices successful-ok
10.3.1.193 - - [31/Mar/2011:11:44:12 +0300] "POST /admin/ HTTP/1.1" 401 60 - -
10.3.1.193 - - [31/Mar/2011:11:44:12 +0300] "POST /admin/ HTTP/1.1" 200 60 - -
10.3.1.193 - root [31/Mar/2011:11:44:25 +0300] "POST /admin/ HTTP/1.1" 200 60 -
 -
localhost - - [31/Mar/2011:11:44:25 +0300] "POST / HTTP/1.1" 401 91

CUPS-Get-Devices successful-ok
localhost - root [31/Mar/2011:11:44:25 +0300] "POST / HTTP/1.1" 200 935

CUPS-Get-Devices -
10.3.1.193 - root [31/Mar/2011:11:44:25 +0300] "POST /admin/ HTTP/1.1" 200 4805
 - -
10.3.1.193 - - [31/Mar/2011:11:44:39 +0300] "POST /admin HTTP/1.1" 200 75 - -
10.3.1.193 - - [31/Mar/2011:11:44:39 +0300] "POST /admin HTTP/1.1" 200 2649 - -
10.3.1.193 - - [31/Mar/2011:11:44:45 +0300] "POST /admin HTTP/1.1" 200 99 - -
10.3.1.193 - - [31/Mar/2011:11:44:45 +0300] "POST /admin HTTP/1.1" 200 2673 - -
10.3.1.193 - - [31/Mar/2011:11:45:03 +0300] "POST /admin HTTP/1.1" 200 130 - -
10.3.1.193 - - [31/Mar/2011:11:45:03 +0300] "POST /admin HTTP/1.1" 200 3210 - -
10.3.1.193 - - [31/Mar/2011:11:45:33 +0300] "POST /admin HTTP/1.1" 200 238 - -
localhost - - [31/Mar/2011:11:45:33 +0300] "POST / HTTP/1.1" 200 376
CUPS-Get-PPDs -
10.3.1.193 - - [31/Mar/2011:11:45:33 +0300] "POST /admin HTTP/1.1" 200 3773 - -
10.3.1.193 - - [31/Mar/2011:11:45:48 +0300] "POST /admin HTTP/1.1" 200 1670 - -
localhost - - [31/Mar/2011:11:45:48 +0300] "POST / HTTP/1.1" 200 711
CUPS-Get-PPDs -
10.3.1.193 - - [31/Mar/2011:11:45:48 +0300] "POST /admin HTTP/1.1" 200 5167 - -
10.3.1.193 - - [31/Mar/2011:11:46:07 +0300] "POST /admin HTTP/1.1" 200 1896 - -
localhost - - [31/Mar/2011:11:46:07 +0300] "POST / HTTP/1.1" 200 376
CUPS-Get-PPDs -
10.3.1.193 - - [31/Mar/2011:11:46:07 +0300] "POST /admin HTTP/1.1" 200 5431 - -
10.3.1.193 - - [31/Mar/2011:11:46:16 +0300] "POST /admin HTTP/1.1" 200 1734 - -
localhost - - [31/Mar/2011:11:46:16 +0300] "POST / HTTP/1.1" 200 711
 CUPS-Get-PPDs -
10.3.1.193 - - [31/Mar/2011:11:46:16 +0300] "POST /admin HTTP/1.1" 200 5231 - -
10.3.1.193 - - [31/Mar/2011:11:46:21 +0300] "POST /admin HTTP/1.1" 200 1746 - -
localhost - - [31/Mar/2011:11:46:21 +0300] "POST /admin/ HTTP/1.1" 401 350

CUPS-Add-Modify-Printer successful-ok
10.3.1.193 - - [31/Mar/2011:11:46:21 +0300] "POST /admin HTTP/1.1" 401 1746 - -
10.3.1.193 - - [31/Mar/2011:11:46:21 +0300] "POST /admin HTTP/1.1" 200 1746 - -
10.3.1.193 - root [31/Mar/2011:11:46:21 +0300] "POST /admin HTTP/1.1" 200 1746
 - -
localhost - - [31/Mar/2011:11:46:21 +0300] "POST /admin/ HTTP/1.1" 401 350

CUPS-Add-Modify-Printer successful-ok
localhost - root [31/Mar/2011:11:46:21 +0300] "POST /admin/ HTTP/1.1" 200 350

CUPS-Add-Modify-Printer successful-ok
10.3.1.193 - root [31/Mar/2011:11:46:21 +0300] "POST /admin HTTP/1.1" 200 9126
- -
10.3.1.193 - root [31/Mar/2011:11:46:49 +0300] "POST /admin HTTP/1.1" 200 268 -
 -
localhost - - [31/Mar/2011:11:46:50 +0300] "POST /admin/ HTTP/1.1" 401 9804

CUPS-Add-Modify-Printer successful-ok
localhost - root [31/Mar/2011:11:46:50 +0300] "POST /admin/ HTTP/1.1" 200 9804

CUPS-Add-Modify-Printer successful-ok
10.3.1.193 - root [31/Mar/2011:11:46:49 +0300] "POST /admin HTTP/1.1" 200 2240
 - -
localhost - - [31/Mar/2011:11:47:37 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 349 Create-Job successful-ok
localhost - - [31/Mar/2011:11:47:37 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 1202 Send-Document client-error-document-format-not-supported
localhost - - [31/Mar/2011:11:47:37 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
localhost - - [31/Mar/2011:11:47:52 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 351 Create-Job successful-ok
localhost - - [31/Mar/2011:11:47:52 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 25219 Send-Document client-error-document-format-not-supported
localhost - - [31/Mar/2011:11:47:52 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
localhost - - [31/Mar/2011:11:48:02 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 350 Create-Job successful-ok
localhost - - [31/Mar/2011:11:48:02 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 268098 Send-Document client-error-document-format-not-supported
localhost - - [31/Mar/2011:11:48:02 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
10.3.1.193 - - [31/Mar/2011:11:48:43 +0300]
"GET /admin/log/access_log HTTP/1.1"

200 4367 - -
10.3.1.193 - - [31/Mar/2011:11:49:08 +0300] "GET /admin/log/error_log HTTP/1.1"

200 1528 - -
localhost - - [31/Mar/2011:11:55:20 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 350 Create-Job successful-ok
localhost - - [31/Mar/2011:11:55:20 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 268098 Send-Document client-error-document-format-not-supported
localhost - - [31/Mar/2011:11:55:20 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
localhost - - [31/Mar/2011:13:08:45 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 350 Create-Job successful-ok
localhost - - [31/Mar/2011:13:08:45 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 268098 Send-Document client-error-document-format-not-supported
localhost - - [31/Mar/2011:13:08:45 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
localhost - - [31/Mar/2011:13:08:54 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 351 Create-Job successful-ok
localhost - - [31/Mar/2011:13:08:54 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 25219 Send-Document client-error-document-format-not-supported
localhost - - [31/Mar/2011:13:08:54 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
localhost - - [31/Mar/2011:13:09:02 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 349 Create-Job successful-ok
localhost - - [31/Mar/2011:13:09:02 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 1202 Send-Document client-error-document-format-not-supported
localhost - - [31/Mar/2011:13:09:02 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
localhost - - [31/Mar/2011:13:18:40 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 458 Print-Job client-error-document-format-not-supported
10.3.1.193 - - [31/Mar/2011:14:25:02 +0300]
"POST /admin HTTP/1.1" 200 145 - -
localhost - - [31/Mar/2011:14:25:02 +0300]
"PUT /admin/conf/cupsd.conf HTTP/1.1"

401 0 - -
10.3.1.193 - - [31/Mar/2011:14:25:02 +0300]
"POST /admin HTTP/1.1" 401 145 - -
10.3.1.193 - - [31/Mar/2011:14:25:02 +0300]
"POST /admin HTTP/1.1" 200 145 - -
10.3.1.193 - root [31/Mar/2011:14:25:10 +0300]
"POST /admin HTTP/1.1" 200 145 -
-
localhost - - [31/Mar/2011:14:25:10 +0300]
"PUT /admin/conf/cupsd.conf HTTP/1.1"

401 0 - -
localhost - root [31/Mar/2011:14:25:10 +0300] "PUT /admin/conf/cupsd.conf

HTTP/1.1" 201 3129 - -
10.3.1.193 - root [31/Mar/2011:14:25:10 +0300] "POST /admin HTTP/1.1" 200 2103
 - -
localhost - - [31/Mar/2011:14:26:43 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 349 Create-Job successful-ok
localhost - - [31/Mar/2011:14:26:43 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 1202 Send-Document client-error-document-format-not-supported
localhost - - [31/Mar/2011:14:26:43 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
10.3.1.193 - root [31/Mar/2011:14:40:02 +0300] "GET /admin/log/access_log

HTTP/1.1" 200 7171 - -
10.3.1.193 - root [31/Mar/2011:14:40:30 +0300] "GET /admin/log/error_log

HTTP/1.1" 200 19642 - -
localhost - - [01/Apr/2011:09:36:07 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 349 Create-Job successful-ok
localhost - - [01/Apr/2011:09:36:07 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 1202 Send-Document client-error-document-format-not-supported
localhost - - [01/Apr/2011:09:36:07 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
localhost - - [01/Apr/2011:11:17:39 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 349 Create-Job successful-ok
localhost - - [01/Apr/2011:11:17:39 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 1202 Send-Document client-error-document-format-not-supported
localhost - - [01/Apr/2011:11:17:39 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
localhost - - [01/Apr/2011:11:17:47 +0300] "POST /printers/a23cupslpd HTTP/1.1"
200 351 Create-Job successful-ok
localhost - - [01/Apr/2011:11:17:47 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 25219 Send-Document client-error-document-format-not-supported
localhost - - [01/Apr/2011:11:17:47 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok
localhost - - [01/Apr/2011:11:17:51 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 350 Create-Job successful-ok
localhost - - [01/Apr/2011:11:17:51 +0300] "POST /printers/a23cupslpd HTTP/1.1"

200 268098 Send-Document client-error-document-format-not-supported
localhost - - [01/Apr/2011:11:17:51 +0300] "POST /jobs/ HTTP/1.1" 200 171

Cancel-Job successful-ok

File error_log:

D [01/Apr/2011:09:36:07 +0300] cupsdAcceptClient: 12 from localhost (Domain)
D [01/Apr/2011:09:36:07 +0300] Report: clients=1
D [01/Apr/2011:09:36:07 +0300] Report: jobs=8
D [01/Apr/2011:09:36:07 +0300] Report: jobs-active=0
D [01/Apr/2011:09:36:07 +0300] Report: printers=1
D [01/Apr/2011:09:36:07 +0300] Report: printers-implicit=0
D [01/Apr/2011:09:36:07 +0300] Report: stringpool-string-count=1172
D [01/Apr/2011:09:36:07 +0300] Report: stringpool-alloc-bytes=7032
D [01/Apr/2011:09:36:07 +0300] Report: stringpool-total-bytes=22728
D [01/Apr/2011:09:36:07 +0300] cupsdReadClient: 12 POST / HTTP/1.1
D [01/Apr/2011:09:36:07 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:09:36:07 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:09:36:07 +0300] cupsdReadClient: 12 1.1 Get-Printer-Attributes 1
D [01/Apr/2011:09:36:07 +0300] Get-Printer-Attributes

ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:09:36:07 +0300] Returning IPP successful-ok for

Get-Printer-Attributes (ipp://localhost:631/printers/a23cupslpd)
from localhost
D [01/Apr/2011:09:36:07 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:09:36:07 +0300]
cupsdReadClient: 12 POST /printers/a23cupslpd
HTTP/1.1
D [01/Apr/2011:09:36:07 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:09:36:07 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:09:36:07 +0300] cupsdReadClient: 12 1.1 Create-Job 1
D [01/Apr/2011:09:36:07 +0300]
Create-Job ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:09:36:07 +0300] cupsdMarkDirty(----J-)
D [01/Apr/2011:09:36:07 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:09:36:07 +0300] add_job: requesting-user-name="root"
I [01/Apr/2011:09:36:07 +0300] [Job 9] Adding start banner page "none".
D [01/Apr/2011:09:36:07 +0300] Discarding unused job-created event...
I [01/Apr/2011:09:36:07 +0300] [Job 9] Queued on "a23cupslpd" by "root".
D [01/Apr/2011:09:36:07 +0300] Returning IPP successful-ok for
Create-Job
(ipp://localhost:631/printers/a23cupslpd)
from localhost
D [01/Apr/2011:09:36:07 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:09:36:07 +0300]
cupsdReadClient: 12 POST /printers/a23cupslpd
HTTP/1.1
D [01/Apr/2011:09:36:07 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:09:36:07 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:09:36:07 +0300] cupsdReadClient: 12 1.1 Send-Document 1
D [01/Apr/2011:09:36:07 +0300]
Send-Document ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:09:36:07 +0300] cupsdIsAuthorized: requesting-user-name="root"
D [01/Apr/2011:09:36:07 +0300] [Job 9] Auto-typing file...
D [01/Apr/2011:09:36:07 +0300] [Job 9] Request file type is text/plain.
D [01/Apr/2011:09:36:07 +0300] Send-Document

client-error-document-format-not-supported:
Unsupported format 'text/plain'!
E [01/Apr/2011:09:36:07 +0300] Returning IPP

client-error-document-format-not-supported for Send-Document

(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:09:36:07 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:09:36:07 +0300] cupsdReadClient: 12 POST /jobs/ HTTP/1.1
D [01/Apr/2011:09:36:07 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:09:36:07 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:09:36:07 +0300] cupsdReadClient: 12 1.1 Cancel-Job 1
D [01/Apr/2011:09:36:07 +0300]
Cancel-Job ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:09:36:07 +0300] cupsdIsAuthorized: requesting-user-name="root"
D [01/Apr/2011:09:36:07 +0300] Discarding unused job-completed event...
I [01/Apr/2011:09:36:07 +0300] [Job 9] Job canceled by "root"
D [01/Apr/2011:09:36:07 +0300] cupsdMarkDirty(----J-)
I [01/Apr/2011:09:36:07 +0300] [Job 9] Canceled by "root".
D [01/Apr/2011:09:36:07 +0300] Returning IPP successful-ok for
Cancel-Job
(ipp://localhost:631/printers/a23cupslpd)
from localhost
D [01/Apr/2011:09:36:07 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:09:36:07 +0300] cupsdReadClient: 12 WAITING Closing on EOF
D [01/Apr/2011:09:36:07 +0300] cupsdCloseClient: 12
D [01/Apr/2011:09:36:08 +0300] [Job 9] Unloading...
I [01/Apr/2011:09:36:38 +0300]
Saving job cache file "/var/cache/cups/job.cache"...
D [01/Apr/2011:09:36:38 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:17:39 +0300] cupsdAcceptClient: 12 from localhost (Domain)
D [01/Apr/2011:11:17:39 +0300] Report: clients=1
D [01/Apr/2011:11:17:39 +0300] Report: jobs=9
D [01/Apr/2011:11:17:39 +0300] Report: jobs-active=0
D [01/Apr/2011:11:17:39 +0300] Report: printers=1
D [01/Apr/2011:11:17:39 +0300] Report: printers-implicit=0
D [01/Apr/2011:11:17:39 +0300] Report: stringpool-string-count=1174
D [01/Apr/2011:11:17:39 +0300] Report: stringpool-alloc-bytes=7032
D [01/Apr/2011:11:17:39 +0300] Report: stringpool-total-bytes=22752
D [01/Apr/2011:11:17:39 +0300] cupsdReadClient: 12 POST / HTTP/1.1
D [01/Apr/2011:11:17:39 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:17:39 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:39 +0300] cupsdReadClient: 12 1.1 Get-Printer-Attributes 1
D [01/Apr/2011:11:17:39 +0300] Get-Printer-Attributes
ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:39 +0300] Returning IPP successful-ok for

Get-Printer-Attributes
(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:39 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:17:39 +0300]
cupsdReadClient: 12 POST /printers/a23cupslpd
HTTP/1.1
D [01/Apr/2011:11:17:39 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:17:39 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:39 +0300] cupsdReadClient: 12 1.1 Create-Job 1
D [01/Apr/2011:11:17:39 +0300]
Create-Job ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:39 +0300] cupsdMarkDirty(----J-)
D [01/Apr/2011:11:17:39 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:39 +0300] add_job: requesting-user-name="root"
I [01/Apr/2011:11:17:39 +0300] [Job 10] Adding start banner page "none".
D [01/Apr/2011:11:17:39 +0300] Discarding unused job-created event...
I [01/Apr/2011:11:17:39 +0300] [Job 10] Queued on "a23cupslpd" by "root".
D [01/Apr/2011:11:17:39 +0300] Returning IPP successful-ok for Create-Job

(ipp://localhost:631/printers/a23cupslpd)
from localhost
D [01/Apr/2011:11:17:39 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:39 +0300]
cupsdReadClient: 12 POST /printers/a23cupslpd
HTTP/1.1
D [01/Apr/2011:11:17:39 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:39 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:39 +0300]
cupsdReadClient: 12 1.1 Send-Document 1
D [01/Apr/2011:11:17:39 +0300]
Send-Document ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:39 +0300] cupsdIsAuthorized: requesting-user-name="root"
D [01/Apr/2011:11:17:39 +0300] [Job 10] Auto-typing file...
D [01/Apr/2011:11:17:39 +0300] [Job 10] Request file type is text/plain.
D [01/Apr/2011:11:17:39 +0300] Send-Document

client-error-document-format-not-supported:
Unsupported format 'text/plain'!
E [01/Apr/2011:11:17:39 +0300] Returning IPP

client-error-document-format-not-supported for Send-Document

(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:39 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:39 +0300] cupsdReadClient: 12 POST /jobs/ HTTP/1.1
D [01/Apr/2011:11:17:39 +0300]
 cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:39 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:39 +0300] cupsdReadClient: 12 1.1 Cancel-Job 1
D [01/Apr/2011:11:17:39 +0300]
Cancel-Job ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:39 +0300] cupsdIsAuthorized: requesting-user-name="root"
D [01/Apr/2011:11:17:39 +0300] Discarding unused job-completed event...
I [01/Apr/2011:11:17:39 +0300] [Job 10] Job canceled by "root"
D [01/Apr/2011:11:17:39 +0300] cupsdMarkDirty(----J-)
I [01/Apr/2011:11:17:39 +0300] [Job 10] Canceled by "root".
D [01/Apr/2011:11:17:39 +0300] Returning IPP successful-ok for Cancel-Job
(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:39 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:39 +0300] cupsdReadClient: 12 WAITING Closing on EOF
D [01/Apr/2011:11:17:39 +0300] cupsdCloseClient: 12
D [01/Apr/2011:11:17:40 +0300] [Job 10] Unloading...
D [01/Apr/2011:11:17:47 +0300] cupsdAcceptClient: 12 from localhost (Domain)
D [01/Apr/2011:11:17:47 +0300] cupsdReadClient: 12 POST / HTTP/1.1
D [01/Apr/2011:11:17:47 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:47 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:47 +0300] cupsdReadClient: 12 1.1 Get-Printer-Attributes 1
D [01/Apr/2011:11:17:47 +0300]
Get-Printer-Attributes
ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:47 +0300] Returning IPP successful-ok for
Get-Printer-Attributes (ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:47 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:47 +0300] cupsdReadClient: 12 POST
/printers/a23cupslpd
HTTP/1.1
D [01/Apr/2011:11:17:47 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:47 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:47 +0300] cupsdReadClient: 12 1.1 Create-Job 1
D [01/Apr/2011:11:17:47 +0300]
Create-Job ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:47 +0300] cupsdMarkDirty(----J-)
D [01/Apr/2011:11:17:47 +0300] add_job: requesting-user-name="root"
I [01/Apr/2011:11:17:47 +0300] [Job 11] Adding start banner page "none".
D [01/Apr/2011:11:17:47 +0300] Discarding unused job-created event...
I [01/Apr/2011:11:17:47 +0300] [Job 11] Queued on "a23cupslpd" by "root".
D [01/Apr/2011:11:17:47 +0300] Returning IPP successful-ok for Create-Job
(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:47 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:47 +0300]
cupsdReadClient: 12 POST /printers/a23cupslpd

HTTP/1.1
D [01/Apr/2011:11:17:47 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:47 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:47 +0300] cupsdReadClient: 12 1.1 Send-Document 1
D [01/Apr/2011:11:17:47 +0300]
Send-Document ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:47 +0300] cupsdIsAuthorized: requesting-user-name="root"
D [01/Apr/2011:11:17:47 +0300] [Job 11] Auto-typing file...
D [01/Apr/2011:11:17:47 +0300] [Job 11] Request file type is application/pdf.
D [01/Apr/2011:11:17:47 +0300] Send-Document
client-error-document-format-not-supported:
Unsupported format 'application/pdf'!
E [01/Apr/2011:11:17:47 +0300] Returning IPP
client-error-document-format-not-supported for Send-Document
(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:47 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:47 +0300] cupsdReadClient: 12 POST /jobs/ HTTP/1.1
D [01/Apr/2011:11:17:47 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:47 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:47 +0300] cupsdReadClient: 12 1.1 Cancel-Job 1
D [01/Apr/2011:11:17:47 +0300]
Cancel-Job ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:47 +0300] cupsdIsAuthorized: requesting-user-name="root"
D [01/Apr/2011:11:17:47 +0300] Discarding unused job-completed event...
I [01/Apr/2011:11:17:47 +0300] [Job 11] Job canceled by "root"
D [01/Apr/2011:11:17:47 +0300] cupsdMarkDirty(----J-)
I [01/Apr/2011:11:17:47 +0300] [Job 11] Canceled by "root".
D [01/Apr/2011:11:17:47 +0300] Returning IPP successful-ok for Cancel-Job
(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:47 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:47 +0300] cupsdReadClient: 12 WAITING Closing on EOF
D [01/Apr/2011:11:17:47 +0300] cupsdCloseClient: 12
D [01/Apr/2011:11:17:48 +0300] [Job 11] Unloading...
D [01/Apr/2011:11:17:51 +0300] cupsdAcceptClient: 12 from localhost (Domain)
D [01/Apr/2011:11:17:51 +0300] cupsdReadClient: 12 POST / HTTP/1.1
D [01/Apr/2011:11:17:51 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:51 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:51 +0300] cupsdReadClient: 12 1.1 Get-Printer-Attributes 1
D [01/Apr/2011:11:17:51 +0300] Get-Printer-Attributes
ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:51 +0300]
Returning IPP successful-ok for
Get-Printer-Attributes
(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:51 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:51 +0300] cupsdReadClient: 12 POST /printers/a23cupslpd
HTTP/1.1
D [01/Apr/2011:11:17:51 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:51 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:51 +0300] cupsdReadClient: 12 1.1 Create-Job 1
D [01/Apr/2011:11:17:51 +0300]
Create-Job ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:51 +0300] cupsdMarkDirty(----J-)
D [01/Apr/2011:11:17:51 +0300] add_job: requesting-user-name="root"
I [01/Apr/2011:11:17:51 +0300] [Job 12] Adding start banner page "none".
D [01/Apr/2011:11:17:51 +0300] Discarding unused job-created event...
I [01/Apr/2011:11:17:51 +0300] [Job 12] Queued on "a23cupslpd" by "root".
D [01/Apr/2011:11:17:51 +0300] Returning IPP successful-ok for Create-Job
(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:51 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:51 +0300]
cupsdReadClient: 12 POST /printers/a23cupslpd
HTTP/1.1
D [01/Apr/2011:11:17:51 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:51 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:51 +0300] cupsdReadClient: 12 1.1 Send-Document 1
D [01/Apr/2011:11:17:51 +0300]
Send-Document ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:51 +0300] cupsdIsAuthorized: requesting-user-name="root"
D [01/Apr/2011:11:17:51 +0300] [Job 12] Auto-typing file...
D [01/Apr/2011:11:17:51 +0300] [Job 12]
Request file type is application/postscript.
D [01/Apr/2011:11:17:51 +0300] Send-Document
client-error-document-format-not-supported: Unsupported format
'application/postscript'!
E [01/Apr/2011:11:17:51 +0300] Returning IPP
client-error-document-format-not-supported for Send-Document
(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:51 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:51 +0300] cupsdReadClient: 12 POST /jobs/ HTTP/1.1
D [01/Apr/2011:11:17:51 +0300]
cupsdSetBusyState: Active clients and dirty files
D [01/Apr/2011:11:17:51 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:17:51 +0300] cupsdReadClient: 12 1.1 Cancel-Job 1
D [01/Apr/2011:11:17:51 +0300]
Cancel-Job ipp://localhost:631/printers/a23cupslpd
D [01/Apr/2011:11:17:51 +0300] cupsdIsAuthorized: requesting-user-name="root"
D [01/Apr/2011:11:17:51 +0300] Discarding unused job-completed event...
I [01/Apr/2011:11:17:51 +0300] [Job 12] Job canceled by "root"
D [01/Apr/2011:11:17:51 +0300] cupsdMarkDirty(----J-)
I [01/Apr/2011:11:17:51 +0300] [Job 12] Canceled by "root".
D [01/Apr/2011:11:17:51 +0300] Returning IPP successful-ok for Cancel-Job
(ipp://localhost:631/printers/a23cupslpd) from localhost
D [01/Apr/2011:11:17:51 +0300] cupsdSetBusyState: Dirty files
D [01/Apr/2011:11:17:51 +0300] cupsdReadClient: 12 WAITING Closing on EOF
D [01/Apr/2011:11:17:51 +0300] cupsdCloseClient: 12
D [01/Apr/2011:11:17:52 +0300] [Job 12] Unloading...
I [01/Apr/2011:11:18:10 +0300] Saving job cache file
"/var/cache/cups/job.cache"...
D [01/Apr/2011:11:18:10 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:26:44 +0300] cupsdAcceptClient: 12 from 10.3.1.193:8631
(IPv4)
D [01/Apr/2011:11:26:44 +0300] Report: clients=1
D [01/Apr/2011:11:26:44 +0300] Report: jobs=12
D [01/Apr/2011:11:26:44 +0300] Report: jobs-active=0
D [01/Apr/2011:11:26:44 +0300] Report: printers=1
D [01/Apr/2011:11:26:44 +0300] Report: printers-implicit=0
D [01/Apr/2011:11:26:44 +0300] Report: stringpool-string-count=1180
D [01/Apr/2011:11:26:44 +0300] Report: stringpool-alloc-bytes=7032
D [01/Apr/2011:11:26:44 +0300] Report: stringpool-total-bytes=22824
D [01/Apr/2011:11:26:44 +0300] cupsdReadClient: 12 GET / HTTP/1.1
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:26:44 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:26:44 +0300] cupsdReadClient: 12 GET /cups.css HTTP/1.1
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:26:44 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:26:44 +0300]
cupsdReadClient: 12 GET /images/left.gif HTTP/1.1
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:26:44 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:26:44 +0300] cupsdReadClient: 12 GET /images/right.gif
HTTP/1.1
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:26:44 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:26:44 +0300] cupsdReadClient: 12 GET /images/sel.gif HTTP/1.1
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:26:44 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:26:44 +0300] cupsdAcceptClient: 14 from 10.3.1.193:8631
(IPv4)
D [01/Apr/2011:11:26:44 +0300]
cupsdReadClient: 14 GET /images/unsel.gif HTTP/1.1
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:26:44 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:44 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:26:47 +0300] cupsdReadClient: 12 GET /images/cups-icon.png

HTTP/1.1
D [01/Apr/2011:11:26:47 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:26:47 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:47 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:26:47 +0300] cupsdReadClient: 14 GET /admin HTTP/1.1
D [01/Apr/2011:11:26:47 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:26:47 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:47 +0300]
[CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [01/Apr/2011:11:26:47 +0300] [CGI]
envp[2] = "CUPS_DOCROOT=/usr/share/cups/html"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[3] = "CUPS_FONTPATH=yes"
D [01/Apr/2011:11:26:47 +0300] [CGI]
envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[10] = "SERVER_ADMIN=root at erp2dev"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.6"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[13] = "TZ=America/Buenos_Aires"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[14] = "USER=root"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[15] =

"CUPS_SERVER=/var/run/cups/cups.sock"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[17] = "IPP_PORT=8631"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[18] = "LANG=en_US.UTF8"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[21] = "SERVER_NAME=erp2dev"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[22] = "SERVER_PORT=8631"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[23] = "REMOTE_ADDR=10.3.1.193"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[24] = "REMOTE_HOST=10.3.1.193"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[25] = "SCRIPT_NAME=/admin"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[26] =
"SCRIPT_FILENAME=/usr/share/cups/html/admin"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0

(Windows; U; Windows NT 5.2; en-US; rv:1.9.1.13) Gecko/20100914
Firefox/3.5.13"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[29] =
"HTTP_REFERER=http://10.3.4.35:8631/"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[30] = "REQUEST_METHOD=GET"
D [01/Apr/2011:11:26:47 +0300] [CGI] envp[31] = "QUERY_STRING="
D [01/Apr/2011:11:26:47 +0300] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi

(PID 26095)
I [01/Apr/2011:11:26:47 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi"
(pid=26095)
D [01/Apr/2011:11:26:47 +0300] cupsdSendCommand: 14 file=15
D [01/Apr/2011:11:26:47 +0300] [CGI] admin.cgi started...
D [01/Apr/2011:11:26:47 +0300] cupsdAcceptClient: 16 from localhost (Domain)
D [01/Apr/2011:11:26:47 +0300] [CGI] http=300f8
D [01/Apr/2011:11:26:47 +0300]
[CGI] org.cups.sid cookie not found, initializing!
D [01/Apr/2011:11:26:47 +0300] [CGI] org.cups.sid cookie is
"158d8d096f00a216cc958c305a7ffb96"
D [01/Apr/2011:11:26:47 +0300] [CGI] No form data, showing main menu...
D [01/Apr/2011:11:26:47 +0300] [CGI] /usr/share/cups/drivers/pscript5.dll: No

such file or directory
D [01/Apr/2011:11:26:47 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [01/Apr/2011:11:26:47 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:47 +0300] cupsdReadClient: 16 1.1 Get-Subscriptions 1
D [01/Apr/2011:11:26:47 +0300] Get-Subscriptions ipp://localhost/
D [01/Apr/2011:11:26:47 +0300] Get-Subscriptions client-error-not-found: No

subscriptions found.
D [01/Apr/2011:11:26:47 +0300] Returning IPP client-error-not-found for
Get-Subscriptions (ipp://localhost/) from localhost
D [01/Apr/2011:11:26:47 +0300] Script header: Set-Cookie:
org.cups.sid=158d8d096f00a216cc958c305a7ffb96;; path=/;
D [01/Apr/2011:11:26:47 +0300]
Script header: Content-Type: text/html;charset=utf-8
D [01/Apr/2011:11:26:47 +0300] Script header:
D [01/Apr/2011:11:26:47 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [01/Apr/2011:11:26:47 +0300] cupsdCloseClient: 16
D [01/Apr/2011:11:26:47 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:26:47 +0300] PID 26095 (/usr/lib/cups/cgi-bin/admin.cgi)

exited with no errors.
D [01/Apr/2011:11:26:54 +0300] cupsdReadClient: 12 GET /admin/log/access_log

HTTP/1.1
D [01/Apr/2011:11:26:54 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:26:54 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:54 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:26:57 +0300] cupsdReadClient: 14 GET /favicon.ico HTTP/1.1
D [01/Apr/2011:11:26:57 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:26:57 +0300] cupsdAuthorize: No authentication data provided.
D [01/Apr/2011:11:26:57 +0300] cupsdReadClient: 14 Closing because Keep-Alive
disabled
D [01/Apr/2011:11:26:57 +0300] cupsdCloseClient: 14
D [01/Apr/2011:11:26:57 +0300] cupsdSetBusyState: Not busy
D [01/Apr/2011:11:27:27 +0300] cupsdReadClient: 12 WAITING Closing on EOF
D [01/Apr/2011:11:27:27 +0300] cupsdCloseClient: 12
D [01/Apr/2011:11:28:05 +0300] cupsdAcceptClient: 12 from 10.3.1.193:8631
(IPv4)
D [01/Apr/2011:11:28:05 +0300] Report: clients=1
D [01/Apr/2011:11:28:05 +0300] Report: jobs=12
D [01/Apr/2011:11:28:05 +0300] Report: jobs-active=0
D [01/Apr/2011:11:28:05 +0300] Report: printers=1
D [01/Apr/2011:11:28:05 +0300] Report: printers-implicit=0
D [01/Apr/2011:11:28:05 +0300] Report: stringpool-string-count=1180
D [01/Apr/2011:11:28:05 +0300] Report: stringpool-alloc-bytes=7032
D [01/Apr/2011:11:28:05 +0300] Report: stringpool-total-bytes=22824
D [01/Apr/2011:11:28:05 +0300]
cupsdReadClient: 12 GET /admin/log/error_log HTTP/1.1
D [01/Apr/2011:11:28:05 +0300] cupsdSetBusyState: Active clients
D [01/Apr/2011:11:28:05 +0300] cupsdAuthorize: No authentication data provided.







More information about the cups mailing list