adddriver RPC

Tom Alsberg alsbergt at cs.huji.ac.il
Wed Mar 12 10:57:57 GMT 2003


Hi there.

Leaving the idea of not providing driver files on the server for a
while (only specifying the name, as in my previous thread here), I was
trying the SPOOLSS adddriver RPC from rpcclient with Samba.

The server runs Samba 3.0 post-HEAD from CVS checked out on the 3rd of
March.  rpcclient is from the same version of Samba.

I went to try it with the HP LaserJet 5Si/5Si MX PS driver.

I put all the files in the print share at (\\dev\print$\w32x86).  The
files are from Windows XP Professional SP1.  The files are, as
reported by the printer test page:

pscript.ntf
pscript.hlp
ps5ui.dll
hplj5si1.ppd
pscript5.dll

I issued the following command from rpcclient:

adddriver "Windows NT x86" "HP LaserJet 5Si/5Si MX PS":pscript5.dll:hplj5si1.ppd:ps5ui.dll:pscript.hlp::pscript.ntf

At first it gave a segmentation fault, which should not happen.  I
assumed there is something wrong deeper than this, but anyway I traced
this to be a NULL pointer dereference of the return value of
get_driver_3_param.  After applying the following patch to
rpcclient/cmd_spoolss.c, just to get it not to seg-fault in that case
so I can get some more details:

--- cmd_spoolss.c.orig  Wed Mar 12 12:36:05 2003
+++ cmd_spoolss.c       Wed Mar 12 12:35:52 2003
@@ -1149,6 +1149,9 @@
 {
        char    *ptr;
 
+       if (!str)
+           return "";
+
        /* get the next token */
        ptr = strtok(str, delim);
-- END OF PATCH
 
So now it just gives:
result was NT_STATUS_UNSUCCESSFUL

Attached is a relevant snippet of the log file in debug level 4.  I
did not yet manage to figure out the problem from it.

Any idea what the trace to this problem is?

  Any help appreciated,
  -- Tom

-- 
  Tom Alsberg - hacker (being the best description fitting this space)
  Web page:	http://www.cs.huji.ac.il/~alsbergt/
DISCLAIMER:  The above message does not even necessarily represent what
my fingers have typed on the keyboard, save anything further.
-------------- next part --------------
[2003/03/12 12:52:21, 3] ../source/auth/auth.c:check_ntlm_password(192)
  check_ntlm_password:  Checking password for unmapped user []\[alsbergt]@[LURCH] with the new password interface
[2003/03/12 12:52:21, 3] ../source/auth/auth.c:check_ntlm_password(195)
  check_ntlm_password:  mapped user is: [DEVIL]\[alsbergt]@[LURCH]
[2003/03/12 12:52:21, 3] ../source/smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/03/12 12:52:21, 3] ../source/smbd/uid.c:push_conn_ctx(287)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/03/12 12:52:21, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/03/12 12:52:21, 3] ../source/smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/03/12 12:52:21, 4] ../source/auth/auth_sam.c:sam_account_ok(321)
  sam_account_ok: Checking SMB password for user alsbergt
[2003/03/12 12:52:21, 3] ../source/auth/auth_sam.c:sam_password_ok(177)
  sam_password_ok: NO NT password stored for user alsbergt.
[2003/03/12 12:52:21, 3] ../source/auth/auth_sam.c:sam_password_ok(240)
  sam_password_ok: NO LanMan password set for user alsbergt (and no NT password supplied)
[2003/03/12 12:52:21, 4] ../source/auth/auth_sam.c:sam_password_ok(254)
  sam_password_ok: LM password check failed for user, no NT password alsbergt
[2003/03/12 12:52:21, 3] ../source/auth/auth_idng.c:check_idng_security(138)
  check_idng_security called: user="alsbergt" (alsbergt), auth succeeded
