printing dialog problem (slow) 2.2.1a

Thu Sep 13 23:56:03 GMT 2001

hi,

i am using samba 2.2.1.a
"all" is working fine:
file sharing
uploading and downloading printer drivers is working (very nice!!)
printing is working

-- but:
1.) if i open a document (in my case ms-word 97) it takes up to 15
seconds for opening and displaying
2.) also when the file is open and i try to change the standard printer
it takes very long (up to 5 minutes) till the printing dialog is
available again  (this happens to autocad docs too)
3.) up to 5 minutes it can take if i open a document with fonts, which
are not installed on the system - i changed the fonts to available fonts
and the speed changed too.
4.) opening the printer dialog - double-clicking a printer on the
printer control panel it take up to 2 minutes to display the properties.

that are my problems, but i think they have the same reason!!

i tried the tests with files stored locally (on C:) and on a network
drive
i noticed a abnormally high load on the smbd process of the user
changing the printer

that are my problems, but i think they have the same reason!!

i have an other hint:
when i connect printer with samba 2.0.10 then in the printer dialog on
the connection (german anschlüsse) tab there is an add. connection over
lan-manager visible and checked on.
if a printer is connected over version 2.2.0 or 2.2.1a then there is no
add. connection available and checked!
i guess that can be a reason too.

now i am running samba 2.0.10 again and its working fine - no
performance problems!!
i will change to the version 2.2.1a when the problem is solved.

hope, that somebody can help me!

thanks,
philipp

this is my config:
suse 7.0 on compaq proliant ml350 256mb 100gb raid-5 (smart 431)
raiserfs
samba 2.2.1a
ldap - server for userauthentication (PAM)
nt4 - server as PDC and BDC for password
there is one netbios name for file sharing and one alias for print
serving
clients are on win2k (with sr1 and without sr) - both with the same
problem

there is a part of my conf-files and a part of the debug level 3 log

smb.conf:
[global]
        workgroup = XXX
        netbios name = HMSERVER01
        netbios aliases = hmprint01
        security = SERVER
        encrypt passwords = Yes
        map to guest = Bad User
        password server = HMSERVER02 HMSERVER04
        username map = /usr/local/samba/lib/usermap
        debug level = 1
        min protocol = NT1
        name resolve order = wins hosts lmhosts bcast
        time server = Yes
        change notify timeout = 300
        os level = 2
        wins server = 10.101.1.19
        hosts allow = 10.101. 10.100. 193.46.46. 193.81.207. 10.254.103.

        preserve case = No
        log file = /var/log/lisec/log.smb
        include = /usr/local/samba/lib/smb.conf.%L

smb.conf.hmserver01

[global]
        server string = File-Server (%L)

[print$]
       path=/work/printers
       guest ok = yes
       read only = yes
       browseable = no
       write list = @admin
       printer admin = @admin
       create mask = 0775
       directory mask = 0775
       locking = no

smb.conf.hmprint01

[global]
        server string = Printserver (%L)
        lpq cache time = 300

[lp01]
        comment = hm verkauf 2.og - hp laserjet 8100n
        printer = lp01
        printable = Yes
        path = /tmp
        create mask = 0700
        guest ok = yes
        printer admin = @admin
        printer driver location = \\hmserver01\print$

[lp03]
        comment = hm service 2.og - hp laserjet 8000n
        printer = lp03
        copy = lp01

..... and so on ...

smb.log - level 3 - hope its the right staff i sent you

  Got API command 0x26 on pipe "spoolss" (pnum 7026)free_pipe_context:
destroying talloc pool of
size 0
[2001/09/12 13:21:33, 3] rpc_server/srv_pipe.c:api_pipe_request(1163)
  Doing \PIPE\spoolss
[2001/09/12 13:21:33, 3] rpc_server/srv_pipe.c:api_rpcTNP(1195)
  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
  checking name: \\hmprint01\lp13
[2001/09/12 13:21:33, 3]
rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(373)
  Setting printer type=\\hmprint01\lp13
