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

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

log.cepure.17groups (text/plain), 84.65 KB, created by Marcis on 2010-07-26 12:09:00 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Marcis

Created: 2010-07-26 12:09:00 UTC

Size: 84.65 KB

patch

obsolete

>[2010/07/26 20:02:39.795630,  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:02:39.795699,  5] auth/auth_util.c:211(make_user_info_map)
>  Mapping user [PROSERVE]\[user1] from workstation [CEPURE]
>[2010/07/26 20:02:39.796700,  5] auth/auth_util.c:122(make_user_info)
>  attempting to make a user_info for user1 (user1)
>[2010/07/26 20:02:39.796736,  5] auth/auth_util.c:132(make_user_info)
>  making strings for user1's user_info struct
>[2010/07/26 20:02:39.796764,  5] auth/auth_util.c:164(make_user_info)
>  making blobs for user1's user_info struct
>[2010/07/26 20:02:39.796785, 10] auth/auth_util.c:182(make_user_info)
>  made an encrypted user_info for user1 (user1)
>[2010/07/26 20:02:39.796806,  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:02:39.796827,  3] auth/auth.c:219(check_ntlm_password)
>  check_ntlm_password:  mapped user is: [PROSERVE]\[user1]@[CEPURE]
>[2010/07/26 20:02:39.796851, 10] auth/auth.c:228(check_ntlm_password)
>  check_ntlm_password: auth_context challenge created by NTLMSSP callback (NTLM2)
>[2010/07/26 20:02:39.796871, 10] auth/auth.c:230(check_ntlm_password)
>  challenge is: 
>[2010/07/26 20:02:39.796891,  5] ../lib/util/util.c:278(_dump_data)
>  [0000] C3 1F AA E7 74 45 19 5C                            ....tE.\ 
>[2010/07/26 20:02:39.796921, 10] auth/auth.c:256(check_ntlm_password)
>  check_ntlm_password: guest had nothing to say
>[2010/07/26 20:02:39.796947,  8] lib/util.c:1869(is_myname)
>  is_myname("PROSERVE") returns 0
>[2010/07/26 20:02:39.796967,  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:02:39.796993, 10] auth/auth.c:256(check_ntlm_password)
>  check_ntlm_password: sam had nothing to say
>[2010/07/26 20:02:39.797018,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.797055,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.797076,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.797096,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.797116,  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:02:39.810318,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.810382,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user PROSERVE+user1
>[2010/07/26 20:02:39.810404,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is proserve+user1
>[2010/07/26 20:02:39.811697,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [PROSERVE+user1]!
>[2010/07/26 20:02:39.811739,  5] auth/auth_util.c:1522(fill_sam_account)
>  fill_sam_account: located username was [PROSERVE+user1]
>[2010/07/26 20:02:39.811780, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username PROSERVE+user1, was 
>[2010/07/26 20:02:39.811835, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 12 -> now SET
>[2010/07/26 20:02:39.811856, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:02:39.811876, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 13 -> now SET
>[2010/07/26 20:02:39.811900, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain CEPURE, was 
>[2010/07/26 20:02:39.811921, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 14 -> now DEFAULT
>[2010/07/26 20:02:39.811943, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 20 -> now DEFAULT
>[2010/07/26 20:02:39.811965,  4] lib/substitute.c:504(automount_server)
>  Home server: cepure
>[2010/07/26 20:02:39.811991, 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:02:39.812016, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 2 -> now DEFAULT
>[2010/07/26 20:02:39.812037,  4] lib/substitute.c:504(automount_server)
>  Home server: cepure
>[2010/07/26 20:02:39.812060, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir \\cepure\PROSERVE+user1, was 
>[2010/07/26 20:02:39.812080, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 1 -> now DEFAULT
>[2010/07/26 20:02:39.812105, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/07/26 20:02:39.812126, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 3 -> now DEFAULT
>[2010/07/26 20:02:39.812147, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:02:39.812168, 11] passdb/pdb_get_set.c:522(pdb_set_init_flags)
>  element 4 -> now DEFAULT
>[2010/07/26 20:02:39.812199, 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:02:39.812228, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 18 -> now SET
>[2010/07/26 20:02:39.812248, 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:02:39.812279, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username user1, was 
>[2010/07/26 20:02:39.812305, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 15 -> now CHANGED
>[2010/07/26 20:02:39.812324, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username user1, was PROSERVE+user1
>[2010/07/26 20:02:39.812344, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 12 -> now CHANGED
>[2010/07/26 20:02:39.812367, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain PROSERVE, was CEPURE
>[2010/07/26 20:02:39.812391, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 14 -> now CHANGED
>[2010/07/26 20:02:39.812411, 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:02:39.812433, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 18 -> now CHANGED
>[2010/07/26 20:02:39.812503, 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:02:39.812536, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-513 -> gid 10513
>[2010/07/26 20:02:39.812560, 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:02:39.812583, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 19 -> now CHANGED
>[2010/07/26 20:02:39.812607, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:02:39.812627, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 13 -> now CHANGED
>[2010/07/26 20:02:39.812647, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:02:39.812667, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 4 -> now CHANGED
>[2010/07/26 20:02:39.812690, 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:02:39.812711, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 2 -> now CHANGED
>[2010/07/26 20:02:39.812730, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was \\cepure\PROSERVE+user1
>[2010/07/26 20:02:39.812751, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 1 -> now CHANGED
>[2010/07/26 20:02:39.812775, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was 
>[2010/07/26 20:02:39.812795, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 3 -> now CHANGED
>[2010/07/26 20:02:39.812817, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 20 -> now CHANGED
>[2010/07/26 20:02:39.812838, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 21 -> now CHANGED
>[2010/07/26 20:02:39.812857, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 9 -> now CHANGED
>[2010/07/26 20:02:39.812882, 11] passdb/pdb_get_set.c:499(pdb_set_init_flags)
>  element 10 -> now CHANGED
>[2010/07/26 20:02:39.812938,  3] auth/auth.c:265(check_ntlm_password)
>  check_ntlm_password: winbind authentication for user [user1] succeeded
>[2010/07/26 20:02:39.812964,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.812987,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.813011,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.813031,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.813051,  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:02:39.813100,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.813122,  5] auth/auth.c:291(check_ntlm_password)
>  check_ntlm_password:  PAM Account for user [PROSERVE+user1] succeeded
>[2010/07/26 20:02:39.813146,  2] auth/auth.c:304(check_ntlm_password)
>  check_ntlm_password:  authentication for user [user1] -> [user1] -> [PROSERVE+user1] succeeded
>[2010/07/26 20:02:39.813167,  5] auth/auth_util.c:2119(free_user_info)
>  attempting to free (and zero) a user_info structure
>[2010/07/26 20:02:39.813187, 10] auth/auth_util.c:2123(free_user_info)
>  structure was created for user1
>[2010/07/26 20:02:39.813645, 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:02:39.813701,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.813724,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.813744,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.813765,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.813791,  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:02:39.814586,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.814635,  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:02:39.814669,  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:02:39.814704,  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:02:39.814749,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2010/07/26 20:02:39.814775,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2010/07/26 20:02:39.814806,  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:02:39.814835,  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:02:39.814863,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-851642300-2639045452-4037496937-3603]
>[2010/07/26 20:02:39.814899,  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:02:39.814927,  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:02:39.814954,  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:02:39.814987,  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:02:39.815015,  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:02:39.815043,  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:02:39.815075,  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:02:39.815102,  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:02:39.815129,  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:02:39.815156,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-32-545]
>[2010/07/26 20:02:39.815194, 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:02:39.815220, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-11 -> gid 110004
>[2010/07/26 20:02:39.815253, 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:02:39.815284, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2527 -> gid 12527
>[2010/07/26 20:02:39.815317, 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:02:39.815341, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2531 -> gid 12531
>[2010/07/26 20:02:39.815380, 10] lib/gencache.c:334(gencache_get_data_blob)
>  Cache entry with key = IDMAP/SID2GID/S-1-5-21-851642300-2639045452-4037496937-3603 couldn't be found 
>[2010/07/26 20:02:39.822242, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-3603 -> gid 13603
>[2010/07/26 20:02:39.822306, 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:02:39.822347, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2526 -> gid 12526
>[2010/07/26 20:02:39.822381, 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:02:39.822406, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2524 -> gid 12524
>[2010/07/26 20:02:39.822445, 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:02:39.822470, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2530 -> gid 12530
>[2010/07/26 20:02:39.822503, 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:02:39.822534, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2532 -> gid 12532
>[2010/07/26 20:02:39.822568, 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:02:39.822599, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2523 -> gid 12523
>[2010/07/26 20:02:39.822633, 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:02:39.822658, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2525 -> gid 12525
>[2010/07/26 20:02:39.822696, 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:02:39.822721, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2529 -> gid 12529
>[2010/07/26 20:02:39.822755, 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:02:39.822784, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2522 -> gid 12522
>[2010/07/26 20:02:39.822817, 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:02:39.822841, 10] passdb/lookup_sid.c:1518(sid_to_gid)
>  sid S-1-5-21-851642300-2639045452-4037496937-2528 -> gid 12528
>[2010/07/26 20:02:39.822915, 10] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-851642300-2639045452-4037496937-3727
>  contains 36 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-3603
>  SID[  8]: S-1-5-21-851642300-2639045452-4037496937-2526
>  SID[  9]: S-1-5-21-851642300-2639045452-4037496937-2524
>  SID[ 10]: S-1-5-21-851642300-2639045452-4037496937-2530
>  SID[ 11]: S-1-5-21-851642300-2639045452-4037496937-2532
>  SID[ 12]: S-1-5-21-851642300-2639045452-4037496937-2523
>  SID[ 13]: S-1-5-21-851642300-2639045452-4037496937-2525
>  SID[ 14]: S-1-5-21-851642300-2639045452-4037496937-2529
>  SID[ 15]: S-1-5-21-851642300-2639045452-4037496937-2522
>  SID[ 16]: S-1-5-21-851642300-2639045452-4037496937-2528
>  SID[ 17]: S-1-5-32-545
>  SID[ 18]: S-1-22-1-13727
>  SID[ 19]: S-1-22-2-10513
>  SID[ 20]: S-1-22-2-110002
>  SID[ 21]: S-1-22-2-110003
>  SID[ 22]: S-1-22-2-110004
>  SID[ 23]: S-1-22-2-12527
>  SID[ 24]: S-1-22-2-12531
>  SID[ 25]: S-1-22-2-13603
>  SID[ 26]: S-1-22-2-12526
>  SID[ 27]: S-1-22-2-12524
>  SID[ 28]: S-1-22-2-12530
>  SID[ 29]: S-1-22-2-12532
>  SID[ 30]: S-1-22-2-12523
>  SID[ 31]: S-1-22-2-12525
>  SID[ 32]: S-1-22-2-12529
>  SID[ 33]: S-1-22-2-12522
>  SID[ 34]: S-1-22-2-12528
>  SID[ 35]: S-1-22-2-110001
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2010/07/26 20:02:39.823342, 10] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 13727
>  Primary group is 10513 and contains 17 supplementary groups
>  Group[  0]: 10513
>  Group[  1]: 110002
>  Group[  2]: 110003
>  Group[  3]: 110004
>  Group[  4]: 12527
>  Group[  5]: 12531
>  Group[  6]: 13603
>  Group[  7]: 12526
>  Group[  8]: 12524
>  Group[  9]: 12530
>  Group[ 10]: 12532
>  Group[ 11]: 12523
>  Group[ 12]: 12525
>  Group[ 13]: 12529
>  Group[ 14]: 12522
>  Group[ 15]: 12528
>  Group[ 16]: 110001
>[2010/07/26 20:02:39.823533, 10] auth/auth_ntlmssp.c:139(auth_ntlmssp_check_password)
>  Got NT session key of length 16
>[2010/07/26 20:02:39.823555, 10] auth/auth_ntlmssp.c:146(auth_ntlmssp_check_password)
>  Got LM session key of length 8
>[2010/07/26 20:02:39.823579, 10] libsmb/ntlmssp.c:815(ntlmssp_server_auth)
>  ntlmssp_server_auth: Created NTLM2 session key.
>[2010/07/26 20:02:39.823605,  3] libsmb/ntlmssp_sign.c:343(ntlmssp_sign_init)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2010/07/26 20:02:39.823633,  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:02:39.823732, 10] smbd/password.c:278(register_existing_vuid)
>  register_existing_vuid: (13727,10513) PROSERVE+user1 user1 PROSERVE guest=0
>[2010/07/26 20:02:39.823759,  3] smbd/password.c:282(register_existing_vuid)
>  register_existing_vuid: User name: PROSERVE+user1	Real name: 
>[2010/07/26 20:02:39.823780,  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:02:39.823806, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 49442F32323632342F31303000
>[2010/07/26 20:02:39.823839, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x89bfa88
>[2010/07/26 20:02:39.823934, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 49442F32323632342F31303000
>[2010/07/26 20:02:39.823969,  7] param/loadparm.c:9399(lp_servicenumber)
>  lp_servicenumber: couldn't find PROSERVE+user1
>[2010/07/26 20:02:39.824729,  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:02:39.824770,  7] param/loadparm.c:9399(lp_servicenumber)
>  lp_servicenumber: couldn't find homes
>[2010/07/26 20:02:39.824830,  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:02:39.824898,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:02:39.824914,  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=22620
>  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:02:39.825074, 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:02:39.825353, 10] lib/util_sock.c:726(read_smb_length_return_keepalive)
>  got smb length of 76
>[2010/07/26 20:02:39.825386,  6] smbd/process.c:1482(process_smb)
>  got message type 0x0 of len 0x4c
>[2010/07/26 20:02:39.825407,  3] smbd/process.c:1485(process_smb)
>  Transaction 3 of length 80 (0 toread)
>[2010/07/26 20:02:39.825427,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:02:39.825438,  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=22620
>  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:02:39.825597, 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:02:39.825666,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtconX (pid 22624) conn 0x0
>[2010/07/26 20:02:39.825739,  0] smbd/process.c:1243(smb_dump)
>  created /tmp/SMBtconX.7.req len 80
>[2010/07/26 20:02:39.825762,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.825784,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.825805,  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:02:39.825853,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:02:39.825888,  4] smbd/reply.c:767(reply_tcon_and_X)
>  Client requested device type [IPC] for share [IPC$]
>[2010/07/26 20:02:39.825921,  5] smbd/service.c:1227(make_connection)
>  making a connection to 'normal' service ipc$
>[2010/07/26 20:02:39.825956, 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:02:39.825996, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 3: SET
>[2010/07/26 20:02:39.826017, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 3: CHANGED
>[2010/07/26 20:02:39.826037, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 1: SET
>[2010/07/26 20:02:39.826056, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 1: CHANGED
>[2010/07/26 20:02:39.826077, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 4: SET
>[2010/07/26 20:02:39.826103, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 4: CHANGED
>[2010/07/26 20:02:39.826122, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 2: SET
>[2010/07/26 20:02:39.826142, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 2: CHANGED
>[2010/07/26 20:02:39.826164,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.826193,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.826220,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.826240,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.826260,  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:02:39.826309, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:02:39.826341,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.826366, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 180
>[2010/07/26 20:02:39.826387, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:02:39.826416, 18] lib/util_tdb.c:358(tdb_unpack)
>  tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:02:39.826444, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 5 -> now SET
>[2010/07/26 20:02:39.826464, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 6 -> now SET
>[2010/07/26 20:02:39.826483, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 7 -> now SET
>[2010/07/26 20:02:39.826503, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 8 -> now SET
>[2010/07/26 20:02:39.826522, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 9 -> now SET
>[2010/07/26 20:02:39.826548, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 10 -> now SET
>[2010/07/26 20:02:39.826568, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 21 -> now SET
>[2010/07/26 20:02:39.826587, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username user1, was 
>[2010/07/26 20:02:39.826607, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 12 -> now SET
>[2010/07/26 20:02:39.826626, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain PROSERVE, was 
>[2010/07/26 20:02:39.826653, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 14 -> now SET
>[2010/07/26 20:02:39.826673, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username user1, was 
>[2010/07/26 20:02:39.826693, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 15 -> now SET
>[2010/07/26 20:02:39.826713, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:02:39.826733, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 13 -> now SET
>[2010/07/26 20:02:39.826763, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2010/07/26 20:02:39.826782, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 1 -> now SET
>[2010/07/26 20:02:39.826802, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/07/26 20:02:39.826822, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 3 -> now SET
>[2010/07/26 20:02:39.826847, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:02:39.826867, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 4 -> now SET
>[2010/07/26 20:02:39.826887, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2010/07/26 20:02:39.826951, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 2 -> now SET
>[2010/07/26 20:02:39.826972, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 22 -> now SET
>[2010/07/26 20:02:39.826999, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 24 -> now SET
>[2010/07/26 20:02:39.827019, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2010/07/26 20:02:39.827039, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 23 -> now SET
>[2010/07/26 20:02:39.827059, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 25 -> now SET
>[2010/07/26 20:02:39.827086,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.827107,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.827127,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.827146,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.827166,  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:02:39.827215, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:02:39.827241,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.827263, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 33 -> now SET
>[2010/07/26 20:02:39.827284, 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:02:39.827312, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 18 -> now SET
>[2010/07/26 20:02:39.827332, 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:02:39.827362, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 16 -> now SET
>[2010/07/26 20:02:39.827382, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 28 -> now SET
>[2010/07/26 20:02:39.827407, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 29 -> now SET
>[2010/07/26 20:02:39.827427, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 30 -> now SET
>[2010/07/26 20:02:39.827449, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 20 -> now SET
>[2010/07/26 20:02:39.827469, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 17 -> now SET
>[2010/07/26 20:02:39.827488, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 26 -> now SET
>[2010/07/26 20:02:39.827527, 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:02:39.827549, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 19 -> now SET
>[2010/07/26 20:02:39.827577,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user PROSERVE+user1
>[2010/07/26 20:02:39.827597,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is proserve+user1
>[2010/07/26 20:02:39.827631,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [PROSERVE+user1]!
>[2010/07/26 20:02:39.827667, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service IPC$, connectpath = /tmp
>[2010/07/26 20:02:39.827690,  3] smbd/service.c:807(make_connection_snum)
>  Connect path is '/tmp' for service [IPC$]
>[2010/07/26 20:02:39.827733, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2010/07/26 20:02:39.827777,  3] smbd/vfs.c:97(vfs_init_default)
>  Initialising default vfs hooks
>[2010/07/26 20:02:39.827811, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for /[Default VFS]/
>[2010/07/26 20:02:39.827834,  5] smbd/vfs.c:87(smb_register_vfs)
>  Successfully added vfs backend '/[Default VFS]/'
>[2010/07/26 20:02:39.827864, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for solarisacl
>[2010/07/26 20:02:39.827886,  5] smbd/vfs.c:87(smb_register_vfs)
>  Successfully added vfs backend 'solarisacl'
>[2010/07/26 20:02:39.827919, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for zfsacl
>[2010/07/26 20:02:39.827940,  5] smbd/vfs.c:87(smb_register_vfs)
>  Successfully added vfs backend 'zfsacl'
>[2010/07/26 20:02:39.827959,  3] smbd/vfs.c:122(vfs_init_custom)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2010/07/26 20:02:39.827979, 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:02:39.828015,  3] smbd/vfs.c:122(vfs_init_custom)
>  Initialising custom vfs hooks from [zfsacl]
>[2010/07/26 20:02:39.828035, 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:02:39.828066,  5] smbd/connection.c:142(claim_connection)
>  claiming [IPC$]
>[2010/07/26 20:02:39.828110, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 605800000100000049504324000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:02:39.828152, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x89991a0
>[2010/07/26 20:02:39.828181, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 605800000100000049504324000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:02:39.828282, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service IPC$, connectpath = /tmp
>[2010/07/26 20:02:39.828309, 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:02:39.828341, 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:02:39.828369, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2010/07/26 20:02:39.828415, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 3: SET
>[2010/07/26 20:02:39.828436, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 3: CHANGED
>[2010/07/26 20:02:39.828465, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 1: SET
>[2010/07/26 20:02:39.828485, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 1: CHANGED
>[2010/07/26 20:02:39.828505, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 4: SET
>[2010/07/26 20:02:39.828524, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 4: CHANGED
>[2010/07/26 20:02:39.828543, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 2: SET
>[2010/07/26 20:02:39.828569, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 2: CHANGED
>[2010/07/26 20:02:39.828590,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.828611,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.828631,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.828651,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.828677,  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:02:39.828719, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:02:39.828745,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.828767, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 180
>[2010/07/26 20:02:39.828795, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:02:39.828817, 18] lib/util_tdb.c:358(tdb_unpack)
>  tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:02:39.828838, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 5 -> now SET
>[2010/07/26 20:02:39.828859, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 6 -> now SET
>[2010/07/26 20:02:39.828879, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 7 -> now SET
>[2010/07/26 20:02:39.828905, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 8 -> now SET
>[2010/07/26 20:02:39.828925, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 9 -> now SET
>[2010/07/26 20:02:39.828944, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 10 -> now SET
>[2010/07/26 20:02:39.828963, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 21 -> now SET
>[2010/07/26 20:02:39.828983, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username user1, was 
>[2010/07/26 20:02:39.829010, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 12 -> now SET
>[2010/07/26 20:02:39.829029, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain PROSERVE, was 
>[2010/07/26 20:02:39.829049, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 14 -> now SET
>[2010/07/26 20:02:39.829068, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username user1, was 
>[2010/07/26 20:02:39.829095, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 15 -> now SET
>[2010/07/26 20:02:39.829115, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:02:39.829134, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 13 -> now SET
>[2010/07/26 20:02:39.829154, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2010/07/26 20:02:39.829173, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 1 -> now SET
>[2010/07/26 20:02:39.829202, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/07/26 20:02:39.829222, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 3 -> now SET
>[2010/07/26 20:02:39.829242, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:02:39.829262, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 4 -> now SET
>[2010/07/26 20:02:39.829293, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2010/07/26 20:02:39.829314, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 2 -> now SET
>[2010/07/26 20:02:39.829335, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 22 -> now SET
>[2010/07/26 20:02:39.829355, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 24 -> now SET
>[2010/07/26 20:02:39.829375, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2010/07/26 20:02:39.829403, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 23 -> now SET
>[2010/07/26 20:02:39.829423, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 25 -> now SET
>[2010/07/26 20:02:39.829444,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.829465,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.829485,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.829512,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.829532,  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:02:39.829572, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:02:39.829598,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.829627, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 33 -> now SET
>[2010/07/26 20:02:39.829648, 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:02:39.829670, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 18 -> now SET
>[2010/07/26 20:02:39.829690, 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:02:39.829728, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 16 -> now SET
>[2010/07/26 20:02:39.829748, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 28 -> now SET
>[2010/07/26 20:02:39.829768, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 29 -> now SET
>[2010/07/26 20:02:39.829788, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 30 -> now SET
>[2010/07/26 20:02:39.829817, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 20 -> now SET
>[2010/07/26 20:02:39.829837, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 17 -> now SET
>[2010/07/26 20:02:39.829857, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 26 -> now SET
>[2010/07/26 20:02:39.829881, 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:02:39.829904, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 19 -> now SET
>[2010/07/26 20:02:39.829940,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (13727, 10513) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.829961,  5] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-851642300-2639045452-4037496937-3727
>  contains 36 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-3603
>  SID[  8]: S-1-5-21-851642300-2639045452-4037496937-2526
>  SID[  9]: S-1-5-21-851642300-2639045452-4037496937-2524
>  SID[ 10]: S-1-5-21-851642300-2639045452-4037496937-2530
>  SID[ 11]: S-1-5-21-851642300-2639045452-4037496937-2532
>  SID[ 12]: S-1-5-21-851642300-2639045452-4037496937-2523
>  SID[ 13]: S-1-5-21-851642300-2639045452-4037496937-2525
>  SID[ 14]: S-1-5-21-851642300-2639045452-4037496937-2529
>  SID[ 15]: S-1-5-21-851642300-2639045452-4037496937-2522
>  SID[ 16]: S-1-5-21-851642300-2639045452-4037496937-2528
>  SID[ 17]: S-1-5-32-545
>  SID[ 18]: S-1-22-1-13727
>  SID[ 19]: S-1-22-2-10513
>  SID[ 20]: S-1-22-2-110002
>  SID[ 21]: S-1-22-2-110003
>  SID[ 22]: S-1-22-2-110004
>  SID[ 23]: S-1-22-2-12527
>  SID[ 24]: S-1-22-2-12531
>  SID[ 25]: S-1-22-2-13603
>  SID[ 26]: S-1-22-2-12526
>  SID[ 27]: S-1-22-2-12524
>  SID[ 28]: S-1-22-2-12530
>  SID[ 29]: S-1-22-2-12532
>  SID[ 30]: S-1-22-2-12523
>  SID[ 31]: S-1-22-2-12525
>  SID[ 32]: S-1-22-2-12529
>  SID[ 33]: S-1-22-2-12522
>  SID[ 34]: S-1-22-2-12528
>  SID[ 35]: S-1-22-2-110001
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2010/07/26 20:02:39.830392,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 13727
>  Primary group is 10513 and contains 17 supplementary groups
>  Group[  0]: 10513
>  Group[  1]: 110002
>  Group[  2]: 110003
>  Group[  3]: 110004
>  Group[  4]: 12527
>  Group[  5]: 12531
>  Group[  6]: 13603
>  Group[  7]: 12526
>  Group[  8]: 12524
>  Group[  9]: 12530
>  Group[ 10]: 12532
>  Group[ 11]: 12523
>  Group[ 12]: 12525
>  Group[ 13]: 12529
>  Group[ 14]: 12522
>  Group[ 15]: 12528
>  Group[ 16]: 110001
>[2010/07/26 20:02:39.830582,  5] smbd/uid.c:354(change_to_user)
>  change_to_user uid=(0,13727) gid=(0,10513)
>[2010/07/26 20:02:39.830614, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service IPC$, connectpath = /tmp
>[2010/07/26 20:02:39.830654, 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:02:39.830693,  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 22624)
>[2010/07/26 20:02:39.830721,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.830742,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.830770,  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:02:39.830809,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:02:39.830837,  3] smbd/reply.c:846(reply_tcon_and_X)
>  tconX service=IPC$ 
>[2010/07/26 20:02:39.830976, 10] lib/util_sock.c:726(read_smb_length_return_keepalive)
>  got smb length of 98
>[2010/07/26 20:02:39.831018,  6] smbd/process.c:1482(process_smb)
>  got message type 0x0 of len 0x62
>[2010/07/26 20:02:39.831039,  3] smbd/process.c:1485(process_smb)
>  Transaction 4 of length 102 (0 toread)
>[2010/07/26 20:02:39.831060,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:02:39.831071,  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=22620
>  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:02:39.831329, 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:02:39.831401,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtrans2 (pid 22624) conn 0x899b600
>[2010/07/26 20:02:39.831470,  0] smbd/process.c:1243(smb_dump)
>  created /tmp/SMBtrans2.6.req len 102
>[2010/07/26 20:02:39.831495,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (13727, 10513) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.831529,  5] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-851642300-2639045452-4037496937-3727
>  contains 36 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-3603
>  SID[  8]: S-1-5-21-851642300-2639045452-4037496937-2526
>  SID[  9]: S-1-5-21-851642300-2639045452-4037496937-2524
>  SID[ 10]: S-1-5-21-851642300-2639045452-4037496937-2530
>  SID[ 11]: S-1-5-21-851642300-2639045452-4037496937-2532
>  SID[ 12]: S-1-5-21-851642300-2639045452-4037496937-2523
>  SID[ 13]: S-1-5-21-851642300-2639045452-4037496937-2525
>  SID[ 14]: S-1-5-21-851642300-2639045452-4037496937-2529
>  SID[ 15]: S-1-5-21-851642300-2639045452-4037496937-2522
>  SID[ 16]: S-1-5-21-851642300-2639045452-4037496937-2528
>  SID[ 17]: S-1-5-32-545
>  SID[ 18]: S-1-22-1-13727
>  SID[ 19]: S-1-22-2-10513
>  SID[ 20]: S-1-22-2-110002
>  SID[ 21]: S-1-22-2-110003
>  SID[ 22]: S-1-22-2-110004
>  SID[ 23]: S-1-22-2-12527
>  SID[ 24]: S-1-22-2-12531
>  SID[ 25]: S-1-22-2-13603
>  SID[ 26]: S-1-22-2-12526
>  SID[ 27]: S-1-22-2-12524
>  SID[ 28]: S-1-22-2-12530
>  SID[ 29]: S-1-22-2-12532
>  SID[ 30]: S-1-22-2-12523
>  SID[ 31]: S-1-22-2-12525
>  SID[ 32]: S-1-22-2-12529
>  SID[ 33]: S-1-22-2-12522
>  SID[ 34]: S-1-22-2-12528
>  SID[ 35]: S-1-22-2-110001
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2010/07/26 20:02:39.831938,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 13727
>  Primary group is 10513 and contains 17 supplementary groups
>  Group[  0]: 10513
>  Group[  1]: 110002
>  Group[  2]: 110003
>  Group[  3]: 110004
>  Group[  4]: 12527
>  Group[  5]: 12531
>  Group[  6]: 13603
>  Group[  7]: 12526
>  Group[  8]: 12524
>  Group[  9]: 12530
>  Group[ 10]: 12532
>  Group[ 11]: 12523
>  Group[ 12]: 12525
>  Group[ 13]: 12529
>  Group[ 14]: 12522
>  Group[ 15]: 12528
>  Group[ 16]: 110001
>[2010/07/26 20:02:39.832126,  5] smbd/uid.c:354(change_to_user)
>  change_to_user uid=(0,13727) gid=(0,10513)
>[2010/07/26 20:02:39.832155,  4] smbd/vfs.c:721(vfs_ChDir)
>  vfs_ChDir to /tmp
>[2010/07/26 20:02:39.832201, 10] smbd/trans2.c:7996(call_trans2getdfsreferral)
>  call_trans2getdfsreferral
>[2010/07/26 20:02:39.832241, 10] smbd/msdfs.c:107(parse_dfs_path)
>  parse_dfs_path: temp = |cepure\SAMBA| after trimming \'s
>[2010/07/26 20:02:39.832263, 10] smbd/msdfs.c:132(parse_dfs_path)
>  parse_dfs_path: hostname: cepure
>[2010/07/26 20:02:39.832291, 10] smbd/msdfs.c:174(parse_dfs_path)
>  parse_dfs_path: servicename: SAMBA
>[2010/07/26 20:02:39.832314,  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:02:39.832336,  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:02:39.832365,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:02:39.832376,  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=22620
>  smb_uid=100
>  smb_mid=5
>  smt_wct=0
>  smb_bcc=0
>[2010/07/26 20:02:39.832535, 10] lib/util_sock.c:726(read_smb_length_return_keepalive)
>  got smb length of 35
>[2010/07/26 20:02:39.832560,  6] smbd/process.c:1482(process_smb)
>  got message type 0x0 of len 0x23
>[2010/07/26 20:02:39.832581,  3] smbd/process.c:1485(process_smb)
>  Transaction 5 of length 39 (0 toread)
>[2010/07/26 20:02:39.832614,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:02:39.832624,  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=22620
>  smb_uid=100
>  smb_mid=6
>  smt_wct=0
>  smb_bcc=0
>[2010/07/26 20:02:39.832741,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtdis (pid 22624) conn 0x899b600
>[2010/07/26 20:02:39.832803,  0] smbd/process.c:1243(smb_dump)
>  created /tmp/SMBtdis.6.req len 39
>[2010/07/26 20:02:39.832838,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.832859,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.832878,  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:02:39.832942,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:02:39.832981,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.833010,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.833030,  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:02:39.833064,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:02:39.833085,  3] smbd/service.c:1251(close_cnum)
>  cepure (192.168.0.96) closed connection to service IPC$
>[2010/07/26 20:02:39.833117,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to IPC$
>[2010/07/26 20:02:39.833162, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 605800000100000049504324000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:02:39.833205, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x899b210
>[2010/07/26 20:02:39.833233, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 605800000100000049504324000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:02:39.833290,  4] smbd/vfs.c:721(vfs_ChDir)
>  vfs_ChDir to /
>[2010/07/26 20:02:39.833316,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.833336,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.833355,  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:02:39.833389,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:02:39.833427,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:02:39.833439,  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=22620
>  smb_uid=100
>  smb_mid=6
>  smt_wct=0
>  smb_bcc=0
>[2010/07/26 20:02:39.833663, 10] lib/util_sock.c:726(read_smb_length_return_keepalive)
>  got smb length of 80
>[2010/07/26 20:02:39.833689,  6] smbd/process.c:1482(process_smb)
>  got message type 0x0 of len 0x50
>[2010/07/26 20:02:39.833722,  3] smbd/process.c:1485(process_smb)
>  Transaction 6 of length 84 (0 toread)
>[2010/07/26 20:02:39.833742,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:02:39.833753,  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=22620
>  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:02:39.833920, 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:02:39.833982,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtconX (pid 22624) conn 0x0
>[2010/07/26 20:02:39.834055,  0] smbd/process.c:1243(smb_dump)
>  created /tmp/SMBtconX.8.req len 84
>[2010/07/26 20:02:39.834087,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.834107,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.834127,  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:02:39.834162,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:02:39.834197,  4] smbd/reply.c:767(reply_tcon_and_X)
>  Client requested device type [?????] for share [SAMBA]
>[2010/07/26 20:02:39.834225,  5] smbd/service.c:1227(make_connection)
>  making a connection to 'normal' service samba
>[2010/07/26 20:02:39.834252, 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:02:39.834280, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 3: SET
>[2010/07/26 20:02:39.834301, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 3: CHANGED
>[2010/07/26 20:02:39.834329, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 1: SET
>[2010/07/26 20:02:39.834350, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 1: CHANGED
>[2010/07/26 20:02:39.834369, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 4: SET
>[2010/07/26 20:02:39.834389, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 4: CHANGED
>[2010/07/26 20:02:39.834408, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 2: SET
>[2010/07/26 20:02:39.834437, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 2: CHANGED
>[2010/07/26 20:02:39.834459,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.834480,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.834499,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.834519,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.834548,  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:02:39.834590, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:02:39.834616,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.834639, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 180
>[2010/07/26 20:02:39.834670, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:02:39.834692, 18] lib/util_tdb.c:358(tdb_unpack)
>  tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:02:39.834713, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 5 -> now SET
>[2010/07/26 20:02:39.834733, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 6 -> now SET
>[2010/07/26 20:02:39.834753, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 7 -> now SET
>[2010/07/26 20:02:39.834790, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 8 -> now SET
>[2010/07/26 20:02:39.834810, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 9 -> now SET
>[2010/07/26 20:02:39.834829, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 10 -> now SET
>[2010/07/26 20:02:39.834849, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 21 -> now SET
>[2010/07/26 20:02:39.834869, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username user1, was 
>[2010/07/26 20:02:39.834905, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 12 -> now SET
>[2010/07/26 20:02:39.834925, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain PROSERVE, was 
>[2010/07/26 20:02:39.834945, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 14 -> now SET
>[2010/07/26 20:02:39.834964, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username user1, was 
>[2010/07/26 20:02:39.834995, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 15 -> now SET
>[2010/07/26 20:02:39.835014, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:02:39.835033, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 13 -> now SET
>[2010/07/26 20:02:39.835053, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2010/07/26 20:02:39.835073, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 1 -> now SET
>[2010/07/26 20:02:39.835100, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/07/26 20:02:39.835120, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 3 -> now SET
>[2010/07/26 20:02:39.835140, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:02:39.835160, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 4 -> now SET
>[2010/07/26 20:02:39.835190, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2010/07/26 20:02:39.835210, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 2 -> now SET
>[2010/07/26 20:02:39.835229, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 22 -> now SET
>[2010/07/26 20:02:39.835249, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 24 -> now SET
>[2010/07/26 20:02:39.835268, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2010/07/26 20:02:39.835297, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 23 -> now SET
>[2010/07/26 20:02:39.835317, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 25 -> now SET
>[2010/07/26 20:02:39.835338,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.835358,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.835387,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.835406,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.835426,  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:02:39.835466, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:02:39.835502,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.835525, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 33 -> now SET
>[2010/07/26 20:02:39.835545, 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:02:39.835567, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 18 -> now SET
>[2010/07/26 20:02:39.835586, 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:02:39.835631, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 16 -> now SET
>[2010/07/26 20:02:39.835651, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 28 -> now SET
>[2010/07/26 20:02:39.835670, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 29 -> now SET
>[2010/07/26 20:02:39.835689, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 30 -> now SET
>[2010/07/26 20:02:39.835722, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 20 -> now SET
>[2010/07/26 20:02:39.835741, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 17 -> now SET
>[2010/07/26 20:02:39.835761, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 26 -> now SET
>[2010/07/26 20:02:39.835785, 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:02:39.835816, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 19 -> now SET
>[2010/07/26 20:02:39.835837,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user PROSERVE+user1
>[2010/07/26 20:02:39.835857,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is proserve+user1
>[2010/07/26 20:02:39.835879,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [PROSERVE+user1]!
>[2010/07/26 20:02:39.835915, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service SAMBA, connectpath = /SAMBA
>[2010/07/26 20:02:39.835947,  3] smbd/service.c:807(make_connection_snum)
>  Connect path is '/SAMBA' for service [SAMBA]
>[2010/07/26 20:02:39.835977, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2010/07/26 20:02:39.836001,  3] smbd/vfs.c:97(vfs_init_default)
>  Initialising default vfs hooks
>[2010/07/26 20:02:39.836021,  3] smbd/vfs.c:122(vfs_init_custom)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2010/07/26 20:02:39.836051, 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:02:39.836080,  3] smbd/vfs.c:122(vfs_init_custom)
>  Initialising custom vfs hooks from [zfsacl]
>[2010/07/26 20:02:39.836101, 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:02:39.836394,  5] smbd/connection.c:142(claim_connection)
>  claiming [SAMBA]
>[2010/07/26 20:02:39.836442, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 605800000100000053414D42410000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:02:39.836482, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x8a1d0d8
>[2010/07/26 20:02:39.836512, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 605800000100000053414D42410000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:02:39.836582, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service SAMBA, connectpath = /SAMBA
>[2010/07/26 20:02:39.836622, 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:02:39.836645, 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:02:39.836673, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2010/07/26 20:02:39.836701, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2010/07/26 20:02:39.836740,  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:02:39.836785,  5] smbd/password.c:423(user_in_netgroup)
>  Unable to get default yp domain, let's try without specifying it
>[2010/07/26 20:02:39.836807,  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:02:39.836959,  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:02:39.837005, 10] passdb/lookup_sid.c:69(lookup_name)
>  lookup_name: PROSERVE\domain admins => PROSERVE (domain), domain admins (name)
>[2010/07/26 20:02:39.837027, 10] passdb/lookup_sid.c:70(lookup_name)
>  lookup_name: flags = 0x077
>[2010/07/26 20:02:39.837403,  3] lib/util_sid.c:228(string_to_sid)
>  string_to_sid: Sid PROSERVE+administrator does not start with 'S-'.
>[2010/07/26 20:02:39.837442, 10] passdb/lookup_sid.c:69(lookup_name)
>  lookup_name: PROSERVE\administrator => PROSERVE (domain), administrator (name)
>[2010/07/26 20:02:39.837463, 10] passdb/lookup_sid.c:70(lookup_name)
>  lookup_name: flags = 0x073
>[2010/07/26 20:02:39.837760, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 3: SET
>[2010/07/26 20:02:39.837785, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 3: CHANGED
>[2010/07/26 20:02:39.837806, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 1: SET
>[2010/07/26 20:02:39.837837, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 1: CHANGED
>[2010/07/26 20:02:39.837857, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 4: SET
>[2010/07/26 20:02:39.837877, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 4: CHANGED
>[2010/07/26 20:02:39.837898, 11] passdb/pdb_get_set.c:299(pdb_get_init_flags)
>  element 2: SET
>[2010/07/26 20:02:39.837918, 11] passdb/pdb_get_set.c:304(pdb_get_init_flags)
>  element 2: CHANGED
>[2010/07/26 20:02:39.837950,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.837971,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.837992,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.838012,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.838031,  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:02:39.838084, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:02:39.838110,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.838133, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 180
>[2010/07/26 20:02:39.838154, 18] lib/util_tdb.c:204(tdb_pack_va)
>  tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:02:39.838186, 18] lib/util_tdb.c:358(tdb_unpack)
>  tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 180) -> 180
>[2010/07/26 20:02:39.838208, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 5 -> now SET
>[2010/07/26 20:02:39.838228, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 6 -> now SET
>[2010/07/26 20:02:39.838248, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 7 -> now SET
>[2010/07/26 20:02:39.838284, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 8 -> now SET
>[2010/07/26 20:02:39.838304, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 9 -> now SET
>[2010/07/26 20:02:39.838324, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 10 -> now SET
>[2010/07/26 20:02:39.838343, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 21 -> now SET
>[2010/07/26 20:02:39.838363, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username user1, was 
>[2010/07/26 20:02:39.838399, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 12 -> now SET
>[2010/07/26 20:02:39.838418, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain PROSERVE, was 
>[2010/07/26 20:02:39.838438, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 14 -> now SET
>[2010/07/26 20:02:39.838457, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username user1, was 
>[2010/07/26 20:02:39.838477, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 15 -> now SET
>[2010/07/26 20:02:39.838507, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2010/07/26 20:02:39.838527, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 13 -> now SET
>[2010/07/26 20:02:39.838547, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2010/07/26 20:02:39.838567, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 1 -> now SET
>[2010/07/26 20:02:39.838597, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/07/26 20:02:39.838617, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 3 -> now SET
>[2010/07/26 20:02:39.838637, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/07/26 20:02:39.838657, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 4 -> now SET
>[2010/07/26 20:02:39.838676, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2010/07/26 20:02:39.838708, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 2 -> now SET
>[2010/07/26 20:02:39.838728, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 22 -> now SET
>[2010/07/26 20:02:39.838748, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 24 -> now SET
>[2010/07/26 20:02:39.838767, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2010/07/26 20:02:39.838797, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 23 -> now SET
>[2010/07/26 20:02:39.838818, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 25 -> now SET
>[2010/07/26 20:02:39.838839,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.838860,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.838879,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/07/26 20:02:39.838910,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.838929,  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:02:39.838968, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/07/26 20:02:39.838994,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.839025, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 33 -> now SET
>[2010/07/26 20:02:39.839045, 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:02:39.839069, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 18 -> now SET
>[2010/07/26 20:02:39.839088, 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:02:39.839138, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 16 -> now SET
>[2010/07/26 20:02:39.839158, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 28 -> now SET
>[2010/07/26 20:02:39.839177, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 29 -> now SET
>[2010/07/26 20:02:39.839197, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 30 -> now SET
>[2010/07/26 20:02:39.839216, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 20 -> now SET
>[2010/07/26 20:02:39.839252, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 17 -> now SET
>[2010/07/26 20:02:39.839271, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 26 -> now SET
>[2010/07/26 20:02:39.839295, 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:02:39.839317, 11] passdb/pdb_get_set.c:510(pdb_set_init_flags)
>  element 19 -> now SET
>[2010/07/26 20:02:39.839357,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (13727, 10513) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.839377,  5] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-851642300-2639045452-4037496937-3727
>  contains 36 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-3603
>  SID[  8]: S-1-5-21-851642300-2639045452-4037496937-2526
>  SID[  9]: S-1-5-21-851642300-2639045452-4037496937-2524
>  SID[ 10]: S-1-5-21-851642300-2639045452-4037496937-2530
>  SID[ 11]: S-1-5-21-851642300-2639045452-4037496937-2532
>  SID[ 12]: S-1-5-21-851642300-2639045452-4037496937-2523
>  SID[ 13]: S-1-5-21-851642300-2639045452-4037496937-2525
>  SID[ 14]: S-1-5-21-851642300-2639045452-4037496937-2529
>  SID[ 15]: S-1-5-21-851642300-2639045452-4037496937-2522
>  SID[ 16]: S-1-5-21-851642300-2639045452-4037496937-2528
>  SID[ 17]: S-1-5-32-545
>  SID[ 18]: S-1-22-1-13727
>  SID[ 19]: S-1-22-2-10513
>  SID[ 20]: S-1-22-2-110002
>  SID[ 21]: S-1-22-2-110003
>  SID[ 22]: S-1-22-2-110004
>  SID[ 23]: S-1-22-2-12527
>  SID[ 24]: S-1-22-2-12531
>  SID[ 25]: S-1-22-2-13603
>  SID[ 26]: S-1-22-2-12526
>  SID[ 27]: S-1-22-2-12524
>  SID[ 28]: S-1-22-2-12530
>  SID[ 29]: S-1-22-2-12532
>  SID[ 30]: S-1-22-2-12523
>  SID[ 31]: S-1-22-2-12525
>  SID[ 32]: S-1-22-2-12529
>  SID[ 33]: S-1-22-2-12522
>  SID[ 34]: S-1-22-2-12528
>  SID[ 35]: S-1-22-2-110001
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2010/07/26 20:02:39.839799,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 13727
>  Primary group is 10513 and contains 17 supplementary groups
>  Group[  0]: 10513
>  Group[  1]: 110002
>  Group[  2]: 110003
>  Group[  3]: 110004
>  Group[  4]: 12527
>  Group[  5]: 12531
>  Group[  6]: 13603
>  Group[  7]: 12526
>  Group[  8]: 12524
>  Group[  9]: 12530
>  Group[ 10]: 12532
>  Group[ 11]: 12523
>  Group[ 12]: 12525
>  Group[ 13]: 12529
>  Group[ 14]: 12522
>  Group[ 15]: 12528
>  Group[ 16]: 110001
>[2010/07/26 20:02:39.839994,  5] smbd/uid.c:354(change_to_user)
>  change_to_user uid=(0,13727) gid=(0,10513)
>[2010/07/26 20:02:39.840027, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service SAMBA, connectpath = /SAMBA
>[2010/07/26 20:02:39.840061,  0] smbd/service.c:1031(make_connection_snum)
>  '/SAMBA' does not exist or permission denied when connecting to [SAMBA] Error was Permission denied
>[2010/07/26 20:02:39.840100,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.840121,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.840140,  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:02:39.840181,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:02:39.840232,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to SAMBA
>[2010/07/26 20:02:39.840320, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 605800000100000053414D42410000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:02:39.840375, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x8a1c958
>[2010/07/26 20:02:39.840404, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 605800000100000053414D42410000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:02:39.840468,  5] lib/messages.c:329(messaging_deregister)
>  Deregistering messaging pointer for type 784 - private_data=8a0d970
>[2010/07/26 20:02:39.840529,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/reply.c(776) cmd=117 (SMBtconX) NT_STATUS_BAD_NETWORK_NAME
>[2010/07/26 20:02:39.840561,  5] lib/util.c:617(show_msg)
>[2010/07/26 20:02:39.840573,  5] lib/util.c:627(show_msg)
>  size=35
>  smb_com=0x75
>  smb_rcls=204
>  smb_reh=0
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51203
>  smb_tid=65535
>  smb_pid=22620
>  smb_uid=100
>  smb_mid=7
>  smt_wct=0
>  smb_bcc=0
>[2010/07/26 20:02:39.842244,  5] lib/util_sock.c:462(read_fd_with_timeout)
>  read_fd_with_timeout: blocking read. EOF from client.
>[2010/07/26 20:02:39.842297, 10] smbd/process.c:286(receive_smb_raw_talloc)
>  receive_smb_raw: NT_STATUS_END_OF_FILE
>[2010/07/26 20:02:39.842320,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/07/26 20:02:39.842341,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/07/26 20:02:39.842405,  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:02:39.842444,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/07/26 20:02:39.842472, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 49442F32323632342F31303000
>[2010/07/26 20:02:39.842504, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x8995930
>[2010/07/26 20:02:39.842546, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 49442F32323632342F31303000
>[2010/07/26 20:02:39.842590,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to 
>[2010/07/26 20:02:39.842640, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 60580000FFFFFFFF00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:02:39.842684, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x899b210
>[2010/07/26 20:02:39.842712, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 60580000FFFFFFFF00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
>[2010/07/26 20:02:39.842888,  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