LINUX.ORG.RU

Проблемы с cups в debian etch


0

0

Поставил себе debian etch от августа 2006. Все вроде работает, как и в sarge и даже лучше, но только не cups. Даже принтер нельзя настроить. Вот что выдает KDE Printing Tool:

Не удаётся получить список принтеров. Сообщение об ошибке от менеджера: Не удалось подключиться к серверу CUPS. Убедитесь, что сервер CUPS правильно установлен и запущен. Ошибка: узел не найден.

Запускал его из консоли, но других сообщений не обнаружил. Вот вывод /var/log/cups/error_log:

E [15/Nov/2006:06:25:40 +0300] Unable to bind socket for address 127.0.0.1:631 - Cannot assign requested address.
E [19/Nov/2006:11:27:34 +0300] Unable to bind socket for address 127.0.0.1:631 - Cannot assign requested address.
E [19/Nov/2006:11:28:00 +0300] Unable to bind socket for address 127.0.0.1:631 - Cannot assign requested address.
E [19/Nov/2006:11:28:00 +0300] Unable to bind socket for address 127.0.0.1:631 - Cannot assign requested address.
I [19/Nov/2006:15:22:53 +0300] Loaded configuration file "/etc/cups/cupsd.conf"
N [19/Nov/2006:15:22:53 +0300] Group and SystemGroup cannot use the same groups!
I [19/Nov/2006:15:22:53 +0300] Resetting Group to "root"...
W [19/Nov/2006:15:22:53 +0300] Repairing ownership of "/var/cache/cups"
W [19/Nov/2006:15:22:53 +0300] Repairing ownership of "/var/run/cups"
W [19/Nov/2006:15:22:53 +0300] Repairing ownership of "/etc/cups"
W [19/Nov/2006:15:22:53 +0300] Repairing ownership of "/etc/cups/ppd"
W [19/Nov/2006:15:22:53 +0300] Repairing ownership of "/etc/cups/ssl"
W [19/Nov/2006:15:22:53 +0300] Repairing ownership of "/etc/cups/printers.conf"
W [19/Nov/2006:15:22:53 +0300] Repairing ownership of "/var/spool/cups"
W [19/Nov/2006:15:22:53 +0300] Repairing ownership of "/var/spool/cups/tmp"
I [19/Nov/2006:15:22:53 +0300] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [19/Nov/2006:15:22:53 +0300] Configured for up to 100 clients.
I [19/Nov/2006:15:22:53 +0300] Allowing up to 100 client connections per host.
I [19/Nov/2006:15:22:53 +0300] Using policy "default" as the default!
I [19/Nov/2006:15:22:53 +0300] Partial reload complete.
I [19/Nov/2006:15:22:53 +0300] Listening to 127.0.0.1:631 on fd 2...
I [19/Nov/2006:15:22:53 +0300] Listening to /var/run/cups/cups.sock on fd 3...
I [19/Nov/2006:15:24:15 +0300] Scheduler shutting down normally.
I [19/Nov/2006:15:24:15 +0300] Saving remote.cache...
I [19/Nov/2006:15:24:15 +0300] Saving job cache file "/var/cache/cups/job.cache"...
I [19/Nov/2006:15:25:19 +0300] Loaded configuration file "/etc/cups/cupsd.conf"
N [19/Nov/2006:15:25:19 +0300] Group and SystemGroup cannot use the same groups!
I [19/Nov/2006:15:25:19 +0300] Resetting Group to "root"...
W [19/Nov/2006:15:25:19 +0300] Repairing ownership of "/etc/cups"
W [19/Nov/2006:15:25:19 +0300] Repairing ownership of "/etc/cups/ppd"
W [19/Nov/2006:15:25:19 +0300] Repairing ownership of "/etc/cups/printers.conf"
I [19/Nov/2006:15:25:19 +0300] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [19/Nov/2006:15:25:19 +0300] Configured for up to 100 clients.
I [19/Nov/2006:15:25:19 +0300] Allowing up to 100 client connections per host.
I [19/Nov/2006:15:25:19 +0300] Using policy "default" as the default!
I [19/Nov/2006:15:25:19 +0300] Full reload is required.
I [19/Nov/2006:15:25:19 +0300] Loaded MIME database from '/etc/cups': 34 types, 38 filters...
I [19/Nov/2006:15:25:19 +0300] Loading job cache file "/var/cache/cups/job.cache"...
I [19/Nov/2006:15:25:19 +0300] Full reload complete.
I [19/Nov/2006:15:25:19 +0300] Listening to 127.0.0.1:631 on fd 2...
I [19/Nov/2006:15:25:19 +0300] Listening to /var/run/cups/cups.sock on fd 3...