[2003/03/12 12:52:21, 3] ../source/smbd/uid.c:fetch_sid_from_gid_cache(654)
  fetch sid from gid cache 6 -> S-1-5-21-2763996366-3983801899-2901956018-1013
[2003/03/12 12:52:21, 3] ../source/auth/auth.c:check_ntlm_password(229)
  check_ntlm_password: idng authentication for user [alsbergt] suceeded
[2003/03/12 12:52:21, 3] ../source/smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/03/12 12:52:21, 3] ../source/smbd/uid.c:push_conn_ctx(287)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/03/12 12:52:21, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/03/12 12:52:21, 3] ../source/smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/03/12 12:52:21, 2] ../source/auth/auth.c:check_ntlm_password(270)
  check_ntlm_password:  authentication for user [alsbergt] -> [alsbergt] -> [alsbergt] suceeded
[2003/03/12 12:52:21, 3] ../source/smbd/password.c:register_vuid(198)
  User name: alsbergt	Real name: Tom Alsberg
[2003/03/12 12:52:21, 3] ../source/smbd/password.c:register_vuid(216)
  UNIX uid 3228 is UNIX user alsbergt, and will be vuid 100
[2003/03/12 12:52:21, 3] ../source/smbd/password.c:register_vuid(232)
  Adding/updating homes service for user 'alsbergt' using home direcotry: '/cs/system/alsbergt'
[2003/03/12 12:52:21, 3] ../source/param/loadparm.c:lp_add_home(2227)
  adding home's share [alsbergt] for user 'alsbergt' at '/cs/system/alsbergt'
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 3 of length 62
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBtconX (pid 63090)
[2003/03/12 12:52:21, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/03/12 12:52:21, 4] ../source/smbd/reply.c:reply_tcon_and_X(255)
  Got device type IPC
[2003/03/12 12:52:21, 3] ../source/lib/access.c:check_access(311)
  check_access: no hostnames in host allow/deny list.
[2003/03/12 12:52:21, 2] ../source/lib/access.c:check_access(322)
  Allowed connection from  (132.65.80.139)
[2003/03/12 12:52:21, 3] ../source/smbd/service.c:make_connection_snum(525)
  Connect path is '/tmp' for service [IPC$]
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_srvsvc_nt.c:get_share_security(217)
  get_share_security: using default secdesc for IPC$
[2003/03/12 12:52:21, 3] ../source/lib/util_seaccess.c:se_access_check(267)
[2003/03/12 12:52:21, 3] ../source/lib/util_seaccess.c:se_access_check(268)
  se_access_check: user sid is S-1-5-21-2763996366-3983801899-2901956018-7456
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1013
  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-2763996366-3983801899-2901956018-1011
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1061
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1081
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1085
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1169
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1211
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-21419
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-21457
[2003/03/12 12:52:21, 3] ../source/smbd/vfs.c:vfs_init_default(136)
  Initialising default vfs hooks
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_srvsvc_nt.c:get_share_security(217)
  get_share_security: using default secdesc for IPC$
