samba-3.0alpha23 gives PANIC error on Printer Open

Kristis Makris kristis.makris at datasoft.com
Thu Apr 10 20:48:19 GMT 2003


Hello,

I am using samba-3.0alpha23 compiled from source. I get an INTERNAL
ERROR and a PANIC error message in the
/var/log/samba/<somemachinename>.log, when I attempt to "Open" a printer
out of a list of printers configured in a Windows2000+SP3 workstation. I
have configured smb.conf to authenticate against an OpenLDAP server
provided by RH7.3's openldap-servers-2.0.27-2.7.3 package.

I need to mention that in the list of printers configure for the Win2K
machine, the entry for the particular printer I am trying to open shows
up with the comment "Access denied, unable to connect".


Below is the complete error message I view in <somemachinename>.log
_only_ as a result of the Printer->Open operation. smb.conf is
configured with "log level = 2". I can provide log files at different
levels if needed.

Thanks for any help,
Kristis

+++++++++++++++++++++++++++++++++++++++

[2003/04/10 13:36:00, 3] smbd/process.c:process_smb(882)
  Transaction 11 of length 286
[2003/04/10 13:36:00, 3] smbd/process.c:switch_message(676)
  switch message SMBtrans (pid 28538)
[2003/04/10 13:36:00, 3] smbd/ipc.c:reply_trans(511)
  trans <\PIPE\> data=198 params=0 setup=2
[2003/04/10 13:36:00, 3] smbd/ipc.c:named_pipe(326)
  named pipe command on <> name
[2003/04/10 13:36:00, 3] smbd/ipc.c:api_fd_reply(288)
  Got API command 0x26 on pipe "spoolss" (pnum 72ee)free_pipe_context:
destroying talloc pool of size 0
[2003/04/10 13:36:00, 3] rpc_server/srv_pipe.c:api_pipe_request(1282)
  Doing \PIPE\spoolss
[2003/04/10 13:36:00, 3] rpc_server/srv_pipe.c:api_rpcTNP(1345)
  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
  checking name: \\N_prtsrv\hp4050
[2003/04/10 13:36:00, 3]
rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(414)
  Setting printer type=\\N_prtsrv\hp4050
[2003/04/10 13:36:00, 3] lib/util_seaccess.c:se_access_check(267)
[2003/04/10 13:36:00, 3] lib/util_seaccess.c:se_access_check(268)
  se_access_check: user sid is
S-1-5-21-1264390313-3394352946-2369289212-11060
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11001
  se_access_check: also S-1-1-0
  se_access_check: also S-1-5-2
  se_access_check: also S-1-5-11
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11009
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11011
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11033
[2003/04/10 13:36:00, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(544)
  free_pipe_context: destroying talloc pool of size 76
[2003/04/10 13:36:00, 3] smbd/process.c:process_smb(882)
  Transaction 12 of length 4244
[2003/04/10 13:36:00, 3] smbd/process.c:switch_message(676)
  switch message SMBtrans (pid 28538)
[2003/04/10 13:36:00, 3] smbd/ipc.c:reply_trans(511)
  trans <\PIPE\> data=4156 params=0 setup=2
[2003/04/10 13:36:00, 3] smbd/ipc.c:named_pipe(326)
  named pipe command on <> name
[2003/04/10 13:36:00, 3] smbd/ipc.c:api_fd_reply(288)
  Got API command 0x26 on pipe "spoolss" (pnum 72ee)free_pipe_context:
destroying talloc pool of size 0
[2003/04/10 13:36:00, 3] rpc_server/srv_pipe.c:api_pipe_request(1282)
  Doing \PIPE\spoolss
[2003/04/10 13:36:00, 3] rpc_server/srv_pipe.c:api_rpcTNP(1345)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2003/04/10 13:36:00, 3] smbd/sec_ctx.c:push_sec_ctx(255)
  push_sec_ctx(5030, 5000) : sec_ctx_stack_ndx = 1
[2003/04/10 13:36:00, 3] smbd/uid.c:push_conn_ctx(286)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
[2003/04/10 13:36:00, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/04/10 13:36:00, 3] smbd/sec_ctx.c:pop_sec_ctx(385)
  pop_sec_ctx (5030, 5000) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:00, 3] printing/printing.c:print_cache_expired(1649)
  print cache expired for queue hp4050 (last_qscan_time = 1050006897,
time now = 1050006960, qcachetime = 10)
[2003/04/10 13:36:00, 3] printing/printing.c:print_queue_update(959)
  0 jobs in queue for hp4050
[2003/04/10 13:36:00, 0] lib/fault.c:fault_report(36)
  ===============================================================
[2003/04/10 13:36:00, 0] lib/fault.c:fault_report(37)
  INTERNAL ERROR: Signal 11 in pid 28538 (3.0alpha23)
  Please read the file BUGS.txt in the distribution
