Servidor CUPS demorando muito tempo para imprimir determinados trabalhos

1

Eu tenho um servidor CUPS configurado, para o qual as máquinas Windows podem imprimir. Tudo parece funcionar bem (Microsoft Office e alguns outros programas que eu tentei), com exceção do Adobe Acrobat Pro. Demora muito tempo - às vezes cerca de 40 minutos - antes que os trabalhos de impressão sejam executados com sucesso.

Defino o LogLevel do CUPS como Debug e observo que essas são sempre as últimas duas linhas antes de serem interrompidas:

...
D [25/May/2012:15:09:24 -0700] cupsdSetBusyState: Not busy
D [25/May/2012:15:09:24 -0700] cupsdReadClient: 12 POST /printers/printer HTTP/1.1
D [25/May/2012:15:09:24 -0700] cupsdSetBusyState: Active clients
D [25/May/2012:15:09:24 -0700] cupsdAuthorize: No authentication data provided.
D [25/May/2012:15:09:24 -0700] cupsdReadClient: 12 1.0 Print-Job 2

Depois dessa última linha, nada acontece por um tempo.

Nada, isto é, exceto essas linhas, repetidas vezes sem conta:

D [25/May/2012:15:15:03 -0700] Report: clients=1
D [25/May/2012:15:15:03 -0700] Report: jobs=72
D [25/May/2012:15:15:03 -0700] Report: jobs-active=0
D [25/May/2012:15:15:03 -0700] Report: printers=2
D [25/May/2012:15:15:03 -0700] Report: printers-implicit=0
D [25/May/2012:15:15:03 -0700] Report: stringpool-string-count=8555
D [25/May/2012:15:15:03 -0700] Report: stringpool-alloc-bytes=11344
D [25/May/2012:15:15:03 -0700] Report: stringpool-total-bytes=163552
D [25/May/2012:15:15:15 -0700] cupsdNetIFUpdate: "lo" = localhost:631
D [25/May/2012:15:15:15 -0700] cupsdNetIFUpdate: "eth0" = <server-ip>:631
D [25/May/2012:15:15:15 -0700] cupsdNetIFUpdate: "lo" = localhost:631
D [25/May/2012:15:15:15 -0700] cupsdNetIFUpdate: "eth0" = <server-ip>%eth0:631

Eles ficam repetindo até algum tempo arbitrário (e variável) depois, quando vejo essas linhas, e o documento começa a ser impresso em breve.

D [25/May/2012:15:15:35 -0700] Print-Job http://<server-ip>:631/printers/printer
D [25/May/2012:15:15:35 -0700] [Job ???] Auto-typing file...
I [25/May/2012:15:15:35 -0700] [Job ???] Request file type is application/postscript.
D [25/May/2012:15:15:35 -0700] cupsdMarkDirty(----J-)
...

Imprimir a partir do Microsoft Word não produz nenhum problema, no entanto:

...
D [25/May/2012:14:17:25 -0700] cupsdReadClient: 17 1.0 Print-Job 2
D [25/May/2012:14:17:25 -0700] Print-Job http://<server-ip>:631/printers/printer
...

Wireshark e tcpdump indicam que a comunicação está ocorrendo em todos os momentos, no entanto.

Você tem alguma ideia sobre como posso resolver esse problema?

EDITAR

Eu imprimi essencialmente o mesmo documento novamente. Desta vez, observei algo diferente antes do jeito:

D [25/May/2012:15:50:02 -0700] cupsdReadClient: 17 POST /printers/printer HTTP/1.1
D [25/May/2012:15:50:02 -0700] cupsdAuthorize: No authentication data provided.
D [25/May/2012:15:50:02 -0700] cupsdReadClient: 17 1.0 Print-Job 2
D [25/May/2012:15:50:02 -0700] cupsdReadClient: 21 1.0 Get-Jobs 10
D [25/May/2012:15:50:02 -0700] Get-Jobs http://<server-ip>:631/printers/printer
D [25/May/2012:15:50:02 -0700] Returning IPP successful-ok for Get-Jobs (http://<server-ip>:631/printers/printer) from <client-ip>
D [25/May/2012:15:50:23 -0700] Report: clients=4
D [25/May/2012:15:50:23 -0700] Report: jobs=73
D [25/May/2012:15:50:23 -0700] Report: jobs-active=0
D [25/May/2012:15:50:23 -0700] Report: printers=2
...
D [25/May/2012:15:54:23 -0700] Report: stringpool-alloc-bytes=11376
D [25/May/2012:15:54:23 -0700] Report: stringpool-total-bytes=167048
D [25/May/2012:15:54:30 -0700] Closing client 12 after 300 seconds of inactivity...
D [25/May/2012:15:54:30 -0700] cupsdCloseClient: 12
D [25/May/2012:15:54:30 -0700] Closing client 13 after 300 seconds of inactivity...
D [25/May/2012:15:54:30 -0700] cupsdCloseClient: 13
D [25/May/2012:15:54:40 -0700] cupsdNetIFUpdate: "lo" = localhost:631
D [25/May/2012:15:54:40 -0700] cupsdNetIFUpdate: "eth0" = <server-ip>:631
...
D [25/May/2012:15:55:23 -0700] Report: stringpool-total-bytes=167048
D [25/May/2012:15:55:32 -0700] Print-Job http://<server-ip>:631/printers/printer
D [25/May/2012:15:55:32 -0700] [Job ???] Auto-typing file...
I [25/May/2012:15:55:32 -0700] [Job ???] Request file type is application/postscript.
D [25/May/2012:15:55:32 -0700] cupsdMarkDirty(----J-)
...

O arquivo saiu da impressora às 15:56, mas esta parece ser, de longe, a maior parte do atraso.

EDIT 2

Devo observar que a impressão em um arquivo XPS do Adobe Acrobat e a impressão na impressora do servidor CUPS a partir do arquivo XPS não produz esse atraso. Então, parece que é o Adobe Acrobat quem é o culpado.

SOLUÇÃO

Eu finalmente resolvi o problema escolhendo um driver de postscript não genérico para a impressora (no Windows).

    
por wrongusername 26.05.2012 / 00:46

1 resposta

1

Você observou o tamanho do arquivo de impressão gerado pelo Adobe Acrobat?

Se muito grande comparado ao Word, isso poderia explicar o atraso.

    
por 28.05.2012 / 10:41