[2003/03/12 12:52:21, 3] ../source/lib/util_seaccess.c:se_access_check(267)
[2003/03/12 12:52:21, 3] ../source/lib/util_seaccess.c:se_access_check(268)
  se_access_check: user sid is S-1-5-21-2763996366-3983801899-2901956018-7456
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1013
  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-2763996366-3983801899-2901956018-1011
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1061
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1081
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1085
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1169
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1211
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-21419
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-21457
[2003/03/12 12:52:21, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (3228, 6) - sec_ctx_stack_ndx = 0
[2003/03/12 12:52:21, 3] ../source/smbd/service.c:make_connection_snum(681)
  lurch (132.65.80.139) connect to service IPC$ initially as user alsbergt (uid=3228, gid=6) (pid 63090)
[2003/03/12 12:52:21, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/03/12 12:52:21, 3] ../source/smbd/reply.c:reply_tcon_and_X(296)
  tconX service=IPC$ 
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 4 of length 104
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBntcreateX (pid 63090)
[2003/03/12 12:52:21, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (3228, 6) - sec_ctx_stack_ndx = 0
[2003/03/12 12:52:21, 3] ../source/smbd/vfs.c:vfs_ChDir(650)
  vfs_ChDir to /tmp
[2003/03/12 12:52:21, 4] ../source/smbd/nttrans.c:nt_open_pipe(465)
  nt_open_pipe: Opening pipe \lsarpc.
[2003/03/12 12:52:21, 3] ../source/smbd/nttrans.c:nt_open_pipe(482)
  nt_open_pipe: Known pipe lsarpc opening.
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(178)
  Open pipe requested lsarpc (pipes_open=0)
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe_hnd.c:make_internal_rpc_pipe_p(279)
  Create pipe requested lsarpc
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe_hnd.c:make_internal_rpc_pipe_p(372)
  Created internal pipe lsarpc (pipes_open=0)
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(258)
  Opened pipe lsarpc with handle 7717 (pipes_open=1)
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 5 of length 158
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBtrans (pid 63090)
[2003/03/12 12:52:21, 4] ../source/smbd/uid.c:change_to_user(122)
  change_to_user: Skipping user change - already user
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:reply_trans(472)
  trans <\PIPE\> data=72 params=0 setup=2
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:named_pipe(326)
  named pipe command on <> name
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1142)
  search for pipe pnum=7717
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:api_fd_reply(288)
  Got API command 0x26 on pipe "lsarpc" (pnum 7717)api_pipe_bind_req: \PIPE\lsarpc -> \PIPE\lsass
[2003/03/12 12:52:21, 3] ../source/rpc_server/srv_pipe.c:check_bind_req(712)
  check_bind_req for \PIPE\lsarpc
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 6 of length 154
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBtrans (pid 63090)
[2003/03/12 12:52:21, 4] ../source/smbd/uid.c:change_to_user(122)
  change_to_user: Skipping user change - already user
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:reply_trans(472)
  trans <\PIPE\> data=68 params=0 setup=2
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:named_pipe(326)
  named pipe command on <> name
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1142)
  search for pipe pnum=7717
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:api_fd_reply(288)
  Got API command 0x26 on pipe "lsarpc" (pnum 7717)free_pipe_context: destroying talloc pool of size 0
[2003/03/12 12:52:21, 3] ../source/rpc_server/srv_pipe.c:api_pipe_request(1264)
  Doing \PIPE\lsarpc
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe.c:api_rpcTNP(1319)
  api_rpcTNP: lsarpc op 0x6 - api_rpcTNP: rpc command: LSA_OPENPOLICY
[2003/03/12 12:52:21, 3] ../source/rpc_parse/parse_lsa.c:lsa_io_sec_qos(186)
  lsa_io_sec_qos: length c does not match size 8
[2003/03/12 12:52:21, 3] ../source/lib/util_seaccess.c:se_access_check(267)
[2003/03/12 12:52:21, 3] ../source/lib/util_seaccess.c:se_access_check(268)
  se_access_check: user sid is S-1-5-21-2763996366-3983801899-2901956018-7456
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1013
  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-2763996366-3983801899-2901956018-1011
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1061
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1081
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1085
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1169
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1211
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-21419
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-21457
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_lsa_hnd.c:create_policy_hnd(142)
  Opened policy hnd[1] [000] 00 00 00 00 01 00 00 00  00 00 00 00 65 11 6F 3E  ........ ....e.o>
  [010] 72 F6 00 00                                       r... 
[2003/03/12 12:52:21, 3] ../source/rpc_server/srv_pipe_hnd.c:free_pipe_context(544)
  free_pipe_context: destroying talloc pool of size 808
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 7 of length 132
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBtrans (pid 63090)
[2003/03/12 12:52:21, 4] ../source/smbd/uid.c:change_to_user(122)
  change_to_user: Skipping user change - already user
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:reply_trans(472)
  trans <\PIPE\> data=46 params=0 setup=2
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:named_pipe(326)
  named pipe command on <> name
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1142)
  search for pipe pnum=7717
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:api_fd_reply(288)
  Got API command 0x26 on pipe "lsarpc" (pnum 7717)free_pipe_context: destroying talloc pool of size 0
