ESXI + Ubuntu + xRDP + 1С: зависает печать (ESXI 6.7, Ubuntu 18.04, 1С 8.3.13.1644, CUPS-сервер 2.27, печать из 1С зависает намертво)

makus · Сообщение **makus** » 28.02.2019 16:55

Добрый день.

ESXI 6.7, Ubuntu 18.04, 1С 8.3.13.1644, CUPS-сервер 2.27 (/etc/cups/client.conf на терминалке). Сервер 1С работает на отдельной виртуалке, на Ubuntu 18.04 + PostgreSQL 10. Терминальный сервер на Ubuntu 18.04, xRDP, Mate. Всё хозяйство перенесено со старого сервера на Proxmox+LXC. Там печать работала.
1С стабильно зависает при попытке распечатать документ. Из любой конфигурации, на принтер любой фирмы: выводит окно отправки задания на печать — и всё. Точнее, зависает, если открывать Файл-Печать или жать «CTRL-P». При нажатии кнопки «Печать» над печатной формой документа («CTRL-SHIFT-P»), печать обычно работает. Печать в файл работает. Если открыть параметры страницы, кнопка «Принтер» не срабатывает, свойства принтера не открываются.
Из других приложений в терминалке печать работает.

Пробовал переустанавливать CUPS, пробовал подключаться к старому CUPS-серверу, с которого печать работала до переезда на новый, пробовал отключиться от CUPS-сервера и настроить принтеры прямо на терминалке. Пробовал поставить HPLIP и повесить его в трее у клиента (у кого-то так работало). Обновлял конфигурации 1С и платформу. Пробовал сбросить изменения в табличных формах (т.е. их не было, так что я сначала менял форму, потом откатывался на типовую), т.к. говорят, это может помочь. Наверное, делал ещё что-то, но уже не помню.

Обычно окно отправки задания не отрисовывалось, даже правая граница не дорисовывалась, только какие-то фрагменты фона, на котором окно открылось. После того, как понастраивал терминалку (1С ругалась на доступ к временным файлам) по статье с Хабра, указав в /etc/login.defs

Код: Выделить всё

umask 002

и изменив права на /tmp:

Код: Выделить всё

chown -R root:users /tmp
chmod g+s /tmp

, окно внезапно начало сначала полностью отрисовываться, а только потом зависать

Права на /tmp после перезагрузки не сохранились:

Код: Выделить всё

drwxrwxrwt

Когда отправляю задание на печать, в папке /tmp появляется файл:

Код: Выделить всё

-rw------- 1 <пользователь> <группа-пользователя> 0 wxPrintPS_IFN0XZ

Фантазия закончилась, где копать — не знаю.

Сообщение **Bizdelnick** » 28.02.2019 17:19

makus писал: ↑
28.02.2019 16:55
Пробовал переустанавливать CUPS, пробовал подключаться к старому CUPS-серверу, с которого печать работала до переезда на новый, пробовал отключиться от CUPS-сервера и настроить принтеры прямо на терминалке. Пробовал поставить HPLIP и повесить его в трее у клиента (у кого-то так работало). Обновлял конфигурации 1С и платформу. Пробовал сбросить изменения в табличных формах (т.е. их не было, так что я сначала менял форму, потом откатывался на типовую), т.к. говорят, это может помочь. Наверное, делал ещё что-то, но уже не помню.

А логи CUPS посмотреть не пробовали? И на клиенте, и на сервере.

makus · Сообщение **makus** » 28.02.2019 18:30

Bizdelnick писал: ↑
28.02.2019 17:19
А логи CUPS посмотреть не пробовали? И на клиенте, и на сервере.

На сервере:

Spoiler

