Arch Linux

Please read this before reporting a bug:
https://wiki.archlinux.org/index.php/Reporting_Bug_Guidelines

Do NOT report bugs when a package is just outdated, or it is in Unsupported. Use the 'flag out of date' link on the package page, or the Mailing List.

REPEAT: Do NOT report bugs for outdated packages!
Tasklist

FS#43708 - [cups] cannot add printer - unable to get list of printer drivers

Attached to Project: Arch Linux
Opened by Pascal (psunix) - Thursday, 05 February 2015, 15:21 GMT
Last edited by Andreas Radke (AndyRTR) - Saturday, 27 February 2016, 10:31 GMT
Task Type Bug Report
Category Packages: Extra
Status Closed
Assigned To Andreas Radke (AndyRTR)
Architecture All
Severity High
Priority Normal
Reported Version
Due in Version Undecided
Due Date Undecided
Percent Complete 100%
Votes 9
Private No

Details

Description: when trying to install a new printer, the error message "unable to get list of printer drivers" is shown on the cups webinterface.

see https://bbs.archlinux.org/viewtopic.php?pid=1495064#p1495064 for more details

Additional info:
* cups 2.0.1-1, cups-filters 1.0.61-4, foomatic-db 3:2014 1229-1, foomatic-db-engine 3:2014 1229-1, foomatic-db-nonfree 3:2014 1229-1

Steps to reproduce:

by webinterface

1. access cups webinterface
2. add new printer
3. at second step, error message is shown after a while

by xfce printer tool

1. open printer tool
2. add new printer
3. app freezes after a while
This task depends upon

Closed by  Andreas Radke (AndyRTR)
Saturday, 27 February 2016, 10:31 GMT
Reason for closing:  Fixed
Comment by Dusan Kazik (prescott) - Thursday, 05 February 2015, 19:12 GMT
i have problem with installing canon mg2550.
cups log:

$ tail -f /var/log/cups/error_log
E [05/Feb/2015:19:48:40 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [05/Feb/2015:20:02:55 +0100] [cups-deviced] PID 1098 (kodak1400) stopped with status 1!
E [05/Feb/2015:20:02:55 +0100] [cups-deviced] PID 1097 (selphy) stopped with status 1!
E [05/Feb/2015:20:02:56 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [05/Feb/2015:20:02:56 +0100] [cups-driverd] Bad line from "gutenprint.5.2": "
E [05/Feb/2015:20:04:07 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [05/Feb/2015:20:04:08 +0100] [cups-driverd] Bad line from "gutenprint.5.2": "
E [05/Feb/2015:20:09:42 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [05/Feb/2015:20:10:24 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [05/Feb/2015:20:10:55 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
Comment by Andreas Radke (AndyRTR) - Thursday, 05 February 2015, 19:15 GMT
Please provide some cups debug log!
Comment by Andreas Radke (AndyRTR) - Thursday, 05 February 2015, 19:18 GMT
Ok, that's not the actual error.
Comment by Dusan Kazik (prescott) - Thursday, 05 February 2015, 19:20 GMT
$ tail -f /var/log/cups/error_log
D [05/Feb/2015:20:20:58 +0100] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f5f231fd2f0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [05/Feb/2015:20:20:58 +0100] [Client 3] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [05/Feb/2015:20:20:58 +0100] [Client 3] bytes=0, http_state=0, data_remaining=75
D [05/Feb/2015:20:20:58 +0100] [Client 3] Flushing write buffer.
D [05/Feb/2015:20:20:58 +0100] [Client 3] New state is HTTP_STATE_WAITING
D [05/Feb/2015:20:20:58 +0100] [Client 3] Waiting for request.
D [05/Feb/2015:20:20:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [05/Feb/2015:20:20:58 +0100] [Client 3] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [05/Feb/2015:20:20:58 +0100] [Client 3] Closing connection.
D [05/Feb/2015:20:20:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 4] Accepted from localhost:51148 (IPv6)
D [05/Feb/2015:20:21:13 +0100] [Client 4] Waiting for request.
D [05/Feb/2015:20:21:13 +0100] [Client 4] POST / HTTP/1.1
D [05/Feb/2015:20:21:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 4] Read: status=200
D [05/Feb/2015:20:21:13 +0100] [Client 4] No authentication data provided.
D [05/Feb/2015:20:21:13 +0100] [Client 4] 2.0 CUPS-Get-Devices 1
D [05/Feb/2015:20:21:13 +0100] CUPS-Get-Devices
D [05/Feb/2015:20:21:13 +0100] cupsdIsAuthorized: username=""
D [05/Feb/2015:20:21:13 +0100] [Client 4] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
D [05/Feb/2015:20:21:13 +0100] [Client 4] cupsdSendHeader: code=401, type="text/html", auth_type=0
D [05/Feb/2015:20:21:13 +0100] [Client 4] WWW-Authenticate: Basic realm="CUPS", trc="y"
D [05/Feb/2015:20:21:13 +0100] [Client 5] Accepted from localhost:51149 (IPv6)
D [05/Feb/2015:20:21:13 +0100] [Client 5] Waiting for request.
D [05/Feb/2015:20:21:13 +0100] [Client 6] Accepted from localhost:51150 (IPv6)
D [05/Feb/2015:20:21:13 +0100] [Client 6] Waiting for request.
D [05/Feb/2015:20:21:13 +0100] [Client 5] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [05/Feb/2015:20:21:13 +0100] [Client 5] Closing connection.
D [05/Feb/2015:20:21:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 7] Accepted from localhost:51151 (IPv6)
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for request.
D [05/Feb/2015:20:21:13 +0100] [Client 6] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [05/Feb/2015:20:21:13 +0100] [Client 6] Closing connection.
D [05/Feb/2015:20:21:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 7] POST / HTTP/1.1
D [05/Feb/2015:20:21:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 7] Read: status=200
D [05/Feb/2015:20:21:13 +0100] [Client 7] Authorized as root using Local
D [05/Feb/2015:20:21:13 +0100] [Client 7] 2.0 CUPS-Get-Devices 1
D [05/Feb/2015:20:21:13 +0100] CUPS-Get-Devices
D [05/Feb/2015:20:21:13 +0100] cupsdIsAuthorized: username="root"
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-deviced"
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[1] = "1"
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[2] = "0"
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[3] = "2"
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[4] = "2"
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[5] = "requested-attributes=all exclude-schemes='beh','cups-pdf','bluetooth','dnssd','http','https','ipp','lpd','ncp','parallel','scsi','smb','snmp','socket'"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@dusan.pc"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/2.0.1"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[13] = "USER=root"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[17] = "IPP_PORT=631"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[18] = "AUTH_TYPE=Local"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[19] = "LANG=sk_SK.UTF8"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[22] = "SERVER_NAME=localhost"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[23] = "SERVER_PORT=631"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[24] = "REMOTE_ADDR=[v1.::1]"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[26] = "SCRIPT_NAME=/"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc/"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[28] = "REMOTE_USER=root"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[30] = "HTTP_USER_AGENT=CUPS/2.0.1 (Linux 3.18.5-1-ARCH; x86_64) IPP/2.0"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[31] = "REQUEST_METHOD=POST"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[32] = "CONTENT_LENGTH=244"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[33] = "CONTENT_TYPE=application/ipp"
D [05/Feb/2015:20:21:13 +0100] cupsdAddCert: Adding certificate for PID 1273
D [05/Feb/2015:20:21:13 +0100] [CGI] Started /usr/lib/cups/daemon/cups-deviced (PID 1273)
I [05/Feb/2015:20:21:13 +0100] [Client 7] Started "/usr/lib/cups/daemon/cups-deviced" (pid=1273, file=18)
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/ipps (PID 1274)
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/serial (PID 1275)
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/hpfax (PID 1276)
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/gutenprint52+usb (PID 1277)
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/hp (PID 1278)
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/usb (PID 1279)
D [05/Feb/2015:20:21:13 +0100] [CGI] Multi-Call Dye-sublimation CUPS Backend version 0.48G
D [05/Feb/2015:20:21:13 +0100] [CGI] Copyright 2007-2014 Solomon Peachy
D [05/Feb/2015:20:21:13 +0100] [CGI] This free software comes with ABSOLUTELY NO WARRANTY!
D [05/Feb/2015:20:21:13 +0100] [CGI] Licensed under the GNU GPL. Run with '-G' for more details.
D [05/Feb/2015:20:21:13 +0100] [CGI]
D [05/Feb/2015:20:21:13 +0100] [CGI] CUPS Usage:
D [05/Feb/2015:20:21:13 +0100] [CGI] DEVICE_URI=someuri gutenprint52+usb job user title num-copies options [ filename ]
D [05/Feb/2015:20:21:13 +0100] [CGI]
D [05/Feb/2015:20:21:13 +0100] [CGI] Standalone Usage:
D [05/Feb/2015:20:21:13 +0100] [CGI] gutenprint52+usb
D [05/Feb/2015:20:21:13 +0100] [ -D ] [ -G ]
D [05/Feb/2015:20:21:13 +0100] [ -S serialnum ] [ -B backendname ]
D [05/Feb/2015:20:21:13 +0100] [ -V extra_vid ] [ -P extra_pid ] [ -T extra_type ]
D [05/Feb/2015:20:21:13 +0100] [ backend_specific_args ]
D [05/Feb/2015:20:21:13 +0100] [ -d copies ] [ - | infile ]
D [05/Feb/2015:20:21:13 +0100] [CGI] -B canonselphy # Canon SELPHY CP/ES version 0.82.2G
D [05/Feb/2015:20:21:13 +0100] [CGI] -B kodak6800 # Kodak 6800/6850 version 0.32
D [05/Feb/2015:20:21:13 +0100] [ -c filename ] # Get tone curve
D [05/Feb/2015:20:21:13 +0100] [ -C filename ] # Set tone curve
D [05/Feb/2015:20:21:13 +0100] [CGI] -B kodak605 # Kodak 605 version 0.18
D [05/Feb/2015:20:21:13 +0100] [ -C filename ] # Set tone curve
D [05/Feb/2015:20:21:13 +0100] [ -m ] # Query media
D [05/Feb/2015:20:21:13 +0100] [ -s ] # Query status
D [05/Feb/2015:20:21:13 +0100] [CGI] -B kodak1400 # Kodak 1400/805 version 0.31
D [05/Feb/2015:20:21:13 +0100] [ -C filename ] # Set tone curve
D [05/Feb/2015:20:21:13 +0100] [CGI] -B shinkos2145 # Shinko/Sinfonia CHC-S2145 (S2) version 0.31
D [05/Feb/2015:20:21:13 +0100] [ -b 0|1 ] # Disable/Enable control panel
D [05/Feb/2015:20:21:13 +0100] [ -c filename ] # Get user/NV tone curve
D [05/Feb/2015:20:21:13 +0100] [ -C filename ] # Set user/NV tone curve
D [05/Feb/2015:20:21:13 +0100] [ -e ] # Query error log
D [05/Feb/2015:20:21:13 +0100] [ -f ] # Use fast return mode
D [05/Feb/2015:20:21:13 +0100] [ -F ] # Flash Printer LED
D [05/Feb/2015:20:21:13 +0100] [ -l filename ] # Get current tone curve
D [05/Feb/2015:20:21:13 +0100] [ -L filename ] # Set current tone curve
D [05/Feb/2015:20:21:13 +0100] [ -m ] # Query media
D [05/Feb/2015:20:21:13 +0100] [ -i ] # Query printer info
D [05/Feb/2015:20:21:13 +0100] [ -r ] # Reset user/NV tone curve
D [05/Feb/2015:20:21:13 +0100] [ -R ] # Reset printer to factory defaults
D [05/Feb/2015:20:21:13 +0100] [ -s ] # Query status
D [05/Feb/2015:20:21:13 +0100] [ -u ] # Query user string
D [05/Feb/2015:20:21:13 +0100] [ -U sometext ] # Set user string
D [05/Feb/2015:20:21:13 +0100] [ -X jobid ] # Abort a printjob
D [05/Feb/2015:20:21:13 +0100] [CGI] -B sonyupdr150 # Sony UP-DR150/UP-DR200 version 0.13
D [05/Feb/2015:20:21:13 +0100] [CGI] -B mitsu70x # Mitsubishi CP-D70/D707/K60 version 0.16
D [05/Feb/2015:20:21:13 +0100] [ -s ] # Query status
D [05/Feb/2015:20:21:13 +0100] [CGI] -B dnpds40 # DNP DS40/DS80/DSRX1 version 0.30
D [05/Feb/2015:20:21:13 +0100] [ -i ] # Query printer info
D [05/Feb/2015:20:21:13 +0100] [ -s ] # Query status
D [05/Feb/2015:20:21:13 +0100] [ -n ] # Query counters
D [05/Feb/2015:20:21:13 +0100] [ -N A|B|M ] # Clear counter A/B/M
D [05/Feb/2015:20:21:13 +0100] [ -p num ] # Set counter P
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] PID 1277 (gutenprint52+usb) exited with no errors.
D [05/Feb/2015:20:21:13 +0100] [Client 7] CGI data ready to be sent.
D [05/Feb/2015:20:21:13 +0100] [CGI] Flushed attributes...
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] Found device "ipps"...
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [Client 7] Script header: Content-Type: application/ipp
D [05/Feb/2015:20:21:13 +0100] [Client 7] Script header:
D [05/Feb/2015:20:21:13 +0100] [Client 7] Sending status 200 for CGI.
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdSendHeader: code=200, type="(null)", auth_type=0
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] PID 1274 (ipps) exited with no errors.
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] PID 1275 (serial) exited with no errors.
D [05/Feb/2015:20:21:13 +0100] [CGI] Loading USB quirks from "/usr/share/cups/usb".
D [05/Feb/2015:20:21:13 +0100] [CGI] Loaded 113 quirks.
D [05/Feb/2015:20:21:13 +0100] [CGI] list_devices
D [05/Feb/2015:20:21:13 +0100] [CGI] libusb_get_device_list=14
D [05/Feb/2015:20:21:13 +0100] [Client 7] CGI data ready to be sent.
D [05/Feb/2015:20:21:13 +0100] [CGI] Flushed attributes...
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] Found device "usb://Canon/MG2500%20series?serial=F1953E&interface=1"...
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] PID 1279 (usb) exited with no errors.
D [05/Feb/2015:20:21:13 +0100] [Client 7] CGI data ready to be sent.
D [05/Feb/2015:20:21:13 +0100] [CGI] Flushed attributes...
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] Found device "hp"...
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] PID 1278 (hp) exited with no errors.
D [05/Feb/2015:20:21:13 +0100] [CGI] warning: python-dbus not installed.
D [05/Feb/2015:20:21:13 +0100] [Client 7] CGI data ready to be sent.
D [05/Feb/2015:20:21:13 +0100] [CGI] Flushed attributes...
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] Found device "hpfax"...
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [cups-deviced] PID 1276 (hpfax) exited with no errors.
D [05/Feb/2015:20:21:13 +0100] [Client 7] CGI data ready to be sent.
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [Client 7] CGI data ready to be sent.
D [05/Feb/2015:20:21:13 +0100] PID 1273 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
D [05/Feb/2015:20:21:13 +0100] [Client 7] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1273, file=18
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for CGI data.
D [05/Feb/2015:20:21:13 +0100] [Client 7] Sending 0-length chunk.
D [05/Feb/2015:20:21:13 +0100] [Client 7] Flushing write buffer.
D [05/Feb/2015:20:21:13 +0100] [Client 7] New state is HTTP_STATE_WAITING
D [05/Feb/2015:20:21:13 +0100] [Client 7] Waiting for request.
D [05/Feb/2015:20:21:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 7] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [05/Feb/2015:20:21:13 +0100] [Client 7] Closing connection.
D [05/Feb/2015:20:21:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 8] Accepted from localhost:51152 (IPv6)
D [05/Feb/2015:20:21:13 +0100] [Client 8] Waiting for request.
D [05/Feb/2015:20:21:13 +0100] [Client 8] POST / HTTP/1.1
D [05/Feb/2015:20:21:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 8] Read: status=200
D [05/Feb/2015:20:21:13 +0100] [Client 8] No authentication data provided.
D [05/Feb/2015:20:21:13 +0100] [Client 8] 2.0 CUPS-Get-Printers 2
D [05/Feb/2015:20:21:13 +0100] CUPS-Get-Printers
D [05/Feb/2015:20:21:13 +0100] CUPS-Get-Printers client-error-not-found: No destinations added.
D [05/Feb/2015:20:21:13 +0100] [Client 8] Returning IPP client-error-not-found for CUPS-Get-Printers (no URI) from localhost
D [05/Feb/2015:20:21:13 +0100] [Client 8] Content-Length: 116
D [05/Feb/2015:20:21:13 +0100] [Client 8] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [05/Feb/2015:20:21:13 +0100] [Client 8] con->http=0x7f5f2321b850
D [05/Feb/2015:20:21:13 +0100] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=116, response=0x7f5f231fde60(IPP_STATE_DATA), pipe_pid=0, file=-1
D [05/Feb/2015:20:21:13 +0100] [Client 8] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [05/Feb/2015:20:21:13 +0100] [Client 8] bytes=0, http_state=0, data_remaining=116
D [05/Feb/2015:20:21:13 +0100] [Client 8] Flushing write buffer.
D [05/Feb/2015:20:21:13 +0100] [Client 8] New state is HTTP_STATE_WAITING
D [05/Feb/2015:20:21:13 +0100] [Client 8] Waiting for request.
D [05/Feb/2015:20:21:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 8] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [05/Feb/2015:20:21:13 +0100] [Client 8] Closing connection.
D [05/Feb/2015:20:21:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 9] Accepted from localhost:51153 (IPv6)
D [05/Feb/2015:20:21:13 +0100] [Client 9] Waiting for request.
D [05/Feb/2015:20:21:13 +0100] [Client 9] POST / HTTP/1.1
D [05/Feb/2015:20:21:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [05/Feb/2015:20:21:13 +0100] [Client 9] Read: status=200
D [05/Feb/2015:20:21:13 +0100] [Client 9] No authentication data provided.
D [05/Feb/2015:20:21:13 +0100] [Client 9] Read: status=100
D [05/Feb/2015:20:21:13 +0100] [Client 9] Read: status=100
D [05/Feb/2015:20:21:13 +0100] [Client 9] Read: status=100
D [05/Feb/2015:20:21:13 +0100] [Client 9] 2.0 CUPS-Get-PPDs 1
D [05/Feb/2015:20:21:13 +0100] CUPS-Get-PPDs
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-driverd"
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[1] = "list"
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[2] = "1"
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[3] = "0"
D [05/Feb/2015:20:21:13 +0100] [CGI] argv[4] = "requested-attributes=all"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@dusan.pc"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/2.0.1"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[13] = "USER=root"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[17] = "IPP_PORT=631"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[18] = "LANG=sk_SK.UTF8"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[21] = "SERVER_NAME=localhost"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[22] = "SERVER_PORT=631"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[25] = "SCRIPT_NAME=/"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc/"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[28] = "HTTP_USER_AGENT=CUPS/2.0.1 (Linux 3.18.5-1-ARCH; x86_64) IPP/2.0"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[29] = "REQUEST_METHOD=POST"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[30] = "CONTENT_LENGTH=75"
D [05/Feb/2015:20:21:13 +0100] [CGI] envp[31] = "CONTENT_TYPE=application/ipp"
D [05/Feb/2015:20:21:13 +0100] [CGI] Started /usr/lib/cups/daemon/cups-driverd (PID 1285)
I [05/Feb/2015:20:21:13 +0100] [Client 9] Started "/usr/lib/cups/daemon/cups-driverd" (pid=1285, file=18)
D [05/Feb/2015:20:21:13 +0100] [Client 9] Waiting for CGI data.
I [05/Feb/2015:20:21:13 +0100] [cups-driverd] Read "/var/cache/cups/ppds.dat", 9167 PPDs...
I [05/Feb/2015:20:21:13 +0100] [cups-driverd] Read "/var/cache/cups/ppds.dat", 9167 PPDs...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/C"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Ricoh"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Ricoh/PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Ricoh/PXL"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Sharp"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Infotec"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Infotec/PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Infotec/PXL"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/HP"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/InfoPrint"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/InfoPrint/PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/InfoPrint/PXL"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Others"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-8465PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-8465PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2090PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2090PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3113PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3113PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-3145PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-3145PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2065PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2065PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2110PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2110PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2070PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2070PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC5115PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC5115PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC5160PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC5160PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2100PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2100PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3074PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3074PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3093PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3093PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2050PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2050PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-3155PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-3155PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3073PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3073PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-3165PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-3165PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2055PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2055PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3094PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3094PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2060PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2060PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-8445PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-8445PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2105PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2105PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3114PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-PPC3114PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2045PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oce/Oce-VarioPrint_2045PS/1"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Samsung"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Samsung/PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Samsung/PXL"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Genicom"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/NRG"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/NRG/PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/NRG/PXL"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Lexmark"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Lexmark/PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/KONICA_MINOLTA"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Lanier"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Lanier/PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Lanier/PXL"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Toshiba"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Savin"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Savin/PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Savin/PXL"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Oki"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Dell"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Epson"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Brother"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Gestetner"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Gestetner/PS"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Gestetner/PXL"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Xerox"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Kyocera"...
D [05/Feb/2015:20:21:13 +0100] [CGI] ppdc: Unknown token "<html xmlns:v="urn:schemas-microsoft-com:vml"
D [05/Feb/2015:20:21:13 +0100] [CGI] xmlns:o="urn:schemas-microsoft-com:office:office"
D [05/Feb/2015:20:21:13 +0100] [CGI] xmlns:w="urn:schemas-microsoft-com:office:word"
D [05/Feb/2015:20:21:13 +0100] [CGI] xmlns:st1="urn:schemas-microsoft-com:office:smarttags"
D [05/Feb/2015:20:21:13 +0100] [CGI] xmlns="http://www.w3.org/TR/REC-html40">" seen on line 5 of /usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm.
E [05/Feb/2015:20:21:13 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Kyocera/es"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Kyocera/it"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Kyocera/fr"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Kyocera/en"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Kyocera/pt"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/model/foomatic-db-ppds/Kyocera/de"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/drv"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/cups/drv/hp"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/ppd"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/ppd/cupsfilters"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] Loading "/usr/share/ppd/HP"...
D [05/Feb/2015:20:21:13 +0100] [cups-driverd] ChangedPPD=0
I [05/Feb/2015:20:21:13 +0100] [cups-driverd] No new or changed PPDs...
I [05/Feb/2015:20:21:13 +0100] [cups-driverd] No new or changed PPDs...
I [05/Feb/2015:20:21:28 +0100] Saving subscriptions.conf...
D [05/Feb/2015:20:21:28 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients and dirty files"
Comment by Andreas Radke (AndyRTR) - Friday, 06 February 2015, 06:13 GMT
It would be nice to check this again with latest cups 1.7.x release if it's a regression in cups itself
or something in between how all the filters and foomatic work together. Can you abs rebuild an old 1.7.x release?
https://projects.archlinux.org/svntogit/packages.git/tree/trunk?h=packages/cups&id=04c7a31c0cde97ff437ae04e324b98e58b66938f

I've seen such errors from time to time but they are usually not a problem that affects many users.

A starting good place to ask is also always the cups user mailing list. I'm subscribed to it.
Comment by Dusan Kazik (prescott) - Saturday, 21 February 2015, 07:30 GMT
i have fixed this issue by removing all foomatic packages and reinstalling gutenprint package.
Comment by Michael Saalfeld (MichaelS) - Monday, 23 February 2015, 15:03 GMT
Hi,

if you have hplip installed, this is a known problem:

https://wiki.archlinux.org/index.php/CUPS#HP_Printer
Comment by Maxwell Draven (Ravenman) - Friday, 27 February 2015, 23:37 GMT
Same problem here. I have one Brother MFC-L8850CDW.

Following the Prescott's tip, I have added the printer to CUPS.
Comment by Andreas Radke (AndyRTR) - Saturday, 18 April 2015, 07:42 GMT
Status? Have you asked upstream about your problem?
Comment by Antonio (kokoko3k) - Friday, 08 May 2015, 10:57 GMT
I did not rebuild from abs, but i rolled back to:
cups 1.7.5-1
cups-filters 1.0.66-1
foomatic-db 3:20141229-1
foomatic-db-engine 3:20141229-1
foomatic-db-nonfree 3:20141229-1
libcups 1.7.5-1
lib32-libcups-1.7.5-1

...and the problem persists.

The hanging process is:
/usr/bin/perl /usr/lib/cups/driver/foomatic list

could it be a perl problem?

Comment by Antonio (kokoko3k) - Friday, 08 May 2015, 11:43 GMT
Ok, i don't know how i did it but now it works with all the foomatic-* package installed.
In the intent of bisecting the problem, i got a list of foomatic-db package files,
then i
1* proceeded to blindly remove bunch of files from: /usr/share/foomatic/db/source/driver/
2* after every remove, i tried: /usr/bin/perl /usr/lib/cups/driver/foomatic list
3* if it didn't hanged, i did pacman -S foomatic-db, and back to *1 (removing other files from /usr/share/foomatic/db/source/driver/)
4* after some tries, the command /usr/lib/cups/driver/foomatic list succeded.

..and with my great surprise, it still works with the complete foomatic-db package installed.

My guess is that some "out of package" file was in the same directory scanned by "foomatic list"
Comment by Antonio (kokoko3k) - Friday, 08 May 2015, 18:32 GMT
It seems that gutenprint installs files into /usr/share/foomatic/db/source/driver/
I deleted it while bisecting, and reinstalled only foomatic-db, so gutenprint files remained missing and foomatic list did not hang.

But reinstalling gutenprint made the it hangs again.
So, in the end, the culprit package(s) seems to be gutenprint (5.2.9 and 5.2.10) vs foomatic-db-engine
Comment by Andreas Radke (AndyRTR) - Friday, 08 May 2015, 18:50 GMT
I suggest to raise your issue on the gutenprint mailing list and cc the mail till.kamppeter@gmail.com being the foomatic upstream maintainer.
Comment by Dominik Schrempf (dschrempf) - Sunday, 30 August 2015, 15:16 GMT
  • Field changed: Percent Complete (100% → 0%)
This bug still affects me. I cannot add a Brother HL-2150N connected via USB. Error from cups log is the same:

E [30/Aug/2015:11:45:54 +0200] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [30/Aug/2015:11:47:19 +0200] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [30/Aug/2015:11:47:37 +0200] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [30/Aug/2015:11:49:07 +0200] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [30/Aug/2015:11:50:19 +0200] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [30/Aug/2015:11:51:30 +0200] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
E [30/Aug/2015:11:55:30 +0200] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
Comment by Andreas Radke (AndyRTR) - Sunday, 30 August 2015, 15:31 GMT
See last comment. Not a packaging bug.
Comment by Marc Rechté (mrechte) - Friday, 26 February 2016, 16:00 GMT
  • Field changed: Percent Complete (100% → 0%)
The problem is still present
Comment by Andreas Radke (AndyRTR) - Saturday, 27 February 2016, 10:31 GMT
@Marc Rechté (mrechte) - you may be running into something different. The original bug has been fixed. Feel free to open a new one and provide logs and so on.

Loading...