[2003/03/12 12:52:21, 3] ../source/rpc_server/srv_pipe.c:api_pipe_request(1264)
  Doing \PIPE\lsarpc
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe.c:api_rpcTNP(1319)
  api_rpcTNP: lsarpc op 0x7 - api_rpcTNP: rpc command: LSA_QUERYINFOPOLICY
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_lsa_hnd.c:find_policy_by_hnd_internal(162)
  Found policy hnd[0] [000] 00 00 00 00 01 00 00 00  00 00 00 00 65 11 6F 3E  ........ ....e.o>
  [010] 72 F6 00 00                                       r... 
[2003/03/12 12:52:21, 3] ../source/rpc_server/srv_pipe_hnd.c:free_pipe_context(544)
  free_pipe_context: destroying talloc pool of size 512
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 8 of length 130
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBtrans (pid 63090)
[2003/03/12 12:52:21, 4] ../source/smbd/uid.c:change_to_user(122)
  change_to_user: Skipping user change - already user
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:reply_trans(472)
  trans <\PIPE\> data=44 params=0 setup=2
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:named_pipe(326)
  named pipe command on <> name
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1142)
  search for pipe pnum=7717
[2003/03/12 12:52:21, 3] ../source/smbd/ipc.c:api_fd_reply(288)
  Got API command 0x26 on pipe "lsarpc" (pnum 7717)free_pipe_context: destroying talloc pool of size 0
[2003/03/12 12:52:21, 3] ../source/rpc_server/srv_pipe.c:api_pipe_request(1264)
  Doing \PIPE\lsarpc
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe.c:api_rpcTNP(1319)
  api_rpcTNP: lsarpc op 0x0 - api_rpcTNP: rpc command: LSA_CLOSE
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_lsa_hnd.c:find_policy_by_hnd_internal(162)
  Found policy hnd[0] [000] 00 00 00 00 01 00 00 00  00 00 00 00 65 11 6F 3E  ........ ....e.o>
  [010] 72 F6 00 00                                       r... 
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_lsa_hnd.c:find_policy_by_hnd_internal(162)
  Found policy hnd[0] [000] 00 00 00 00 01 00 00 00  00 00 00 00 65 11 6F 3E  ........ ....e.o>
  [010] 72 F6 00 00                                       r... 
[2003/03/12 12:52:21, 3] ../source/rpc_server/srv_lsa_hnd.c:close_policy_hnd(200)
  Closed policy
[2003/03/12 12:52:21, 3] ../source/rpc_server/srv_pipe_hnd.c:free_pipe_context(544)
  free_pipe_context: destroying talloc pool of size 0
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 9 of length 45
[2003/03/12 12:52:21, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBclose (pid 63090)
[2003/03/12 12:52:21, 4] ../source/smbd/uid.c:change_to_user(122)
  change_to_user: Skipping user change - already user
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1142)
  search for pipe pnum=7717
[2003/03/12 12:52:21, 4] ../source/rpc_server/srv_pipe_hnd.c:close_rpc_pipe_hnd(1074)
  closed pipe name lsarpc pnum=7717 (pipes_open=0)