А вот access_log:

localhost - - [19/Nov/2006:15:06:19 +0300] "POST / HTTP/1.1" 200 1256 CUPS-Get-Devices -
localhost - - [19/Nov/2006:15:06:22 +0300] "POST / HTTP/1.1" 200 1256 CUPS-Get-Devices -
localhost - - [19/Nov/2006:15:06:29 +0300] "POST / HTTP/1.1" 200 1197 CUPS-Get-Devices -
localhost - - [19/Nov/2006:15:09:37 +0300] "POST / HTTP/1.1" 200 1256 CUPS-Get-Devices -
localhost - - [19/Nov/2006:15:09:40 +0300] "POST / HTTP/1.1" 200 1256 CUPS-Get-Devices -
localhost - - [19/Nov/2006:15:09:45 +0300] "POST / HTTP/1.1" 200 1197 CUPS-Get-Devices -
localhost - - [19/Nov/2006:15:12:00 +0300] "POST /admin/ HTTP/1.1" 200 363 CUPS-Add-Modify-Printer client-error-bad-request
localhost - - [19/Nov/2006:15:21:08 +0300] "GET /admin/conf/cupsd.conf HTTP/1.1" 401 0 - -
localhost - root [19/Nov/2006:15:21:19 +0300] "GET /admin/conf/cupsd.conf HTTP/1.1" 200 2253 - -
localhost - - [19/Nov/2006:15:21:19 +0300] "POST /classes/ HTTP/1.1" 200 75 CUPS-Get-Classes client-error-not-found
localhost - - [19/Nov/2006:15:22:52 +0300] "POST /classes/ HTTP/1.1" 200 75 CUPS-Get-Classes client-error-not-found
localhost - root [19/Nov/2006:15:22:53 +0300] "PUT /admin/conf/cupsd.conf HTTP/1.1" 201 20041 - -

Проверял - loopback интерфейс присутствует. Обновлял cupsys до текущего (1.2.5, кажется) - не помогает.

★★
Ответ на: комментарий от fugu

Пытался, но 631-й порт не находится. Сорри, забыл написать в предыдущем сообщении.

KS ★★
() автор топика

Судя по первым сообщениям об ошибке, 631-ый порт был кем-то занят, возможно, был запущен ещё 1 экземпляр cupsd.

Evil_And ★★
()
Ответ на: комментарий от Evil_And

А как можно узнать, кто занимает порт? Сканером портов (забыл, как эта прога называется)? Сомневаюсь, чтобы инициализационые скрипты 2 экземпляра cupsd запускали. Да и ps этого не подтверждает.

KS ★★
() автор топика
Ответ на: комментарий от TarANtuL

>ifconfig в студию - если там нет lo, то туда и копать.

Вот вывод ifconfig -a :

eth0 Link encap:Ethernet HWaddr 00:C0:DF:03:DA:D7
BROADCAST MULTICAST MTU:1500 Metric:1
RX packets:0 errors:0 dropped:0 overruns:0 frame:0
TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:0 (0.0 b) TX bytes:0 (0.0 b)
Interrupt:5 Base address:0xec00

lo Link encap:Local Loopback
LOOPBACK MTU:16436 Metric:1
RX packets:0 errors:0 dropped:0 overruns:0 frame:0
TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:0 (0.0 b) TX bytes:0 (0.0 b)

