[samba-jp:19871] Re: samba-3.0.25b-1.el4_6.2になったらLDAPが使えなくなりました。

2007年 11月 27日 (火) 12:03:32 JST

近藤です。

早速のレスありがとうございます。

> passdb backendは２つ以上かけなくなりました。

他の方からも有りましたが、そうだったんですか。
とりあえず、passdb backendをLDAPのみでやってみたところ、
\\server001まではLDAPユーザがアクセスできました。
（プリンタとFAXアイコンが出てきました。）
\\server001\ldap_user1にアクセスすると認証できないのか
ユーザ名とパスワードを聞いてきます。

> /var/log/samba の中を調べてもらえないでしょうか？

私もログに何かでてるかなとおもってlog level を10にしてたんですが、
ログが大量にでてよく分からずでした。（すみません。）

 Finding user ldap_user1

というのがあったので、LDAPからユーザは引けているような...
でも
ldapsam_get_account_policy: failed to retrieve from ldap
という記述も見当たりました。
最終的には
[2007/11/27 10:27:45, 2] smbd/service.c:make_connection_snum(616)
  user 'ldap_user1' (from session setup) not permitted to access this share
(ldap_user1)
[2007/11/27 10:27:45, 3] smbd/error.c:error_packet_set(106)
  error packet at smbd/reply.c(514) cmd=117 (SMBtconX)
NT_STATUS_ACCESS_DENIED
となっているみたいです。

長々とすみませんが、ログを付けさせてもらいます。
（実際のサーバ名やユーザ名は伏せさせてもらってます。また、40K以下に
するためにログをカットしています。）

[2007/11/27 10:27:44, 10] smbd/msdfs.c:parse_dfs_path(84)
  parse_dfs_path: temp = |SERVER001\ldap_user1| after trimming \'s
[2007/11/27 10:27:44, 10] smbd/msdfs.c:parse_dfs_path(107)
  parse_dfs_path: hostname: SERVER001
[2007/11/27 10:27:44, 3] smbd/msdfs.c:get_referred_path(624)
  get_referred_path: |ldap_user1| in dfs path \SERVER001\ldap_user1 is not a 
dfs root.
[2007/11/27 10:27:44, 3] smbd/error.c:error_packet_set(106)
  error packet at smbd/trans2.c(6201) cmd=50 (SMBtrans2) NT_STATUS_NOT_FOUND
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:44, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 100
[2007/11/27 10:27:44, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0x64
[2007/11/27 10:27:44, 3] smbd/process.c:process_smb(1068)
  Transaction 61 of length 104
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:44, 10] lib/util.c:dump_data(2283)
  [000] 00 5C 00 73 00 72 00 76  00 73 00 76 00 63 00 00  .\.s.r.v .s.v.c..
  [010] 00                                                .
[2007/11/27 10:27:44, 3] smbd/process.c:switch_message(926)
  switch message SMBntcreateX (pid 5676) conn 0x8515698
[2007/11/27 10:27:44, 3] smbd/sec_ctx.c:set_sec_ctx(241)
  setting sec ctx (85024510, 41000) - sec_ctx_stack_ndx = 0
[2007/11/27 10:27:44, 5] auth/auth_util.c:debug_nt_user_token(454)
  NT user token of user S-1-5-21-1171046599-1538780772-1025607528-17.0.0.10
  contains 9 SIDs
＜略＞
[2007/11/27 10:27:44, 5] auth/auth_util.c:debug_unix_user_token(474)
  UNIX token of user 85024510
  Primary group is 41000 and contains 5 supplementary groups
＜略＞
[2007/11/27 10:27:44, 5] smbd/uid.c:change_to_user(273)
  change_to_user uid=(85024510,85024510) gid=(0,41000)
[2007/11/27 10:27:44, 10] smbd/nttrans.c:reply_ntcreate_and_X(515)
  reply_ntcreate_and_X: flags = 0x16, access_mask = 0x2019f file_attributes 
= 0x0, share_access = 0x3, create_disposition = 0x1 create_options = 
0x400040 root_dir_fid = 0x0
[2007/11/27 10:27:44, 4] smbd/nttrans.c:nt_open_pipe(328)
  nt_open_pipe: Opening pipe \srvsvc.
[2007/11/27 10:27:44, 3] smbd/nttrans.c:nt_open_pipe(349)
  nt_open_pipe: Known pipe srvsvc opening.