[2003/03/12 12:52:25, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 10 of length 106
[2003/03/12 12:52:25, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBntcreateX (pid 63090)
[2003/03/12 12:52:25, 4] ../source/smbd/uid.c:change_to_user(122)
  change_to_user: Skipping user change - already user
[2003/03/12 12:52:25, 4] ../source/smbd/nttrans.c:nt_open_pipe(465)
  nt_open_pipe: Opening pipe \spoolss.
[2003/03/12 12:52:25, 3] ../source/smbd/nttrans.c:nt_open_pipe(482)
  nt_open_pipe: Known pipe spoolss opening.
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(178)
  Open pipe requested spoolss (pipes_open=0)
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_pipe_hnd.c:make_internal_rpc_pipe_p(279)
  Create pipe requested spoolss
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_pipe_hnd.c:make_internal_rpc_pipe_p(372)
  Created internal pipe spoolss (pipes_open=0)
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(258)
  Opened pipe spoolss with handle 7718 (pipes_open=1)
[2003/03/12 12:52:25, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 11 of length 158
[2003/03/12 12:52:25, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBtrans (pid 63090)
[2003/03/12 12:52:25, 4] ../source/smbd/uid.c:change_to_user(122)
  change_to_user: Skipping user change - already user
[2003/03/12 12:52:25, 3] ../source/smbd/ipc.c:reply_trans(472)
  trans <\PIPE\> data=72 params=0 setup=2
[2003/03/12 12:52:25, 3] ../source/smbd/ipc.c:named_pipe(326)
  named pipe command on <> name
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1142)
  search for pipe pnum=7718
[2003/03/12 12:52:25, 3] ../source/smbd/ipc.c:api_fd_reply(288)
  Got API command 0x26 on pipe "spoolss" (pnum 7718)api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2003/03/12 12:52:25, 3] ../source/rpc_server/srv_pipe.c:check_bind_req(712)
  check_bind_req for \PIPE\spoolss
[2003/03/12 12:52:25, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 12 of length 310
[2003/03/12 12:52:25, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBtrans (pid 63090)
[2003/03/12 12:52:25, 4] ../source/smbd/uid.c:change_to_user(122)
  change_to_user: Skipping user change - already user
[2003/03/12 12:52:25, 3] ../source/smbd/ipc.c:reply_trans(472)
  trans <\PIPE\> data=224 params=0 setup=2
[2003/03/12 12:52:25, 3] ../source/smbd/ipc.c:named_pipe(326)
  named pipe command on <> name
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1142)
  search for pipe pnum=7718
[2003/03/12 12:52:25, 3] ../source/smbd/ipc.c:api_fd_reply(288)
  Got API command 0x26 on pipe "spoolss" (pnum 7718)free_pipe_context: destroying talloc pool of size 0
[2003/03/12 12:52:25, 3] ../source/rpc_server/srv_pipe.c:api_pipe_request(1264)
  Doing \PIPE\spoolss
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_pipe.c:api_rpcTNP(1319)
  api_rpcTNP: spoolss op 0x9 - api_rpcTNP: rpc command: SPOOLSS_ADDPRINTERDRIVER
[2003/03/12 12:52:25, 3] ../source/smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(3228, 6) : sec_ctx_stack_ndx = 1
[2003/03/12 12:52:25, 3] ../source/smbd/uid.c:push_conn_ctx(287)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
[2003/03/12 12:52:25, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/03/12 12:52:25, 3] ../source/lib/access.c:check_access(311)
  check_access: no hostnames in host allow/deny list.
[2003/03/12 12:52:25, 2] ../source/lib/access.c:check_access(322)
  Allowed connection from  (132.65.80.139)
[2003/03/12 12:52:25, 3] ../source/smbd/service.c:make_connection_snum(525)
  Connect path is '/v1/samba-test/share/print' for service [print$]
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_srvsvc_nt.c:get_share_security(217)
  get_share_security: using default secdesc for print$
[2003/03/12 12:52:25, 3] ../source/lib/util_seaccess.c:se_access_check(267)
[2003/03/12 12:52:25, 3] ../source/lib/util_seaccess.c:se_access_check(268)
  se_access_check: user sid is S-1-5-21-2763996366-3983801899-2901956018-7456
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1013
  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-2763996366-3983801899-2901956018-1011
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1061
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1081
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1085
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1169
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1211
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-21419
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-21457
[2003/03/12 12:52:25, 3] ../source/smbd/vfs.c:vfs_init_default(136)
  Initialising default vfs hooks
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_srvsvc_nt.c:get_share_security(217)
  get_share_security: using default secdesc for print$
[2003/03/12 12:52:25, 3] ../source/lib/util_seaccess.c:se_access_check(267)
[2003/03/12 12:52:25, 3] ../source/lib/util_seaccess.c:se_access_check(268)
  se_access_check: user sid is S-1-5-21-2763996366-3983801899-2901956018-7456
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1013
  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-2763996366-3983801899-2901956018-1011
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1061
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1081
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1085
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1169
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-1211
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-21419
  se_access_check: also S-1-5-21-2763996366-3983801899-2901956018-21457
[2003/03/12 12:52:25, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (3228, 6) - sec_ctx_stack_ndx = 1
[2003/03/12 12:52:25, 1] ../source/smbd/service.c:make_connection_snum(681)
  lurch (132.65.80.139) connect to service print$ initially as user alsbergt (uid=3228, gid=6) (pid 63090)
[2003/03/12 12:52:25, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/03/12 12:52:25, 3] ../source/smbd/vfs.c:vfs_ChDir(650)
  vfs_ChDir to /v1/samba-test/share/print
[2003/03/12 12:52:25, 3] ../source/smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (3228, 6) - sec_ctx_stack_ndx = 0
[2003/03/12 12:52:25, 3] ../source/smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(3228, 6) : sec_ctx_stack_ndx = 1
[2003/03/12 12:52:25, 3] ../source/smbd/uid.c:push_conn_ctx(287)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
[2003/03/12 12:52:25, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (3228, 6) - sec_ctx_stack_ndx = 1
[2003/03/12 12:52:25, 3] ../source/lib/util.c:unix_clean_name(580)
  unix_clean_name [W32X86/]
[2003/03/12 12:52:25, 3] ../source/lib/util.c:unix_clean_name(580)
  unix_clean_name [w32x86]
[2003/03/12 12:52:25, 4] ../source/smbd/open.c:open_file_shared1(969)
  calling open_file with flags=0x0 flags2=0x0 mode=00
[2003/03/12 12:52:25, 3] ../source/printing/nt_printing.c:get_correct_cversion(1130)
  get_correct_cversion: Can't open file [w32x86], errno = 21
[2003/03/12 12:52:25, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/03/12 12:52:25, 1] ../source/smbd/service.c:close_cnum(861)
  lurch (132.65.80.139) closed connection to service print$
[2003/03/12 12:52:25, 3] ../source/smbd/connection.c:yield_connection(61)
  Yielding connection to print$
[2003/03/12 12:52:25, 3] ../source/smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/03/12 12:52:25, 3] ../source/smbd/vfs.c:vfs_ChDir(650)
  vfs_ChDir to /
[2003/03/12 12:52:25, 3] ../source/smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (3228, 6) - sec_ctx_stack_ndx = 0
[2003/03/12 12:52:25, 3] ../source/rpc_server/srv_pipe_hnd.c:free_pipe_context(544)
  free_pipe_context: destroying talloc pool of size 278
[2003/03/12 12:52:25, 3] ../source/smbd/process.c:process_smb(882)
  Transaction 13 of length 45
[2003/03/12 12:52:25, 3] ../source/smbd/process.c:switch_message(676)
  switch message SMBclose (pid 63090)
[2003/03/12 12:52:25, 4] ../source/smbd/uid.c:change_to_user(122)
  change_to_user: Skipping user change - already user
[2003/03/12 12:52:25, 3] ../source/smbd/vfs.c:vfs_ChDir(650)
  vfs_ChDir to /tmp
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1142)
  search for pipe pnum=7718
[2003/03/12 12:52:25, 4] ../source/rpc_server/srv_pipe_hnd.c:close_rpc_pipe_hnd(1074)
  closed pipe name spoolss pnum=7718 (pipes_open=0)



More information about the samba-technical mailing list