ppp0 Link encap:Point-to-Point Protocol
inet addr:212.129.107.251 P-t-P:212.129.107.1 Mask:255.255.255.255
UP POINTOPOINT RUNNING NOARP MULTICAST MTU:1500 Metric:1
RX packets:456 errors:3 dropped:0 overruns:0 frame:0
TX packets:458 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:3
RX bytes:249622 (243.7 KiB) TX bytes:47173 (46.0 KiB)

sit0 Link encap:IPv6-in-IPv4
NOARP MTU:1480 Metric:1
RX packets:0 errors:0 dropped:0 overruns:0 frame:0
TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:0 (0.0 b) TX bytes:0 (0.0 b)

KS ★★
() автор топика
Ответ на: комментарий от sdio

Вывод `netstat -ltnp | grep 631`:


tcp 0 0 127.0.0.1:631 0.0.0.0:* LISTEN 3986/cupsd


ps -A | grep cups :


3986 ? 00:00:00 cupsd


Вроде ничего странного, но не работает...

KS ★★
() автор топика
Ответ на: комментарий от KS

А где inet addres?

lo Link encap:Local Loopback
inet addr:127.0.0.1 Mask:255.0.0.0
inet6 addr: ::1/128 Scope:Host
UP LOOPBACK RUNNING MTU:16436 Metric:1
RX packets:18 errors:0 dropped:0 overruns:0 frame:0
TX packets:18 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:900 (900.0 b) TX bytes:900 (900.0 b)

для запуска в /etc/network/interfaces

auto lo
iface lo inet loopback

TarANtuL
()
Ответ на: комментарий от TarANtuL

Удалось подключиться через localhost и принтер. Только вот KDE-шные проги упорно пишут те же ошибки, что и раньше (не найти список принтеров, убедитесь, что сервер cups запущен и правильно настроен). Да и в localhost:631 при попытке редактировать конфигурационный файл выдается No such file or directory.

Какой файл не находится?

KS ★★
() автор топика
Ответ на: комментарий от TarANtuL

Еще интересно, что в качестве сервера записан /var/run/cups/cups.sock с портом 631.

Я проверил: такого файла нет.

KS ★★
() автор топика
Ответ на: комментарий от KS

проверь, есть ли в /etc/cups/cupsd.conf строчка

Listen localhost:631 или Listen 127.0.0.1:631

И попробуй закомментировать Listen /var/run/cups/cups.sock

Evil_And ★★
()
Ответ на: комментарий от KS

В KDE Printing Tool установил сервер localhost - теперь ошибок при выводе принтеров нет. Правда, печать тоже не идет. Почему-то владелец всех заданий становится root, задание не выполняется и принтер останавливается (в системе, а реальный вообще не реагирует на задание).

Посмотрел права пользователей и обнаружил, что они не входят в группу lp - исправил, но это не изменило ситуацию. Вот вывод errors_log последней страницы:

d [25/Nov/2006:11:10:00 +0300] cupsdAcceptClient(lis=0x80d4a78) 0 Clients = 2
D [25/Nov/2006:11:10:00 +0300] cupsdAcceptClient: 10 from localhost:631 (IPv4)
d [25/Nov/2006:11:10:00 +0300] cupsdAcceptClient: 10 connected to server on localhost:631
d [25/Nov/2006:11:10:00 +0300] cupsdAcceptClient: Adding fd 10 to InputSet...
d [25/Nov/2006:11:10:00 +0300] cupsdReadClient: 10, used=0, file=-1 state=0
D [25/Nov/2006:11:10:00 +0300] cupsdReadClient: 10 POST /printers/ HTTP/1.1
d [25/Nov/2006:11:10:00 +0300] cupsdFindBest: uri = "/printers/"...
d [25/Nov/2006:11:10:00 +0300] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [25/Nov/2006:11:10:00 +0300] cupsdFindBest: Location /admin/conf Limit 7f
d [25/Nov/2006:11:10:00 +0300] cupsdFindBest: Location /admin Limit 7f
d [25/Nov/2006:11:10:00 +0300] cupsdFindBest: Location / Limit 7f
d [25/Nov/2006:11:10:00 +0300] cupsdFindBest: best = /
d [25/Nov/2006:11:10:00 +0300] cupsdAuthorize: con->uri="/printers/", con->best=0x80aff38(/)
d [25/Nov/2006:11:10:00 +0300] cupsdAuthorize: Authorization=""
D [25/Nov/2006:11:10:00 +0300] cupsdAuthorize: No authentication data provided.
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: con->uri="/printers/", con->best=0x80aff38(/)
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [25/Nov/2006:11:10:00 +0300] POST /printers/
d [25/Nov/2006:11:10:00 +0300] CONTENT_TYPE = application/ipp
d [25/Nov/2006:11:10:00 +0300] cupsdReadClient: 10 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=72, con->file=-1
d [25/Nov/2006:11:10:00 +0300] cupsdProcessIPPRequest(0x80dd138[10]): operation_id = 4002
D [25/Nov/2006:11:10:00 +0300] CUPS-Get-Printers
d [25/Nov/2006:11:10:00 +0300] get_printers(0x80dd138[10], 0)
d [25/Nov/2006:11:10:00 +0300] cupsdFindPolicyOp(p=0x80b7a90, op=4002(CUPS-Get-Printers))
d [25/Nov/2006:11:10:00 +0300] cupsdFindPolicyOp: Found wildcard match...
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: con->uri="/printers/", con->best=0x80a1fd8((null))
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: op=0(unknown-0000)
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [25/Nov/2006:11:10:00 +0300] add_printer_state_reasons(0x80dd138[10], 0x80a68b8[ML1520])
d [25/Nov/2006:11:10:00 +0300] printer-uri-supported="ipp://localhost:631/printers/ML1520"
d [25/Nov/2006:11:10:00 +0300] add_queued_job_count(0x80dd138[10], 0x80a68b8[ML1520])
d [25/Nov/2006:11:10:00 +0300] copy_attrs(to=0x80ac138, from=0x80b1a00, ra=(nil), group=0, quickcopy=0)
d [25/Nov/2006:11:10:00 +0300] copy_attribute(0x80ac138, 0x809fc38[uri-authentication-supported,4,44])

...

d [25/Nov/2006:11:10:00 +0300] copy_attribute(0x80ac138, 0x80d4cc0[printer-op-policy-supported,4,42])

...

D [25/Nov/2006:11:10:00 +0300] cupsdAuthorize: username="root"
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: con->uri="/admin/conf/cupsd.conf", con->best=0x80d4898(/admin/conf)
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: level=AUTH_USER, type=AUTH_BASIC, satisfy=AUTH_SATISFY_ALL, num_names=1
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [25/Nov/2006:11:10:00 +0300] cupsdIsAuthorized: username="root"
d [25/Nov/2006:11:10:00 +0300] PUT /admin/conf/cupsd.conf
d [25/Nov/2006:11:10:00 +0300] CONTENT_TYPE =
d [25/Nov/2006:11:10:00 +0300] cupsdReadClient: 11 REQUEST /var/spool/cups/0000000c=10
d [25/Nov/2006:11:10:00 +0300] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=0, con->file=10
d [25/Nov/2006:11:10:00 +0300] cupsdReadClient: 11 writing 1891 bytes to 10

...

