Arch Linux

RufusD

Member

Registered: 2010-03-21

Posts: 86

[SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

Hi there!
I just installed arch off the netinstall CD. Right now I'm struggling to get it recognize my printer. I fired up rekonq at localhost:631 and clicked my way to Add Printer. When prompted, I provided root's name and password. This brings me to the Add Printer page with the "hourglass-like" circle spinning while it's searching for printers. It doesn't find anything.
At connecting the printer to my USB port I get the following in /var/log/messages.log:

Apr  2 12:14:40 Godot kernel: usb 1-3: new high speed USB device using ehci_hcd and address 6
Apr  2 12:14:40 Godot kernel: usb 1-3: configuration #1 chosen from 1 choice
Apr  2 12:14:40 Godot kernel: usblp0: USB Bidirectional printer dev 6 if 1 alt 0 proto 2 vid 0x043D pid 0x0121
Apr  2 12:14:40 Godot kernel: generic-usb 0003:043D:0121.0004: hiddev1,hidraw0: USB HID v1.00 Device [Lexmark International Lexmark X543] on usb-0000:00:02.2-3/input2

As per http://wiki.archlinux.org/index.php/Cups#Kernel_modules I also tried rmmodding usblp. This doesn't change anything in CUPS' web frontend. However, the modules seems to get reloaded:

Apr  2 12:16:45 Godot kernel: usb 1-4: new high speed USB device using ehci_hcd and address 7
Apr  2 12:16:45 Godot kernel: usb 1-4: configuration #1 chosen from 1 choice
Apr  2 12:16:45 Godot kernel: generic-usb 0003:043D:0121.0005: hiddev0,hidraw0: USB HID v1.00 Device [Lexmark International Lexmark X543] on usb-0000:00:02.2-4/input2
Apr  2 12:16:45 Godot kernel: usblp1: USB Bidirectional printer dev 7 if 1 alt 0 proto 2 vid 0x043D pid 0x0121
Apr  2 12:16:45 Godot kernel: usbcore: registered new interface driver usblp

and it also appears again in lsmod. So I tried blacklisting usblp, which again doesn't change anything in CUPS' frontend. However, it complains in /var/log/messages:

Apr  2 12:18:25 Godot kernel: usb 1-4: new high speed USB device using ehci_hcd and address 8
Apr  2 12:18:25 Godot kernel: usb 1-4: configuration #1 chosen from 1 choice
Apr  2 12:18:25 Godot kernel: generic-usb 0003:043D:0121.0006: hiddev0,hidraw0: USB HID v1.00 Device [Lexmark International Lexmark X543] on usb-0000:00:02.2-4/input2
Apr  2 12:18:25 Godot load-modules.sh: Not loading module 'usblp' for alias 'usb:v043Dp0121d0000dc00dsc00dp00ic07isc01ip02' because it is blacklisted
Apr  2 12:18:25 Godot load-modules.sh: 'usb:v043Dp0121d0000dc00dsc00dp00icFFiscFFipFF' is not a valid module or alias name

I set cupsd.conf's logging level to debug, which yields the following in errors_log. At 12:22:30 I pointed rekonq at localhost:631/admin, at 12:22:32 I clicked "Add printer":

D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 16 POST /admin/ HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdSetBusyState: Active clients
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: No authentication data provided.
D [02/Apr/2010:12:22:30 +0200] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[10] = "SERVER_ADMIN=root@Godot"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.3"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[13] = "USER=root"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[16] = "IPP_PORT=631"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[17] = "LANG=de.UTF8"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[18] = "REDIRECT_STATUS=1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[20] = "SERVER_NAME=localhost"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[21] = "SERVER_PORT=631"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[23] = "REMOTE_HOST=localhost"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[24] = "SCRIPT_NAME=/admin/"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin/"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[27] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux; de-DE) AppleWebKit/532.4 (KHTML, like Gecko) rekonq Safari/532.4"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[28] = "HTTP_REFERER=http://localhost:631/admin"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[29] = "REQUEST_METHOD=POST"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[30] = "CONTENT_LENGTH=14"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[31] = "CONTENT_TYPE=application/x-www-form-urlencoded"
D [02/Apr/2010:12:22:30 +0200] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 2520)
I [02/Apr/2010:12:22:30 +0200] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=2520)
D [02/Apr/2010:12:22:30 +0200] cupsdSendCommand: 16 file=14
D [02/Apr/2010:12:22:30 +0200] [CGI] admin.cgi started...
D [02/Apr/2010:12:22:30 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [02/Apr/2010:12:22:30 +0200] [CGI] http=0xb8f4a5d8
D [02/Apr/2010:12:22:30 +0200] [CGI] op="add-printer"...
D [02/Apr/2010:12:22:30 +0200] [CGI] do_am_printer: DEVICE_URI="(null)"
D [02/Apr/2010:12:22:30 +0200] [CGI] Getting list of devices...
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: No authentication data provided.
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 12 1.1 CUPS-Get-Devices 1
D [02/Apr/2010:12:22:30 +0200] CUPS-Get-Devices
D [02/Apr/2010:12:22:30 +0200] cupsdIsAuthorized: username=""
D [02/Apr/2010:12:22:30 +0200] Returning HTTP Nicht berechtigt for CUPS-Get-Devices (no URI) from localhost
D [02/Apr/2010:12:22:30 +0200] cupsdSendHeader: 12 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [02/Apr/2010:12:22:30 +0200] [CGI] cgi_passwd(prompt="Das Kennwort für „daemon" auf „localhost"? ") called!
D [02/Apr/2010:12:22:30 +0200] Script header: Status: 401
D [02/Apr/2010:12:22:30 +0200] cupsdSendHeader: 16 WWW-Authenticate: Basic realm="CUPS"
D [02/Apr/2010:12:22:30 +0200] Script header: 
D [02/Apr/2010:12:22:30 +0200] cupsdWriteClient: 16 Closing because Keep-Alive disabled
D [02/Apr/2010:12:22:30 +0200] cupsdCloseClient: 16
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 12 WAITING Closing on EOF
D [02/Apr/2010:12:22:30 +0200] cupsdCloseClient: 12
D [02/Apr/2010:12:22:30 +0200] cupsdSetBusyState: Not busy
D [02/Apr/2010:12:22:30 +0200] PID 2520 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [02/Apr/2010:12:22:30 +0200] cupsdAcceptClient: 12 from localhost:631 (IPv4)
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 12 POST /admin/ HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdSetBusyState: Active clients
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: Authorized as root using Basic
D [02/Apr/2010:12:22:30 +0200] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[10] = "SERVER_ADMIN=root@Godot"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.3"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[13] = "USER=root"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[16] = "IPP_PORT=631"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[17] = "CUPSD_AUTH_TYPE=Basic"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[18] = "LANG=de.UTF8"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[21] = "SERVER_NAME=localhost"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[22] = "SERVER_PORT=631"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[25] = "SCRIPT_NAME=/admin/"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin/"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[27] = "REMOTE_USER=root"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux; de-DE) AppleWebKit/532.4 (KHTML, like Gecko) rekonq Safari/532.4"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[30] = "HTTP_REFERER=http://localhost:631/admin"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[31] = "REQUEST_METHOD=POST"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[32] = "CONTENT_LENGTH=14"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[33] = "CONTENT_TYPE=application/x-www-form-urlencoded"
D [02/Apr/2010:12:22:30 +0200] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 2521)
I [02/Apr/2010:12:22:30 +0200] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=2521)
D [02/Apr/2010:12:22:30 +0200] cupsdSendCommand: 12 file=16
D [02/Apr/2010:12:22:30 +0200] [CGI] admin.cgi started...
D [02/Apr/2010:12:22:30 +0200] cupsdAcceptClient: 14 from localhost (Domain)
D [02/Apr/2010:12:22:30 +0200] [CGI] http=0xb81f05d8
D [02/Apr/2010:12:22:30 +0200] [CGI] op="add-printer"...
D [02/Apr/2010:12:22:30 +0200] [CGI] do_am_printer: DEVICE_URI="(null)"
D [02/Apr/2010:12:22:30 +0200] [CGI] Getting list of devices...
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 14 POST / HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: No authentication data provided.
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 14 1.1 CUPS-Get-Devices 1
D [02/Apr/2010:12:22:30 +0200] CUPS-Get-Devices
D [02/Apr/2010:12:22:30 +0200] cupsdIsAuthorized: username=""
D [02/Apr/2010:12:22:30 +0200] Returning HTTP Nicht berechtigt for CUPS-Get-Devices (no URI) from localhost
D [02/Apr/2010:12:22:30 +0200] cupsdSendHeader: 14 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 14 WAITING Closing on EOF
D [02/Apr/2010:12:22:30 +0200] cupsdCloseClient: 14
D [02/Apr/2010:12:22:30 +0200] cupsdAcceptClient: 14 from localhost (Domain)
D [02/Apr/2010:12:22:30 +0200] cupsdAcceptClient: 17 from localhost (Domain)
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 14 WAITING Closing on EOF
D [02/Apr/2010:12:22:30 +0200] cupsdCloseClient: 14
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 17 POST / HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: Authorized as root using Local
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 17 1.1 CUPS-Get-Devices 1
D [02/Apr/2010:12:22:30 +0200] CUPS-Get-Devices
D [02/Apr/2010:12:22:30 +0200] cupsdIsAuthorized: username="root"
D [02/Apr/2010:12:22:30 +0200] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-deviced"
D [02/Apr/2010:12:22:30 +0200] [CGI] argv[1] = "1"
D [02/Apr/2010:12:22:30 +0200] [CGI] argv[2] = "0"
D [02/Apr/2010:12:22:30 +0200] [CGI] argv[3] = "30"
D [02/Apr/2010:12:22:30 +0200] [CGI] argv[4] = "2"
D [02/Apr/2010:12:22:30 +0200] [CGI] argv[5] = "requested-attributes=all"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[10] = "SERVER_ADMIN=root@Godot"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.3"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[13] = "USER=root"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[16] = "IPP_PORT=631"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[17] = "CUPSD_AUTH_TYPE=Local"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[18] = "LANG=de.UTF8"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[21] = "SERVER_NAME=localhost"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[22] = "SERVER_PORT=631"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[23] = "REMOTE_ADDR="
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[25] = "SCRIPT_NAME=/"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc/"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[27] = "REMOTE_USER=root"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[29] = "HTTP_USER_AGENT=CUPS/1.4.3"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[30] = "REQUEST_METHOD=POST"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[31] = "CONTENT_LENGTH=88"
D [02/Apr/2010:12:22:30 +0200] [CGI] envp[32] = "CONTENT_TYPE=application/ipp"
D [02/Apr/2010:12:22:30 +0200] [CGI] Started /usr/lib/cups/daemon/cups-deviced (PID 2522)
I [02/Apr/2010:12:22:30 +0200] Started "/usr/lib/cups/daemon/cups-deviced" (pid=2522)
D [02/Apr/2010:12:22:30 +0200] cupsdSendCommand: 17 file=14
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/usb (PID 2523)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/serial (PID 2524)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/ipp (PID 2525)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/smb (PID 2526)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/snmp (PID 2527)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/parallel (PID 2528)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/scsi (PID 2529)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/mdns (PID 2530)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/http (PID 2531)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/lpd (PID 2532)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/socket (PID 2533)
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Started backend /usr/lib/cups/backend/dnssd (PID 2534)
D [02/Apr/2010:12:22:30 +0200] [CGI] list_devices
D [02/Apr/2010:12:22:30 +0200] [CGI] usb_find_busses=3
D [02/Apr/2010:12:22:30 +0200] [CGI] Flushed attributes...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Found device "http"...
D [02/Apr/2010:12:22:30 +0200] Script header: Content-Type: application/ipp
D [02/Apr/2010:12:22:30 +0200] Script header: 
D [02/Apr/2010:12:22:30 +0200] [CGI] Flushed attributes...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Found device "scsi"...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2529 (scsi) exited with no errors.
D [02/Apr/2010:12:22:30 +0200] [CGI] Flushed attributes...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Found device "lpd"...
D [02/Apr/2010:12:22:30 +0200] Script header: MIME-Version: 1.0
D [02/Apr/2010:12:22:30 +0200] Script header: Content-Type: multipart/x-mixed-replace; boundary="CUPS-MULTIPART"
D [02/Apr/2010:12:22:30 +0200] Script header: 
D [02/Apr/2010:12:22:30 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:30 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:30 +0200] [CGI] Flushed attributes...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Found device "ipp"...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2525 (ipp) exited with no errors.
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2530 (mdns) exited with no errors.
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2531 (http) exited with no errors.
D [02/Apr/2010:12:22:30 +0200] [CGI] Flushed attributes...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Found device "socket"...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2532 (lpd) exited with no errors.
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2533 (socket) exited with no errors.
E [02/Apr/2010:12:22:30 +0200] [CGI] Unable to create avahi client: Resource temporarily unavailable
E [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2534 (dnssd) stopped with status 1!
D [02/Apr/2010:12:22:30 +0200] [CGI] Flushed attributes...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Found device "smb"...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2526 (smb) exited with no errors.
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2524 (serial) exited with no errors.
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 13 GET /images/wait.gif HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: No authentication data provided.
E [02/Apr/2010:12:22:30 +0200] Bad request line "" from localhost!
D [02/Apr/2010:12:22:30 +0200] cupsdCloseClient: 13
D [02/Apr/2010:12:22:30 +0200] cupsdAcceptClient: 13 from localhost:631 (IPv4)
D [02/Apr/2010:12:22:30 +0200] [CGI] Flushed attributes...
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] Found device "parallel:/dev/lp0"...
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 13 GET /images/right.gif HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: No authentication data provided.
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2528 (parallel) exited with no errors.
D [02/Apr/2010:12:22:30 +0200] cupsdAcceptClient: 18 from localhost:631 (IPv4)
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 18 GET /cups.css HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: No authentication data provided.
D [02/Apr/2010:12:22:30 +0200] cupsdAcceptClient: 19 from localhost:631 (IPv4)
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 19 GET /images/left.gif HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: No authentication data provided.
E [02/Apr/2010:12:22:30 +0200] Bad request line "" from localhost!
D [02/Apr/2010:12:22:30 +0200] cupsdCloseClient: 13
E [02/Apr/2010:12:22:30 +0200] Bad request line "" from localhost!
D [02/Apr/2010:12:22:30 +0200] cupsdCloseClient: 19
D [02/Apr/2010:12:22:30 +0200] [CGI] usb_find_devices=7
D [02/Apr/2010:12:22:30 +0200] [CGI] Failed to set configuration 1 for 043d:0121
D [02/Apr/2010:12:22:30 +0200] [CGI] Failed to claim interface 1 for 043d:0121: Operation not permitted
D [02/Apr/2010:12:22:30 +0200] [cups-deviced] PID 2523 (usb) exited with no errors.
D [02/Apr/2010:12:22:30 +0200] cupsdAcceptClient: 13 from localhost:631 (IPv4)
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 13 GET /images/sel.gif HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: No authentication data provided.
D [02/Apr/2010:12:22:30 +0200] cupsdAcceptClient: 19 from localhost:631 (IPv4)
D [02/Apr/2010:12:22:30 +0200] cupsdReadClient: 19 GET /images/unsel.gif HTTP/1.1
D [02/Apr/2010:12:22:30 +0200] cupsdAuthorize: No authentication data provided.
D [02/Apr/2010:12:22:32 +0200] [cups-deviced] PID 2527 (snmp) exited with no errors.
D [02/Apr/2010:12:22:32 +0200] PID 2522 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
D [02/Apr/2010:12:22:32 +0200] [CGI] Got device list!
D [02/Apr/2010:12:22:32 +0200] cupsdReadClient: 17 WAITING Closing on EOF
D [02/Apr/2010:12:22:32 +0200] cupsdCloseClient: 17
D [02/Apr/2010:12:22:32 +0200] PID 2521 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [02/Apr/2010:12:22:32 +0200] cupsdWriteClient: 12 Closing because Keep-Alive disabled
D [02/Apr/2010:12:22:32 +0200] cupsdCloseClient: 12
D [02/Apr/2010:12:22:32 +0200] cupsdSetBusyState: Not busy
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"
D [02/Apr/2010:12:22:32 +0200] [CGI] Regular expression "[a-z]+://"