[2003/04/10 13:36:00, 0] lib/fault.c:fault_report(39)
  ===============================================================
[2003/04/10 13:36:00, 0] lib/util.c:smb_panic(1429)
  PANIC: internal error
[2003/04/10 13:36:01, 3] auth/auth.c:check_ntlm_password(189)
  check_ntlm_password:  Checking password for unmapped user
[DS2]\[makris_k]@[LEONIDAS] with the new password interface
[2003/04/10 13:36:01, 3] auth/auth.c:check_ntlm_password(192)
  check_ntlm_password:  mapped user is: [DS2]\[makris_k]@[LEONIDAS]
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:push_sec_ctx(255)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 3] smbd/uid.c:push_conn_ctx(286)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 2] passdb/pdb_ldap.c:ldapsam_search_one_user(648)
  ldapsam_search_one_user: searching
for:[(&(uid=makris_k)(objectclass=sambaAccount))]
[2003/04/10 13:36:01, 2] passdb/pdb_ldap.c:ldapsam_open_connection(262)
  ldapsam_open_connection: connection opened
[2003/04/10 13:36:01, 2] passdb/pdb_ldap.c:ldapsam_connect_system(421)
  ldap_connect_system: succesful connection to the LDAP server
[2003/04/10 13:36:01, 2] passdb/pdb_ldap.c:init_sam_from_ldap(1059)
  Entry found for user: makris_k
[2003/04/10 13:36:01, 2]
passdb/pdb_ldap.c:ldapsam_search_one_group(2187)
  ldapsam_search_one_group: searching
for:[(&(objectClass=sambaGroupMapping)(gidNumber=5000))]
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:pop_sec_ctx(385)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:push_sec_ctx(255)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 3] smbd/uid.c:push_conn_ctx(286)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 2]
passdb/pdb_ldap.c:ldapsam_search_one_group(2187)
  ldapsam_search_one_group: searching
for:[(&(objectClass=sambaGroupMapping)(gidNumber=5000))]
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:pop_sec_ctx(385)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:push_sec_ctx(255)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 3] smbd/uid.c:push_conn_ctx(286)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 2]
passdb/pdb_ldap.c:ldapsam_search_one_group(2187)
  ldapsam_search_one_group: searching
for:[(&(objectClass=sambaGroupMapping)(gidNumber=5004))]
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:pop_sec_ctx(385)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:push_sec_ctx(255)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 3] smbd/uid.c:push_conn_ctx(286)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 2]
passdb/pdb_ldap.c:ldapsam_search_one_group(2187)
  ldapsam_search_one_group: searching
for:[(&(objectClass=sambaGroupMapping)(gidNumber=5005))]
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:pop_sec_ctx(385)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:push_sec_ctx(255)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 3] smbd/uid.c:push_conn_ctx(286)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 2]
passdb/pdb_ldap.c:ldapsam_search_one_group(2187)
  ldapsam_search_one_group: searching
for:[(&(objectClass=sambaGroupMapping)(gidNumber=5016))]
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:pop_sec_ctx(385)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] auth/auth.c:check_ntlm_password(226)
  check_ntlm_password: sam authentication for user [makris_k] suceeded
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:push_sec_ctx(255)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 3] smbd/uid.c:push_conn_ctx(286)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:pop_sec_ctx(385)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 2] auth/auth.c:check_ntlm_password(263)
  check_ntlm_password:  authentication for user [makris_k] -> [makris_k]
-> [makris_k] suceeded
[2003/04/10 13:36:01, 3] smbd/password.c:register_vuid(198)
  User name: makris_k	Real name: Kristis Makris
[2003/04/10 13:36:01, 3] smbd/password.c:register_vuid(216)
  UNIX uid 5030 is UNIX user makris_k, and will be vuid 100
[2003/04/10 13:36:01, 3] smbd/password.c:register_vuid(231)
  Adding/updating homes service for user 'makris_k' using home
direcotry: '/home/makris_k'
[2003/04/10 13:36:01, 3] smbd/process.c:process_smb(882)
  Transaction 3 of length 86
[2003/04/10 13:36:01, 3] smbd/process.c:switch_message(676)
  switch message SMBtconX (pid 28540)
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/service.c:make_connection_snum(533)
  Connect path is '/tmp' for service [IPC$]
[2003/04/10 13:36:01, 3] lib/util_seaccess.c:se_access_check(267)
[2003/04/10 13:36:01, 3] lib/util_seaccess.c:se_access_check(268)
  se_access_check: user sid is
S-1-5-21-1264390313-3394352946-2369289212-11060
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11001
  se_access_check: also S-1-1-0
  se_access_check: also S-1-5-2
  se_access_check: also S-1-5-11
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11009
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11011
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11033
[2003/04/10 13:36:01, 3] smbd/vfs.c:vfs_init_default(136)
  Initialising default vfs hooks
