Все было просто прекрасно целый 1 год, все печатало, принтера пробрасывали через NX Client Community Edition и через OpenNX да через что угодно.
И в одно "прекрасное" утро красота закончилась: абсолютно все принтера (порядка 10), проброшенные через разные клиенты, перестали определяться. При этом печать на принтера, которые находятся в одной локалке с сервером и подключены по самбе, происходила БЕЗ ПРОБЛЕМ вообще. Никакие административные манипуляции с сервером не выполнялись, вообще, каждодневная рутинная работа менеджеров и бухгалтеров.
Ах да, кроме принтеров все осталось по прежнему, клиенты коннектятся на сервак, иксы поднимаются, wine работает и слава богу 1С тоже
Чистая переустановка rx и cups (со всеми пакетами, предварительной зачисткой и т.д.) ничего не дало. Че было сразу в логи не полезть, не знаю.
Как потом показали логи капса, что-то случилось с правами доступа к системе печати, но что конкретно произошло, ну не могу понять и все тут.
Вот error_log для cups при запуске клиента nx:
Код: Выделить всё
E [09/Dec/2012:17:45:41 +0200] Unknown directive SystemGroup on line 1 of /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cupsd.conf.
W [09/Dec/2012:17:45:41 +0200] Please move "AccessLog log/access_log" on line 2 of /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:17:45:41 +0200] Please move "ErrorLog log/error_log" on line 3 of /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:17:45:41 +0200] Please move "PageLog log/page_log" on line 4 of /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:17:45:41 +0200] Please move "TempDir /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/spool/tmp" on line 6 of /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:17:45:41 +0200] Please move "RequestRoot /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/spool" on line 7 of /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:17:45:41 +0200] Please move "ServerRoot /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/" on line 8 of /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:17:45:41 +0200] Please move "StateDir /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/" on line 9 of /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:17:45:41 +0200] Please move "CacheDir /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cache" on line 10 of /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cups-files.conf file; this will become an error in a future release.
I [09/Dec/2012:17:45:41 +0200] Listening to ::1:11000 (IPv6)
I [09/Dec/2012:17:45:41 +0200] Listening to 127.0.0.1:11000 (IPv4)
I [09/Dec/2012:17:45:41 +0200] Listening to /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cups.sock (Domain)
D [09/Dec/2012:17:45:41 +0200] cupsdDenyIP(loc=0x7fddbdf69640(/), address=0:0:0:0, netmask=0:0:0:0)
W [09/Dec/2012:17:45:41 +0200] No limit for CUPS-Get-Document defined in policy default - using Send-Document's policy
I [09/Dec/2012:17:45:41 +0200] Remote access is disabled.
D [09/Dec/2012:17:45:41 +0200] Added auto ServerAlias localhost
I [09/Dec/2012:17:45:41 +0200] Loaded configuration file "/home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cupsd.conf"
D [09/Dec/2012:17:45:41 +0200] Repairing ownership of "/var/spool/cups"
D [09/Dec/2012:17:45:41 +0200] Repairing ownership of "/var/cache/cups"
D [09/Dec/2012:17:45:41 +0200] Repairing ownership of "/var/cache/cups/rss"
D [09/Dec/2012:17:45:41 +0200] Repairing ownership of "/var/run/cups"
D [09/Dec/2012:17:45:41 +0200] Repairing access permissions of "/var/run/cups/certs"
E [09/Dec/2012:17:45:41 +0200] Unable to change permissions of "/var/run/cups/certs" - Operation not permitted
D [09/Dec/2012:17:45:41 +0200] Repairing ownership of "/home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups"
D [09/Dec/2012:17:45:41 +0200] Repairing ownership of "/home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/ppd"
D [09/Dec/2012:17:45:41 +0200] Repairing ownership of "/home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/classes.conf"
D [09/Dec/2012:17:45:41 +0200] Repairing access permissions of "/home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/classes.conf"
D [09/Dec/2012:17:45:41 +0200] Repairing ownership of "/home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/printers.conf"
D [09/Dec/2012:17:45:41 +0200] Repairing access permissions of "/home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/printers.conf"
I [09/Dec/2012:17:45:41 +0200] Using default TempDir of /var/spool/cups/tmp...
D [09/Dec/2012:17:45:41 +0200] Repairing ownership of "/var/spool/cups/tmp"
I [09/Dec/2012:17:45:41 +0200] Configured for up to 100 clients.
I [09/Dec/2012:17:45:41 +0200] Allowing up to 100 client connections per host.
I [09/Dec/2012:17:45:41 +0200] Using policy "default" as the default!
I [09/Dec/2012:17:45:41 +0200] Full reload is required.
I [09/Dec/2012:17:45:41 +0200] Loaded MIME database from "/usr/share/cups/mime" and "/home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups": 37 types, 76 filters...
D [09/Dec/2012:17:45:41 +0200] cupsdLoadRemoteCache: Not loading remote cache.
I [09/Dec/2012:17:45:41 +0200] Loading job cache file "/var/cache/cups/job.cache"...
I [09/Dec/2012:17:45:41 +0200] Full reload complete.
I [09/Dec/2012:17:45:41 +0200] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [09/Dec/2012:17:45:41 +0200] Listening to ::1:11000 on fd 6...
I [09/Dec/2012:17:45:41 +0200] Listening to 127.0.0.1:11000 on fd 7...
I [09/Dec/2012:17:45:41 +0200] Listening to /home/kvatraporto/.nx/C-servertk-2000-2EEAD2F786F1388DDE6BCCF8E3EDF8C4/cups/cups.sock on fd 8...
I [09/Dec/2012:17:45:41 +0200] Resuming new connection processing...
E [09/Dec/2012:17:45:41 +0200] Unable to write pid file
У всех папок вызывающих ошибки и у файла cupsd.pid из /var/run/cups/ (это его по оконцовке заклинило) стоят дефолтные права (проверил на чистой системе)
Опять же на часть папок (/var/spool/cups, /var/cache/cups и т.п.) я влупил право на запись для всех, они тоже не пускали.
Как я обрадовался, разрешив запись для всех для cupsd.pid, принтер пробросился, драйвер присвоился и т.д. (правда до перезагрузки сервера, права выстанавливаются на дефолтные, ну эт не беда)
НО НЕ ЗАПЕЧАТАЛ (Обнаружена ошибка во время операции CUPS: «client-error-request-value-too-long».), привожу лог с разблокированным cupsd.pid и отправкой задания на печать(тестовая страница):
Код: Выделить всё
E [09/Dec/2012:18:02:17 +0200] Unknown directive SystemGroup on line 1 of /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cupsd.conf.
W [09/Dec/2012:18:02:17 +0200] Please move "AccessLog log/access_log" on line 2 of /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:18:02:17 +0200] Please move "ErrorLog log/error_log" on line 3 of /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:18:02:17 +0200] Please move "PageLog log/page_log" on line 4 of /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:18:02:17 +0200] Please move "TempDir /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/spool/tmp" on line 6 of /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:18:02:17 +0200] Please move "RequestRoot /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/spool" on line 7 of /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:18:02:17 +0200] Please move "ServerRoot /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/" on line 8 of /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:18:02:17 +0200] Please move "StateDir /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/" on line 9 of /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cups-files.conf file; this will become an error in a future release.
W [09/Dec/2012:18:02:17 +0200] Please move "CacheDir /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cache" on line 10 of /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cupsd.conf to the /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cups-files.conf file; this will become an error in a future release.
I [09/Dec/2012:18:02:17 +0200] Listening to ::1:11000 (IPv6)
I [09/Dec/2012:18:02:17 +0200] Listening to 127.0.0.1:11000 (IPv4)
I [09/Dec/2012:18:02:17 +0200] Listening to /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cups.sock (Domain)
D [09/Dec/2012:18:02:17 +0200] cupsdDenyIP(loc=0x7feffa66a640(/), address=0:0:0:0, netmask=0:0:0:0)
W [09/Dec/2012:18:02:17 +0200] No limit for CUPS-Get-Document defined in policy default - using Send-Document's policy
I [09/Dec/2012:18:02:17 +0200] Remote access is disabled.
D [09/Dec/2012:18:02:17 +0200] Added auto ServerAlias localhost
I [09/Dec/2012:18:02:17 +0200] Loaded configuration file "/home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cupsd.conf"
D [09/Dec/2012:18:02:17 +0200] Repairing ownership of "/var/spool/cups"
D [09/Dec/2012:18:02:17 +0200] Repairing ownership of "/var/cache/cups"
D [09/Dec/2012:18:02:17 +0200] Repairing ownership of "/var/cache/cups/rss"
D [09/Dec/2012:18:02:17 +0200] Repairing ownership of "/var/run/cups"
D [09/Dec/2012:18:02:17 +0200] Repairing access permissions of "/var/run/cups/certs"
E [09/Dec/2012:18:02:17 +0200] Unable to change permissions of "/var/run/cups/certs" - Operation not permitted
D [09/Dec/2012:18:02:17 +0200] Repairing ownership of "/home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups"
D [09/Dec/2012:18:02:17 +0200] Repairing ownership of "/home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/ppd"
D [09/Dec/2012:18:02:17 +0200] Repairing ownership of "/home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/classes.conf"
D [09/Dec/2012:18:02:17 +0200] Repairing access permissions of "/home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/classes.conf"
D [09/Dec/2012:18:02:17 +0200] Repairing ownership of "/home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/printers.conf"
D [09/Dec/2012:18:02:17 +0200] Repairing access permissions of "/home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/printers.conf"
I [09/Dec/2012:18:02:17 +0200] Using default TempDir of /var/spool/cups/tmp...
D [09/Dec/2012:18:02:17 +0200] Repairing ownership of "/var/spool/cups/tmp"
I [09/Dec/2012:18:02:17 +0200] Configured for up to 100 clients.
I [09/Dec/2012:18:02:17 +0200] Allowing up to 100 client connections per host.
I [09/Dec/2012:18:02:17 +0200] Using policy "default" as the default!
I [09/Dec/2012:18:02:17 +0200] Full reload is required.
I [09/Dec/2012:18:02:17 +0200] Loaded MIME database from "/usr/share/cups/mime" and "/home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups": 37 types, 76 filters...
D [09/Dec/2012:18:02:17 +0200] cupsdLoadRemoteCache: Not loading remote cache.
I [09/Dec/2012:18:02:17 +0200] Loading job cache file "/var/cache/cups/job.cache"...
I [09/Dec/2012:18:02:17 +0200] Full reload complete.
I [09/Dec/2012:18:02:17 +0200] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [09/Dec/2012:18:02:17 +0200] Listening to ::1:11000 on fd 6...
I [09/Dec/2012:18:02:17 +0200] Listening to 127.0.0.1:11000 on fd 7...
I [09/Dec/2012:18:02:17 +0200] Listening to /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/cups.sock on fd 8...
I [09/Dec/2012:18:02:17 +0200] Resuming new connection processing...
D [09/Dec/2012:18:02:17 +0200] Discarding unused server-started event...
D [09/Dec/2012:18:02:18 +0200] Report: clients=0
D [09/Dec/2012:18:02:18 +0200] Report: jobs=0
D [09/Dec/2012:18:02:18 +0200] Report: jobs-active=0
D [09/Dec/2012:18:02:18 +0200] Report: printers=0
D [09/Dec/2012:18:02:18 +0200] Report: printers-implicit=0
D [09/Dec/2012:18:02:18 +0200] Report: stringpool-string-count=123
D [09/Dec/2012:18:02:18 +0200] Report: stringpool-alloc-bytes=4440
D [09/Dec/2012:18:02:18 +0200] Report: stringpool-total-bytes=3488
D [09/Dec/2012:18:02:19 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [09/Dec/2012:18:02:19 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [09/Dec/2012:18:02:19 +0200] cupsdSetBusyState: Active clients
D [09/Dec/2012:18:02:19 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:19 +0200] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [09/Dec/2012:18:02:19 +0200] CUPS-Get-Printers
D [09/Dec/2012:18:02:19 +0200] CUPS-Get-Printers client-error-not-found: Нет добавленных назначений.
D [09/Dec/2012:18:02:19 +0200] Returning IPP client-error-not-found for CUPS-Get-Printers (no URI) from localhost
D [09/Dec/2012:18:02:19 +0200] cupsdSetBusyState: Not busy
D [09/Dec/2012:18:02:19 +0200] cupsdReadClient: 11 WAITING Closing on EOF
D [09/Dec/2012:18:02:19 +0200] cupsdCloseClient: 11
D [09/Dec/2012:18:02:25 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [09/Dec/2012:18:02:25 +0200] cupsdReadClient: 11 POST /admin/ HTTP/1.1
D [09/Dec/2012:18:02:25 +0200] cupsdSetBusyState: Active clients
D [09/Dec/2012:18:02:25 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:25 +0200] cupsdReadClient: 11 1.1 CUPS-Add-Modify-Printer 1
D [09/Dec/2012:18:02:25 +0200] CUPS-Add-Modify-Printer ipp://localhost/printers/29000
I [09/Dec/2012:18:02:25 +0200] Setting 29000 printer-is-accepting-jobs to 1 (was 0.)
I [09/Dec/2012:18:02:25 +0200] Setting 29000 printer-state to 3 (was 5.)
D [09/Dec/2012:18:02:25 +0200] Discarding unused printer-state-changed event...
D [09/Dec/2012:18:02:25 +0200] load_ppd: Loading /var/cache/cups/29000.ipp...
D [09/Dec/2012:18:02:25 +0200] cupsdRegisterPrinter(p=0x7feffa69e710(29000))
D [09/Dec/2012:18:02:25 +0200] cupsdMarkDirty(P-----)
D [09/Dec/2012:18:02:25 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:25 +0200] cupsdMarkDirty(---p--)
D [09/Dec/2012:18:02:25 +0200] Discarding unused printer-added event...
I [09/Dec/2012:18:02:25 +0200] New printer "29000" added by "anonymous".
D [09/Dec/2012:18:02:25 +0200] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost/printers/29000) from localhost
D [09/Dec/2012:18:02:25 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:25 +0200] cupsdReadClient: 11 POST /admin/ HTTP/1.1
D [09/Dec/2012:18:02:25 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:25 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:25 +0200] cupsdReadClient: 11 1.1 CUPS-Add-Modify-Printer 1
D [09/Dec/2012:18:02:25 +0200] CUPS-Add-Modify-Printer ipp://localhost/printers/29000
I [09/Dec/2012:18:02:25 +0200] Setting 29000 device-uri to "nxsmb://127.0.0.1:5000/cifs/29000" (was "file:///dev/null".)
D [09/Dec/2012:18:02:25 +0200] load_ppd: Loading /var/cache/cups/29000.ipp...
D [09/Dec/2012:18:02:25 +0200] cupsdRegisterPrinter(p=0x7feffa69e710(29000))
D [09/Dec/2012:18:02:25 +0200] cupsdMarkDirty(P-----)
D [09/Dec/2012:18:02:25 +0200] cupsdMarkDirty(---p--)
D [09/Dec/2012:18:02:25 +0200] Discarding unused printer-modified event...
I [09/Dec/2012:18:02:25 +0200] Printer "29000" modified by "anonymous".
D [09/Dec/2012:18:02:25 +0200] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost/printers/29000) from localhost
D [09/Dec/2012:18:02:25 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:25 +0200] cupsdReadClient: 11 POST /admin/ HTTP/1.1
D [09/Dec/2012:18:02:25 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:25 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:25 +0200] cupsdReadClient: 11 1.1 CUPS-Add-Modify-Printer 1
D [09/Dec/2012:18:02:25 +0200] CUPS-Add-Modify-Printer ipp://localhost/printers/29000
D [09/Dec/2012:18:02:25 +0200] copy_model: Running "cups-driverd cat foomatic:Epson-Stylus_Photo_R290-gutenprint-ijs.5.2.ppd"...
D [09/Dec/2012:18:02:32 +0200] Copied PPD file successfully!
D [09/Dec/2012:18:02:32 +0200] cupsdMarkDirty(P-----)
D [09/Dec/2012:18:02:32 +0200] load_ppd: Loading /home/kvatraporto/.nx/C-servertk-2000-A5F9708E0314C124E49A631E88ACA3DC/cups/ppd/29000.ppd...
D [09/Dec/2012:18:02:32 +0200] load_ppd: Saving /var/cache/cups/29000.ipp...
D [09/Dec/2012:18:02:32 +0200] cupsdRegisterPrinter(p=0x7feffa69e710(29000))
D [09/Dec/2012:18:02:32 +0200] cupsdMarkDirty(P-----)
D [09/Dec/2012:18:02:32 +0200] cupsdMarkDirty(---p--)
D [09/Dec/2012:18:02:32 +0200] Discarding unused printer-modified event...
I [09/Dec/2012:18:02:32 +0200] Printer "29000" modified by "anonymous".
D [09/Dec/2012:18:02:32 +0200] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost/printers/29000) from localhost
D [09/Dec/2012:18:02:32 +0200] PID 5582 (/usr/lib/cups/daemon/cups-driverd) exited with no errors.
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 11 WAITING Closing on EOF
D [09/Dec/2012:18:02:32 +0200] cupsdCloseClient: 11
D [09/Dec/2012:18:02:32 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 11 POST /admin/ HTTP/1.1
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 11 1.1 CUPS-Set-Default 1
D [09/Dec/2012:18:02:32 +0200] CUPS-Set-Default ipp://localhost/printers/29000
D [09/Dec/2012:18:02:32 +0200] Discarding unused printer-state-changed event...
D [09/Dec/2012:18:02:32 +0200] cupsdMarkDirty(PCRp--)
I [09/Dec/2012:18:02:32 +0200] Default destination set to "29000" by "anonymous".
D [09/Dec/2012:18:02:32 +0200] Returning IPP successful-ok for CUPS-Set-Default (ipp://localhost/printers/29000) from localhost
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 11 WAITING Closing on EOF
D [09/Dec/2012:18:02:32 +0200] cupsdCloseClient: 11
D [09/Dec/2012:18:02:32 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [09/Dec/2012:18:02:32 +0200] cupsdAcceptClient: 13 from localhost (Domain)
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 13 1.1 Create-Printer-Subscription 1
D [09/Dec/2012:18:02:32 +0200] Create-Printer-Subscription /
D [09/Dec/2012:18:02:32 +0200] cupsdCreateSubscription(con=0x7feffa7ff760(13), uri="/")
D [09/Dec/2012:18:02:32 +0200] pullmethod="ippget"
D [09/Dec/2012:18:02:32 +0200] notify-lease-duration=86400
D [09/Dec/2012:18:02:32 +0200] notify-time-interval=0
D [09/Dec/2012:18:02:32 +0200] cupsdAddSubscription(mask=18f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [09/Dec/2012:18:02:32 +0200] Added subscription 1 for server
D [09/Dec/2012:18:02:32 +0200] cupsdMarkDirty(-----S)
D [09/Dec/2012:18:02:32 +0200] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [09/Dec/2012:18:02:32 +0200] CUPS-Get-Printers
D [09/Dec/2012:18:02:32 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [09/Dec/2012:18:02:32 +0200] CUPS-Get-Printers
D [09/Dec/2012:18:02:32 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 13 WAITING Closing on EOF
D [09/Dec/2012:18:02:32 +0200] cupsdCloseClient: 13
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [09/Dec/2012:18:02:32 +0200] CUPS-Get-Printers
D [09/Dec/2012:18:02:32 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [09/Dec/2012:18:02:32 +0200] CUPS-Get-Classes
D [09/Dec/2012:18:02:32 +0200] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:32 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:32 +0200] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [09/Dec/2012:18:02:32 +0200] CUPS-Get-Default
D [09/Dec/2012:18:02:32 +0200] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [09/Dec/2012:18:02:32 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:34 +0200] cupsdAcceptClient: 13 from localhost (Domain)
D [09/Dec/2012:18:02:34 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [09/Dec/2012:18:02:34 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:34 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:34 +0200] cupsdReadClient: 13 1.1 Get-Notifications 1
D [09/Dec/2012:18:02:34 +0200] Get-Notifications /
D [09/Dec/2012:18:02:34 +0200] cupsdIsAuthorized: requesting-user-name="kvatraporto"
D [09/Dec/2012:18:02:34 +0200] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [09/Dec/2012:18:02:34 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:34 +0200] cupsdReadClient: 13 WAITING Closing on EOF
D [09/Dec/2012:18:02:34 +0200] cupsdCloseClient: 13
D [09/Dec/2012:18:02:35 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [09/Dec/2012:18:02:35 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:35 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:35 +0200] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [09/Dec/2012:18:02:35 +0200] Get-Printer-Attributes ipp://localhost/printers/29000
D [09/Dec/2012:18:02:35 +0200] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/29000) from localhost
D [09/Dec/2012:18:02:35 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:35 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [09/Dec/2012:18:02:35 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:35 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:35 +0200] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [09/Dec/2012:18:02:35 +0200] Get-Printer-Attributes ipp://localhost/printers/29000
D [09/Dec/2012:18:02:35 +0200] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/29000) from localhost
D [09/Dec/2012:18:02:35 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:35 +0200] cupsdAcceptClient: 13 from localhost:11000 (IPv6)
D [09/Dec/2012:18:02:35 +0200] cupsdReadClient: 13 GET /printers/29000.ppd HTTP/1.1
D [09/Dec/2012:18:02:35 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:35 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:35 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:35 +0200] cupsdReadClient: 13 WAITING Closing on EOF
D [09/Dec/2012:18:02:35 +0200] cupsdCloseClient: 13
D [09/Dec/2012:18:02:40 +0200] cupsdAcceptClient: 13 from localhost (Domain)
D [09/Dec/2012:18:02:40 +0200] cupsdReadClient: 13 POST /printers/29000 HTTP/1.1
D [09/Dec/2012:18:02:40 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:40 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:40 +0200] cupsdReadClient: 13 1.1 Print-Job 1
E [09/Dec/2012:18:02:40 +0200] Unable to create request file /var/spool/cups/00000000: Permission denied
D [09/Dec/2012:18:02:50 +0200] cupsdReadClient: 13 Closing on unknown HTTP state 0
D [09/Dec/2012:18:02:50 +0200] cupsdCloseClient: 13
D [09/Dec/2012:18:02:50 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:50 +0200] cupsdAcceptClient: 13 from localhost (Domain)
D [09/Dec/2012:18:02:50 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [09/Dec/2012:18:02:50 +0200] cupsdSetBusyState: Active clients and dirty files
D [09/Dec/2012:18:02:50 +0200] cupsdAuthorize: No authentication data provided.
D [09/Dec/2012:18:02:50 +0200] cupsdReadClient: 13 1.1 Get-Jobs 1
D [09/Dec/2012:18:02:50 +0200] Get-Jobs ipp://localhost/printers/
D [09/Dec/2012:18:02:50 +0200] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [09/Dec/2012:18:02:50 +0200] cupsdSetBusyState: Dirty files
D [09/Dec/2012:18:02:50 +0200] cupsdReadClient: 13 WAITING Closing on EOF
D [09/Dec/2012:18:02:50 +0200] cupsdCloseClient: 13
I [09/Dec/2012:18:02:56 +0200] Saving printers.conf...
I [09/Dec/2012:18:02:56 +0200] Saving classes.conf...
E [09/Dec/2012:18:02:56 +0200] Unable to save remote.cache - Permission denied
I [09/Dec/2012:18:02:56 +0200] Generating printcap /var/run/cups/printcap...
I [09/Dec/2012:18:02:56 +0200] Saving subscriptions.conf...
D [09/Dec/2012:18:02:56 +0200] cupsdSetBusyState: Not busy
Опять permission и т.п.
Пробовал и эти пути в провах пропустить, но лезут следующие, следующие, следующие и т.д.
Отсюда и сделал вывод, что вся проблема либо в пользователе от чьего имени происходит печать, либо от пользователя nx, либо не знаю от чего.
Так вот собственно и вопрос, есть идеи? В какую сторону рыть?
Буду очень премного благодарен за помощь, мой мозг кипит