Attachment 5866 Details for Bug 7588 – log for machine when connecting user is member in 16 unix groups

log for machine when connecting user is member in 16 unix groups

log.cepure.16groups (text/plain), 92.99 KB, created by Marcis on 2010-07-26 12:08:40 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Marcis

Created: 2010-07-26 12:08:40 UTC

Size: 92.99 KB

patch

obsolete

>[2010/07/26 20:03:46.980822,  6] param/loadparm.c:7133(lp_file_list_changed)
>  lp_file_list_changed()
>  file /opt/samba/lib/smb.conf -> /opt/samba/lib/smb.conf  last mod_time: Mon Jul 26 18:46:25 2010
>  
>[2010/07/26 20:03:46.980918,  5] auth/auth_util.c:211(make_user_info_map)
>  Mapping user [PROSERVE]\[user1] from workstation [CEPURE]
>[2010/07/26 20:03:46.981892,  5] auth/auth_util.c:122(make_user_info)
>  attempting to make a user_info for user1 (user1)
>[2010/07/26 20:03:46.981923,  5] auth/auth_util.c:132(make_user_info)
>  making strings for user1's user_info struct
>[2010/07/26 20:03:46.981954,  5] auth/auth_util.c:164(make_user_info)
>  making blobs for user1's user_info struct
>[2010/07/26 20:03:46.981980, 10] auth/auth_util.c:182(make_user_info)
>  made an encrypted user_info for user1 (user1)
>[2010/07/26 20:03:46.982004,  3] auth/auth.c:216(check_ntlm_password)
>  check_ntlm_password:  Checking password for unmapped user [PROSERVE]\[user1]@[CEPURE] with the new password interface
>[2010/07/26 20:03:46.982030,  3] auth/auth.c:219(check_ntlm_password)
>  check_ntlm_password:  mapped user is: [PROSERVE]\[user1]@[CEPURE]
>[2010/07/26 20:03:46.982059, 10] auth/auth.c:228(check_ntlm_password)
>  check_ntlm_password: auth_context challenge created by NTLMSSP callback (NTLM2)
>[2010/07/26 20:03:46.982083, 10] auth/auth.c:230(check_ntlm_password)
>  challenge is: 
>[2010/07/26 20:03:46.982107,  5] ../lib/util/util.c:278(_dump_data)
>  [0000] A6 08 6D 3B 49 24 77 0C                            ..m;I$w. 
>[2010/07/26 20:03:46.982141, 10] auth/auth.c:256(check_ntlm_password)
>  check_ntlm_password: guest had nothing to say
>[2010/07/26 20:03:46.982171,  8] lib/util.c:1869(is_myname)
>  is_myname("PROSERVE") returns 0
>[2010/07/26 20:03:46.982196,  6] auth/auth_sam.c:556(check_samstrict_security)
>  check_samstrict_security: PROSERVE is not one of my local names (ROLE_DOMAIN_MEMBER)
>[2010/07/26 20:03:46.982226, 10] auth/auth.c:256(check_ntlm_password)
>  check_ntlm_password: sam had nothing to say
>[2010/07/26 20:03:46.982255,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:46.982296,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:46.982321,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:46.982345,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:46.982369,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:46.994497,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:46.994559,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user PROSERVE+user1
>[2010/07/26 20:03:46.994586,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is proserve+user1
>[2010/07/26 20:03:46.995822,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [PROSERVE+user1]!
>[2010/07/26 20:03:46.995863,  5] auth/auth_util.c:1522(fill_sam_account)
>  fill_sam_account: located username was [PROSERVE+user1]
>[2010/07/26 20:03:46.995905, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username PROSERVE+user1, was 
>[2010/07/26 20:03:46.995954, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 12 -> now SET
>[2010/07/26 20:03:46.995979, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:03:46.996004, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 13 -> now SET
>[2010/07/26 20:03:46.996033, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain CEPURE, was 
>[2010/07/26 20:03:46.996057, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 14 -> now DEFAULT
>[2010/07/26 20:03:46.996084, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 20 -> now DEFAULT
>[2010/07/26 20:03:46.996109,  4] lib/substitute.c:504(automount_server)
>  Home server: cepure
>[2010/07/26 20:03:46.996139, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path \\cepure\PROSERVE+user1\profile, was 
>[2010/07/26 20:03:46.996169, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 2 -> now DEFAULT
>[2010/07/26 20:03:46.996193,  4] lib/substitute.c:504(automount_server)
>  Home server: cepure
>[2010/07/26 20:03:46.996220, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir \\cepure\PROSERVE+user1, was 
>[2010/07/26 20:03:46.996245, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 1 -> now DEFAULT
>[2010/07/26 20:03:46.996275, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/07/26 20:03:46.996300, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 3 -> now DEFAULT
>[2010/07/26 20:03:46.996325, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:03:46.996350, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 4 -> now DEFAULT
>[2010/07/26 20:03:46.996386, 10] passdb/pdb_get_set.c:537(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-4235160793-1365951415-1100996829-28454
>[2010/07/26 20:03:46.996419, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 18 -> now SET
>[2010/07/26 20:03:46.996444, 10] passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-4235160793-1365951415-1100996829-28454 from rid 28454
>[2010/07/26 20:03:46.996482, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username user1, was 
>[2010/07/26 20:03:46.996512, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 15 -> now CHANGED
>[2010/07/26 20:03:46.996536, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username user1, was PROSERVE+user1
>[2010/07/26 20:03:46.996560, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 12 -> now CHANGED
>[2010/07/26 20:03:46.996587, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain PROSERVE, was CEPURE
>[2010/07/26 20:03:46.996616, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 14 -> now CHANGED
>[2010/07/26 20:03:46.996640, 10] passdb/pdb_get_set.c:537(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-851642300-2639045452-4037496937-3727
>[2010/07/26 20:03:46.996666, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 18 -> now CHANGED
>[2010/07/26 20:03:46.996735, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-513, value = 10513, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.996771, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-513 -> gid 10513
>[2010/07/26 20:03:46.996799, 10] passdb/pdb_get_set.c:595(pdb_set_group_sid)
>  pdb_set_group_sid: setting group sid S-1-5-21-851642300-2639045452-4037496937-513
>[2010/07/26 20:03:46.996826, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 19 -> now CHANGED
>[2010/07/26 20:03:46.996855, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:03:46.996879, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 13 -> now CHANGED
>[2010/07/26 20:03:46.996903, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:03:46.996928, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 4 -> now CHANGED
>[2010/07/26 20:03:46.996957, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was \\cepure\PROSERVE+user1\profile
>[2010/07/26 20:03:46.996982, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 2 -> now CHANGED
>[2010/07/26 20:03:46.997006, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was \\cepure\PROSERVE+user1
>[2010/07/26 20:03:46.997030, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 1 -> now CHANGED
>[2010/07/26 20:03:46.997059, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was 
>[2010/07/26 20:03:46.997083, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 3 -> now CHANGED
>[2010/07/26 20:03:46.997108, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 20 -> now CHANGED
>[2010/07/26 20:03:46.997132, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 21 -> now CHANGED
>[2010/07/26 20:03:46.997156, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 9 -> now CHANGED
>[2010/07/26 20:03:46.997185, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 10 -> now CHANGED
>[2010/07/26 20:03:46.997225,  3] auth/auth.c:265(check_ntlm_password)
>  check_ntlm_password: winbind authentication for user [user1] succeeded
>[2010/07/26 20:03:46.997254,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:46.997279,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:46.997308,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:46.997333,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:46.997358,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:46.997411,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:46.997438,  5] auth/auth.c:291(check_ntlm_password)
>  check_ntlm_password:  PAM Account for user [PROSERVE+user1] succeeded
>[2010/07/26 20:03:46.997467,  2] auth/auth.c:304(check_ntlm_password)
>  check_ntlm_password:  authentication for user [user1] -> [user1] -> [PROSERVE+user1] succeeded
>[2010/07/26 20:03:46.997492,  5] auth/auth_util.c:2119(free_user_info)
>  attempting to free (and zero) a user_info structure
>[2010/07/26 20:03:46.997516, 10] auth/auth_util.c:2123(free_user_info)
>  structure was created for user1
>[2010/07/26 20:03:46.997748, 10] auth/token_util.c:356(create_local_nt_token)
>  Create local NT token for S-1-5-21-851642300-2639045452-4037496937-3727
>[2010/07/26 20:03:46.997796,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:46.997822,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:46.997847,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:46.997871,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:46.997901,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:46.998654,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:46.998703,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-3727]
>[2010/07/26 20:03:46.998742,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-513]
>[2010/07/26 20:03:46.998780,  5] lib/privileges.c:128(get_privileges_for_sids)
>  get_privileges_for_sids: sid = S-1-1-0
>  Privilege set:
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2010/07/26 20:03:46.998833,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2010/07/26 20:03:46.998863,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2010/07/26 20:03:46.998899,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2527]
>[2010/07/26 20:03:46.998931,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2531]
>[2010/07/26 20:03:46.998963,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2526]
>[2010/07/26 20:03:46.999002,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2524]
>[2010/07/26 20:03:46.999034,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2530]
>[2010/07/26 20:03:46.999066,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2532]
>[2010/07/26 20:03:46.999104,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2523]
>[2010/07/26 20:03:46.999135,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2525]
>[2010/07/26 20:03:46.999167,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2529]
>[2010/07/26 20:03:46.999203,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2522]
>[2010/07/26 20:03:46.999235,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-2528]
>[2010/07/26 20:03:46.999266,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-32-545]
>[2010/07/26 20:03:46.999301, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-11, value = 110004, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.999335, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-11 -> gid 110004
>[2010/07/26 20:03:46.999372, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2527, value = 12527, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.999401, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2527 -> gid 12527
>[2010/07/26 20:03:46.999445, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2531, value = 12531, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.999474, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2531 -> gid 12531
>[2010/07/26 20:03:46.999512, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2526, value = 12526, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.999549, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2526 -> gid 12526
>[2010/07/26 20:03:46.999587, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2524, value = 12524, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.999621, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2524 -> gid 12524
>[2010/07/26 20:03:46.999658, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2530, value = 12530, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.999687, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2530 -> gid 12530
>[2010/07/26 20:03:46.999724, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2532, value = 12532, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.999758, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2532 -> gid 12532
>[2010/07/26 20:03:46.999796, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2523, value = 12523, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.999831, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2523 -> gid 12523
>[2010/07/26 20:03:46.999868, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2525, value = 12525, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.999897, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2525 -> gid 12525
>[2010/07/26 20:03:46.999941, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2529, value = 12529, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:46.999969, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2529 -> gid 12529
>[2010/07/26 20:03:47.000007, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2522, value = 12522, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:47.000041, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2522 -> gid 12522
>[2010/07/26 20:03:47.000078, 10] lib/gencache.c:345(gencache_get_data_blob)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-2528, value = 12528, timeout = Mon Aug  2 17:57:40 2010
>[2010/07/26 20:03:47.000107, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2528 -> gid 12528
>[2010/07/26 20:03:47.000170, 10] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-851642300-2639045452-4037496937-3727
>  contains 34 SIDs
>  SID[  0]: S-1-5-21-851642300-2639045452-4037496937-3727
>  SID[  1]: S-1-5-21-851642300-2639045452-4037496937-513
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SID[  5]: S-1-5-21-851642300-2639045452-4037496937-2527
>  SID[  6]: S-1-5-21-851642300-2639045452-4037496937-2531
>  SID[  7]: S-1-5-21-851642300-2639045452-4037496937-2526
>  SID[  8]: S-1-5-21-851642300-2639045452-4037496937-2524
>  SID[  9]: S-1-5-21-851642300-2639045452-4037496937-2530
>  SID[ 10]: S-1-5-21-851642300-2639045452-4037496937-2532
>  SID[ 11]: S-1-5-21-851642300-2639045452-4037496937-2523
>  SID[ 12]: S-1-5-21-851642300-2639045452-4037496937-2525
>  SID[ 13]: S-1-5-21-851642300-2639045452-4037496937-2529
>  SID[ 14]: S-1-5-21-851642300-2639045452-4037496937-2522
>  SID[ 15]: S-1-5-21-851642300-2639045452-4037496937-2528
>  SID[ 16]: S-1-5-32-545
>  SID[ 17]: S-1-22-1-13727
>  SID[ 18]: S-1-22-2-10513
>  SID[ 19]: S-1-22-2-110002
>  SID[ 20]: S-1-22-2-110003
>  SID[ 21]: S-1-22-2-110004
>  SID[ 22]: S-1-22-2-12527
>  SID[ 23]: S-1-22-2-12531
>  SID[ 24]: S-1-22-2-12526
>  SID[ 25]: S-1-22-2-12524
>  SID[ 26]: S-1-22-2-12530
>  SID[ 27]: S-1-22-2-12532
>  SID[ 28]: S-1-22-2-12523
>  SID[ 29]: S-1-22-2-12525
>  SID[ 30]: S-1-22-2-12529
>  SID[ 31]: S-1-22-2-12522
>  SID[ 32]: S-1-22-2-12528
>  SID[ 33]: S-1-22-2-110001
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2010/07/26 20:03:47.000647, 10] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 13727
>  Primary group is 10513 and contains 16 supplementary groups
>  Group[  0]: 10513
>  Group[  1]: 110002
>  Group[  2]: 110003
>  Group[  3]: 110004
>  Group[  4]: 12527
>  Group[  5]: 12531
>  Group[  6]: 12526
>  Group[  7]: 12524
>  Group[  8]: 12530
>  Group[  9]: 12532
>  Group[ 10]: 12523
>  Group[ 11]: 12525
>  Group[ 12]: 12529
>  Group[ 13]: 12522
>  Group[ 14]: 12528
>  Group[ 15]: 110001
>[2010/07/26 20:03:47.000860, 10] auth/auth_ntlmssp.c:139(auth_ntlmssp_check_password)
>  Got NT session key of length 16
>[2010/07/26 20:03:47.000915, 10] auth/auth_ntlmssp.c:146(auth_ntlmssp_check_password)
>  Got LM session key of length 8
>[2010/07/26 20:03:47.000943, 10] libsmb/ntlmssp.c:815(ntlmssp_server_auth)
>  ntlmssp_server_auth: Created NTLM2 session key.
>[2010/07/26 20:03:47.000972,  3] libsmb/ntlmssp_sign.c:343(ntlmssp_sign_init)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2010/07/26 20:03:47.001003,  3] libsmb/ntlmssp.c:65(debug_ntlmssp_flags)
>  Got NTLMSSP neg_flags=0x60088215
>    NTLMSSP_NEGOTIATE_UNICODE
>    NTLMSSP_REQUEST_TARGET
>    NTLMSSP_NEGOTIATE_SIGN
>    NTLMSSP_NEGOTIATE_NTLM
>    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
>    NTLMSSP_NEGOTIATE_NTLM2
>    NTLMSSP_NEGOTIATE_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>[2010/07/26 20:03:47.001121, 10] smbd/password.c:278(register_existing_vuid)
>  register_existing_vuid: (13727,10513) PROSERVE+user1 user1 PROSERVE guest=0
>[2010/07/26 20:03:47.001147,  3] smbd/password.c:282(register_existing_vuid)
>  register_existing_vuid: User name: PROSERVE+user1	Real name: 
>[2010/07/26 20:03:47.001179,  3] smbd/password.c:292(register_existing_vuid)
>  register_existing_vuid: UNIX uid 13727 is UNIX user PROSERVE+user1, and will be vuid 100
>[2010/07/26 20:03:47.001208, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 49442F32353830362F31303000
>[2010/07/26 20:03:47.001242, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x8994950
>[2010/07/26 20:03:47.001335, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 49442F32353830362F31303000
>[2010/07/26 20:03:47.001374,  7] param/loadparm.c:9399(lp_servicenumber)
>  lp_servicenumber: couldn't find PROSERVE+user1
>[2010/07/26 20:03:47.002181,  3] smbd/password.c:223(register_homes_share)
>  Adding homes service for user 'PROSERVE+user1' using home directory: '/home/PROSERVE/user1'
>[2010/07/26 20:03:47.002227,  7] param/loadparm.c:9399(lp_servicenumber)
>  lp_servicenumber: couldn't find homes
>[2010/07/26 20:03:47.002299,  6] param/loadparm.c:7133(lp_file_list_changed)
>  lp_file_list_changed()
>  file /opt/samba/lib/smb.conf -> /opt/samba/lib/smb.conf  last mod_time: Mon Jul 26 18:46:25 2010
>  
>[2010/07/26 20:03:47.002405,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.003343,  5] lib/util.c:627(show_msg)
>  size=104
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51203
>  smb_tid=0
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=3
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    9 (0x9)
>  smb_bcc=61
>[2010/07/26 20:03:47.003594, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] A1 07 30 05 A0 03 0A 01   00 55 00 6E 00 69 00 78   ..0..... .U.n.i.x
>  [0010] 00 00 00 53 00 61 00 6D   00 62 00 61 00 20 00 33   ...S.a.m .b.a. .3
>  [0020] 00 2E 00 35 00 2E 00 34   00 00 00 50 00 52 00 4F   ...5...4 ...P.R.O
>  [0030] 00 53 00 45 00 52 00 56   00 45 00 00 00           .S.E.R.V .E...
>[2010/07/26 20:03:47.003916, 10] lib/util_sock.c:726(read_smb_length_return_keepalive)
>  got smb length of 76
>[2010/07/26 20:03:47.003964,  6] smbd/process.c:1482(process_smb)
>  got message type 0x0 of len 0x4c
>[2010/07/26 20:03:47.003990,  3] smbd/process.c:1485(process_smb)
>  Transaction 3 of length 80 (0 toread)
>[2010/07/26 20:03:47.004015,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.004029,  5] lib/util.c:627(show_msg)
>  size=76
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=8
>  smb_flg2=51201
>  smb_tid=65535
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=4
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    8 (0x8)
>  smb_vwv[ 3]=    1 (0x1)
>  smb_bcc=33
>[2010/07/26 20:03:47.004231, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 5C 00 5C 00 43 00 45   00 50 00 55 00 52 00 45   .\.\.C.E .P.U.R.E
>  [0010] 00 5C 00 49 00 50 00 43   00 24 00 00 00 49 50 43   .\.I.P.C .$...IPC
>  [0020] 00                                                . 
>[2010/07/26 20:03:47.004302,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtconX (pid 25806) conn 0x0
>[2010/07/26 20:03:47.004409,  0] smbd/process.c:1243(smb_dump)
>  created /tmp/SMBtconX.9.req len 80
>[2010/07/26 20:03:47.004437,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.004463,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.004488,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.004545,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.004591,  4] smbd/reply.c:767(reply_tcon_and_X)
>  Client requested device type [IPC] for share [IPC$]
>[2010/07/26 20:03:47.004631,  5] smbd/service.c:1227(make_connection)
>  making a connection to 'normal' service ipc$
>[2010/07/26 20:03:47.004674, 10] smbd/share_access.c:238(user_ok_token)
>  user_ok_token: share IPC$ is ok for unix user PROSERVE+user1
>[2010/07/26 20:03:47.004726, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 3: SET
>[2010/07/26 20:03:47.004753, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 3: CHANGED
>[2010/07/26 20:03:47.004777, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 1: SET
>[2010/07/26 20:03:47.004801, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 1: CHANGED
>[2010/07/26 20:03:47.004825, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 4: SET
>[2010/07/26 20:03:47.004849, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 4: CHANGED
>[2010/07/26 20:03:47.004880, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 2: SET
>[2010/07/26 20:03:47.004903, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 2: CHANGED
>[2010/07/26 20:03:47.004929,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.004955,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.004979,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.005010,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.005034,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.005095, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:03:47.005126,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.005161, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 180
>[2010/07/26 20:03:47.005187, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:03:47.005226, 18] lib/util_tdb.c:358(tdb_unpack)
>  tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:03:47.005253, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 5 -> now SET
>[2010/07/26 20:03:47.005285, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 6 -> now SET
>[2010/07/26 20:03:47.005309, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 7 -> now SET
>[2010/07/26 20:03:47.005333, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 8 -> now SET
>[2010/07/26 20:03:47.005357, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 9 -> now SET
>[2010/07/26 20:03:47.005381, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 10 -> now SET
>[2010/07/26 20:03:47.005413, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 21 -> now SET
>[2010/07/26 20:03:47.005437, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username user1, was 
>[2010/07/26 20:03:47.005461, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 12 -> now SET
>[2010/07/26 20:03:47.005485, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain PROSERVE, was 
>[2010/07/26 20:03:47.005509, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 14 -> now SET
>[2010/07/26 20:03:47.005540, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username user1, was 
>[2010/07/26 20:03:47.005564, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 15 -> now SET
>[2010/07/26 20:03:47.005588, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:03:47.005613, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 13 -> now SET
>[2010/07/26 20:03:47.005648, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2010/07/26 20:03:47.005673, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 1 -> now SET
>[2010/07/26 20:03:47.005696, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/07/26 20:03:47.005721, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 3 -> now SET
>[2010/07/26 20:03:47.005744, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:03:47.005780, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 4 -> now SET
>[2010/07/26 20:03:47.005804, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2010/07/26 20:03:47.005829, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 2 -> now SET
>[2010/07/26 20:03:47.005854, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 22 -> now SET
>[2010/07/26 20:03:47.005885, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 24 -> now SET
>[2010/07/26 20:03:47.005909, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2010/07/26 20:03:47.005933, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 23 -> now SET
>[2010/07/26 20:03:47.005957, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 25 -> now SET
>[2010/07/26 20:03:47.005983,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.006015,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.006040,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.006063,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.006087,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.006146, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:03:47.006177,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.006204, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 33 -> now SET
>[2010/07/26 20:03:47.006230, 10] passdb/pdb_get_set.c:537(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-4235160793-1365951415-1100996829-0
>[2010/07/26 20:03:47.006264, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 18 -> now SET
>[2010/07/26 20:03:47.006288, 10] passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-4235160793-1365951415-1100996829-0 from rid 0
>[2010/07/26 20:03:47.006326, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 16 -> now SET
>[2010/07/26 20:03:47.006351, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 28 -> now SET
>[2010/07/26 20:03:47.006375, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 29 -> now SET
>[2010/07/26 20:03:47.006407, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 30 -> now SET
>[2010/07/26 20:03:47.006434, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 20 -> now SET
>[2010/07/26 20:03:47.006459, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 17 -> now SET
>[2010/07/26 20:03:47.006482, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 26 -> now SET
>[2010/07/26 20:03:47.006513, 10] passdb/pdb_get_set.c:595(pdb_set_group_sid)
>  pdb_set_group_sid: setting group sid S-1-5-21-851642300-2639045452-4037496937-513
>[2010/07/26 20:03:47.006547, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 19 -> now SET
>[2010/07/26 20:03:47.006585,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user PROSERVE+user1
>[2010/07/26 20:03:47.006610,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is proserve+user1
>[2010/07/26 20:03:47.006637,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [PROSERVE+user1]!
>[2010/07/26 20:03:47.006686, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service IPC$, connectpath = /tmp
>[2010/07/26 20:03:47.006714,  3] smbd/service.c:807(make_connection_snum)
>  Connect path is '/tmp' for service [IPC$]
>[2010/07/26 20:03:47.006759, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2010/07/26 20:03:47.006800,  3] smbd/vfs.c:97(vfs_init_default)
>  Initialising default vfs hooks
>[2010/07/26 20:03:47.006846, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for /[Default VFS]/
>[2010/07/26 20:03:47.006872,  5] smbd/vfs.c:87(smb_register_vfs)
>  Successfully added vfs backend '/[Default VFS]/'
>[2010/07/26 20:03:47.006919, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for solarisacl
>[2010/07/26 20:03:47.006944,  5] smbd/vfs.c:87(smb_register_vfs)
>  Successfully added vfs backend 'solarisacl'
>[2010/07/26 20:03:47.006981, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for zfsacl
>[2010/07/26 20:03:47.007007,  5] smbd/vfs.c:87(smb_register_vfs)
>  Successfully added vfs backend 'zfsacl'
>[2010/07/26 20:03:47.007031,  3] smbd/vfs.c:122(vfs_init_custom)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2010/07/26 20:03:47.007056, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for /[Default VFS]/
>  Successfully loaded vfs module [/[Default VFS]/] with the new modules system
>[2010/07/26 20:03:47.007098,  3] smbd/vfs.c:122(vfs_init_custom)
>  Initialising custom vfs hooks from [zfsacl]
>[2010/07/26 20:03:47.007123, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for zfsacl
>  Successfully loaded vfs module [zfsacl] with the new modules system
>[2010/07/26 20:03:47.007161,  5] smbd/connection.c:142(claim_connection)
>  claiming [IPC$]
>[2010/07/26 20:03:47.007210, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key CE6400000100000049504324000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:03:47.007252, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x89991a0
>[2010/07/26 20:03:47.007287, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key CE6400000100000049504324000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:03:47.007399, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service IPC$, connectpath = /tmp
>[2010/07/26 20:03:47.007431, 10] smbd/share_access.c:238(user_ok_token)
>  user_ok_token: share IPC$ is ok for unix user PROSERVE+user1
>[2010/07/26 20:03:47.007458, 10] smbd/share_access.c:283(is_share_read_only_for_token)
>  is_share_read_only_for_user: share IPC$ is read-only for unix user PROSERVE+user1
>[2010/07/26 20:03:47.007499, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2010/07/26 20:03:47.007565, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 3: SET
>[2010/07/26 20:03:47.007592, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 3: CHANGED
>[2010/07/26 20:03:47.007616, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 1: SET
>[2010/07/26 20:03:47.007650, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 1: CHANGED
>[2010/07/26 20:03:47.007674, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 4: SET
>[2010/07/26 20:03:47.007698, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 4: CHANGED
>[2010/07/26 20:03:47.007722, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 2: SET
>[2010/07/26 20:03:47.007746, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 2: CHANGED
>[2010/07/26 20:03:47.007781,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.007806,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.007830,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.007855,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.007879,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.007934, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:03:47.007964,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.007991, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 180
>[2010/07/26 20:03:47.008017, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:03:47.008051, 18] lib/util_tdb.c:358(tdb_unpack)
>  tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:03:47.008077, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 5 -> now SET
>[2010/07/26 20:03:47.008103, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 6 -> now SET
>[2010/07/26 20:03:47.008127, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 7 -> now SET
>[2010/07/26 20:03:47.008158, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 8 -> now SET
>[2010/07/26 20:03:47.008183, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 9 -> now SET
>[2010/07/26 20:03:47.008207, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 10 -> now SET
>[2010/07/26 20:03:47.008231, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 21 -> now SET
>[2010/07/26 20:03:47.008254, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username user1, was 
>[2010/07/26 20:03:47.008286, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 12 -> now SET
>[2010/07/26 20:03:47.008310, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain PROSERVE, was 
>[2010/07/26 20:03:47.008335, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 14 -> now SET
>[2010/07/26 20:03:47.008358, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username user1, was 
>[2010/07/26 20:03:47.008383, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 15 -> now SET
>[2010/07/26 20:03:47.008414, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:03:47.008439, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 13 -> now SET
>[2010/07/26 20:03:47.008463, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2010/07/26 20:03:47.008488, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 1 -> now SET
>[2010/07/26 20:03:47.008519, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/07/26 20:03:47.008544, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 3 -> now SET
>[2010/07/26 20:03:47.008568, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:03:47.008592, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 4 -> now SET
>[2010/07/26 20:03:47.008616, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2010/07/26 20:03:47.008650, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 2 -> now SET
>[2010/07/26 20:03:47.008674, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 22 -> now SET
>[2010/07/26 20:03:47.008699, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 24 -> now SET
>[2010/07/26 20:03:47.008722, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2010/07/26 20:03:47.008756, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 23 -> now SET
>[2010/07/26 20:03:47.008781, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 25 -> now SET
>[2010/07/26 20:03:47.008806,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.008831,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.008855,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.008886,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.008911,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.008956, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:03:47.008986,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.009019, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 33 -> now SET
>[2010/07/26 20:03:47.009044, 10] passdb/pdb_get_set.c:537(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-4235160793-1365951415-1100996829-0
>[2010/07/26 20:03:47.009071, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 18 -> now SET
>[2010/07/26 20:03:47.009094, 10] passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-4235160793-1365951415-1100996829-0 from rid 0
>[2010/07/26 20:03:47.009138, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 16 -> now SET
>[2010/07/26 20:03:47.009163, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 28 -> now SET
>[2010/07/26 20:03:47.009187, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 29 -> now SET
>[2010/07/26 20:03:47.009210, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 30 -> now SET
>[2010/07/26 20:03:47.009234, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 20 -> now SET
>[2010/07/26 20:03:47.009266, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 17 -> now SET
>[2010/07/26 20:03:47.009290, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 26 -> now SET
>[2010/07/26 20:03:47.009318, 10] passdb/pdb_get_set.c:595(pdb_set_group_sid)
>  pdb_set_group_sid: setting group sid S-1-5-21-851642300-2639045452-4037496937-513
>[2010/07/26 20:03:47.009344, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 19 -> now SET
>[2010/07/26 20:03:47.009384,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (13727, 10513) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.009409,  5] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-851642300-2639045452-4037496937-3727
>  contains 34 SIDs
>  SID[  0]: S-1-5-21-851642300-2639045452-4037496937-3727
>  SID[  1]: S-1-5-21-851642300-2639045452-4037496937-513
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SID[  5]: S-1-5-21-851642300-2639045452-4037496937-2527
>  SID[  6]: S-1-5-21-851642300-2639045452-4037496937-2531
>  SID[  7]: S-1-5-21-851642300-2639045452-4037496937-2526
>  SID[  8]: S-1-5-21-851642300-2639045452-4037496937-2524
>  SID[  9]: S-1-5-21-851642300-2639045452-4037496937-2530
>  SID[ 10]: S-1-5-21-851642300-2639045452-4037496937-2532
>  SID[ 11]: S-1-5-21-851642300-2639045452-4037496937-2523
>  SID[ 12]: S-1-5-21-851642300-2639045452-4037496937-2525
>  SID[ 13]: S-1-5-21-851642300-2639045452-4037496937-2529
>  SID[ 14]: S-1-5-21-851642300-2639045452-4037496937-2522
>  SID[ 15]: S-1-5-21-851642300-2639045452-4037496937-2528
>  SID[ 16]: S-1-5-32-545
>  SID[ 17]: S-1-22-1-13727
>  SID[ 18]: S-1-22-2-10513
>  SID[ 19]: S-1-22-2-110002
>  SID[ 20]: S-1-22-2-110003
>  SID[ 21]: S-1-22-2-110004
>  SID[ 22]: S-1-22-2-12527
>  SID[ 23]: S-1-22-2-12531
>  SID[ 24]: S-1-22-2-12526
>  SID[ 25]: S-1-22-2-12524
>  SID[ 26]: S-1-22-2-12530
>  SID[ 27]: S-1-22-2-12532
>  SID[ 28]: S-1-22-2-12523
>  SID[ 29]: S-1-22-2-12525
>  SID[ 30]: S-1-22-2-12529
>  SID[ 31]: S-1-22-2-12522
>  SID[ 32]: S-1-22-2-12528
>  SID[ 33]: S-1-22-2-110001
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2010/07/26 20:03:47.009898,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 13727
>  Primary group is 10513 and contains 16 supplementary groups
>  Group[  0]: 10513
>  Group[  1]: 110002
>  Group[  2]: 110003
>  Group[  3]: 110004
>  Group[  4]: 12527
>  Group[  5]: 12531
>  Group[  6]: 12526
>  Group[  7]: 12524
>  Group[  8]: 12530
>  Group[  9]: 12532
>  Group[ 10]: 12523
>  Group[ 11]: 12525
>  Group[ 12]: 12529
>  Group[ 13]: 12522
>  Group[ 14]: 12528
>  Group[ 15]: 110001
>[2010/07/26 20:03:47.010119,  5] smbd/uid.c:354(change_to_user)
>  change_to_user uid=(0,13727) gid=(0,10513)
>[2010/07/26 20:03:47.010152, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service IPC$, connectpath = /tmp
>[2010/07/26 20:03:47.010195, 10] modules/vfs_default.c:152(vfswrap_fs_capabilities)
>  vfswrap_fs_capabilities: timestamp resolution of sec available on share IPC$, directory /tmp
>[2010/07/26 20:03:47.010228,  3] smbd/service.c:1070(make_connection_snum)
>  cepure (192.168.0.96) connect to service IPC$ initially as user PROSERVE+user1 (uid=13727, gid=10513) (pid 25806)
>[2010/07/26 20:03:47.010268,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.010292,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.010315,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.010368,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.010399,  3] smbd/reply.c:846(reply_tcon_and_X)
>  tconX service=IPC$ 
>[2010/07/26 20:03:47.010495, 10] lib/util_sock.c:726(read_smb_length_return_keepalive)
>  got smb length of 98
>[2010/07/26 20:03:47.010528,  6] smbd/process.c:1482(process_smb)
>  got message type 0x0 of len 0x62
>[2010/07/26 20:03:47.010553,  3] smbd/process.c:1485(process_smb)
>  Transaction 4 of length 102 (0 toread)
>[2010/07/26 20:03:47.010586,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.010599,  5] lib/util.c:627(show_msg)
>  size=98
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=8
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=5
>  smt_wct=15
>  smb_vwv[ 0]=   30 (0x1E)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=16644 (0x4104)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=    0 (0x0)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=   30 (0x1E)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=   98 (0x62)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=   16 (0x10)
>  smb_bcc=33
>[2010/07/26 20:03:47.010913, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 44 20 03 00 5C 00 63   00 65 00 70 00 75 00 72   .D ..\.c .e.p.u.r
>  [0010] 00 65 00 5C 00 53 00 41   00 4D 00 42 00 41 00 00   .e.\.S.A .M.B.A..
>  [0020] 00                                                . 
>[2010/07/26 20:03:47.010982,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtrans2 (pid 25806) conn 0x899b600
>[2010/07/26 20:03:47.011068,  0] smbd/process.c:1243(smb_dump)
>  created /tmp/SMBtrans2.7.req len 102
>[2010/07/26 20:03:47.011096,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (13727, 10513) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.011120,  5] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-851642300-2639045452-4037496937-3727
>  contains 34 SIDs
>  SID[  0]: S-1-5-21-851642300-2639045452-4037496937-3727
>  SID[  1]: S-1-5-21-851642300-2639045452-4037496937-513
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SID[  5]: S-1-5-21-851642300-2639045452-4037496937-2527
>  SID[  6]: S-1-5-21-851642300-2639045452-4037496937-2531
>  SID[  7]: S-1-5-21-851642300-2639045452-4037496937-2526
>  SID[  8]: S-1-5-21-851642300-2639045452-4037496937-2524
>  SID[  9]: S-1-5-21-851642300-2639045452-4037496937-2530
>  SID[ 10]: S-1-5-21-851642300-2639045452-4037496937-2532
>  SID[ 11]: S-1-5-21-851642300-2639045452-4037496937-2523
>  SID[ 12]: S-1-5-21-851642300-2639045452-4037496937-2525
>  SID[ 13]: S-1-5-21-851642300-2639045452-4037496937-2529
>  SID[ 14]: S-1-5-21-851642300-2639045452-4037496937-2522
>  SID[ 15]: S-1-5-21-851642300-2639045452-4037496937-2528
>  SID[ 16]: S-1-5-32-545
>  SID[ 17]: S-1-22-1-13727
>  SID[ 18]: S-1-22-2-10513
>  SID[ 19]: S-1-22-2-110002
>  SID[ 20]: S-1-22-2-110003
>  SID[ 21]: S-1-22-2-110004
>  SID[ 22]: S-1-22-2-12527
>  SID[ 23]: S-1-22-2-12531
>  SID[ 24]: S-1-22-2-12526
>  SID[ 25]: S-1-22-2-12524
>  SID[ 26]: S-1-22-2-12530
>  SID[ 27]: S-1-22-2-12532
>  SID[ 28]: S-1-22-2-12523
>  SID[ 29]: S-1-22-2-12525
>  SID[ 30]: S-1-22-2-12529
>  SID[ 31]: S-1-22-2-12522
>  SID[ 32]: S-1-22-2-12528
>  SID[ 33]: S-1-22-2-110001
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2010/07/26 20:03:47.011594,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 13727
>  Primary group is 10513 and contains 16 supplementary groups
>  Group[  0]: 10513
>  Group[  1]: 110002
>  Group[  2]: 110003
>  Group[  3]: 110004
>  Group[  4]: 12527
>  Group[  5]: 12531
>  Group[  6]: 12526
>  Group[  7]: 12524
>  Group[  8]: 12530
>  Group[  9]: 12532
>  Group[ 10]: 12523
>  Group[ 11]: 12525
>  Group[ 12]: 12529
>  Group[ 13]: 12522
>  Group[ 14]: 12528
>  Group[ 15]: 110001
>[2010/07/26 20:03:47.011813,  5] smbd/uid.c:354(change_to_user)
>  change_to_user uid=(0,13727) gid=(0,10513)
>[2010/07/26 20:03:47.011846,  4] smbd/vfs.c:721(vfs_ChDir)
>  vfs_ChDir to /tmp
>[2010/07/26 20:03:47.011894, 10] smbd/trans2.c:7996(call_trans2getdfsreferral)
>  call_trans2getdfsreferral
>[2010/07/26 20:03:47.011945, 10] smbd/msdfs.c:107(parse_dfs_path)
>  parse_dfs_path: temp = |cepure\SAMBA| after trimming \'s
>[2010/07/26 20:03:47.011970, 10] smbd/msdfs.c:132(parse_dfs_path)
>  parse_dfs_path: hostname: cepure
>[2010/07/26 20:03:47.011994, 10] smbd/msdfs.c:174(parse_dfs_path)
>  parse_dfs_path: servicename: SAMBA
>[2010/07/26 20:03:47.012021,  3] smbd/msdfs.c:848(get_referred_path)
>  get_referred_path: |SAMBA| in dfs path \cepure\SAMBA is not a dfs root.
>[2010/07/26 20:03:47.012056,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/trans2.c(8018) cmd=50 (SMBtrans2) NT_STATUS_NOT_FOUND
>[2010/07/26 20:03:47.012089,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.012102,  5] lib/util.c:627(show_msg)
>  size=35
>  smb_com=0x32
>  smb_rcls=37
>  smb_reh=2
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51203
>  smb_tid=1
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=5
>  smt_wct=0
>  smb_bcc=0
>[2010/07/26 20:03:47.012269, 10] lib/util_sock.c:726(read_smb_length_return_keepalive)
>  got smb length of 35
>[2010/07/26 20:03:47.012307,  6] smbd/process.c:1482(process_smb)
>  got message type 0x0 of len 0x23
>[2010/07/26 20:03:47.012332,  3] smbd/process.c:1485(process_smb)
>  Transaction 5 of length 39 (0 toread)
>[2010/07/26 20:03:47.012355,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.012368,  5] lib/util.c:627(show_msg)
>  size=35
>  smb_com=0x71
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=8
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=6
>  smt_wct=0
>  smb_bcc=0
>[2010/07/26 20:03:47.012538,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtdis (pid 25806) conn 0x899b600
>[2010/07/26 20:03:47.012603,  0] smbd/process.c:1243(smb_dump)
>  created /tmp/SMBtdis.7.req len 39
>[2010/07/26 20:03:47.012629,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.012653,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.012677,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.012735,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.012776,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.012801,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.012825,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.012878,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.012903,  3] smbd/service.c:1251(close_cnum)
>  cepure (192.168.0.96) closed connection to service IPC$
>[2010/07/26 20:03:47.012930,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to IPC$
>[2010/07/26 20:03:47.012978, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key CE6400000100000049504324000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:03:47.013023, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x89be6e0
>[2010/07/26 20:03:47.013055, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key CE6400000100000049504324000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:03:47.013117,  4] smbd/vfs.c:721(vfs_ChDir)
>  vfs_ChDir to /
>[2010/07/26 20:03:47.013147,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.013172,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.013205,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.013245,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.013285,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.013299,  5] lib/util.c:627(show_msg)
>  size=35
>  smb_com=0x71
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51203
>  smb_tid=1
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=6
>  smt_wct=0
>  smb_bcc=0
>[2010/07/26 20:03:47.013510, 10] lib/util_sock.c:726(read_smb_length_return_keepalive)
>  got smb length of 80
>[2010/07/26 20:03:47.013538,  6] smbd/process.c:1482(process_smb)
>  got message type 0x0 of len 0x50
>[2010/07/26 20:03:47.013562,  3] smbd/process.c:1485(process_smb)
>  Transaction 6 of length 84 (0 toread)
>[2010/07/26 20:03:47.013587,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.013600,  5] lib/util.c:627(show_msg)
>  size=80
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=8
>  smb_flg2=51201
>  smb_tid=65535
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=7
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    8 (0x8)
>  smb_vwv[ 3]=    1 (0x1)
>  smb_bcc=37
>[2010/07/26 20:03:47.013797, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 5C 00 5C 00 43 00 45   00 50 00 55 00 52 00 45   .\.\.C.E .P.U.R.E
>  [0010] 00 5C 00 53 00 41 00 4D   00 42 00 41 00 00 00 3F   .\.S.A.M .B.A...?
>  [0020] 3F 3F 3F 3F 00                                    ????. 
>[2010/07/26 20:03:47.013878,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtconX (pid 25806) conn 0x0
>[2010/07/26 20:03:47.013955,  0] smbd/process.c:1243(smb_dump)
>  created /tmp/SMBtconX.10.req len 84
>[2010/07/26 20:03:47.013982,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.014006,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.014030,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.014084,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.014113,  4] smbd/reply.c:767(reply_tcon_and_X)
>  Client requested device type [?????] for share [SAMBA]
>[2010/07/26 20:03:47.014143,  5] smbd/service.c:1227(make_connection)
>  making a connection to 'normal' service samba
>[2010/07/26 20:03:47.014173, 10] smbd/share_access.c:238(user_ok_token)
>  user_ok_token: share SAMBA is ok for unix user PROSERVE+user1
>[2010/07/26 20:03:47.014243, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 3: SET
>[2010/07/26 20:03:47.014267, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 3: CHANGED
>[2010/07/26 20:03:47.014291, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 1: SET
>[2010/07/26 20:03:47.014316, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 1: CHANGED
>[2010/07/26 20:03:47.014340, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 4: SET
>[2010/07/26 20:03:47.014373, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 4: CHANGED
>[2010/07/26 20:03:47.014396, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 2: SET
>[2010/07/26 20:03:47.014420, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 2: CHANGED
>[2010/07/26 20:03:47.014445,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.014469,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.014502,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.014526,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.014550,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.014596, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:03:47.014636,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.014662, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 180
>[2010/07/26 20:03:47.014687, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:03:47.014713, 18] lib/util_tdb.c:358(tdb_unpack)
>  tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:03:47.014748, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 5 -> now SET
>[2010/07/26 20:03:47.014772, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 6 -> now SET
>[2010/07/26 20:03:47.014796, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 7 -> now SET
>[2010/07/26 20:03:47.014820, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 8 -> now SET
>[2010/07/26 20:03:47.014844, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 9 -> now SET
>[2010/07/26 20:03:47.014877, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 10 -> now SET
>[2010/07/26 20:03:47.014901, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 21 -> now SET
>[2010/07/26 20:03:47.014925, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username user1, was 
>[2010/07/26 20:03:47.014949, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 12 -> now SET
>[2010/07/26 20:03:47.014973, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain PROSERVE, was 
>[2010/07/26 20:03:47.015011, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 14 -> now SET
>[2010/07/26 20:03:47.015035, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username user1, was 
>[2010/07/26 20:03:47.015059, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 15 -> now SET
>[2010/07/26 20:03:47.015082, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:03:47.015120, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 13 -> now SET
>[2010/07/26 20:03:47.015144, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2010/07/26 20:03:47.015169, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 1 -> now SET
>[2010/07/26 20:03:47.015192, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/07/26 20:03:47.015216, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 3 -> now SET
>[2010/07/26 20:03:47.015250, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:03:47.015274, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 4 -> now SET
>[2010/07/26 20:03:47.015298, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2010/07/26 20:03:47.015323, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 2 -> now SET
>[2010/07/26 20:03:47.015357, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 22 -> now SET
>[2010/07/26 20:03:47.015381, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 24 -> now SET
>[2010/07/26 20:03:47.015405, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2010/07/26 20:03:47.015430, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 23 -> now SET
>[2010/07/26 20:03:47.015454, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 25 -> now SET
>[2010/07/26 20:03:47.015489,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.015513,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.015537,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.015561,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.015584,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.015638, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:03:47.015670,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.015695, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 33 -> now SET
>[2010/07/26 20:03:47.015719, 10] passdb/pdb_get_set.c:537(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-4235160793-1365951415-1100996829-0
>[2010/07/26 20:03:47.015756, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 18 -> now SET
>[2010/07/26 20:03:47.015779, 10] passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-4235160793-1365951415-1100996829-0 from rid 0
>[2010/07/26 20:03:47.015816, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 16 -> now SET
>[2010/07/26 20:03:47.015839, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 28 -> now SET
>[2010/07/26 20:03:47.015873, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 29 -> now SET
>[2010/07/26 20:03:47.015897, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 30 -> now SET
>[2010/07/26 20:03:47.015920, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 20 -> now SET
>[2010/07/26 20:03:47.015944, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 17 -> now SET
>[2010/07/26 20:03:47.015968, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 26 -> now SET
>[2010/07/26 20:03:47.016010, 10] passdb/pdb_get_set.c:595(pdb_set_group_sid)
>  pdb_set_group_sid: setting group sid S-1-5-21-851642300-2639045452-4037496937-513
>[2010/07/26 20:03:47.016036, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 19 -> now SET
>[2010/07/26 20:03:47.016061,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user PROSERVE+user1
>[2010/07/26 20:03:47.016085,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is proserve+user1
>[2010/07/26 20:03:47.016125,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [PROSERVE+user1]!
>[2010/07/26 20:03:47.016164, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service SAMBA, connectpath = /SAMBA
>[2010/07/26 20:03:47.016191,  3] smbd/service.c:807(make_connection_snum)
>  Connect path is '/SAMBA' for service [SAMBA]
>[2010/07/26 20:03:47.016224, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2010/07/26 20:03:47.016261,  3] smbd/vfs.c:97(vfs_init_default)
>  Initialising default vfs hooks
>[2010/07/26 20:03:47.016286,  3] smbd/vfs.c:122(vfs_init_custom)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2010/07/26 20:03:47.016311, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for /[Default VFS]/
>  Successfully loaded vfs module [/[Default VFS]/] with the new modules system
>[2010/07/26 20:03:47.016347,  3] smbd/vfs.c:122(vfs_init_custom)
>  Initialising custom vfs hooks from [zfsacl]
>[2010/07/26 20:03:47.016383, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for zfsacl
>  Successfully loaded vfs module [zfsacl] with the new modules system
>[2010/07/26 20:03:47.016650,  5] smbd/connection.c:142(claim_connection)
>  claiming [SAMBA]
>[2010/07/26 20:03:47.016702, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key CE6400000100000053414D42410000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:03:47.016748, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x898ff48
>[2010/07/26 20:03:47.016794, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key CE6400000100000053414D42410000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:03:47.016873, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service SAMBA, connectpath = /SAMBA
>[2010/07/26 20:03:47.016905, 10] smbd/share_access.c:238(user_ok_token)
>  user_ok_token: share SAMBA is ok for unix user PROSERVE+user1
>[2010/07/26 20:03:47.016932, 10] smbd/share_access.c:283(is_share_read_only_for_token)
>  is_share_read_only_for_user: share SAMBA is read-write for unix user PROSERVE+user1
>[2010/07/26 20:03:47.016977, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2010/07/26 20:03:47.017011, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2010/07/26 20:03:47.017040,  3] lib/util_sid.c:228(string_to_sid)
>  string_to_sid: Sid @PROSERVE+domain admins does not start with 'S-'.
>[2010/07/26 20:03:47.017096,  5] smbd/password.c:423(user_in_netgroup)
>  Unable to get default yp domain, let's try without specifying it
>[2010/07/26 20:03:47.017145,  5] smbd/password.c:430(user_in_netgroup)
>  looking for user PROSERVE+user1 of domain (ANY) in netgroup PROSERVE+domain admins
>[2010/07/26 20:03:47.017300,  5] smbd/password.c:453(user_in_netgroup)
>  looking for user proserve+user1 of domain (ANY) in netgroup PROSERVE+domain admins
>[2010/07/26 20:03:47.017354, 10] passdb/lookup_sid.c:69(lookup_name)
>  lookup_name: PROSERVE\domain admins => PROSERVE (domain), domain admins (name)
>[2010/07/26 20:03:47.017397, 10] passdb/lookup_sid.c:70(lookup_name)
>  lookup_name: flags = 0x077
>[2010/07/26 20:03:47.017766,  3] lib/util_sid.c:228(string_to_sid)
>  string_to_sid: Sid PROSERVE+administrator does not start with 'S-'.
>[2010/07/26 20:03:47.017797, 10] passdb/lookup_sid.c:69(lookup_name)
>  lookup_name: PROSERVE\administrator => PROSERVE (domain), administrator (name)
>[2010/07/26 20:03:47.017822, 10] passdb/lookup_sid.c:70(lookup_name)
>  lookup_name: flags = 0x073
>[2010/07/26 20:03:47.018117, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 3: SET
>[2010/07/26 20:03:47.018145, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 3: CHANGED
>[2010/07/26 20:03:47.018169, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 1: SET
>[2010/07/26 20:03:47.018193, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 1: CHANGED
>[2010/07/26 20:03:47.018217, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 4: SET
>[2010/07/26 20:03:47.018253, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 4: CHANGED
>[2010/07/26 20:03:47.018280, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 2: SET
>[2010/07/26 20:03:47.018305, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 2: CHANGED
>[2010/07/26 20:03:47.018332,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.018358,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.018395,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.018420,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.018445,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.018498, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:03:47.018529,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.018569, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 180
>[2010/07/26 20:03:47.018596, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:03:47.018623, 18] lib/util_tdb.c:358(tdb_unpack)
>  tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:03:47.018649, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 5 -> now SET
>[2010/07/26 20:03:47.018685, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 6 -> now SET
>[2010/07/26 20:03:47.018709, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 7 -> now SET
>[2010/07/26 20:03:47.018733, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 8 -> now SET
>[2010/07/26 20:03:47.018757, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 9 -> now SET
>[2010/07/26 20:03:47.018781, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 10 -> now SET
>[2010/07/26 20:03:47.018816, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 21 -> now SET
>[2010/07/26 20:03:47.018840, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username user1, was 
>[2010/07/26 20:03:47.018864, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 12 -> now SET
>[2010/07/26 20:03:47.018887, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain PROSERVE, was 
>[2010/07/26 20:03:47.018912, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 14 -> now SET
>[2010/07/26 20:03:47.018951, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username user1, was 
>[2010/07/26 20:03:47.018975, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 15 -> now SET
>[2010/07/26 20:03:47.018999, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:03:47.019023, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 13 -> now SET
>[2010/07/26 20:03:47.019062, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2010/07/26 20:03:47.019087, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 1 -> now SET
>[2010/07/26 20:03:47.019111, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/07/26 20:03:47.019135, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 3 -> now SET
>[2010/07/26 20:03:47.019159, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:03:47.019194, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 4 -> now SET
>[2010/07/26 20:03:47.019218, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2010/07/26 20:03:47.019243, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 2 -> now SET
>[2010/07/26 20:03:47.019267, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 22 -> now SET
>[2010/07/26 20:03:47.019302, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 24 -> now SET
>[2010/07/26 20:03:47.019326, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2010/07/26 20:03:47.019350, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 23 -> now SET
>[2010/07/26 20:03:47.019374, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 25 -> now SET
>[2010/07/26 20:03:47.019400,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.019436,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.019460,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:03:47.019484,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.019508,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.019565, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:03:47.019595,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.019622, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 33 -> now SET
>[2010/07/26 20:03:47.019646, 10] passdb/pdb_get_set.c:537(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-4235160793-1365951415-1100996829-0
>[2010/07/26 20:03:47.019687, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 18 -> now SET
>[2010/07/26 20:03:47.019711, 10] passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-4235160793-1365951415-1100996829-0 from rid 0
>[2010/07/26 20:03:47.019748, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 16 -> now SET
>[2010/07/26 20:03:47.019771, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 28 -> now SET
>[2010/07/26 20:03:47.019806, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 29 -> now SET
>[2010/07/26 20:03:47.019830, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 30 -> now SET
>[2010/07/26 20:03:47.019854, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 20 -> now SET
>[2010/07/26 20:03:47.019878, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 17 -> now SET
>[2010/07/26 20:03:47.019902, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 26 -> now SET
>[2010/07/26 20:03:47.019946, 10] passdb/pdb_get_set.c:595(pdb_set_group_sid)
>  pdb_set_group_sid: setting group sid S-1-5-21-851642300-2639045452-4037496937-513
>[2010/07/26 20:03:47.019972, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 19 -> now SET
>[2010/07/26 20:03:47.020006,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (13727, 10513) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.020032,  5] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-851642300-2639045452-4037496937-3727
>  contains 34 SIDs
>  SID[  0]: S-1-5-21-851642300-2639045452-4037496937-3727
>  SID[  1]: S-1-5-21-851642300-2639045452-4037496937-513
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SID[  5]: S-1-5-21-851642300-2639045452-4037496937-2527
>  SID[  6]: S-1-5-21-851642300-2639045452-4037496937-2531
>  SID[  7]: S-1-5-21-851642300-2639045452-4037496937-2526
>  SID[  8]: S-1-5-21-851642300-2639045452-4037496937-2524
>  SID[  9]: S-1-5-21-851642300-2639045452-4037496937-2530
>  SID[ 10]: S-1-5-21-851642300-2639045452-4037496937-2532
>  SID[ 11]: S-1-5-21-851642300-2639045452-4037496937-2523
>  SID[ 12]: S-1-5-21-851642300-2639045452-4037496937-2525
>  SID[ 13]: S-1-5-21-851642300-2639045452-4037496937-2529
>  SID[ 14]: S-1-5-21-851642300-2639045452-4037496937-2522
>  SID[ 15]: S-1-5-21-851642300-2639045452-4037496937-2528
>  SID[ 16]: S-1-5-32-545
>  SID[ 17]: S-1-22-1-13727
>  SID[ 18]: S-1-22-2-10513
>  SID[ 19]: S-1-22-2-110002
>  SID[ 20]: S-1-22-2-110003
>  SID[ 21]: S-1-22-2-110004
>  SID[ 22]: S-1-22-2-12527
>  SID[ 23]: S-1-22-2-12531
>  SID[ 24]: S-1-22-2-12526
>  SID[ 25]: S-1-22-2-12524
>  SID[ 26]: S-1-22-2-12530
>  SID[ 27]: S-1-22-2-12532
>  SID[ 28]: S-1-22-2-12523
>  SID[ 29]: S-1-22-2-12525
>  SID[ 30]: S-1-22-2-12529
>  SID[ 31]: S-1-22-2-12522
>  SID[ 32]: S-1-22-2-12528
>  SID[ 33]: S-1-22-2-110001
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2010/07/26 20:03:47.020524,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 13727
>  Primary group is 10513 and contains 16 supplementary groups
>  Group[  0]: 10513
>  Group[  1]: 110002
>  Group[  2]: 110003
>  Group[  3]: 110004
>  Group[  4]: 12527
>  Group[  5]: 12531
>  Group[  6]: 12526
>  Group[  7]: 12524
>  Group[  8]: 12530
>  Group[  9]: 12532
>  Group[ 10]: 12523
>  Group[ 11]: 12525
>  Group[ 12]: 12529
>  Group[ 13]: 12522
>  Group[ 14]: 12528
>  Group[ 15]: 110001
>[2010/07/26 20:03:47.020747,  5] smbd/uid.c:354(change_to_user)
>  change_to_user uid=(0,13727) gid=(0,10513)
>[2010/07/26 20:03:47.020787, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service SAMBA, connectpath = /SAMBA
>[2010/07/26 20:03:47.020842, 10] modules/vfs_default.c:152(vfswrap_fs_capabilities)
>  vfswrap_fs_capabilities: timestamp resolution of sec available on share SAMBA, directory /SAMBA
>[2010/07/26 20:03:47.020902,  1] smbd/service.c:1070(make_connection_snum)
>  cepure (192.168.0.96) connect to service SAMBA initially as user PROSERVE+user1 (uid=13727, gid=10513) (pid 25806)
>[2010/07/26 20:03:47.020933,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.020969,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.020993,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.021038,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.021072,  3] smbd/reply.c:846(reply_tcon_and_X)
>  tconX service=SAMBA 
>[2010/07/26 20:03:47.021185, 10] lib/util_sock.c:726(read_smb_length_return_keepalive)
>  got smb length of 88
>[2010/07/26 20:03:47.021231,  6] smbd/process.c:1482(process_smb)
>  got message type 0x0 of len 0x58
>[2010/07/26 20:03:47.021256,  3] smbd/process.c:1485(process_smb)
>  Transaction 7 of length 92 (0 toread)
>[2010/07/26 20:03:47.021280,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.021293,  5] lib/util.c:627(show_msg)
>  size=88
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=8
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=8
>  smt_wct=15
>  smb_vwv[ 0]=   20 (0x14)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=16644 (0x4104)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=    0 (0x0)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=   20 (0x14)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=   88 (0x58)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    5 (0x5)
>  smb_bcc=23
>[2010/07/26 20:03:47.021615, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 44 20 01 01 00 00 00   00 5C 00 73 00 68 00 61   .D ..... .\.s.h.a
>  [0010] 00 72 00 65 00 00 00                              .r.e... 
>[2010/07/26 20:03:47.021684,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtrans2 (pid 25806) conn 0x899b600
>[2010/07/26 20:03:47.021764,  0] smbd/process.c:1243(smb_dump)
>  created /tmp/SMBtrans2.8.req len 92
>[2010/07/26 20:03:47.021792,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (13727, 10513) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.021829,  5] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-851642300-2639045452-4037496937-3727
>  contains 34 SIDs
>  SID[  0]: S-1-5-21-851642300-2639045452-4037496937-3727
>  SID[  1]: S-1-5-21-851642300-2639045452-4037496937-513
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SID[  5]: S-1-5-21-851642300-2639045452-4037496937-2527
>  SID[  6]: S-1-5-21-851642300-2639045452-4037496937-2531
>  SID[  7]: S-1-5-21-851642300-2639045452-4037496937-2526
>  SID[  8]: S-1-5-21-851642300-2639045452-4037496937-2524
>  SID[  9]: S-1-5-21-851642300-2639045452-4037496937-2530
>  SID[ 10]: S-1-5-21-851642300-2639045452-4037496937-2532
>  SID[ 11]: S-1-5-21-851642300-2639045452-4037496937-2523
>  SID[ 12]: S-1-5-21-851642300-2639045452-4037496937-2525
>  SID[ 13]: S-1-5-21-851642300-2639045452-4037496937-2529
>  SID[ 14]: S-1-5-21-851642300-2639045452-4037496937-2522
>  SID[ 15]: S-1-5-21-851642300-2639045452-4037496937-2528
>  SID[ 16]: S-1-5-32-545
>  SID[ 17]: S-1-22-1-13727
>  SID[ 18]: S-1-22-2-10513
>  SID[ 19]: S-1-22-2-110002
>  SID[ 20]: S-1-22-2-110003
>  SID[ 21]: S-1-22-2-110004
>  SID[ 22]: S-1-22-2-12527
>  SID[ 23]: S-1-22-2-12531
>  SID[ 24]: S-1-22-2-12526
>  SID[ 25]: S-1-22-2-12524
>  SID[ 26]: S-1-22-2-12530
>  SID[ 27]: S-1-22-2-12532
>  SID[ 28]: S-1-22-2-12523
>  SID[ 29]: S-1-22-2-12525
>  SID[ 30]: S-1-22-2-12529
>  SID[ 31]: S-1-22-2-12522
>  SID[ 32]: S-1-22-2-12528
>  SID[ 33]: S-1-22-2-110001
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2010/07/26 20:03:47.022296,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 13727
>  Primary group is 10513 and contains 16 supplementary groups
>  Group[  0]: 10513
>  Group[  1]: 110002
>  Group[  2]: 110003
>  Group[  3]: 110004
>  Group[  4]: 12527
>  Group[  5]: 12531
>  Group[  6]: 12526
>  Group[  7]: 12524
>  Group[  8]: 12530
>  Group[  9]: 12532
>  Group[ 10]: 12523
>  Group[ 11]: 12525
>  Group[ 12]: 12529
>  Group[ 13]: 12522
>  Group[ 14]: 12528
>  Group[ 15]: 110001
>[2010/07/26 20:03:47.022537,  5] smbd/uid.c:354(change_to_user)
>  change_to_user uid=(0,13727) gid=(0,10513)
>[2010/07/26 20:03:47.022577,  4] smbd/vfs.c:721(vfs_ChDir)
>  vfs_ChDir to /SAMBA
>[2010/07/26 20:03:47.022621,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 257
>[2010/07/26 20:03:47.022669,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file "share"
>[2010/07/26 20:03:47.022702,  5] smbd/filename.c:328(unix_convert)
>  unix_convert begin: name = share, dirpath = , start = share
>[2010/07/26 20:03:47.022736,  5] smbd/filename.c:351(unix_convert)
>  conversion of base_name finished share -> share
>[2010/07/26 20:03:47.022778,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [share] [/SAMBA]
>[2010/07/26 20:03:47.022819, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [share] -> [/SAMBA/share]
>[2010/07/26 20:03:47.022845,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: share reduced to /SAMBA/share
>[2010/07/26 20:03:47.022893, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2010/07/26 20:03:47.022951,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo share (fnum = -1) level=257 call=5 total_data=0
>[2010/07/26 20:03:47.022981,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: share (fnum = -1) level=257 max_data=16644
>[2010/07/26 20:03:47.023010,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: share
>[2010/07/26 20:03:47.023036,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning rd
>[2010/07/26 20:03:47.023081,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning rd
>[2010/07/26 20:03:47.023125, 10] smbd/trans2.c:4428(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: SMB_QUERY_FILE_BASIC_INFO
>[2010/07/26 20:03:47.023154,  5] smbd/trans2.c:4441(smbd_do_qfilepathinfo)
>  SMB_QFBI - create: Mon Jul 26 11:25:48 2010
>   access: Mon Jul 26 18:21:02 2010
>   write: Mon Jul 26 11:25:48 2010
>   change: Mon Jul 26 11:25:48 2010
>   mode: 11
>[2010/07/26 20:03:47.023245,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 36, useable_space = 131010
>[2010/07/26 20:03:47.023270,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 36, paramsize = 2, datasize = 36
>[2010/07/26 20:03:47.023295,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.023308,  5] lib/util.c:627(show_msg)
>  size=96
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51203
>  smb_tid=1
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=8
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   36 (0x24)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   36 (0x24)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=41
>[2010/07/26 20:03:47.023570, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 00 00 00 86 25 8D   26 9C 2C CB 01 75 57 89   ......%. &.,..uW.
>  [0010] 28 D6 2C CB 01 86 25 8D   26 9C 2C CB 01 86 25 8D   (.,...%. &.,...%.
>  [0020] 26 9C 2C CB 01 11 00 00   00                       &.,..... .
>[2010/07/26 20:03:47.023684, 10] lib/util_sock.c:726(read_smb_length_return_keepalive)
>  got smb length of 35
>[2010/07/26 20:03:47.023713,  6] smbd/process.c:1482(process_smb)
>  got message type 0x0 of len 0x23
>[2010/07/26 20:03:47.023738,  3] smbd/process.c:1485(process_smb)
>  Transaction 8 of length 39 (0 toread)
>[2010/07/26 20:03:47.023761,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.023787,  5] lib/util.c:627(show_msg)
>  size=35
>  smb_com=0x71
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=8
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=9
>  smt_wct=0
>  smb_bcc=0
>[2010/07/26 20:03:47.023932,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtdis (pid 25806) conn 0x899b600
>[2010/07/26 20:03:47.024004,  0] smbd/process.c:1243(smb_dump)
>  created /tmp/SMBtdis.8.req len 39
>[2010/07/26 20:03:47.024030,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.024068,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.024092,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.024137,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.024166,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.024203,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.024227,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.024267,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.024293,  1] smbd/service.c:1251(close_cnum)
>  cepure (192.168.0.96) closed connection to service SAMBA
>[2010/07/26 20:03:47.024339,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to SAMBA
>[2010/07/26 20:03:47.024387, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key CE6400000100000053414D42410000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:03:47.024442, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x89be6e0
>[2010/07/26 20:03:47.024474, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key CE6400000100000053414D42410000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:03:47.024539,  4] smbd/vfs.c:721(vfs_ChDir)
>  vfs_ChDir to /
>[2010/07/26 20:03:47.024581,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.024606,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.024630,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.024670,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.024702,  5] lib/messages.c:329(messaging_deregister)
>  Deregistering messaging pointer for type 784 - private_data=89987a8
>[2010/07/26 20:03:47.024772,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:03:47.024787,  5] lib/util.c:627(show_msg)
>  size=35
>  smb_com=0x71
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51203
>  smb_tid=1
>  smb_pid=25805
>  smb_uid=100
>  smb_mid=9
>  smt_wct=0
>  smb_bcc=0
>[2010/07/26 20:03:47.024971,  5] lib/util_sock.c:462(read_fd_with_timeout)
>  read_fd_with_timeout: blocking read. EOF from client.
>[2010/07/26 20:03:47.025011, 10] smbd/process.c:286(receive_smb_raw_talloc)
>  receive_smb_raw: NT_STATUS_END_OF_FILE
>[2010/07/26 20:03:47.025036,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:03:47.025061,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:03:47.025084,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/07/26 20:03:47.025142,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:03:47.025173, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 49442F32353830362F31303000
>[2010/07/26 20:03:47.025204, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x8995930
>[2010/07/26 20:03:47.025236, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 49442F32353830362F31303000
>[2010/07/26 20:03:47.025282,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to 
>[2010/07/26 20:03:47.025348, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key CE640000FFFFFFFF00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:03:47.025405, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x899b210
>[2010/07/26 20:03:47.025437, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key CE640000FFFFFFFF00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:03:47.025588,  3] smbd/server.c:902(exit_server_common)
>  Server exit (failed to receive smb request)

Actions: View

Attachments on bug 7588: 5866 | 5867 | 5868 | 5869 | 8950 | 8951 | 8996 | 8997