Printer queue problem (LPRng 3.4.2, Samba 1.9.18p4, Solaris 2.5.1/2.6)

Wed Apr 15 17:44:00 GMT 1998

I've got the same problem with SysV printing, it goes wrong every time, if
there is more then 12 jobs in the printer queue. I think that Jeremy
Allison is working on a patch (the first one didn't fix it), until it's
fixed you can use something like this in your smb.conf:

	lpq command = lpq -P%p | head -12

People on Windows clients can only see the first 12 jobs, but it
saves the server...

BTW, There is no problem with NT 4.0 clients.

Best,

Steen Jensen                                 System & net administrator
-- 
Aalborg University			     e-mail: sj at kom.auc.dk
Institute of Electronic Systems
Department of Communication Technology

On Tue, 14 Apr 1998, Owen L. Magee wrote:

mageeol> I've been having a problem with Win95 clients watching a printer
mageeol> queue on the Samba server (called labserv2).  labserv2 is a
mageeol> Solaris 2.5.1 machine running samba 1.9.18p4.  The printing
mageeol> system is LPRng 3.4.2. 
mageeol> 
mageeol> A user can watch the printer queue from a Win95 client machine. 
mageeol> However, when the printer queue gets backed up (usually about 12
mageeol> jobs), the queue window locks.  The corresponding smbd process on
mageeol> labserv2 starts sucking up lots of CPU time.  Once you have about
mageeol> 10 clients doing this...well, things start slowing down pretty
mageeol> badly (although, to samba's credit, it is still very usable at a
mageeol> load average of 10 :) . 
mageeol> 
mageeol> I managed to reproduce this behavior on labserv1, which is
mageeol> currently unused.  labserv1 runs Solaris 2.6, samba 1.9.18p4, and
mageeol> LPRng 3.4.5.  LPRng is set up so that the printers are served
mageeol> remotely from labserv2.  Using a debug level of 10 and a single
mageeol> client watching a busy printer queue, I was able to capture
mageeol> debugging information.  At the end of this post is the debugging
mageeol> info that just loops over and over again, as fast as it can, on
mageeol> the spazzed smbd process. 
mageeol> 
mageeol> As far as I know, I never had this problem with samba 1.9.17p5
mageeol> (and yes, we were using LPRng at the time).  This only started
mageeol> happening when I upgraded to samba 1.9.18. 
mageeol> 
mageeol> Does anybody have any clues?
mageeol> 
mageeol> Owen Magee
mageeol> mageeol at mail.auburn.edu
mageeol> 
mageeol> -------------------------------------------------------------------------
mageeol> 
mageeol> got message type 0x0 of len 0x83
mageeol> 04/03/1998 10:03:43 Transaction 249 of length 135
mageeol> size=131
mageeol> smb_com=0x25
mageeol> smb_rcls=0
mageeol> smb_reh=0
mageeol> smb_err=0
mageeol> smb_flg=0
mageeol> smb_flg2=0
mageeol> smb_tid=68
mageeol> smb_pid=7487
mageeol> smb_uid=100
mageeol> smb_mid=25730
mageeol> smt_wct=14
mageeol> smb_vwv[0]=55 (0x37)
mageeol> smb_vwv[1]=0 (0x0)
mageeol> smb_vwv[2]=6 (0x6)
mageeol> smb_vwv[3]=0 (0x0)
mageeol> smb_vwv[4]=0 (0x0)
mageeol> smb_vwv[5]=0 (0x0)
mageeol> smb_vwv[6]=5000 (0x1388)
mageeol> smb_vwv[7]=0 (0x0)
mageeol> smb_vwv[8]=0 (0x0)
mageeol> smb_vwv[9]=55 (0x37)
mageeol> smb_vwv[10]=76 (0x4C)
mageeol> smb_vwv[11]=0 (0x0)
mageeol> smb_vwv[12]=0 (0x0)
mageeol> smb_vwv[13]=0 (0x0)
mageeol> smb_bcc=68
mageeol> [000] 5C 50 49 50 45 5C 4C 41  4E 4D 41 4E 00 46 00 7A  \PIPE\LA NMAN.F.z
mageeol> [010] 57 72 4C 68 00 42 31 33  42 57 57 57 7A 7A 7A 7A  WrLh.B13 BWWWzzzz
mageeol> [020] 7A 57 4E 00 50 43 4C 41  42 42 55 53 00 02 00 00  zWN.PCLA BBUS....
mageeol> [030] 00 57 42 32 31 42 42 31  36 42 31 30 7A 57 57 7A  .WB21BB1 6B10zWWz
mageeol> [040] 44 44 7A 00                                       DDz. 
mageeol> switch message SMBtrans (pid 3880)
mageeol> Skipping become_user - already user
mageeol> trans <\PIPE\LANMAN> data=0 params=55 setup=0
mageeol> calling named_pipe
mageeol> named pipe command on <LANMAN> name
mageeol> Got API command 70 of form <zWrLh> <B13BWWWzzzzzWN>
mageeol> (tdscnt=0,tpscnt=55,mdrcnt=0
mageeol> ,mprcnt=6)
mageeol> Doing DosPrintQGetInfo
mageeol> PrintQueue uLevel=2 name=PCLABBUS
mageeol> Using cached lpq output
mageeol> QUEUE2: Printer: pclabbus at labserv2 
mageeol> 
mageeol> QUEUE2:  Queue: 13 printable jobs
mageeol> 
mageeol> QUEUE2:  Server: pid 14995 active
mageeol> 
mageeol> QUEUE2:  Unspooler: pid 21532 active
mageeol> 
mageeol> QUEUE2:  Status: printed all 160850 bytes at 10:03:41
mageeol> 
mageeol> QUEUE2:  Filter_status: ifhp NOTICE - printer status code 10023 -
mageeol> processing job
mageeol>  at Apr  3 10:03:36 
mageeol> 
mageeol> QUEUE2:  Rank   Owner/ID                   Class Job  Files
mageeol> Size T
mageeol> ime    
mageeol> 
mageeol> QUEUE2: active  kingrod at labserv2+198           A  198 busine.a004i.
mageeol> 160850 0
mageeol> 9:57:55
mageeol> 
mageeol> Time reported for job 198 is Fri Apr  3 09:57:55 1998
mageeol> QUEUE2: 2       kingrod at labserv2+218           A  218 busine.a004i.
mageeol> 108888 0
mageeol> 9:58:02
mageeol> 
mageeol> Time reported for job 218 is Fri Apr  3 09:58:02 1998
mageeol> QUEUE2: 3       hardidl at labserv2+821           A  821 busine.a0050n
mageeol> 98349 1
mageeol> 0:00:25
mageeol> 
mageeol> Time reported for job 821 is Fri Apr  3 10:00:25 1998
mageeol> QUEUE2: 4       brownc6 at labserv2+967           A  967 busine.a0051U
mageeol> 98460 1
mageeol> 0:01:11
mageeol> 
mageeol> Time reported for job 967 is Fri Apr  3 10:01:11 1998
mageeol> QUEUE2: 5       adamsj5 at labserv2+993           A  993 busine.a004pz
mageeol> 98819 1
mageeol> 0:01:18
mageeol> 
mageeol> Time reported for job 993 is Fri Apr  3 10:01:18 1998
mageeol> QUEUE2: 6       brownc6 at labserv2+054           A   54 busine.a0051U
mageeol> 160483 1
mageeol> 0:01:30
mageeol> 
mageeol> Time reported for job 54 is Fri Apr  3 10:01:30 1998
mageeol> QUEUE2: 7       mageeol at labserv1+903           A  903 .bash_profile
mageeol> 2959 10
mageeol> :03:02
mageeol> 
mageeol> Time reported for job 903 is Fri Apr  3 10:03:02 1998
mageeol> QUEUE2: 8       mageeol at labserv1+904           A  904 .bash_profile
mageeol> 2959 10
mageeol> :03:04
mageeol> 
mageeol> Time reported for job 904 is Fri Apr  3 10:03:04 1998
mageeol> QUEUE2: 9       mageeol at labserv1+905           A  905 .bash_profile
mageeol> 2959 10
mageeol> :03:06
mageeol> 
mageeol> Time reported for job 905 is Fri Apr  3 10:03:06 1998
mageeol> QUEUE2: 10      mageeol at labserv1+908           A  908 .bash_profile
mageeol> 2959 10
mageeol> :03:21
mageeol> 
mageeol> Time reported for job 908 is Fri Apr  3 10:03:21 1998
mageeol> QUEUE2: 11      mageeol at labserv1+909           A  909 .bash_profile
mageeol> 2959 10
mageeol> :03:22
mageeol> 
mageeol> Time reported for job 909 is Fri Apr  3 10:03:22 1998
mageeol> QUEUE2: 12      mageeol at labserv1+914           A  914 .bash_profile
mageeol> 2959 10
mageeol> :03:39
mageeol> 
mageeol> Time reported for job 914 is Fri Apr  3 10:03:39 1998
mageeol> QUEUE2: 13      mageeol at labserv1+915           A  915 .bash_profile
mageeol> 2959 10
mageeol> :03:40
mageeol> 
mageeol> Time reported for job 915 is Fri Apr  3 10:03:40 1998
mageeol> printqgetinfo: errorcode 2123
mageeol> size=63
mageeol> smb_com=0x25
mageeol> smb_rcls=0
mageeol> smb_reh=0
mageeol> smb_err=0
mageeol> smb_flg=128
mageeol> smb_flg2=1
mageeol> smb_tid=68
mageeol> smb_pid=7487
mageeol> smb_uid=100
mageeol> smb_mid=25730
mageeol> smt_wct=10
mageeol> smb_vwv[0]=6 (0x6)
mageeol> smb_vwv[1]=0 (0x0)
mageeol> smb_vwv[2]=0 (0x0)
mageeol> smb_vwv[3]=6 (0x6)
mageeol> smb_vwv[4]=55 (0x37)
mageeol> smb_vwv[5]=0 (0x0)
mageeol> smb_vwv[6]=0 (0x0)
mageeol> smb_vwv[7]=63 (0x3F)
mageeol> smb_vwv[8]=0 (0x0)
mageeol> smb_vwv[9]=0 (0x0)
mageeol> smb_bcc=8
mageeol> [000] 4B 08 00 00 00 00 00 00                           K....... 
mageeol> write_socket(6,67)
mageeol> write_socket(6,67) wrote 67
mageeol> 
mageeol> 
mageeol> ------------------------------
mageeol> 