[2001/09/12 13:21:33, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(417)
  free_pipe_context: destroying talloc pool of size 5754
[2001/09/12 13:21:33, 3] smbd/process.c:process_smb(837)
  Transaction 85 of length 4236
[2001/09/12 13:21:33, 3] smbd/process.c:switch_message(650)
  switch message SMBtrans (pid 12955)
[2001/09/12 13:21:33, 3] smbd/ipc.c:reply_trans(485)
  trans <\PIPE\> data=4156 params=0 setup=2
[2001/09/12 13:21:33, 3] smbd/ipc.c:named_pipe(336)
  named pipe command on <> name
[2001/09/12 13:21:33, 3] smbd/ipc.c:api_fd_reply(298)
  Got API command 0x26 on pipe "spoolss" (pnum 7026)free_pipe_context:
destroying talloc pool of
size 0
[2001/09/12 13:21:33, 3] rpc_server/srv_pipe.c:api_pipe_request(1163)
  Doing \PIPE\spoolss
[2001/09/12 13:21:33, 3] rpc_server/srv_pipe.c:api_rpcTNP(1195)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2001/09/12 13:21:33, 3] printing/printing.c:print_cache_expired(758)
  print cache expired
[2001/09/12 13:21:33, 3] printing/print_generic.c:print_run_command(90)
  Running the command `lpq -Plp13' gave 0
[2001/09/12 13:21:33, 3] printing/printing.c:print_queue_update(392)
  0 jobs in queue for lp13
[2001/09/12 13:21:33, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(417)
  free_pipe_context: destroying talloc pool of size 8602
[2001/09/12 13:21:33, 3] smbd/process.c:process_smb(837)
  Transaction 86 of length 63
[2001/09/12 13:21:33, 3] smbd/process.c:switch_message(650)
  switch message SMBreadX (pid 12955)
[2001/09/12 13:21:33, 3] smbd/pipes.c:reply_pipe_read_and_X(241)
  readX-IPC pnum=7026 min=3112 max=3112 nread=3112
[2001/09/12 13:21:33, 3] smbd/process.c:process_smb(837)
  Transaction 87 of length 96
[2001/09/12 13:21:33, 3] smbd/process.c:switch_message(650)
  switch message SMBntcreateX (pid 12955)
[2001/09/12 13:21:33, 3] smbd/nttrans.c:nt_open_pipe(621)
  nt_open_pipe: Known pipe spoolss opening.
[2001/09/12 13:21:33, 3] smbd/process.c:process_smb(837)
  Transaction 88 of length 124
[2001/09/12 13:21:33, 3] smbd/process.c:switch_message(650)
  switch message SMBwrite (pid 12955)
[2001/09/12 13:21:33, 3] rpc_server/srv_pipe.c:api_pipe_bind_req(763)
  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2001/09/12 13:21:33, 3] smbd/pipes.c:reply_pipe_write(143)
  write-IPC pnum=7027 nwritten=72
[2001/09/12 13:21:33, 3] smbd/process.c:process_smb(837)
  Transaction 89 of length 63
[2001/09/12 13:21:33, 3] smbd/process.c:switch_message(650)
  switch message SMBreadX (pid 12955)
[2001/09/12 13:21:33, 3] smbd/pipes.c:reply_pipe_read_and_X(241)
  readX-IPC pnum=7027 min=1024 max=1024 nread=68
[2001/09/12 13:21:33, 3] smbd/process.c:process_smb(837)
  Transaction 90 of length 274
[2001/09/12 13:21:33, 3] smbd/process.c:switch_message(650)
  switch message SMBtrans (pid 12955)
[2001/09/12 13:21:33, 3] smbd/ipc.c:reply_trans(485)
  trans <\PIPE\> data=194 params=0 setup=2
[2001/09/12 13:21:33, 3] smbd/ipc.c:named_pipe(336)
  named pipe command on <> name
[2001/09/12 13:21:33, 3] smbd/ipc.c:api_fd_reply(298)
  Got API command 0x26 on pipe "spoolss" (pnum 7027)free_pipe_context:
destroying talloc pool of
size 0
[2001/09/12 13:21:33, 3] rpc_server/srv_pipe.c:api_pipe_request(1163)
  Doing \PIPE\spoolss
[2001/09/12 13:21:33, 3] rpc_server/srv_pipe.c:api_rpcTNP(1195)
  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
  checking name: \\hmprint01\lp13
[2001/09/12 13:21:33, 3]
rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(373)
  Setting printer type=\\hmprint01\lp13
[2001/09/12 13:21:34, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(417)
  free_pipe_context: destroying talloc pool of size 5754
[2001/09/12 13:21:34, 3] smbd/process.c:process_smb(837)
  Transaction 91 of length 4236
[2001/09/12 13:21:34, 3] smbd/process.c:switch_message(650)
  switch message SMBtrans (pid 12955)
[2001/09/12 13:21:34, 3] smbd/ipc.c:reply_trans(485)
  trans <\PIPE\> data=4156 params=0 setup=2
[2001/09/12 13:21:34, 3] smbd/ipc.c:named_pipe(336)
  named pipe command on <> name
[2001/09/12 13:21:34, 3] smbd/ipc.c:api_fd_reply(298)
  Got API command 0x26 on pipe "spoolss" (pnum 7027)free_pipe_context:
destroying talloc pool of
size 0
[2001/09/12 13:21:34, 3] rpc_server/srv_pipe.c:api_pipe_request(1163)
  Doing \PIPE\spoolss
[2001/09/12 13:21:34, 3] rpc_server/srv_pipe.c:api_rpcTNP(1195)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2001/09/12 13:21:34, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(417)
  free_pipe_context: destroying talloc pool of size 8602
[2001/09/12 13:21:34, 3] smbd/process.c:process_smb(837)
  Transaction 92 of length 63
[2001/09/12 13:21:34, 3] smbd/process.c:switch_message(650)
  switch message SMBreadX (pid 12955)
[2001/09/12 13:21:34, 3] smbd/pipes.c:reply_pipe_read_and_X(241)
  readX-IPC pnum=7027 min=3112 max=3112 nread=3112
[2001/09/12 13:21:34, 3] smbd/process.c:process_smb(837)
  Transaction 93 of length 4236
[2001/09/12 13:21:34, 3] smbd/process.c:switch_message(650)
  switch message SMBtrans (pid 12955)
[2001/09/12 13:21:34, 3] smbd/ipc.c:reply_trans(485)
  trans <\PIPE\> data=4156 params=0 setup=2
[2001/09/12 13:21:34, 3] smbd/ipc.c:named_pipe(336)
  named pipe command on <> name
[2001/09/12 13:21:34, 3] smbd/ipc.c:api_fd_reply(298)
  Got API command 0x26 on pipe "spoolss" (pnum 7027)free_pipe_context:
destroying talloc pool of
size 0
[2001/09/12 13:21:34, 3] rpc_server/srv_pipe.c:api_pipe_request(1163)
  Doing \PIPE\spoolss
[2001/09/12 13:21:34, 3] rpc_server/srv_pipe.c:api_rpcTNP(1195)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2001/09/12 13:21:34, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(417)
  free_pipe_context: destroying talloc pool of size 8602
[2001/09/12 13:21:34, 3] smbd/process.c:process_smb(837)
  Transaction 94 of length 124
[2001/09/12 13:21:34, 3] smbd/process.c:switch_message(650)
  switch message SMBtrans (pid 12955)
[2001/09/12 13:21:34, 3] smbd/ipc.c:reply_trans(485)
  trans <\PIPE\> data=44 params=0 setup=2
[2001/09/12 13:21:34, 3] smbd/ipc.c:named_pipe(336)
  named pipe command on <> name
[2001/09/12 13:21:34, 3] smbd/ipc.c:api_fd_reply(298)
  Got API command 0x26 on pipe "spoolss" (pnum 7027)free_pipe_context:
destroying talloc pool of
size 0
[2001/09/12 13:21:34, 3] rpc_server/srv_pipe.c:api_pipe_request(1163)
  Doing \PIPE\spoolss
[2001/09/12 13:21:34, 3] rpc_server/srv_pipe.c:api_rpcTNP(1195)
  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
[2001/09/12 13:21:34, 3] rpc_server/srv_lsa_hnd.c:close_policy_hnd(186)
  Closed policy
[2001/09/12 13:21:34, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(417)
  free_pipe_context: destroying talloc pool of size 0
[2001/09/12 13:21:34, 3] smbd/process.c:process_smb(837)
  Transaction 95 of length 45

.... there is many more but the mailbox is only for 40kb ....