[2007/11/27 10:27:44, 4] rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(180)
  Open pipe requested srvsvc (pipes_open=0)
[2007/11/27 10:27:44, 4] 
rpc_server/srv_pipe_hnd.c:make_internal_rpc_pipe_p(285)
  Create pipe requested srvsvc
[2007/11/27 10:27:44, 10] rpc_server/srv_lsa_hnd.c:init_pipe_handle_list(77)
  init_pipe_handles: created handle list for pipe srvsvc
[2007/11/27 10:27:44, 10] rpc_server/srv_lsa_hnd.c:init_pipe_handle_list(93)
  init_pipe_handles: pipe_handles ref count = 1 for pipe srvsvc
[2007/11/27 10:27:44, 4] 
rpc_server/srv_pipe_hnd.c:make_internal_rpc_pipe_p(366)
  Created internal pipe srvsvc (pipes_open=0)
[2007/11/27 10:27:44, 4] rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(263)
  Opened pipe srvsvc with handle 74ab (pipes_open=1)
[2007/11/27 10:27:44, 5] rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(269)
  open pipes: name srvsvc pnum=74ab
[2007/11/27 10:27:44, 5] smbd/nttrans.c:do_ntcreate_pipe_open(427)
  do_ntcreate_pipe_open: open pipe = \srvsvc
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:44, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 136
[2007/11/27 10:27:44, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0x88
[2007/11/27 10:27:44, 3] smbd/process.c:process_smb(1068)
  Transaction 62 of length 140
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞                       .+.H`... .
[2007/11/27 10:27:44, 3] smbd/process.c:switch_message(926)
  switch message SMBwriteX (pid 5676) conn 0x8515698
[2007/11/27 10:27:44, 4] smbd/uid.c:change_to_user(183)
  change_to_user: Skipping user change - already user
[2007/11/27 10:27:44, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1271)
  search for pipe pnum=74ab
[2007/11/27 10:27:44, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1275)
  pipe name srvsvc pnum=74ab (pipes_open=1)
[2007/11/27 10:27:44, 6] rpc_server/srv_pipe_hnd.c:write_to_pipe(937)
  write_to_pipe: 74ab name: srvsvc open: Yes len: 72
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 72
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 0, pdu_needed_len = 0, 
incoming data = 72
[2007/11/27 10:27:44, 10] rpc_server/srv_pipe_hnd.c:fill_rpc_header(395)
  fill_rpc_header: data_to_copy = 72, len_needed_to_complete_hdr = 16, 
receive_len = 0
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 16
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 56
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 0, 
incoming data = 56
＜略＞
[2007/11/27 10:27:44, 5] 
rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(482)
  unmarshall_rpc_header: using little-endian RPC
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(511)
  unmarshall_rpc_header: type = 11, flags = 3
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 0
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 56
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 56, 
incoming data = 56
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:process_complete_pdu(719)
  process_complete_pdu: processing packet type 11
[2007/11/27 10:27:44, 5] rpc_server/srv_pipe.c:api_pipe_bind_req(1523)
  api_pipe_bind_req: decode request. 1523
[2007/11/27 10:27:44, 3] rpc_server/srv_pipe.c:api_pipe_bind_req(1534)
  api_pipe_bind_req: \PIPE\srvsvc -> \PIPE\ntsvcs
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_debug(84)
  000000 smb_io_rpc_hdr_rb
＜略＞
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 56
[2007/11/27 10:27:44, 3] smbd/pipes.c:reply_pipe_write_and_X(232)
  writeX-IPC pnum=74ab nwritten=72
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:44, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 59
[2007/11/27 10:27:44, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0x3b
[2007/11/27 10:27:44, 3] smbd/process.c:process_smb(1068)
  Transaction 63 of length 63
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:44, 3] smbd/process.c:switch_message(926)
  switch message SMBreadX (pid 5676) conn 0x8515698
[2007/11/27 10:27:44, 4] smbd/uid.c:change_to_user(183)
  change_to_user: Skipping user change - already user
[2007/11/27 10:27:44, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1271)
  search for pipe pnum=74ab
[2007/11/27 10:27:44, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1275)
  pipe name srvsvc pnum=74ab (pipes_open=1)
[2007/11/27 10:27:44, 6] rpc_server/srv_pipe_hnd.c:read_from_pipe(995)
  read_from_pipe: 74ab name: srvsvc len: 1024
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:read_from_internal_pipe(1054)
  read_from_pipe: srvsvc: current_pdu_len = 68, current_pdu_sent = 0 
returning 68 bytes.
[2007/11/27 10:27:44, 3] smbd/pipes.c:reply_pipe_read_and_X(277)
  readX-IPC pnum=74ab min=1024 max=1024 nread=68
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞                                       ....
[2007/11/27 10:27:44, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 184
[2007/11/27 10:27:44, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0xb8
[2007/11/27 10:27:44, 3] smbd/process.c:process_smb(1068)
  Transaction 64 of length 188
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞                       .....
[2007/11/27 10:27:44, 3] smbd/process.c:switch_message(926)
  switch message SMBtrans (pid 5676) conn 0x8515698
[2007/11/27 10:27:44, 4] smbd/uid.c:change_to_user(183)
  change_to_user: Skipping user change - already user
[2007/11/27 10:27:44, 3] smbd/ipc.c:handle_trans(373)
  trans <\PIPE\> data=100 params=0 setup=2
[2007/11/27 10:27:44, 5] smbd/ipc.c:handle_trans(404)
  calling named_pipe
[2007/11/27 10:27:44, 3] smbd/ipc.c:named_pipe(340)
  named pipe command on <> name
[2007/11/27 10:27:44, 5] smbd/ipc.c:api_fd_reply(265)
  api_fd_reply
[2007/11/27 10:27:44, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1271)
  search for pipe pnum=74ab
[2007/11/27 10:27:44, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1275)
  pipe name srvsvc pnum=74ab (pipes_open=1)
[2007/11/27 10:27:44, 3] smbd/ipc.c:api_fd_reply(300)
  Got API command 0x26 on pipe "srvsvc" (pnum 74ab)
[2007/11/27 10:27:44, 10] smbd/ipc.c:api_fd_reply(305)
  api_fd_reply: p:0x8516650 max_trans_reply: 1024
[2007/11/27 10:27:44, 6] rpc_server/srv_pipe_hnd.c:write_to_pipe(937)
  write_to_pipe: 74ab name: srvsvc open: Yes len: 100
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 100
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 0, pdu_needed_len = 0, 
incoming data = 100
[2007/11/27 10:27:44, 10] rpc_server/srv_pipe_hnd.c:fill_rpc_header(395)
  fill_rpc_header: data_to_copy = 100, len_needed_to_complete_hdr = 16, 
receive_len = 0
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 16
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 84
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 0, 
incoming data = 84
＜略＞
[2007/11/27 10:27:44, 5] 
rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(482)
  unmarshall_rpc_header: using little-endian RPC
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(511)
  unmarshall_rpc_header: type = 0, flags = 3
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 0
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 84
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 84, 
incoming data = 84
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:process_complete_pdu(719)
  process_complete_pdu: processing packet type 0
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_debug(84)
  000000 smb_io_rpc_hdr_req req
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint32(710)
      0000 alloc_hint: 0000004c
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint16(681)
      0004 context_id: 0000
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint16(681)
      0006 opnum     : 0010
[2007/11/27 10:27:44, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(529)
  free_pipe_context: destroying talloc pool of size 70
[2007/11/27 10:27:44, 5] rpc_server/srv_pipe.c:api_pipe_request(2223)
  Requested \PIPE\srvsvc
[2007/11/27 10:27:44, 4] rpc_server/srv_pipe.c:api_rpcTNP(2258)
  api_rpcTNP: srvsvc op 0x10 - api_rpcTNP: rpc command: 
SRV_NET_SHARE_GET_INFO
[2007/11/27 10:27:44, 6] rpc_server/srv_pipe.c:api_rpcTNP(2284)
＜略＞
      0048 info_level: 00000001
[2007/11/27 10:27:44, 5] 
rpc_server/srv_srvsvc_nt.c:_srv_net_share_get_info(1424)
  _srv_net_share_get_info: 1424
[2007/11/27 10:27:44, 5] 
rpc_server/srv_srvsvc_nt.c:init_srv_r_net_share_get_info(716)
  init_srv_r_net_share_get_info: 716
[2007/11/27 10:27:44, 5] rpc_parse/parse_srv.c:init_srv_share_info1(143)
  init_srv_share_info1: ldap_user1        0 Home Directory
[2007/11/27 10:27:44, 5] rpc_parse/parse_srv.c:init_srv_share_info1_str(102)
  init_srv_share_info1_str
[2007/11/27 10:27:44, 5] 
rpc_server/srv_srvsvc_nt.c:_srv_net_share_get_info(1430)
  _srv_net_share_get_info: 1430
＜略＞
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:dbg_rw_punival(942)
                  0040 buffer     : H.o.m.e. .D.i.r.e.c.t.o.r.y...
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_werror(830)
      0060 status: WERR_OK
[2007/11/27 10:27:44, 5] rpc_server/srv_pipe.c:api_rpcTNP(2305)
  api_rpcTNP: called srvsvc successfully
[2007/11/27 10:27:44, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(529)
  free_pipe_context: destroying talloc pool of size 90
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 84
[2007/11/27 10:27:44, 6] rpc_server/srv_pipe_hnd.c:read_from_pipe(995)
  read_from_pipe: 74ab name: srvsvc len: 1024
[2007/11/27 10:27:44, 10] 
rpc_server/srv_pipe_hnd.c:read_from_internal_pipe(1068)
  read_from_pipe: srvsvc: fault_state = 0 : data_sent_length = 0, 
prs_offset(&p->out_data.rdata) = 100.
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_debug(84)
  000000 smb_io_rpc_hdr hdr
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint8(616)
      0000 major     : 05
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint8(616)
      0001 minor     : 00
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint8(616)
      0002 pkt_type  : 02
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint8(616)
      0003 flags     : 03
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint8(616)
      0004 pack_type0: 10
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint8(616)
      0005 pack_type1: 00
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint8(616)
      0006 pack_type2: 00
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint8(616)
      0007 pack_type3: 00
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint16(681)
      0008 frag_len  : 007c
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint16(681)
      000a auth_len  : 0000
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint32(710)
      000c call_id   : 00000001
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_debug(84)
  000010 smb_io_rpc_hdr_resp resp
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint32(710)
      0010 alloc_hint: 00000064
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint16(681)
      0014 context_id: 0000
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint8(616)
      0016 cancel_ct : 00
[2007/11/27 10:27:44, 5] rpc_parse/parse_prs.c:prs_uint8(616)
      0017 reserved  : 00
[2007/11/27 10:27:44, 5] smbd/ipc.c:copy_trans_params_and_data(60)
  copy_trans_params_and_data: params[0..0] data[0..124]
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:44, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 41
[2007/11/27 10:27:44, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0x29
[2007/11/27 10:27:44, 3] smbd/process.c:process_smb(1068)
  Transaction 65 of length 45
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:44, 3] smbd/process.c:switch_message(926)
  switch message SMBclose (pid 5676) conn 0x8515698
[2007/11/27 10:27:44, 4] smbd/uid.c:change_to_user(183)
  change_to_user: Skipping user change - already user
[2007/11/27 10:27:44, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1271)
  search for pipe pnum=74ab
[2007/11/27 10:27:44, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1275)
  pipe name srvsvc pnum=74ab (pipes_open=1)
[2007/11/27 10:27:44, 5] smbd/pipes.c:reply_pipe_close(297)
  reply_pipe_close: pnum:74ab
[2007/11/27 10:27:44, 10] rpc_server/srv_lsa_hnd.c:close_policy_by_pipe(241)
  close_policy_by_pipe: deleted handle list for pipe srvsvc
[2007/11/27 10:27:44, 4] rpc_server/srv_pipe_hnd.c:close_rpc_pipe_hnd(1169)
  closed pipe name srvsvc pnum=74ab (pipes_open=0)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:44, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 100
[2007/11/27 10:27:45, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0x64
[2007/11/27 10:27:45, 3] smbd/process.c:process_smb(1068)
  Transaction 66 of length 104
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 10] lib/util.c:dump_data(2283)
  [000] 00 5C 00 77 00 6B 00 73  00 73 00 76 00 63 00 00  .\.w.k.s .s.v.c..
  [010] 00                                                .
[2007/11/27 10:27:45, 3] smbd/process.c:switch_message(926)
  switch message SMBntcreateX (pid 5676) conn 0x8515698
[2007/11/27 10:27:45, 4] smbd/uid.c:change_to_user(183)
  change_to_user: Skipping user change - already user
[2007/11/27 10:27:45, 10] smbd/nttrans.c:reply_ntcreate_and_X(515)
  reply_ntcreate_and_X: flags = 0x16, access_mask = 0x2019f file_attributes 
= 0x0, share_access = 0x3, create_disposition = 0x1 create_options = 
0x400040 root_dir_fid = 0x0
[2007/11/27 10:27:45, 4] smbd/nttrans.c:nt_open_pipe(328)
  nt_open_pipe: Opening pipe \wkssvc.
[2007/11/27 10:27:45, 3] smbd/nttrans.c:nt_open_pipe(349)
  nt_open_pipe: Known pipe wkssvc opening.
[2007/11/27 10:27:45, 4] rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(180)
  Open pipe requested wkssvc (pipes_open=0)
[2007/11/27 10:27:45, 4] 
rpc_server/srv_pipe_hnd.c:make_internal_rpc_pipe_p(285)
  Create pipe requested wkssvc
[2007/11/27 10:27:45, 10] rpc_server/srv_lsa_hnd.c:init_pipe_handle_list(77)
  init_pipe_handles: created handle list for pipe wkssvc
[2007/11/27 10:27:45, 10] rpc_server/srv_lsa_hnd.c:init_pipe_handle_list(93)
  init_pipe_handles: pipe_handles ref count = 1 for pipe wkssvc
[2007/11/27 10:27:45, 4] 
rpc_server/srv_pipe_hnd.c:make_internal_rpc_pipe_p(366)
  Created internal pipe wkssvc (pipes_open=0)
[2007/11/27 10:27:45, 4] rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(263)
  Opened pipe wkssvc with handle 74ac (pipes_open=1)
[2007/11/27 10:27:45, 5] rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(269)
  open pipes: name wkssvc pnum=74ac
[2007/11/27 10:27:45, 5] smbd/nttrans.c:do_ntcreate_pipe_open(427)
  do_ntcreate_pipe_open: open pipe = \wkssvc
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 136
[2007/11/27 10:27:45, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0x88
[2007/11/27 10:27:45, 3] smbd/process.c:process_smb(1068)
  Transaction 67 of length 140
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 3] smbd/process.c:switch_message(926)
  switch message SMBwriteX (pid 5676) conn 0x8515698
[2007/11/27 10:27:45, 4] smbd/uid.c:change_to_user(183)
  change_to_user: Skipping user change - already user
[2007/11/27 10:27:45, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1271)
  search for pipe pnum=74ac
[2007/11/27 10:27:45, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1275)
  pipe name wkssvc pnum=74ac (pipes_open=1)
[2007/11/27 10:27:45, 6] rpc_server/srv_pipe_hnd.c:write_to_pipe(937)
  write_to_pipe: 74ac name: wkssvc open: Yes len: 72
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 72
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 0, pdu_needed_len = 0, 
incoming data = 72
[2007/11/27 10:27:45, 10] rpc_server/srv_pipe_hnd.c:fill_rpc_header(395)
  fill_rpc_header: data_to_copy = 72, len_needed_to_complete_hdr = 16, 
receive_len = 0
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 16
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 56
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 0, 
incoming data = 56
＜略＞
[2007/11/27 10:27:45, 5] 
rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(482)
  unmarshall_rpc_header: using little-endian RPC
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(511)
  unmarshall_rpc_header: type = 11, flags = 3
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 0
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 56
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 56, 
incoming data = 56
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:process_complete_pdu(719)
  process_complete_pdu: processing packet type 11
＜略＞
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 56
[2007/11/27 10:27:45, 3] smbd/pipes.c:reply_pipe_write_and_X(232)
  writeX-IPC pnum=74ac nwritten=72
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 59
[2007/11/27 10:27:45, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0x3b
[2007/11/27 10:27:45, 3] smbd/process.c:process_smb(1068)
  Transaction 68 of length 63
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 3] smbd/process.c:switch_message(926)
  switch message SMBreadX (pid 5676) conn 0x8515698
[2007/11/27 10:27:45, 4] smbd/uid.c:change_to_user(183)
  change_to_user: Skipping user change - already user
[2007/11/27 10:27:45, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1271)
  search for pipe pnum=74ac
[2007/11/27 10:27:45, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1275)
  pipe name wkssvc pnum=74ac (pipes_open=1)
[2007/11/27 10:27:45, 6] rpc_server/srv_pipe_hnd.c:read_from_pipe(995)
  read_from_pipe: 74ac name: wkssvc len: 1024
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:read_from_internal_pipe(1054)
  read_from_pipe: wkssvc: current_pdu_len = 68, current_pdu_sent = 0 
returning 68 bytes.
[2007/11/27 10:27:45, 3] smbd/pipes.c:reply_pipe_read_and_X(277)
  readX-IPC pnum=74ac min=1024 max=1024 nread=68
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 152
[2007/11/27 10:27:45, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0x98
[2007/11/27 10:27:45, 3] smbd/process.c:process_smb(1068)
  Transaction 69 of length 156
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 3] smbd/process.c:switch_message(926)
  switch message SMBtrans (pid 5676) conn 0x8515698
[2007/11/27 10:27:45, 4] smbd/uid.c:change_to_user(183)
  change_to_user: Skipping user change - already user
[2007/11/27 10:27:45, 3] smbd/ipc.c:handle_trans(373)
  trans <\PIPE\> data=68 params=0 setup=2
[2007/11/27 10:27:45, 5] smbd/ipc.c:handle_trans(404)
  calling named_pipe
[2007/11/27 10:27:45, 3] smbd/ipc.c:named_pipe(340)
  named pipe command on <> name
[2007/11/27 10:27:45, 5] smbd/ipc.c:api_fd_reply(265)
  api_fd_reply
[2007/11/27 10:27:45, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1271)
  search for pipe pnum=74ac
[2007/11/27 10:27:45, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1275)
  pipe name wkssvc pnum=74ac (pipes_open=1)
[2007/11/27 10:27:45, 3] smbd/ipc.c:api_fd_reply(300)
  Got API command 0x26 on pipe "wkssvc" (pnum 74ac)
[2007/11/27 10:27:45, 10] smbd/ipc.c:api_fd_reply(305)
  api_fd_reply: p:0x8516650 max_trans_reply: 1024
[2007/11/27 10:27:45, 6] rpc_server/srv_pipe_hnd.c:write_to_pipe(937)
  write_to_pipe: 74ac name: wkssvc open: Yes len: 68
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 68
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 0, pdu_needed_len = 0, 
incoming data = 68
[2007/11/27 10:27:45, 10] rpc_server/srv_pipe_hnd.c:fill_rpc_header(395)
  fill_rpc_header: data_to_copy = 68, len_needed_to_complete_hdr = 16, 
receive_len = 0
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 16
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 52
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 0, 
incoming data = 52
＜略＞
[2007/11/27 10:27:45, 5] 
rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(482)
  unmarshall_rpc_header: using little-endian RPC
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(511)
  unmarshall_rpc_header: type = 0, flags = 3
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 0
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(959)
  write_to_pipe: data_left = 52
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:process_incoming_data(852)
  process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 52, 
incoming data = 52
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:process_complete_pdu(719)
  process_complete_pdu: processing packet type 0
[2007/11/27 10:27:45, 5] rpc_parse/parse_prs.c:prs_debug(84)
  000000 smb_io_rpc_hdr_req req
[2007/11/27 10:27:45, 5] rpc_parse/parse_prs.c:prs_uint32(710)
      0000 alloc_hint: 0.0.0.1c
[2007/11/27 10:27:45, 5] rpc_parse/parse_prs.c:prs_uint16(681)
      0004 context_id: 0000
[2007/11/27 10:27:45, 5] rpc_parse/parse_prs.c:prs_uint16(681)
      0006 opnum     : 0000
[2007/11/27 10:27:45, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(529)
  free_pipe_context: destroying talloc pool of size 70
[2007/11/27 10:27:45, 5] rpc_server/srv_pipe.c:api_pipe_request(2223)
  Requested \PIPE\wkssvc
[2007/11/27 10:27:45, 4] rpc_server/srv_pipe.c:api_rpcTNP(2258)
  api_rpcTNP: wkssvc op 0x0 - api_rpcTNP: rpc command: 
WKSSVC_NETWKSTAGETINFO
[2007/11/27 10:27:45, 6] rpc_server/srv_pipe.c:api_rpcTNP(2284)
  api_rpc_cmds[0].fn == 0xb11080
＜略＞
[2007/11/27 10:27:45, 0] librpc/ndr/ndr.c:ndr_print_debug_helper(203)
              result                   : WERR_OK
[2007/11/27 10:27:45, 5] rpc_server/srv_pipe.c:api_rpcTNP(2305)
  api_rpcTNP: called wkssvc successfully
[2007/11/27 10:27:45, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(529)
  free_pipe_context: destroying talloc pool of size 34
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(963)
  write_to_pipe: data_used = 52
[2007/11/27 10:27:45, 6] rpc_server/srv_pipe_hnd.c:read_from_pipe(995)
  read_from_pipe: 74ac name: wkssvc len: 1024
[2007/11/27 10:27:45, 10] 
rpc_server/srv_pipe_hnd.c:read_from_internal_pipe(1068)
  read_from_pipe: wkssvc: fault_state = 0 : data_sent_length = 0, 
prs_offset(&p->out_data.rdata) = 84.
＜略＞
[2007/11/27 10:27:45, 5] smbd/ipc.c:copy_trans_params_and_data(60)
  copy_trans_params_and_data: params[0..0] data[0..108]
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 41
[2007/11/27 10:27:45, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0x29
[2007/11/27 10:27:45, 3] smbd/process.c:process_smb(1068)
  Transaction 70 of length 45
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 3] smbd/process.c:switch_message(926)
  switch message SMBclose (pid 5676) conn 0x8515698
[2007/11/27 10:27:45, 4] smbd/uid.c:change_to_user(183)
  change_to_user: Skipping user change - already user
[2007/11/27 10:27:45, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1271)
  search for pipe pnum=74ac
[2007/11/27 10:27:45, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1275)
  pipe name wkssvc pnum=74ac (pipes_open=1)
[2007/11/27 10:27:45, 5] smbd/pipes.c:reply_pipe_close(297)
  reply_pipe_close: pnum:74ac
[2007/11/27 10:27:45, 10] rpc_server/srv_lsa_hnd.c:close_policy_by_pipe(241)
  close_policy_by_pipe: deleted handle list for pipe wkssvc
[2007/11/27 10:27:45, 4] rpc_server/srv_pipe_hnd.c:close_rpc_pipe_hnd(1169)
  closed pipe name wkssvc pnum=74ac (pipes_open=0)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 10] 
lib/util_sock.c:read_smb_length_return_keepalive(623)
  got smb length of 110
[2007/11/27 10:27:45, 6] smbd/process.c:process_smb(1067)
  got message type 0x0 of len 0x6e
[2007/11/27 10:27:45, 3] smbd/process.c:process_smb(1068)
  Transaction 71 of length 114
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(506)
[2007/11/27 10:27:45, 5] lib/util.c:show_msg(516)
＜略＞
[2007/11/27 10:27:45, 3] smbd/process.c:switch_message(926)
  switch message SMBtrans2 (pid 5676) conn 0x8517670
[2007/11/27 10:27:45, 3] smbd/sec_ctx.c:set_sec_ctx(241)
  setting sec ctx (99, 99) - sec_ctx_stack_ndx = 0
[2007/11/27 10:27:45, 5] auth/auth_util.c:debug_nt_user_token(454)
  NT user token of user S-1-5-21-2533135874-4032939792-2516561083-501
  contains 4 SIDs
  SID[  0]: S-1-5-21-2533135874-4032939792-2516561083-501
  SID[  1]: S-1-1-0
  SID[  2]: S-1-5-2
  SID[  3]: S-1-5-32-546
  SE_PRIV  0x0 0x0 0x0 0x0
[2007/11/27 10:27:45, 5] auth/auth_util.c:debug_unix_user_token(474)
  UNIX token of user 99
  Primary group is 99 and contains 0 supplementary groups
[2007/11/27 10:27:45, 5] smbd/uid.c:change_to_user(273)
  change_to_user uid=(99,99) gid=(0,99)
[2007/11/27 10:27:45, 10] smbd/trans2.c:call_trans2getdfsreferral(6188)
  call_trans2getdfsreferral
[2007/11/27 10:27:45, 10] smbd/msdfs.c:parse_dfs_path(84)
  parse_dfs_path: temp = |SERVER001\ldap_user1| after trimming \'s
[2007/11/27 10:27:45, 10] smbd/msdfs.c:parse_dfs_path(107)
  parse_dfs_path: hostname: SERVER001
[2007/11/27 10:27:45, 3] smbd/msdfs.c:get_referred_path(624)
  get_referred_path: |ldap_user1| in dfs path \SERVER001\ldap_user1 is not a 
dfs root.
[2007/11/27 10:27:45, 3] smbd/error.c:error_packet_set(106)
  error packet at smbd/trans2.c(6201) cmd=50 (SMBtrans2) NT_STATUS_NOT_FOUND