d [25/Nov/2006:11:10:00 +0300] cupsdReadClient: 11 Closing data file 10, size=20091.
I [25/Nov/2006:11:10:00 +0300] Installing config file "/etc/cups/cupsd.conf"...
D [25/Nov/2006:11:10:00 +0300] cupsdSendError: 11 code=201 (Created)
D [25/Nov/2006:11:10:00 +0300] cupsdCloseClient: 7
d [25/Nov/2006:11:10:00 +0300] cupsdCloseClient: Removing fd 7 from InputSet and OutputSet...
D [25/Nov/2006:11:10:00 +0300] cupsdCloseClient: 8
d [25/Nov/2006:11:10:00 +0300] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
D [25/Nov/2006:11:10:00 +0300] cupsdCloseClient: 11
d [25/Nov/2006:11:10:00 +0300] cupsdCloseClient: Removing fd 11 from InputSet and OutputSet...
d [25/Nov/2006:11:10:00 +0300] cupsdPauseListening: Clearing input bits...
d [25/Nov/2006:11:10:00 +0300] cupsdPauseListening: Removing fd 0 from InputSet...
d [25/Nov/2006:11:10:00 +0300] cupsdStopListening: closing all listen sockets.
d [25/Nov/2006:11:10:00 +0300] cupsdPauseListening: Clearing input bits...
d [25/Nov/2006:11:10:00 +0300] cupsdPauseListening: Removing fd 0 from InputSet...
d [25/Nov/2006:11:10:00 +0300] cupsdStopBrowsing: Removing fd 3 from InputSet...
I [25/Nov/2006:11:10:00 +0300] Saving remote.cache...
d [25/Nov/2006:11:10:00 +0300] cupsdStopServer: Removing fd 4 from InputSet...
I [25/Nov/2006:11:10:00 +0300] Loaded configuration file "/etc/cups/cupsd.conf"
N [25/Nov/2006:11:10:00 +0300] Group and SystemGroup cannot use the same groups!
I [25/Nov/2006:11:10:00 +0300] Resetting Group to "root"...
I [25/Nov/2006:11:10:00 +0300] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [25/Nov/2006:11:10:00 +0300] Configured for up to 100 clients.
I [25/Nov/2006:11:10:00 +0300] Allowing up to 100 client connections per host.
I [25/Nov/2006:11:10:00 +0300] Using policy "default" as the default!
I [25/Nov/2006:11:10:00 +0300] Partial reload complete.
I [25/Nov/2006:11:10:00 +0300] Listening to 127.0.0.1:631 on fd 2...
I [25/Nov/2006:11:10:39 +0300] Adding start banner page "none" to job 9.
I [25/Nov/2006:11:10:39 +0300] Adding end banner page "none" to job 9.
I [25/Nov/2006:11:10:39 +0300] Job 9 queued on "ML1520" by "root".
I [25/Nov/2006:11:10:39 +0300] Started filter /usr/lib/cups/filter/pstops (PID 5418) for job 9.
I [25/Nov/2006:11:10:39 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 5419) for job 9.
I [25/Nov/2006:11:10:39 +0300] Started backend /usr/lib/cups/backend/parallel (PID 5420) for job 9.
E [25/Nov/2006:11:10:39 +0300] PID 5420 (/usr/lib/cups/backend/parallel) stopped with status 1!
I [25/Nov/2006:11:10:39 +0300] Hint: Try setting the LogLevel to "debug" to find out more.
E [25/Nov/2006:11:10:39 +0300] [Job 9] Unable to open parallel port device file "/dev/lp0": Permission denied
E [25/Nov/2006:11:10:43 +0300] PID 5419 (/usr/lib/cups/filter/foomatic-rip) stopped with status 9!
I [25/Nov/2006:11:10:43 +0300] Hint: Try setting the LogLevel to "debug" to find out more.
I [25/Nov/2006:11:10:43 +0300] [Job 9] Backend returned status 1 (failed)
I [25/Nov/2006:11:10:43 +0300] Saving printers.conf...
I [25/Nov/2006:11:11:04 +0300] Job 9 was canceled by "root".

Пробовал сменить драйвер на ml1510 (работал в Debian Sarge за отсутствием родного ml1520) - не помогает.

KS ★★
() автор топика
Ответ на: комментарий от KS

Сразу же - права на /dev/lp0:

crw-rw---- 1 root lp 6, 0 2006-11-25 10:01 /dev/lp0

KS ★★
() автор топика
Вы не можете добавлять комментарии в эту тему. Тема перемещена в архив.