Attachment 1408 Details for Bug 3047 – Log excerpt from smbd.log with loglevel 10 describing the bug

Log excerpt from smbd.log with loglevel 10 describing the bug

bug.txt (text/plain), 11.09 KB, created by Christian "Xtra" Schiffler on 2005-08-26 13:58:51 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Christian "Xtra" Schiffler

Created: 2005-08-26 13:58:51 UTC

Size: 11.09 KB

patch

obsolete

>Reproduced on version 3.0.14a.
>
>How to reproduce: Open NT User/Group Manager and try to add an user to an group.
>
>System environment is an system that has all users and groups (except for root
>and demon users/groups) stored in LDAP Database for domain wide login.
>
>Log snippet with loglevel 10 is encluded.
>
>[2005/08/24 18:25:45, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(777)
>  process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 0,
>incoming data = 110
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_debug(82)
>  000000 smb_io_rpc_hdr
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint8(584)
>      0000 major     : 05
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint8(584)
>      0001 minor     : 00
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint8(584)
>      0002 pkt_type  : 00
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint8(584)
>      0003 flags     : 03
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint8(584)
>      0004 pack_type0: 10
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint8(584)
>      0005 pack_type1: 00
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint8(584)
>      0006 pack_type2: 00
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint8(584)
>      0007 pack_type3: 00
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint16(613)
>      0008 frag_len  : 007e
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint16(613)
>      000a auth_len  : 0000
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint32(642)
>      000c call_id   : 00000046
>[2005/08/24 18:25:45, 5] rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(486)
>  unmarshall_rpc_header: using little-endian RPC
>[2005/08/24 18:25:45, 10] rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(515)
>  unmarshall_rpc_header: type = 0, flags = 3
>[2005/08/24 18:25:45, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(878)
>  write_to_pipe: data_used = 0
>[2005/08/24 18:25:45, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(874)
>  write_to_pipe: data_left = 110
>[2005/08/24 18:25:45, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(777)
>  process_incoming_data: Start: pdu_received_len = 0, pdu_needed_len = 110,
>incoming data = 110
>[2005/08/24 18:25:45, 10] rpc_server/srv_pipe_hnd.c:process_complete_pdu(720)
>  process_complete_pdu: processing packet type 0
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_debug(82)
>  000000 smb_io_rpc_hdr_req req
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint32(642)
>      0000 alloc_hint: 00000066
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint16(613)
>      0004 context_id: 0000
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint16(613)
>      0006 opnum     : 0015
>[2005/08/24 18:25:45, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(542)
>  free_pipe_context: destroying talloc pool of size 0
>[2005/08/24 18:25:45, 5] rpc_server/srv_pipe.c:api_pipe_request(1497)
>  Requested \PIPE\samr
>[2005/08/24 18:25:45, 4] rpc_server/srv_pipe.c:api_rpcTNP(1531)
>  api_rpcTNP: samr op 0x15 - api_rpcTNP: rpc command: SAMR_SET_GROUPINFO
>[2005/08/24 18:25:45, 6] rpc_server/srv_pipe.c:api_rpcTNP(1557)
>  api_rpc_cmds[31].fn == 0x8141c80
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_debug(82)
>  000000 samr_io_q_set_groupinfo
>[2005/08/24 18:25:45, 6] rpc_parse/parse_prs.c:prs_debug(82)
>      000000 smb_io_pol_hnd pol
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint32(642)
>          0000 data1: 00000000
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint32(642)
>          0004 data2: 0000001d
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint16(613)
>          0008 data3: 0000
>          0014 switch_value1: 0004
>[2005/08/24 18:25:45, 7] rpc_parse/parse_prs.c:prs_debug(82)
>          000016 samr_io_group_info4 group_info4
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint16(613)
>              0016 hdr_level: 0004
>[2005/08/24 18:25:45, 8] rpc_parse/parse_prs.c:prs_debug(82)
>              000018 smb_io_unihdr hdr_acct_desc
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint16(613)
>                  0018 uni_str_len: 003a
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint16(613)
>                  001a uni_max_len: 003c
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint32(642)
>                  001c buffer     : 00b36630
>[2005/08/24 18:25:45, 8] rpc_parse/parse_prs.c:prs_debug(82)
>              000020 smb_io_unistr2 uni_acct_desc
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint32(642)
>                  0020 uni_max_len: 0000001e
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint32(642)
>                  0024 offset     : 00000000
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:prs_uint32(642)
>                  0028 uni_str_len: 0000001d
>[2005/08/24 18:25:45, 5] rpc_parse/parse_prs.c:dbg_rw_punival(814)
>                  002c buffer     : N.e.t.b.i.o.s. .D.o.m.a.i.n.
>.A.d.m.i.n.i.s.t.r.a.t.o.r.s.
>[2005/08/24 18:25:45, 4] rpc_server/srv_lsa_hnd.c:find_policy_by_hnd_internal(162)
>  Found policy hnd[0] [000] 00 00 00 00 1D 00 00 00  00 00 00 00 89 9F 0C 43 
>........ .......C
>  [010] 25 36 00 00                                       %6..
>[2005/08/24 18:25:45, 5] rpc_server/srv_samr_nt.c:access_check_samr_function(201)
>  _samr_set_groupinfo: access check ((granted: 0x000d000f;  required: 0x00000002)
>[2005/08/24 18:25:45, 10] groupdb/mapping.c:get_domain_group_from_sid(799)
>  get_domain_group_from_sid
>[2005/08/24 18:25:45, 3] smbd/sec_ctx.c:push_sec_ctx(256)
>  push_sec_ctx(1000, 513) : sec_ctx_stack_ndx = 1
>[2005/08/24 18:25:45, 3] smbd/uid.c:push_conn_ctx(365)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2005/08/24 18:25:45, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2005/08/24 18:25:45, 5] auth/auth_util.c:debug_nt_user_token(485)
>  NT user token: (NULL)
>[2005/08/24 18:25:45, 5] auth/auth_util.c:debug_unix_user_token(506)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/08/24 18:25:45, 5] lib/smbldap.c:smbldap_search(1038)
>  smbldap_search: base => [ou=Groups,dc=ubg,dc=nobis], filter =>
>[(&(objectClass=sambaGroupMapping)(sambaSID=S-1-5-21-4106371879-142849002-2088313151-512))],$
>[2005/08/24 18:25:45, 2] passdb/pdb_ldap.c:init_group_from_ldap(2000)
>  init_group_from_ldap: Entry found for group: 512
>[2005/08/24 18:25:45, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
>  pop_sec_ctx (1000, 513) - sec_ctx_stack_ndx = 0
>[2005/08/24 18:25:45, 10] groupdb/mapping.c:get_domain_group_from_sid(810)
>  get_domain_group_from_sid: SID found in the TDB
>[2005/08/24 18:25:45, 10] groupdb/mapping.c:get_domain_group_from_sid(817)
>  get_domain_group_from_sid: SID is a domain group
>  get_domain_group_from_sid: SID is a domain group
>[2005/08/24 18:25:45, 10] groupdb/mapping.c:get_domain_group_from_sid(823)
>  get_domain_group_from_sid: SID is mapped to gid:512
>[2005/08/24 18:25:45, 10] groupdb/mapping.c:get_domain_group_from_sid(831)
>  get_domain_group_from_sid: gid exists in UNIX security
>[2005/08/24 18:25:45, 5] lib/smbldap.c:smbldap_search(1038)
>  smbldap_search: base => [ou=Groups,dc=ubg,dc=nobis], filter =>
>[(&(|(objectClass=posixGroup)(objectclass=sambaIdmapEntry))(gidNumber=512))],
>scope => [2]
>[2005/08/24 18:25:45, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:45, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 1 try!
>[2005/08/24 18:25:46, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:46, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 2 try!
>[2005/08/24 18:25:47, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:47, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 3 try!
>[2005/08/24 18:25:48, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:48, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 4 try!
>[2005/08/24 18:25:49, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:49, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 5 try!
>[2005/08/24 18:25:50, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:50, 1] lib/smbldap.c:another_ldap_try(1011)
>[2005/08/24 18:25:50, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 6 try!
>[2005/08/24 18:25:51, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:51, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 7 try!
>[2005/08/24 18:25:52, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:52, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 8 try!
>[2005/08/24 18:25:53, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:53, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 9 try!
>[2005/08/24 18:25:54, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:54, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 10 try!
>[2005/08/24 18:25:55, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:55, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 11 try!
>[2005/08/24 18:25:56, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:56, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 12 try!
>[2005/08/24 18:25:57, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:57, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 13 try!
>[2005/08/24 18:25:58, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:58, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 14 try!
>[2005/08/24 18:25:59, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:25:59, 1] lib/smbldap.c:another_ldap_try(1011)
>  Connection to LDAP server failed for the 15 try!
>[2005/08/24 18:26:00, 0] lib/smbldap.c:smbldap_open(882)
>  smbldap_open: cannot access LDAP when not root..
>[2005/08/24 18:26:00, 0] passdb/pdb_ldap.c:ldapsam_search_one_group(1971)
>  ldapsam_search_one_group: Problem during the LDAP search: LDAP error:  (Timed out)
>  ldapsam_search_one_group: Query was: ou=Groups,dc=ubg,dc=nobis,
>(&(|(objectClass=posixGroup)(objectclass=sambaIdmapEntry))(gidNumber=512))
>[2005/08/24 18:26:00, 5] rpc_parse/parse_prs.c:prs_debug(82)
>  000000 samr_io_r_set_groupinfo
>[2005/08/24 18:26:00, 5] rpc_parse/parse_prs.c:prs_ntstatus(672)
>      0000 status: NT_STATUS_NO_SUCH_GROUP

Actions: View

Attachments on bug 3047: 1408 | 1411