This is the point where I'm at a loss. Any ideas anybody?
Regards,
Rufus D

Last edited by RufusD (2010-04-06 18:56:15)

AndyRTR

Developer

From: Magdeburg/Germany

Registered: 2005-10-07

Posts: 1,641

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

Access to the admin pages may take up tp half an hour creating  the certifications. The log should show you something like "creating cert". And what's the permissions of the printer device?

sironitomas

Member

From: Cordoba, Argentina

Registered: 2009-11-28

Posts: 174

Website

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

I'm have the same kind of of issue, but with an EPSON printer... I don't know how to setup permisions with polkit.

RufusD

Member

Registered: 2010-03-21

Posts: 86

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

@AndyRTR: Are you serious? *Half an hour*? Anyway, I tried it, but to no avail. The log goes like

tail -f cups/error_log
D [04/Apr/2010:20:26:59 +0200] [CGI] Regular expression "[a-z]+://"
D [04/Apr/2010:20:26:59 +0200] [CGI] Regular expression "[a-z]+://"
D [04/Apr/2010:20:26:59 +0200] [CGI] Regular expression "[a-z]+://"
D [04/Apr/2010:20:26:59 +0200] cupsdReadClient: 16 WAITING Closing on EOF
D [04/Apr/2010:20:26:59 +0200] cupsdCloseClient: 16
D [04/Apr/2010:20:26:59 +0200] [CGI] Regular expression "[a-z]+://"
D [04/Apr/2010:20:26:59 +0200] PID 5696 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [04/Apr/2010:20:26:59 +0200] cupsdWriteClient: 15 Closing because Keep-Alive disabled
D [04/Apr/2010:20:26:59 +0200] cupsdCloseClient: 15
D [04/Apr/2010:20:26:59 +0200] cupsdSetBusyState: Not busy
D [04/Apr/2010:20:27:28 +0200] cupsdReadClient: 20 WAITING Closing on EOF
D [04/Apr/2010:20:27:28 +0200] cupsdCloseClient: 20
D [04/Apr/2010:20:27:57 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [04/Apr/2010:20:27:57 +0200] Report: clients=1
D [04/Apr/2010:20:27:57 +0200] Report: jobs=0
D [04/Apr/2010:20:27:57 +0200] Report: jobs-active=0
D [04/Apr/2010:20:27:57 +0200] Report: printers=0
D [04/Apr/2010:20:27:57 +0200] Report: printers-implicit=0
D [04/Apr/2010:20:27:57 +0200] Report: stringpool-string-count=124
D [04/Apr/2010:20:27:57 +0200] Report: stringpool-alloc-bytes=3904
D [04/Apr/2010:20:27:57 +0200] Report: stringpool-total-bytes=2960
D [04/Apr/2010:20:27:57 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [04/Apr/2010:20:27:57 +0200] cupsdSetBusyState: Active clients
D [04/Apr/2010:20:27:57 +0200] cupsdAuthorize: No authentication data provided.
D [04/Apr/2010:20:27:57 +0200] cupsdReadClient: 12 1.1 Get-Notifications 1
D [04/Apr/2010:20:27:57 +0200] Get-Notifications /
D [04/Apr/2010:20:27:57 +0200] cupsdIsAuthorized: requesting-user-name="rufusd"
D [04/Apr/2010:20:27:57 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [04/Apr/2010:20:27:57 +0200] cupsdSetBusyState: Not busy
D [04/Apr/2010:20:27:57 +0200] cupsdReadClient: 12 WAITING Closing on EOF
D [04/Apr/2010:20:27:57 +0200] cupsdCloseClient: 12
D [04/Apr/2010:20:28:57 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [04/Apr/2010:20:28:57 +0200] Report: clients=1
D [04/Apr/2010:20:28:57 +0200] Report: jobs=0
D [04/Apr/2010:20:28:57 +0200] Report: jobs-active=0
D [04/Apr/2010:20:28:57 +0200] Report: printers=0
D [04/Apr/2010:20:28:57 +0200] Report: printers-implicit=0
D [04/Apr/2010:20:28:57 +0200] Report: stringpool-string-count=124
D [04/Apr/2010:20:28:57 +0200] Report: stringpool-alloc-bytes=3904
D [04/Apr/2010:20:28:57 +0200] Report: stringpool-total-bytes=2960
D [04/Apr/2010:20:28:57 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [04/Apr/2010:20:28:57 +0200] cupsdSetBusyState: Active clients
D [04/Apr/2010:20:28:57 +0200] cupsdAuthorize: No authentication data provided.
D [04/Apr/2010:20:28:57 +0200] cupsdReadClient: 12 1.1 Get-Notifications 1
D [04/Apr/2010:20:28:57 +0200] Get-Notifications /
D [04/Apr/2010:20:28:57 +0200] cupsdIsAuthorized: requesting-user-name="rufusd"
D [04/Apr/2010:20:28:57 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [04/Apr/2010:20:28:57 +0200] cupsdSetBusyState: Not busy
D [04/Apr/2010:20:28:57 +0200] cupsdReadClient: 12 WAITING Closing on EOF
D [04/Apr/2010:20:28:57 +0200] cupsdCloseClient: 12
D [04/Apr/2010:20:29:57 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [04/Apr/2010:20:29:57 +0200] Report: clients=1
D [04/Apr/2010:20:29:57 +0200] Report: jobs=0
D [04/Apr/2010:20:29:57 +0200] Report: jobs-active=0
D [04/Apr/2010:20:29:57 +0200] Report: printers=0
D [04/Apr/2010:20:29:57 +0200] Report: printers-implicit=0
D [04/Apr/2010:20:29:57 +0200] Report: stringpool-string-count=124
D [04/Apr/2010:20:29:57 +0200] Report: stringpool-alloc-bytes=3904
D [04/Apr/2010:20:29:57 +0200] Report: stringpool-total-bytes=2960
D [04/Apr/2010:20:29:57 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [04/Apr/2010:20:29:57 +0200] cupsdSetBusyState: Active clients
D [04/Apr/2010:20:29:57 +0200] cupsdAuthorize: No authentication data provided.
D [04/Apr/2010:20:29:57 +0200] cupsdReadClient: 12 1.1 Get-Notifications 1
D [04/Apr/2010:20:29:57 +0200] Get-Notifications /
D [04/Apr/2010:20:29:57 +0200] cupsdIsAuthorized: requesting-user-name="rufusd"
D [04/Apr/2010:20:29:57 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [04/Apr/2010:20:29:57 +0200] cupsdSetBusyState: Not busy
D [04/Apr/2010:20:29:57 +0200] cupsdReadClient: 12 WAITING Closing on EOF
D [04/Apr/2010:20:29:57 +0200] cupsdCloseClient: 12

repeating the last 19 lines every minute. I don't get why it is "requesting-user-name=rufusd", when I log in to localhost:631/admin as root with root's password. Regarding the device you spoke about: when I allow usblp to be loaded, /dev/usblp1 gets created. I assume this is what you are talking about. It's a link to /dev/usb/lp1, the latter of which features permissions of crw-rw---- root:lp. My user is not a member of group lp, I just realize... Is there any advise as to which groups are useful for a single-user desktop PC? Until now, my user is only member of network and <username>

RufusD

Member

Registered: 2010-03-21

Posts: 86

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

adding my user to group "lp" didn't do anything (yes, I logged out of KDE and back in. Actually I even rebooted)

toad

Member

From: if only I knew

Registered: 2008-12-22

Posts: 1,775

Website

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

I don't know, but time to google some of those error messages? There appear to be plenty of hits for you to get your teeth into...

---

never trust a toad...
::Grateful ArchDonor::
::Grateful Wikipedia Donor::

pyther

Member

Registered: 2008-01-21

Posts: 1,395

Website

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

What does ls -l /dev/bus/usb/*/* return when usblp is not loaded?

---

Website - Blog - arch-home
Arch User since March 2005

RufusD

Member

Registered: 2010-03-21

Posts: 86

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

Arghl, stupid me. I had forgotten to install hal-cups-utils. Now everything works fine. usblp is not commented out.
Thanks to everybody for their help!
Rufus

thom_raindog

Member

Registered: 2008-04-29

Posts: 175

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

Well thanks RufusD. That just solved my own problem with a Canon IP4300.. looks like that package REALLY is needed.

corey_s

Member

Registered: 2011-02-09

Posts: 45

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

Arghl, stupid me. I had forgotten to install hal-cups-utils. Now everything works fine. usblp is not commented out.
Thanks to everybody for their help!
Rufus

I'm having the same exact issue as described in the first post.

However, I don't see any package named "hal-cups-utils"...

# pacman -Ss hal-cups-utils

...

How do I get cups to detect my usb printer?

Any advice is very much appreciated - been working on this for a couple hours now, read lots of docs, and have tried everything I can think of.

Thanks!

timosa

Member

From: Finland

Registered: 2011-06-26

Posts: 16

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

However, I don't see any package named "hal-cups-utils"...

# pacman -Ss hal-cups-utils

...

Yaourt is the solution to this problem. See: https://wiki.archlinux.org/index.php/Yaourt

litemotiv

Forum Fellow

Registered: 2008-08-01

Posts: 5,026

Re: [SOLVED] CUPS fails to find my USB-connected Lexmark X543dn

timosa, please don't bump threads without a good reason. There's a very high probability that corey_s either solved his problem long ago, or is not visiting the forums anymore.

Also, Yaourt is not "the solution", the right answer is that hal-cups-utils is in the AUR.

Closing.

---

ᶘ ᵒᴥᵒᶅ