[2003/04/10 13:36:01, 3] lib/util_seaccess.c:se_access_check(267)
[2003/04/10 13:36:01, 3] lib/util_seaccess.c:se_access_check(268)
  se_access_check: user sid is
S-1-5-21-1264390313-3394352946-2369289212-11060
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11001
  se_access_check: also S-1-1-0
  se_access_check: also S-1-5-2
  se_access_check: also S-1-5-11
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11009
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11011
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11033
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (5030, 5000) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/service.c:make_connection_snum(689)
  leonidas (192.168.10.185) connect to service IPC$ initially as user
makris_k (uid=5030, gid=5000) (pid 28540)
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/reply.c:reply_tcon_and_X(307)
  tconX service=IPC$ 
[2003/04/10 13:36:01, 3] smbd/process.c:process_smb(882)
  Transaction 4 of length 106
[2003/04/10 13:36:01, 3] smbd/process.c:switch_message(676)
  switch message SMBntcreateX (pid 28540)
[2003/04/10 13:36:01, 3] smbd/sec_ctx.c:set_sec_ctx(287)
  setting sec ctx (5030, 5000) - sec_ctx_stack_ndx = 0
[2003/04/10 13:36:01, 3] smbd/vfs.c:vfs_ChDir(650)
  vfs_ChDir to /tmp
[2003/04/10 13:36:01, 3] smbd/nttrans.c:nt_open_pipe(482)
  nt_open_pipe: Known pipe spoolss opening.
[2003/04/10 13:36:01, 3] smbd/process.c:process_smb(882)
  Transaction 5 of length 160
[2003/04/10 13:36:01, 3] smbd/process.c:switch_message(676)
  switch message SMBtrans (pid 28540)
[2003/04/10 13:36:01, 3] smbd/ipc.c:reply_trans(511)
  trans <\PIPE\> data=72 params=0 setup=2
[2003/04/10 13:36:01, 3] smbd/ipc.c:named_pipe(326)
  named pipe command on <> name
[2003/04/10 13:36:01, 3] smbd/ipc.c:api_fd_reply(288)
  Got API command 0x26 on pipe "spoolss" (pnum 72cd)api_pipe_bind_req:
\PIPE\spoolss -> \PIPE\spoolss
[2003/04/10 13:36:01, 3] rpc_server/srv_pipe.c:check_bind_req(712)
  check_bind_req for \PIPE\spoolss
[2003/04/10 13:36:01, 3] smbd/process.c:process_smb(882)
  Transaction 6 of length 286
[2003/04/10 13:36:01, 3] smbd/process.c:switch_message(676)
  switch message SMBtrans (pid 28540)
[2003/04/10 13:36:01, 3] smbd/ipc.c:reply_trans(511)
  trans <\PIPE\> data=198 params=0 setup=2
[2003/04/10 13:36:01, 3] smbd/ipc.c:named_pipe(326)
  named pipe command on <> name
[2003/04/10 13:36:01, 3] smbd/ipc.c:api_fd_reply(288)
  Got API command 0x26 on pipe "spoolss" (pnum 72cd)free_pipe_context:
destroying talloc pool of size 0
[2003/04/10 13:36:01, 3] rpc_server/srv_pipe.c:api_pipe_request(1282)
  Doing \PIPE\spoolss
[2003/04/10 13:36:01, 3] rpc_server/srv_pipe.c:api_rpcTNP(1345)
  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
  checking name: \\N_prtsrv\hp4050
[2003/04/10 13:36:01, 3]
rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(414)
  Setting printer type=\\N_prtsrv\hp4050
[2003/04/10 13:36:01, 3] lib/util_seaccess.c:se_access_check(267)
[2003/04/10 13:36:01, 3] lib/util_seaccess.c:se_access_check(268)
  se_access_check: user sid is
S-1-5-21-1264390313-3394352946-2369289212-11060
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11001
  se_access_check: also S-1-1-0
  se_access_check: also S-1-5-2
  se_access_check: also S-1-5-11
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11009
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11011
  se_access_check: also S-1-5-21-1264390313-3394352946-2369289212-11033
[2003/04/10 13:36:01, 3]
rpc_server/srv_spoolss_nt.c:_spoolss_open_printer_ex(1649)
  access DENIED for printer open
[2003/04/10 13:36:01, 3] rpc_server/srv_lsa_hnd.c:close_policy_hnd(200)
  Closed policy
[2003/04/10 13:36:01, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(544)
  free_pipe_context: destroying talloc pool of size 76




-- 
-----------------------------------------------------------------------
kristis.makris at datasoft.com			DataSoft Corporation
(480) 763-5777 x412				http://www.datasoft.com
-----------------------------------------------------------------------



More information about the samba-technical mailing list