D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:02 +0300] [Client 28] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:02 +0300] [Client 28] Accepted from 172.25.248.97:44742 (IPv4)
D [28/Feb/2019:18:21:02 +0300] [Client 28] Waiting for request.
I [28/Feb/2019:18:21:02 +0300] Expiring subscriptions...
D [28/Feb/2019:18:21:02 +0300] Report: clients=4
D [28/Feb/2019:18:21:02 +0300] Report: jobs=499
D [28/Feb/2019:18:21:02 +0300] Report: jobs-active=0
D [28/Feb/2019:18:21:02 +0300] Report: printers=7
D [28/Feb/2019:18:21:02 +0300] Report: stringpool-string-count=61482
D [28/Feb/2019:18:21:02 +0300] Report: stringpool-alloc-bytes=19536
D [28/Feb/2019:18:21:02 +0300] Report: stringpool-total-bytes=1091824
D [28/Feb/2019:18:21:02 +0300] [Client 28] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:02 +0300] [Client 28] Closing connection.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:02 +0300] [Client 29] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:02 +0300] [Client 29] Accepted from 172.25.248.97:44744 (IPv4)
D [28/Feb/2019:18:21:02 +0300] [Client 29] Waiting for request.
D [28/Feb/2019:18:21:02 +0300] [Client 29] POST / HTTP/1.1
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=200, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] No authentication data provided.
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] Read: status=100, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 29] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:02 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:02 +0300] [Client 29] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 172.25.248.97
D [28/Feb/2019:18:21:02 +0300] [Client 29] Content-Length: 6240
D [28/Feb/2019:18:21:02 +0300] [Client 29] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:02 +0300] [Client 29] con->http=0x55c56c5515f0
D [28/Feb/2019:18:21:02 +0300] [Client 29] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6240, response=0x55c56c527ac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:02 +0300] [Client 29] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:02 +0300] [Client 29] bytes=0, http_state=0, data_remaining=6240
D [28/Feb/2019:18:21:02 +0300] [Client 29] Flushing write buffer.
D [28/Feb/2019:18:21:02 +0300] [Client 29] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:02 +0300] [Client 29] Waiting for request.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:02 +0300] [Client 30] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:02 +0300] [Client 30] Accepted from 172.25.248.97:44748 (IPv4)
D [28/Feb/2019:18:21:02 +0300] [Client 30] Waiting for request.
D [28/Feb/2019:18:21:02 +0300] [Client 29] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:02 +0300] [Client 29] Closing connection.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] [Client 30] GET /printers/HP_LaserJet_M425dn_Buh.ppd HTTP/1.1
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:02 +0300] [Client 30] Read: status=200, state=3
D [28/Feb/2019:18:21:02 +0300] [Client 30] No authentication data provided.
D [28/Feb/2019:18:21:02 +0300] [Client 30] Processing GET /printers/HP_LaserJet_M425dn_Buh.ppd
D [28/Feb/2019:18:21:02 +0300] [Client 30] filename="/etc/cups/ppd/HP_LaserJet_M425dn_Buh.ppd", type=application/vnd.cups-ppd
D [28/Feb/2019:18:21:02 +0300] [Client 30] cupsdSendHeader: code=200, type="application/vnd.cups-ppd", auth_type=0
D [28/Feb/2019:18:21:02 +0300] [Client 30] Sending file.
D [28/Feb/2019:18:21:02 +0300] [Client 30] con->http=0x55c56c558bd0
D [28/Feb/2019:18:21:02 +0300] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9224, response=(nil)(), pipe_pid=0, file=12
D [28/Feb/2019:18:21:02 +0300] [Client 30] con->http=0x55c56c558bd0
D [28/Feb/2019:18:21:02 +0300] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9224, response=(nil)(), pipe_pid=0, file=12
D [28/Feb/2019:18:21:02 +0300] [Client 30] con->http=0x55c56c558bd0
D [28/Feb/2019:18:21:02 +0300] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9224, response=(nil)(), pipe_pid=0, file=12
D [28/Feb/2019:18:21:02 +0300] [Client 30] con->http=0x55c56c558bd0
D [28/Feb/2019:18:21:02 +0300] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9224, response=(nil)(), pipe_pid=0, file=12
D [28/Feb/2019:18:21:02 +0300] [Client 30] con->http=0x55c56c558bd0
D [28/Feb/2019:18:21:02 +0300] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9224, response=(nil)(), pipe_pid=0, file=12
D [28/Feb/2019:18:21:02 +0300] [Client 30] Flushing write buffer.
D [28/Feb/2019:18:21:02 +0300] [Client 30] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:02 +0300] [Client 30] Waiting for request.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] [Client 30] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:02 +0300] [Client 30] Closing connection.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:02 +0300] [Client 31] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:02 +0300] [Client 31] Accepted from 172.25.248.97:44750 (IPv4)
D [28/Feb/2019:18:21:02 +0300] [Client 31] Waiting for request.
D [28/Feb/2019:18:21:02 +0300] [Client 31] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:02 +0300] [Client 31] Closing connection.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:02 +0300] [Client 32] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:02 +0300] [Client 32] Accepted from 172.25.248.97:44752 (IPv4)
D [28/Feb/2019:18:21:02 +0300] [Client 32] Waiting for request.
D [28/Feb/2019:18:21:02 +0300] [Client 32] POST / HTTP/1.1
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] [Client 32] Read: status=200, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 32] No authentication data provided.
D [28/Feb/2019:18:21:02 +0300] [Client 32] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:02 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:02 +0300] [Client 32] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 172.25.248.97
D [28/Feb/2019:18:21:02 +0300] [Client 32] Content-Length: 6240
D [28/Feb/2019:18:21:02 +0300] [Client 32] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:02 +0300] [Client 32] con->http=0x55c56c5515f0
D [28/Feb/2019:18:21:02 +0300] [Client 32] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6240, response=0x55c56c54d2e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:02 +0300] [Client 32] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:02 +0300] [Client 32] bytes=0, http_state=0, data_remaining=6240
D [28/Feb/2019:18:21:02 +0300] [Client 32] Flushing write buffer.
D [28/Feb/2019:18:21:02 +0300] [Client 32] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:02 +0300] [Client 32] Waiting for request.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] [Client 32] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:02 +0300] [Client 32] Closing connection.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:02 +0300] [Client 33] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:02 +0300] [Client 33] Accepted from 172.25.248.97:44754 (IPv4)
D [28/Feb/2019:18:21:02 +0300] [Client 33] Waiting for request.
D [28/Feb/2019:18:21:02 +0300] [Client 33] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:02 +0300] [Client 33] Closing connection.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:02 +0300] [Client 34] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:02 +0300] [Client 34] Accepted from 172.25.248.97:44756 (IPv4)
D [28/Feb/2019:18:21:02 +0300] [Client 34] Waiting for request.
D [28/Feb/2019:18:21:02 +0300] [Client 34] POST / HTTP/1.1
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] [Client 34] Read: status=200, state=6
D [28/Feb/2019:18:21:02 +0300] [Client 34] No authentication data provided.
D [28/Feb/2019:18:21:02 +0300] [Client 34] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:02 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:02 +0300] [Client 34] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 172.25.248.97
D [28/Feb/2019:18:21:02 +0300] [Client 34] Content-Length: 6240
D [28/Feb/2019:18:21:02 +0300] [Client 34] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:02 +0300] [Client 34] con->http=0x55c56c5515f0
D [28/Feb/2019:18:21:02 +0300] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6240, response=0x55c56c5249c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:02 +0300] [Client 34] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:02 +0300] [Client 34] bytes=0, http_state=0, data_remaining=6240
D [28/Feb/2019:18:21:02 +0300] [Client 34] Flushing write buffer.
D [28/Feb/2019:18:21:02 +0300] [Client 34] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:02 +0300] [Client 34] Waiting for request.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:02 +0300] [Client 34] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:02 +0300] [Client 34] Closing connection.
D [28/Feb/2019:18:21:02 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:03 +0300] [Client 35] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:03 +0300] [Client 35] Accepted from 172.25.248.97:44758 (IPv4)
D [28/Feb/2019:18:21:03 +0300] [Client 35] Waiting for request.
D [28/Feb/2019:18:21:03 +0300] [Client 35] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:03 +0300] [Client 35] Closing connection.
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
I [28/Feb/2019:18:21:03 +0300] Expiring subscriptions...
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:03 +0300] [Client 36] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:03 +0300] [Client 36] Accepted from 172.25.248.97:44760 (IPv4)
D [28/Feb/2019:18:21:03 +0300] [Client 36] Waiting for request.
D [28/Feb/2019:18:21:03 +0300] [Client 36] POST / HTTP/1.1
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:03 +0300] [Client 36] Read: status=200, state=6
D [28/Feb/2019:18:21:03 +0300] [Client 36] No authentication data provided.
D [28/Feb/2019:18:21:03 +0300] [Client 36] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:03 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:03 +0300] [Client 36] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 172.25.248.97
D [28/Feb/2019:18:21:03 +0300] [Client 36] Content-Length: 6240
D [28/Feb/2019:18:21:03 +0300] [Client 36] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:03 +0300] [Client 36] con->http=0x55c56c5515f0
D [28/Feb/2019:18:21:03 +0300] [Client 36] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6240, response=0x55c56c545b40(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:03 +0300] [Client 36] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:03 +0300] [Client 36] bytes=0, http_state=0, data_remaining=6240
D [28/Feb/2019:18:21:03 +0300] [Client 36] Flushing write buffer.
D [28/Feb/2019:18:21:03 +0300] [Client 36] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:03 +0300] [Client 36] Waiting for request.
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:03 +0300] [Client 36] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:03 +0300] [Client 36] Closing connection.
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:03 +0300] [Client 37] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:03 +0300] [Client 37] Accepted from 172.25.248.97:44762 (IPv4)
D [28/Feb/2019:18:21:03 +0300] [Client 37] Waiting for request.
D [28/Feb/2019:18:21:03 +0300] [Client 37] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:03 +0300] [Client 37] Closing connection.
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:03 +0300] [Client 38] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:03 +0300] [Client 38] Accepted from 172.25.248.97:44764 (IPv4)
D [28/Feb/2019:18:21:03 +0300] [Client 38] Waiting for request.
D [28/Feb/2019:18:21:03 +0300] [Client 38] POST / HTTP/1.1
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:03 +0300] [Client 38] Read: status=200, state=6
D [28/Feb/2019:18:21:03 +0300] [Client 38] No authentication data provided.
D [28/Feb/2019:18:21:03 +0300] [Client 38] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:03 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:03 +0300] [Client 38] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 172.25.248.97
D [28/Feb/2019:18:21:03 +0300] [Client 38] Content-Length: 6240
D [28/Feb/2019:18:21:03 +0300] [Client 38] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:03 +0300] [Client 38] con->http=0x55c56c5515f0
D [28/Feb/2019:18:21:03 +0300] [Client 38] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6240, response=0x55c56c530980(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:03 +0300] [Client 38] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:03 +0300] [Client 38] bytes=0, http_state=0, data_remaining=6240
D [28/Feb/2019:18:21:03 +0300] [Client 38] Flushing write buffer.
D [28/Feb/2019:18:21:03 +0300] [Client 38] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:03 +0300] [Client 38] Waiting for request.
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:03 +0300] [Client 38] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:03 +0300] [Client 38] Closing connection.
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Feb/2019:18:21:03 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:03 +0300] [Client 39] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:03 +0300] [Client 39] Accepted from 172.25.248.97:44766 (IPv4)
D [28/Feb/2019:18:21:03 +0300] [Client 39] Waiting for request.
D [28/Feb/2019:18:21:04 +0300] [Client 39] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:04 +0300] [Client 39] Closing connection.
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
I [28/Feb/2019:18:21:04 +0300] Expiring subscriptions...
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:04 +0300] [Client 40] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:04 +0300] [Client 40] Accepted from 172.25.248.97:44768 (IPv4)
D [28/Feb/2019:18:21:04 +0300] [Client 40] Waiting for request.
D [28/Feb/2019:18:21:04 +0300] [Client 40] POST / HTTP/1.1
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:04 +0300] [Client 40] Read: status=200, state=6
D [28/Feb/2019:18:21:04 +0300] [Client 40] No authentication data provided.
D [28/Feb/2019:18:21:04 +0300] [Client 40] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:04 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:04 +0300] [Client 40] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 172.25.248.97
D [28/Feb/2019:18:21:04 +0300] [Client 40] Content-Length: 6240
D [28/Feb/2019:18:21:04 +0300] [Client 40] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:04 +0300] [Client 40] con->http=0x55c56c5515f0
D [28/Feb/2019:18:21:04 +0300] [Client 40] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6240, response=0x55c56c527ac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:04 +0300] [Client 40] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:04 +0300] [Client 40] bytes=0, http_state=0, data_remaining=6240
D [28/Feb/2019:18:21:04 +0300] [Client 40] Flushing write buffer.
D [28/Feb/2019:18:21:04 +0300] [Client 40] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:04 +0300] [Client 40] Waiting for request.
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:04 +0300] [Client 40] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:04 +0300] [Client 40] Closing connection.
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:04 +0300] [Client 41] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:04 +0300] [Client 41] Accepted from 172.25.248.97:44770 (IPv4)
D [28/Feb/2019:18:21:04 +0300] [Client 41] Waiting for request.
D [28/Feb/2019:18:21:04 +0300] [Client 41] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:04 +0300] [Client 41] Closing connection.
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:04 +0300] [Client 42] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:04 +0300] [Client 42] Accepted from 172.25.248.97:44772 (IPv4)
D [28/Feb/2019:18:21:04 +0300] [Client 42] Waiting for request.
D [28/Feb/2019:18:21:04 +0300] [Client 42] POST / HTTP/1.1
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:04 +0300] [Client 42] Read: status=200, state=6
D [28/Feb/2019:18:21:04 +0300] [Client 42] No authentication data provided.
D [28/Feb/2019:18:21:04 +0300] [Client 42] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:04 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:04 +0300] [Client 42] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 172.25.248.97
D [28/Feb/2019:18:21:04 +0300] [Client 42] Content-Length: 6240
D [28/Feb/2019:18:21:04 +0300] [Client 42] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:04 +0300] [Client 42] con->http=0x55c56c5515f0
D [28/Feb/2019:18:21:04 +0300] [Client 42] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6240, response=0x55c56c54d2e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:04 +0300] [Client 42] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:04 +0300] [Client 42] bytes=0, http_state=0, data_remaining=6240
D [28/Feb/2019:18:21:04 +0300] [Client 42] Flushing write buffer.
D [28/Feb/2019:18:21:04 +0300] [Client 42] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:04 +0300] [Client 42] Waiting for request.
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:04 +0300] [Client 42] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:04 +0300] [Client 42] Closing connection.
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:04 +0300] [Client 43] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:04 +0300] [Client 43] Accepted from 172.25.248.97:44774 (IPv4)
D [28/Feb/2019:18:21:04 +0300] [Client 43] Waiting for request.
D [28/Feb/2019:18:21:04 +0300] [Client 43] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:04 +0300] [Client 43] Closing connection.
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:04 +0300] [Client 44] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:04 +0300] [Client 44] Accepted from 172.25.248.97:44776 (IPv4)
D [28/Feb/2019:18:21:04 +0300] [Client 44] Waiting for request.
D [28/Feb/2019:18:21:04 +0300] [Client 44] POST / HTTP/1.1
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:04 +0300] [Client 44] Read: status=200, state=6
D [28/Feb/2019:18:21:04 +0300] [Client 44] No authentication data provided.
D [28/Feb/2019:18:21:04 +0300] [Client 44] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:04 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:04 +0300] [Client 44] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 172.25.248.97
D [28/Feb/2019:18:21:04 +0300] [Client 44] Content-Length: 6240
D [28/Feb/2019:18:21:04 +0300] [Client 44] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:04 +0300] [Client 44] con->http=0x55c56c5515f0
D [28/Feb/2019:18:21:04 +0300] [Client 44] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6240, response=0x55c56c5249c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:04 +0300] [Client 44] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:04 +0300] [Client 44] bytes=0, http_state=0, data_remaining=6240
D [28/Feb/2019:18:21:04 +0300] [Client 44] Flushing write buffer.
D [28/Feb/2019:18:21:04 +0300] [Client 44] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:04 +0300] [Client 44] Waiting for request.
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:04 +0300] [Client 44] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:04 +0300] [Client 44] Closing connection.
D [28/Feb/2019:18:21:04 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:05 +0300] [Client 45] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:05 +0300] [Client 45] Accepted from 172.25.248.97:44778 (IPv4)
D [28/Feb/2019:18:21:05 +0300] [Client 45] Waiting for request.
I [28/Feb/2019:18:21:05 +0300] Expiring subscriptions...
D [28/Feb/2019:18:21:05 +0300] [Client 45] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:05 +0300] [Client 45] Closing connection.
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:05 +0300] [Client 46] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:05 +0300] [Client 46] Accepted from 172.25.248.97:44780 (IPv4)
D [28/Feb/2019:18:21:05 +0300] [Client 46] Waiting for request.
D [28/Feb/2019:18:21:05 +0300] [Client 46] POST / HTTP/1.1
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:05 +0300] [Client 46] Read: status=200, state=6
D [28/Feb/2019:18:21:05 +0300] [Client 46] No authentication data provided.
D [28/Feb/2019:18:21:05 +0300] [Client 46] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:05 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:05 +0300] [Client 46] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 172.25.248.97
D [28/Feb/2019:18:21:05 +0300] [Client 46] Content-Length: 6240
D [28/Feb/2019:18:21:05 +0300] [Client 46] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:05 +0300] [Client 46] con->http=0x55c56c5515f0
D [28/Feb/2019:18:21:05 +0300] [Client 46] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6240, response=0x55c56c545b40(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:05 +0300] [Client 46] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:05 +0300] [Client 46] bytes=0, http_state=0, data_remaining=6240
D [28/Feb/2019:18:21:05 +0300] [Client 46] Flushing write buffer.
D [28/Feb/2019:18:21:05 +0300] [Client 46] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:05 +0300] [Client 46] Waiting for request.
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:05 +0300] [Client 46] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:05 +0300] [Client 46] Closing connection.
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:05 +0300] [Client 47] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:05 +0300] [Client 47] Accepted from 172.25.248.97:44782 (IPv4)
D [28/Feb/2019:18:21:05 +0300] [Client 47] Waiting for request.
D [28/Feb/2019:18:21:05 +0300] [Client 47] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:05 +0300] [Client 47] Closing connection.
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:05 +0300] [Client 48] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:05 +0300] [Client 48] Accepted from 172.25.248.97:44784 (IPv4)
D [28/Feb/2019:18:21:05 +0300] [Client 48] Waiting for request.
D [28/Feb/2019:18:21:05 +0300] [Client 48] POST / HTTP/1.1
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:05 +0300] [Client 48] Read: status=200, state=6
D [28/Feb/2019:18:21:05 +0300] [Client 48] No authentication data provided.
D [28/Feb/2019:18:21:05 +0300] [Client 48] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:05 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:05 +0300] [Client 48] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 172.25.248.97
D [28/Feb/2019:18:21:05 +0300] [Client 48] Content-Length: 6240
D [28/Feb/2019:18:21:05 +0300] [Client 48] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:05 +0300] [Client 48] con->http=0x55c56c5515f0
D [28/Feb/2019:18:21:05 +0300] [Client 48] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6240, response=0x55c56c530980(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:05 +0300] [Client 48] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:05 +0300] [Client 48] bytes=0, http_state=0, data_remaining=6240
D [28/Feb/2019:18:21:05 +0300] [Client 48] Flushing write buffer.
D [28/Feb/2019:18:21:05 +0300] [Client 48] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:05 +0300] [Client 48] Waiting for request.
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:05 +0300] [Client 48] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:05 +0300] [Client 48] Closing connection.
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Feb/2019:18:21:05 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:05 +0300] [Client 49] Server address is "172.25.248.98".
D [28/Feb/2019:18:21:05 +0300] [Client 49] Accepted from 172.25.248.97:44786 (IPv4)
D [28/Feb/2019:18:21:05 +0300] [Client 49] Waiting for request.
I [28/Feb/2019:18:21:06 +0300] Expiring subscriptions...
D [28/Feb/2019:18:21:31 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:31 +0300] [Client 50] Server address is "/var/run/cups/cups.sock".
D [28/Feb/2019:18:21:31 +0300] [Client 50] Accepted from localhost (Domain)
D [28/Feb/2019:18:21:31 +0300] [Client 50] Waiting for request.
I [28/Feb/2019:18:21:31 +0300] Expiring subscriptions...
D [28/Feb/2019:18:21:31 +0300] [Client 50] POST / HTTP/1.1
D [28/Feb/2019:18:21:31 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=200, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] No authentication data provided.
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] 2.0 CUPS-Get-Printers 1
D [28/Feb/2019:18:21:31 +0300] CUPS-Get-Printers
D [28/Feb/2019:18:21:31 +0300] [Client 50] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Feb/2019:18:21:31 +0300] [Client 50] Content-Length: 71947
D [28/Feb/2019:18:21:31 +0300] [Client 50] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:31 +0300] [Client 50] con->http=0x55c56c558bd0
D [28/Feb/2019:18:21:31 +0300] [Client 50] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=71947, response=0x55c56c524990(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:31 +0300] [Client 50] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:31 +0300] [Client 50] bytes=0, http_state=0, data_remaining=71947
D [28/Feb/2019:18:21:31 +0300] [Client 50] Flushing write buffer.
D [28/Feb/2019:18:21:31 +0300] [Client 50] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:31 +0300] [Client 50] Waiting for request.
D [28/Feb/2019:18:21:31 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:31 +0300] [Client 50] POST / HTTP/1.1
D [28/Feb/2019:18:21:31 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=200, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] No authentication data provided.
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] Read: status=100, state=6
D [28/Feb/2019:18:21:31 +0300] [Client 50] 2.0 CUPS-Get-Classes 1
D [28/Feb/2019:18:21:31 +0300] CUPS-Get-Classes
D [28/Feb/2019:18:21:31 +0300] [Client 50] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Feb/2019:18:21:31 +0300] [Client 50] Content-Length: 75
D [28/Feb/2019:18:21:31 +0300] [Client 50] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2019:18:21:31 +0300] [Client 50] con->http=0x55c56c558bd0
D [28/Feb/2019:18:21:31 +0300] [Client 50] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55c56c567050(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2019:18:21:31 +0300] [Client 50] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2019:18:21:31 +0300] [Client 50] bytes=0, http_state=0, data_remaining=75
D [28/Feb/2019:18:21:31 +0300] [Client 50] Flushing write buffer.
D [28/Feb/2019:18:21:31 +0300] [Client 50] New state is HTTP_STATE_WAITING
D [28/Feb/2019:18:21:31 +0300] [Client 50] Waiting for request.
D [28/Feb/2019:18:21:31 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2019:18:21:31 +0300] [Client 50] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2019:18:21:31 +0300] [Client 50] Closing connection.
D [28/Feb/2019:18:21:31 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [28/Feb/2019:18:21:32 +0300] Expiring subscriptions...

На клиенте лог пустой (LogLevel debug).

Добавлено (18:34):

Система везде 64-битная, если это имеет значение.

Добавлено (18:46):

Client 30, если я правильно понимаю. И я пробовал разные драйверы, в том числе и generic, проблема печати из 1С не решалась (из других программ печатает).

Сообщение **Bizdelnick** » 28.02.2019 18:52

А какая версия CUPS на клиенте? Вот это — не Ваш случай? Видимо, не Ваш, раз и на клиенте, и на сервере Ubuntu 18.04.
Хотя по такой ошибке много разного находится, вплоть до нерабочего USB-порта/кабеля.

makus · Сообщение **makus** » 28.02.2019 19:01

Bizdelnick писал: ↑
28.02.2019 18:52
А какие версии CUPS на сервере и на клиенте? Вот это — не Ваш случай?
Хотя по такой ошибке много разного находится, вплоть до нерабочего USB-порта/кабеля.

CUPS 2.27 на обеих виртуалках, cups-browsed был мёртв. Перезапустил и на сервере, и на клиенте — не помогло.

Забыл уточнить: принтер сетевой, подключение настроено вот так:

Код: Выделить всё

hp:/net/HP_LaserJet_400_MFP_M425dn?ip=172.25.248.8

Сообщение **Bizdelnick** » 28.02.2019 19:38

makus писал: ↑
28.02.2019 19:01
Забыл уточнить: принтер сетевой, подключение настроено вот так:
Код: Выделить всё
hp:/net/HP_LaserJet_400_MFP_M425dn?ip=172.25.248.8

А разве сетевое подключение так настраивается? По идее должно быть что-то вроде

Код: Выделить всё

socket://172.25.248.8

Хотя, может быть, у этого принтера всё по-другому, не знаю.

makus · Сообщение **makus** » 28.02.2019 20:52

Так hplip настроил. Но я поменял на «socket://» — проблема осталась.

Сообщение **Bizdelnick** » 28.02.2019 21:07

А, да, ему таки нужен плагин. Я таких принтеров не настраивал, но, видимо, таки правильно hp:.
Итого, что получается. Похоже, 1С запрашивает какую-то инфу о принтере, которую CUPS не удаётся получить. Очевидно, проблема на стороне сервера.
А печатать напрямую на принтер пробовали, раз уж он сетевой? Вы это подразумевали под

makus писал: ↑
28.02.2019 16:55
поставить HPLIP и повесить его в трее у клиента

?

makus · Сообщение **makus** » 28.02.2019 21:33

Bizdelnick писал: ↑
28.02.2019 21:07
Итого, что получается. Похоже, 1С запрашивает какую-то инфу о принтере, которую CUPS не удаётся получить. Очевидно, проблема на стороне сервера.

Если печатать из 1С прямо на принтер (кнопка над табличной формой или Ctrl-Shift-P), документ печатается. Не помню насчёт форматирования, запоминаются ли так пользовательские настройки, но печатается.
Если же нажимать Файл-Печать или Ctrl-P, открывается системное окно печати документа. После нажатия в этом окне кнопки печати, системное окно закрывается, открывается окно отправки документа на печать в 1С и зависает так, что и кнопка «Отмена» не работает.

print-2.png

print-3.png

print-4.png

Bizdelnick писал: ↑
28.02.2019 21:07
А печатать напрямую на принтер пробовали, раз уж он сетевой? Вы это подразумевали?

Пробовал отключиться от сервера и настроить принтеры прямо на терминалке. Проблема сохранилась.

Я тут где-то находил упоминание, что если заменить формат задания в 1С с postscript на pdf, всё может заработать. Проблема в том, что я не могу изменить в 1С настройки принтера, т.к. по нажатию кнопки «Принтер» в настройке параметров печати, ничего не происходит.

makus · Сообщение **makus** » 04.03.2019 12:32

Попробовал снова настроить принтер локально, на терминалке. Добавил его с помощью hplip, но ничего не изменилось. Проблема осталась.

makus · Сообщение **makus** » 04.03.2019 15:26

Созданная в 1С пустая таблица распечаталась без проблем, сохранённый в mxl и отправленный на печать файл документа из 1С завис, сохранённый в pdf и в xlsx документ, распечатанный из pdf-вьюера и из OnlyOffice соответственно, напечатались без проблем.

makus · Сообщение **makus** » 27.03.2019 22:04

Переписывался с разработчиками 1С. Кажется, проблема решена в тестовой версии платформы 8.3.13.1827. По крайней мере, я провёл тесты с разными Бухгалтериями 3.0, разных версий, и печать заработала. На два разных принтера.
Правда, свойства принтера из настроек печати всё равно не открываются, но это уже не так страшно.

Жду релиза этой версии платформы.

unixforum.org