/usr/lib/cups/backend/dnssd failed (was: How to debug CUPS/dnssd/mdns)
Marc Haber
mh+usenetspam1002 at zugschl.us
Fri Aug 6 03:39:48 PDT 2010
Hi,
I still cannot print to my networked printer. If this is the wrong
forum to ask questions like that, I would appreciate a pointer in the
right direction.
Marc Haber <mh+usenetspam1002 at zugschl.us> wrote:
>I'm having a new CUPS issue when printing to my networked printer.
>
>Setup:
>Debian Unstable with cups 1.4.4-2
>A Lexmark C510n, advertising itself on the network via mdns
>
>Printing via CUPS used to work until some time the week before last. I
>don't remember what I changed back then.
>
>When pringint, I get the error message "/usr/lib/cups/backend/dnssd
>failed", and the CUPS printer goes into Pause. When I tell CUPS to
>resume the printer, it pauses itself again immedately without any
>visible network activity in tshark. Noone tries to resolve the
>printer's name or to print.
>
>When the printer's queue is empty, I can resume the printer (it's idle
>then), and when I try printing something, no network activity is seen
>and the printer is paused again.
>
>How can I debug the dnssd-Backend? How do I test whether the mdns
>announcements go through?
>
>I am on log level debug, and there is no clear error message in the
>error log.
Error log attached:
|D [06/Aug/2010:12:35:59 +0200] cupsdAcceptClient: 12 from localhost:631 (IPv4)
|D [06/Aug/2010:12:35:59 +0200] cupsdReadClient: 12 GET /printers/C510-Sch8 HTTP/1.1
|D [06/Aug/2010:12:35:59 +0200] cupsdSetBusyState: Active clients
|D [06/Aug/2010:12:35:59 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:35:59 +0200] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[10] = "SERVER_ADMIN=root at swivel"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[13] = "USER=root"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[16] = "IPP_PORT=631"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[17] = "LANG=de.UTF8"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[18] = "REDIRECT_STATUS=1"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[20] = "SERVER_NAME=localhost"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[21] = "SERVER_PORT=631"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[23] = "REMOTE_HOST=localhost"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[24] = "SCRIPT_NAME=/printers/C510-Sch8"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/C510-Sch8"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[26] = "PATH_INFO=/C510-Sch8"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.9.1.11) Gecko/20100721 Iceweasel/3.5.11 (like Firefox/3.5.11)"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[29] = "REQUEST_METHOD=GET"
|D [06/Aug/2010:12:35:59 +0200] [CGI] envp[30] = "QUERY_STRING="
|D [06/Aug/2010:12:35:59 +0200] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 8004)
|I [06/Aug/2010:12:35:59 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8004)
|D [06/Aug/2010:12:35:59 +0200] cupsdSendCommand: 12 file=14
|D [06/Aug/2010:12:35:59 +0200] [CGI] org.cups.sid cookie not found, initializing!
|D [06/Aug/2010:12:35:59 +0200] [CGI] org.cups.sid cookie is "87fcfc956bb11ddd50370602a583ba11"
|D [06/Aug/2010:12:35:59 +0200] cupsdAcceptClient: 15 from localhost (Domain)
|D [06/Aug/2010:12:35:59 +0200] cupsdReadClient: 15 POST / HTTP/1.1
|D [06/Aug/2010:12:35:59 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:35:59 +0200] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
|D [06/Aug/2010:12:35:59 +0200] CUPS-Get-Default
|D [06/Aug/2010:12:35:59 +0200] CUPS-Get-Default client-error-not-found: No default printer
|D [06/Aug/2010:12:35:59 +0200] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
|D [06/Aug/2010:12:35:59 +0200] [CGI] show_printer(http=0xb86c78e0, printer="C510-Sch8")
|D [06/Aug/2010:12:35:59 +0200] cupsdReadClient: 15 POST / HTTP/1.1
|D [06/Aug/2010:12:35:59 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:35:59 +0200] cupsdReadClient: 15 1.1 Get-Printer-Attributes 1
|D [06/Aug/2010:12:35:59 +0200] Get-Printer-Attributes ipp://localhost/printers/C510-Sch8
|D [06/Aug/2010:12:35:59 +0200] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/C510-Sch8) from localhost
|D [06/Aug/2010:12:35:59 +0200] Script header: Set-Cookie: org.cups.sid=87fcfc956bb11ddd50370602a583ba11;; path=/;
|D [06/Aug/2010:12:35:59 +0200] Script header: Content-Type: text/html;charset=utf-8
|D [06/Aug/2010:12:35:59 +0200] Script header:
|D [06/Aug/2010:12:35:59 +0200] [CGI] Regular expression ".*Clean.*"
|D [06/Aug/2010:12:35:59 +0200] [CGI] matches[0].rm_so=0
|D [06/Aug/2010:12:35:59 +0200] [CGI] matches[1].rm_so=-1
|D [06/Aug/2010:12:35:59 +0200] [CGI] Regular expression ".*PrintSelfTestPage.*"
|D [06/Aug/2010:12:35:59 +0200] [CGI] matches[0].rm_so=0
|D [06/Aug/2010:12:35:59 +0200] [CGI] matches[1].rm_so=-1
|D [06/Aug/2010:12:35:59 +0200] cupsdReadClient: 15 POST / HTTP/1.1
|D [06/Aug/2010:12:35:59 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:35:59 +0200] cupsdReadClient: 15 1.1 Get-Jobs 1
|D [06/Aug/2010:12:35:59 +0200] Get-Jobs ipp://localhost:631/printers/C510-Sch8
|D [06/Aug/2010:12:35:59 +0200] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/C510-Sch8) from localhost
|D [06/Aug/2010:12:35:59 +0200] cupsdReadClient: 15 WAITING Closing on EOF
|D [06/Aug/2010:12:35:59 +0200] cupsdCloseClient: 15
|D [06/Aug/2010:12:35:59 +0200] PID 8004 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
|D [06/Aug/2010:12:35:59 +0200] cupsdSetBusyState: Not busy
|D [06/Aug/2010:12:36:06 +0200] cupsdReadClient: 12 POST /printers/C510-Sch8 HTTP/1.1
|D [06/Aug/2010:12:36:06 +0200] cupsdSetBusyState: Active clients
|D [06/Aug/2010:12:36:06 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:06 +0200] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[10] = "SERVER_ADMIN=root at swivel"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[13] = "USER=root"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[16] = "IPP_PORT=631"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[17] = "LANG=de.UTF8"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[18] = "REDIRECT_STATUS=1"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[20] = "SERVER_NAME=localhost"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[21] = "SERVER_PORT=631"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[23] = "REMOTE_HOST=localhost"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[24] = "SCRIPT_NAME=/printers/C510-Sch8"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/C510-Sch8"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[26] = "PATH_INFO=/C510-Sch8"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=87fcfc956bb11ddd50370602a583ba11"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.9.1.11) Gecko/20100721 Iceweasel/3.5.11 (like Firefox/3.5.11)"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[30] = "HTTP_REFERER=http://localhost:631/printers/C510-Sch8"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[31] = "REQUEST_METHOD=POST"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[32] = "CONTENT_LENGTH=64"
|D [06/Aug/2010:12:36:06 +0200] [CGI] envp[33] = "CONTENT_TYPE=application/x-www-form-urlencoded"
|D [06/Aug/2010:12:36:06 +0200] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 8008)
|I [06/Aug/2010:12:36:06 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8008)
|D [06/Aug/2010:12:36:06 +0200] cupsdSendCommand: 12 file=15
|D [06/Aug/2010:12:36:06 +0200] [CGI] org.cups.sid cookie is "87fcfc956bb11ddd50370602a583ba11"
|D [06/Aug/2010:12:36:06 +0200] cupsdAcceptClient: 14 from localhost (Domain)
|D [06/Aug/2010:12:36:06 +0200] cupsdReadClient: 14 POST /printers/C510-Sch8 HTTP/1.1
|D [06/Aug/2010:12:36:06 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:06 +0200] cupsdReadClient: 14 1.1 Print-Job 1
|D [06/Aug/2010:12:36:06 +0200] Print-Job ipp://localhost:631/printers/C510-Sch8
|D [06/Aug/2010:12:36:06 +0200] [Job ???] Auto-typing file...
|I [06/Aug/2010:12:36:06 +0200] [Job ???] Request file type is application/vnd.cups-banner.
|D [06/Aug/2010:12:36:06 +0200] cupsdMarkDirty(----J-)
|D [06/Aug/2010:12:36:06 +0200] cupsdSetBusyState: Active clients and dirty files
|D [06/Aug/2010:12:36:06 +0200] Adding default job-sheets values "none,none"...
|I [06/Aug/2010:12:36:06 +0200] [Job 1730] Adding start banner page "none".
|D [06/Aug/2010:12:36:06 +0200] Discarding unused job-created event...
|D [06/Aug/2010:12:36:06 +0200] cupsdMarkDirty(----J-)
|I [06/Aug/2010:12:36:06 +0200] [Job 1730] Adding end banner page "none".
|I [06/Aug/2010:12:36:06 +0200] [Job 1730] File of type application/vnd.cups-banner queued by "anonymous".
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] hold_until=0
|I [06/Aug/2010:12:36:06 +0200] [Job 1730] Queued on "C510-Sch8" by "anonymous".
|D [06/Aug/2010:12:36:06 +0200] cupsdMarkDirty(----J-)
|D [06/Aug/2010:12:36:06 +0200] cupsdSetBusyState: Active clients, printing jobs, and dirty files
|D [06/Aug/2010:12:36:06 +0200] Discarding unused printer-state-changed event...
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] job-sheets=none,none
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] argv[0]="C510-Sch8"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] argv[1]="1730"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] argv[2]="anonymous"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] argv[3]="Test Page"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] argv[4]="1"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] argv[5]="job-uuid=urn:uuid:e29e3223-2152-372e-4f89-e794aea2efd7 job-originating-host-name=localhost"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] argv[6]="/var/spool/cups/d01730-001"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[1]="CUPS_DATADIR=/usr/share/cups"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[6]="CUPS_SERVERROOT=/etc/cups"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[7]="CUPS_STATEDIR=/var/run/cups"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[8]="HOME=/var/spool/cups/tmp"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[10]="SERVER_ADMIN=root at swivel"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[11]="SOFTWARE=CUPS/1.4.4"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[12]="TMPDIR=/var/spool/cups/tmp"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[13]="USER=root"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[15]="CUPS_ENCRYPTION=IfRequested"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[16]="IPP_PORT=631"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[17]="CHARSET=utf-8"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[18]="LANG=de.UTF-8"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[19]="PPD=/etc/cups/ppd/C510-Sch8.ppd"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[20]="RIP_MAX_CACHE=778576k"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[21]="CONTENT_TYPE=application/vnd.cups-banner"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[22]="DEVICE_URI=dnssd://C510-Sch8._pdl-datastream._tcp.local/"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[23]="PRINTER_INFO=C510-Sch8"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[24]="PRINTER_LOCATION="
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[25]="PRINTER=C510-Sch8"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[26]="CUPS_FILETYPE=document"
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] envp[27]="FINAL_CONTENT_TYPE=printer/C510-Sch8"
|I [06/Aug/2010:12:36:06 +0200] [Job 1730] Started filter /usr/lib/cups/filter/bannertops (PID 8009)
|I [06/Aug/2010:12:36:06 +0200] [Job 1730] Started filter /usr/lib/cups/filter/pstopdf (PID 8010)
|I [06/Aug/2010:12:36:06 +0200] [Job 1730] Started filter /usr/lib/cups/filter/pdftopdf (PID 8011)
|I [06/Aug/2010:12:36:06 +0200] [Job 1730] Started filter /usr/lib/cups/filter/foomatic-rip (PID 8012)
|I [06/Aug/2010:12:36:06 +0200] [Job 1730] Started backend /usr/lib/cups/backend/dnssd (PID 8013)
|D [06/Aug/2010:12:36:06 +0200] Discarding unused job-state-changed event...
|D [06/Aug/2010:12:36:06 +0200] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/C510-Sch8) from localhost
|D [06/Aug/2010:12:36:06 +0200] cupsdReadClient: 14 WAITING Closing on EOF
|D [06/Aug/2010:12:36:06 +0200] cupsdCloseClient: 14
|D [06/Aug/2010:12:36:06 +0200] PID 8008 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
|D [06/Aug/2010:12:36:06 +0200] Script header: Content-Type: text/html;charset=utf-8
|D [06/Aug/2010:12:36:06 +0200] Script header:
|D [06/Aug/2010:12:36:06 +0200] cupsdSetBusyState: Printing jobs and dirty files
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] pstopdf 5 args: 1730 anonymous Test Page 1 job-uuid=urn:uuid:e29e3223-2152-372e-4f89-e794aea2efd7 job-originating-host-name=localhost
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Resolving "C510-Sch8._pdl-datastream._tcp.local"...
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] STATE: +connecting-to-device
|D [06/Aug/2010:12:36:06 +0200] Discarding unused printer-state-changed event...
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] load_banner(filename="/var/spool/cups/d01730-001")
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Resolving "C510-Sch8", regtype="_pdl-datastream._tcp", domain="local."...
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Page = 595x842; 18,36 to 577,806
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] PPD: /etc/cups/ppd/C510-Sch8.ppd
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Unable to resolve URI!
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] STATE: -connecting-to-device
|D [06/Aug/2010:12:36:06 +0200] Discarding unused printer-state-changed event...
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Drucker konnte nicht gefunden werden!
|D [06/Aug/2010:12:36:06 +0200] PID 8013 (/usr/lib/cups/backend/dnssd) stopped with status 1!
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Getting input from file
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] foomatic-rip version 4.0.1.208 running...
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Parsing PPD file ...
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Added option PageSize
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Added option ImageableArea
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Added option PaperDimension
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Added option Resolution
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Added option InputSlot
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Added option Duplex
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Added option Font
|D [06/Aug/2010:12:36:06 +0200] [Job 1730]
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Parameter Summary
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] -----------------
|D [06/Aug/2010:12:36:06 +0200] [Job 1730]
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Spooler: cups
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Printer: C510-Sch8
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Shell: /bin/bash
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] PPD file: /etc/cups/ppd/C510-Sch8.ppd
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] ATTR file:
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Printer model: Lexmark C510 Foomatic/Postscript
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Job title: Test Page
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] File(s) to be printed:
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] <STDIN>
|D [06/Aug/2010:12:36:06 +0200] [Job 1730]
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Printing system options:
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Pondering option 'job-uuid=urn:uuid:e29e3223-2152-372e-4f89-e794aea2efd7'
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Unknown option job-uuid=urn:uuid:e29e3223-2152-372e-4f89-e794aea2efd7.
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Pondering option 'job-originating-host-name=localhost'
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Unknown option job-originating-host-name=localhost.
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Options from the PPD file:
|D [06/Aug/2010:12:36:06 +0200] [Job 1730]
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] ================================================
|D [06/Aug/2010:12:36:06 +0200] [Job 1730]
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] File: <STDIN>
|D [06/Aug/2010:12:36:06 +0200] [Job 1730]
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] ================================================
|D [06/Aug/2010:12:36:06 +0200] [Job 1730]
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] PNG image: 128x128x8, color_type=6 (RGB+ALPHA)
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] PNG image: 192x128x8, color_type=2 (RGB)
|D [06/Aug/2010:12:36:06 +0200] PID 8009 (/usr/lib/cups/filter/bannertops) exited with no errors.
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Resolution: 1200x1200
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Page size: A4
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Width: 595, height: 842, absolute margins: 18, 36, 577, 806
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Relative margins: 18, 36, 18, 36
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] PPD options: -r1200 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] PostScript to be injected:
|D [06/Aug/2010:12:36:06 +0200] [Job 1730] Running cat | /usr/bin/gs -q -dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode -dPDFSETTINGS=/printer -dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -r1200 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sOutputFile=- -c .setpdfwrite -f -
|D [06/Aug/2010:12:36:07 +0200] PID 8010 (/usr/lib/cups/filter/pstopdf) exited with no errors.
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Filetype: PDF
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Driver does not understand PDF input, converting to PostScript
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Starting process "pdf-to-ps" (generation 1)
|D [06/Aug/2010:12:36:07 +0200] Report: clients=1
|D [06/Aug/2010:12:36:07 +0200] Report: jobs=334
|D [06/Aug/2010:12:36:07 +0200] Report: jobs-active=2
|D [06/Aug/2010:12:36:07 +0200] Report: printers=4
|D [06/Aug/2010:12:36:07 +0200] Report: printers-implicit=0
|D [06/Aug/2010:12:36:07 +0200] Report: stringpool-string-count=23842
|D [06/Aug/2010:12:36:07 +0200] Report: stringpool-alloc-bytes=14920
|D [06/Aug/2010:12:36:07 +0200] Report: stringpool-total-bytes=440584
|D [06/Aug/2010:12:36:07 +0200] PID 8011 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Filetype: PostScript
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Reading PostScript input ...
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] --> This document is DSC-conforming!
|D [06/Aug/2010:12:36:07 +0200] [Job 1730]
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] -----------
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Found: %%BeginProlog
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Inserting option code into "Prolog" section.
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Found: %%EndProlog
|D [06/Aug/2010:12:36:07 +0200] [Job 1730]
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] -----------
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] New page: %%Page: 1 1
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] "Setup" section is missing, inserting it.
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Inserting PostScript code for CUPS' page accounting
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Inserting option code into "Setup" section.
|D [06/Aug/2010:12:36:07 +0200] [Job 1730]
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Found: %%BeginPageSetup
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Inserting option code into "PageSetup" section.
|D [06/Aug/2010:12:36:07 +0200] [Job 1730] Flushing FIFO.
|D [06/Aug/2010:12:36:08 +0200] [Job 1730]
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] Starting renderer with command: "cat"
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] Starting process "kid3" (generation 1)
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] Starting process "kid4" (generation 2)
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] Starting process "renderer" (generation 2)
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] JCL: %-12345X at PJL
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] <job data>
|D [06/Aug/2010:12:36:08 +0200] [Job 1730]
|D [06/Aug/2010:12:36:08 +0200] [Job 1730]
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] Closing renderer
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] renderer exited with status 0
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] kid4 exited with status 0
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] kid3 finished
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] kid3 exited with status 0
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] pdf-to-ps exited with status 0
|D [06/Aug/2010:12:36:08 +0200] [Job 1730]
|D [06/Aug/2010:12:36:08 +0200] [Job 1730] Closing foomatic-rip.
|D [06/Aug/2010:12:36:08 +0200] PID 8012 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
|I [06/Aug/2010:12:36:08 +0200] [Job 1730] Backend returned status 1 (failed)
|D [06/Aug/2010:12:36:08 +0200] Discarding unused job-state-changed event...
|I [06/Aug/2010:12:36:08 +0200] [Job 1730] Printer stopped due to backend errors; please consult the error_log file for details.
|D [06/Aug/2010:12:36:08 +0200] cupsdMarkDirty(----J-)
|D [06/Aug/2010:12:36:08 +0200] Discarding unused printer-stopped event...
|D [06/Aug/2010:12:36:08 +0200] cupsdMarkDirty(P-----)
|D [06/Aug/2010:12:36:08 +0200] cupsdRegisterPrinter(p=0xb806eb80(C510-Sch8))
|D [06/Aug/2010:12:36:08 +0200] cupsdMarkDirty(P-----)
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 12 GET /printers/C510-Sch8 HTTP/1.1
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Active clients and dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:08 +0200] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[10] = "SERVER_ADMIN=root at swivel"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[13] = "USER=root"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[16] = "IPP_PORT=631"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[17] = "LANG=de.UTF8"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[18] = "REDIRECT_STATUS=1"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[20] = "SERVER_NAME=localhost"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[21] = "SERVER_PORT=631"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[23] = "REMOTE_HOST=localhost"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[24] = "SCRIPT_NAME=/printers/C510-Sch8"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/C510-Sch8"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[26] = "PATH_INFO=/C510-Sch8"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=87fcfc956bb11ddd50370602a583ba11"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.9.1.11) Gecko/20100721 Iceweasel/3.5.11 (like Firefox/3.5.11)"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[30] = "REQUEST_METHOD=GET"
|D [06/Aug/2010:12:36:08 +0200] [CGI] envp[31] = "QUERY_STRING="
|D [06/Aug/2010:12:36:08 +0200] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 8049)
|I [06/Aug/2010:12:36:08 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8049)
|D [06/Aug/2010:12:36:08 +0200] cupsdSendCommand: 12 file=14
|D [06/Aug/2010:12:36:08 +0200] [CGI] org.cups.sid cookie is "87fcfc956bb11ddd50370602a583ba11"
|D [06/Aug/2010:12:36:08 +0200] cupsdAcceptClient: 15 from localhost (Domain)
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 15 POST / HTTP/1.1
|D [06/Aug/2010:12:36:08 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
|D [06/Aug/2010:12:36:08 +0200] CUPS-Get-Default
|D [06/Aug/2010:12:36:08 +0200] CUPS-Get-Default client-error-not-found: No default printer
|D [06/Aug/2010:12:36:08 +0200] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
|D [06/Aug/2010:12:36:08 +0200] [CGI] show_printer(http=0xb7a658e0, printer="C510-Sch8")
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 15 POST / HTTP/1.1
|D [06/Aug/2010:12:36:08 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 15 1.1 Get-Printer-Attributes 1
|D [06/Aug/2010:12:36:08 +0200] Get-Printer-Attributes ipp://localhost/printers/C510-Sch8
|D [06/Aug/2010:12:36:08 +0200] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/C510-Sch8) from localhost
|D [06/Aug/2010:12:36:08 +0200] Script header: Content-Type: text/html;charset=utf-8
|D [06/Aug/2010:12:36:08 +0200] Script header:
|D [06/Aug/2010:12:36:08 +0200] [CGI] Regular expression ".*Clean.*"
|D [06/Aug/2010:12:36:08 +0200] [CGI] matches[0].rm_so=0
|D [06/Aug/2010:12:36:08 +0200] [CGI] matches[1].rm_so=-1
|D [06/Aug/2010:12:36:08 +0200] [CGI] Regular expression ".*PrintSelfTestPage.*"
|D [06/Aug/2010:12:36:08 +0200] [CGI] matches[0].rm_so=0
|D [06/Aug/2010:12:36:08 +0200] [CGI] matches[1].rm_so=-1
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 15 POST / HTTP/1.1
|D [06/Aug/2010:12:36:08 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 15 1.1 Get-Jobs 1
|D [06/Aug/2010:12:36:08 +0200] Get-Jobs ipp://localhost:631/printers/C510-Sch8
|D [06/Aug/2010:12:36:08 +0200] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/C510-Sch8) from localhost
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 15 WAITING Closing on EOF
|D [06/Aug/2010:12:36:08 +0200] cupsdCloseClient: 15
|D [06/Aug/2010:12:36:08 +0200] PID 8049 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 12 GET /cups.css HTTP/1.1
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Active clients and dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 12 GET /images/left.gif HTTP/1.1
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Active clients and dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdAcceptClient: 14 from localhost:631 (IPv4)
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 14 GET /images/right.gif HTTP/1.1
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Active clients and dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 12 GET /images/unsel.gif HTTP/1.1
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Active clients and dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdReadClient: 14 GET /images/sel.gif HTTP/1.1
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Active clients and dirty files
|D [06/Aug/2010:12:36:08 +0200] cupsdAuthorize: No authentication data provided.
|D [06/Aug/2010:12:36:08 +0200] cupsdSetBusyState: Dirty files
|D [06/Aug/2010:12:36:13 +0200] cupsdReadClient: 14 WAITING Closing on EOF
|D [06/Aug/2010:12:36:13 +0200] cupsdCloseClient: 14
|D [06/Aug/2010:12:36:13 +0200] cupsdReadClient: 12 WAITING Closing on EOF
|D [06/Aug/2010:12:36:13 +0200] cupsdCloseClient: 12
--
-------------------------------------- !! No courtesy copies, please !! -----
Marc Haber | " Questions are the | Mailadresse im Header
Mannheim, Germany | Beginning of Wisdom " | http://www.zugschlus.de/
Nordisch by Nature | Lt. Worf, TNG "Rightful Heir" | Fon: *49 621 72739834
More information about the cups
mailing list