[SOLVED] CUPS problem sending job to network printer

matiasar · 08-14-2013, 09:16 AM

I have a printer server (running squeeze), with a couple of printer directly connected. One of them is a Brother HL-2130.
The print server provides printing services for my LAN, using samba backend, IPP and now cups-lpd too.

From win boxes users can print files to that HL-2130 without any trouble.

The only problem I have is sending print jobs from my own workstation (which runs Debian Squeeze too).
I configured CUPS to send job to the network printer, using LPD: lpd://PRINTSRV_IP_ADDR/HL2130

From my box cups interface job is processed and sent. After printing a file job appears at the "completed jobs" list in cups GUI.
But within CUPS GUI in the print server, job is retained and has a size of 0 bytes.

I've set up debug level logging in cups (server) and this is what it appears in error_log:

Code:

D [14/Aug/2013:09:37:53 -0300] cupsdAcceptClient: 12 from localhost (Domain)
D [14/Aug/2013:09:37:53 -0300] cupsdReadClient: 12 POST / HTTP/1.1
D [14/Aug/2013:09:37:53 -0300] cupsdSetBusyState: Active clients
D [14/Aug/2013:09:37:53 -0300] cupsdAuthorize: No authentication data provided.
D [14/Aug/2013:09:37:53 -0300] cupsdReadClient: 12 1.1 Get-Printer-Attributes 1
D [14/Aug/2013:09:37:53 -0300] Get-Printer-Attributes ipp://localhost/printers/HL2130
D [14/Aug/2013:09:37:53 -0300] Returning IPP successful-ok for Get-Printer-Attributes
(ipp://localhost/printers/HL2130) from localhost
D [14/Aug/2013:09:37:53 -0300] cupsdSetBusyState: Not busy
D [14/Aug/2013:09:37:53 -0300] cupsdReadClient: 12 POST /printers/HL2130 HTTP/1.1
D [14/Aug/2013:09:37:53 -0300] cupsdSetBusyState: Active clients
D [14/Aug/2013:09:37:53 -0300] cupsdAuthorize: No authentication data provided.
D [14/Aug/2013:09:37:53 -0300] cupsdReadClient: 12 1.1 Create-Job 1
D [14/Aug/2013:09:37:53 -0300] Create-Job ipp://localhost/printers/HL2130
D [14/Aug/2013:09:37:53 -0300] cupsdMarkDirty(----J-)
D [14/Aug/2013:09:37:53 -0300] cupsdSetBusyState: Active clients and dirty files
D [14/Aug/2013:09:37:53 -0300] add_job: requesting-user-name="myuser"
D [14/Aug/2013:09:37:53 -0300] Adding default job-sheets values "none,none"...
I [14/Aug/2013:09:37:53 -0300] [Job 4471] Adding start banner page "none".
D [14/Aug/2013:09:37:53 -0300] Discarding unused job-created event...
I [14/Aug/2013:09:37:53 -0300] [Job 4471] Queued on "HL2130" by "myuser".
D [14/Aug/2013:09:37:53 -0300] Returning IPP successful-ok for Create-Job (ipp://localhost/printers/HL2130)
from localhost
D [14/Aug/2013:09:37:53 -0300] cupsdSetBusyState: Dirty files
D [14/Aug/2013:09:37:53 -0300] cupsdReadClient: 12 POST /jobs/4471 HTTP/1.1
D [14/Aug/2013:09:37:53 -0300] cupsdSetBusyState: Active clients and dirty files
D [14/Aug/2013:09:37:53 -0300] cupsdAuthorize: No authentication data provided.
D [14/Aug/2013:09:37:53 -0300] cupsdReadClient: 12 1.1 Send-Document 1
D [14/Aug/2013:09:37:53 -0300] Send-Document ipp://localhost/jobs/4471
D [14/Aug/2013:09:37:53 -0300] cupsdIsAuthorized: requesting-user-name="myuser"
D [14/Aug/2013:09:37:53 -0300] Send-Document client-error-bad-request: ¡¿¡No hay archivo!?!
E [14/Aug/2013:09:37:53 -0300] Returning IPP client-error-bad-request for Send-Document
(ipp://localhost/jobs/4471) from localhost
D [14/Aug/2013:09:37:53 -0300] cupsdSetBusyState: Dirty files
D [14/Aug/2013:09:37:53 -0300] cupsdReadClient: 12 WAITING Closing on EOF
D [14/Aug/2013:09:37:53 -0300] cupsdCloseClient: 12
D [14/Aug/2013:09:38:18 -0300] Report: clients=0
D [14/Aug/2013:09:38:18 -0300] Report: jobs=499

There's a part of the log which is in spanish:

Code:

D [14/Aug/2013:09:37:53 -0300] Send-Document client-error-bad-request: ¡¿¡No hay archivo!?!

It means "empty print file". I tried using IPP protocol instead of legacy LPR/LPD, and received a similar error (empty-print-file) in server's logs.

I did the test of sending a print job to the same network printer (HL2130) from a win box, using LPR too. I it printed fine...
If it's worth, I could post the log part when sending job from win through LPD.
So I tend to think it could be a problem related with cups. At my debian workstation I'm running cups 1.4.4-7

Any tips or piece of advice to help me solve this issue? Thanks in advance.

matiasar · 08-14-2013, 09:38 AM

Well, I tried sending job from a wheezy box (with cups 1.5.x) and it worked!
So I'll see if I can upgrade cups or my squeeze box.

I'll send my final results and mark post solved soon as possible.

Regards!

matiasar · 08-16-2013, 12:02 PM

Well, I think I finally found the reason.
I updated cups to 1.5.3-5 but I'm affraid that wasn't the reason.
I think after some packages upgrade some files from Brother device driver, filters, etc were missing.

File missing were in these directories:

Code:

/usr/local/Brother/Printer/HL2130/inf/
/usr/local/Brother/Printer/HL2130/lpd/

Those files are within package hl2132lpr-2.1.0-1.i386.deb, distributed by Brother.

Code:

dpkg -c hl2132lpr-2.1.0-1.i386.deb 
drwxr-xr-x root/root         0 2010-05-24 00:12 ./
drwxr-xr-x root/root         0 2010-05-24 00:12 ./var/
drwxr-xr-x root/root         0 2010-05-24 00:12 ./var/spool/
drwxr-xr-x root/root         0 2010-05-24 00:12 ./var/spool/lpd/
drw-r--r-- root/root         0 2010-05-24 00:09 ./var/spool/lpd/HL2132/
drwxr-xr-x root/root         0 2010-05-24 00:12 ./usr/
drwxr-xr-x root/root         0 2010-05-24 00:12 ./usr/local/
drwxr-xr-x root/root         0 2010-05-24 00:12 ./usr/local/Brother/
drwxr-xr-x root/root         0 2010-05-24 00:12 ./usr/local/Brother/Printer/
drwxr-xr-x root/root         0 2010-05-24 00:12 ./usr/local/Brother/Printer/HL2132/
drwxr-xr-x root/root         0 2010-05-24 00:12 ./usr/local/Brother/Printer/HL2132/inf/
-rwxr-xr-x root/root      1667 2010-05-24 00:09 ./usr/local/Brother/Printer/HL2132/inf/setupPrintcap2
-rwxr-xr-x root/root      7976 2010-05-24 00:09 ./usr/local/Brother/Printer/HL2132/inf/braddprinter
-rwxr-xr-x root/root     29806 2010-05-24 00:09 ./usr/local/Brother/Printer/HL2132/inf/brprintconflsr3
-rw-rw-rw- root/root       165 2010-05-24 00:09 ./usr/local/Brother/Printer/HL2132/inf/brHL2132rc
-rw-r--r-- root/root       665 2010-05-24 00:09 ./usr/local/Brother/Printer/HL2132/inf/brHL2132func
-rw-r--r-- root/root       215 2010-05-24 00:09 ./usr/local/Brother/Printer/HL2132/inf/paperinf
drwxr-xr-x root/root         0 2010-05-24 00:12 ./usr/local/Brother/Printer/HL2132/lpd/
-rwxr-xr-x root/root     55723 2010-05-24 00:09 ./usr/local/Brother/Printer/HL2132/lpd/rawtobr3
-rwxr-xr-x root/root      3150 2010-05-24 00:09 ./usr/local/Brother/Printer/HL2132/lpd/psconvert2
-rwxr-xr-x root/root      8144 2010-05-24 00:09 ./usr/local/Brother/Printer/HL2132/lpd/filterHL2132
drwxr-xr-x root/root         0 2010-05-24 00:12 ./usr/share/
drwxr-xr-x root/root         0 2010-05-24 00:12 ./usr/share/doc/

I think those files were really needed in order to print over network using LPr or IPP.
So, after reinstalling that packages printer started printing just fine.