Attachment 4472 Details for Bug 6574 – Samba logs of my successful login

Samba logs of my successful login

log.login (text/plain), 116.04 KB, created by Karim Elatov on 2009-07-26 18:30:37 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Karim Elatov

Created: 2009-07-26 18:30:37 UTC

Size: 116.04 KB

patch

obsolete

>[2009/07/26 16:45:10,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to 
>[2009/07/26 16:45:10, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 6F1B0000FFFFFFFF0000
>[2009/07/26 16:45:10, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x8838180
>[2009/07/26 16:45:10, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 6F1B0000FFFFFFFF0000
>[2009/07/26 16:45:10,  3] smbd/server.c:844(exit_server_common)
>  Server exit (failed to receive smb request)
>[2009/07/26 16:46:05,  6] param/loadparm.c:6989(lp_file_list_changed)
>  lp_file_list_changed()
>  file /usr/local/adm/config/samba/smb.conf -> /usr/local/adm/config/samba/smb.conf  last mod_time: Sun Jul 26 16:43:45 2009
>  
>[2009/07/26 16:46:05,  5] auth/auth_util.c:208(make_user_info_map)
>  Mapping user [AD]\[elatov] from workstation [MOXZ]
>[2009/07/26 16:46:05,  5] auth/auth_util.c:120(make_user_info)
>  attempting to make a user_info for elatov (elatov)
>[2009/07/26 16:46:05,  5] auth/auth_util.c:130(make_user_info)
>  making strings for elatov's user_info struct
>[2009/07/26 16:46:05,  5] auth/auth_util.c:162(make_user_info)
>  making blobs for elatov's user_info struct
>[2009/07/26 16:46:05, 10] auth/auth_util.c:180(make_user_info)
>  made an encrypted user_info for elatov (elatov)
>[2009/07/26 16:46:05,  3] auth/auth.c:222(check_ntlm_password)
>  check_ntlm_password:  Checking password for unmapped user [AD]\[elatov]@[MOXZ] with the new password interface
>[2009/07/26 16:46:05,  3] auth/auth.c:225(check_ntlm_password)
>  check_ntlm_password:  mapped user is: [AD]\[elatov]@[MOXZ]
>[2009/07/26 16:46:05, 10] auth/auth.c:234(check_ntlm_password)
>  check_ntlm_password: auth_context challenge created by NTLMSSP callback (NTLM2)
>[2009/07/26 16:46:05, 10] auth/auth.c:236(check_ntlm_password)
>  challenge is: 
>[2009/07/26 16:46:05,  5] ../lib/util/util.c:304(_dump_data)
>  [0000] 0C 2A C8 A2 2E 7F 25 36                            .*....%6 
>[2009/07/26 16:46:05, 10] auth/auth.c:262(check_ntlm_password)
>  check_ntlm_password: guest had nothing to say
>[2009/07/26 16:46:05,  8] lib/util.c:1879(is_myname)
>  is_myname("AD") returns 0
>[2009/07/26 16:46:05,  6] auth/auth_sam.c:416(check_samstrict_security)
>  check_samstrict_security: AD is not one of my local names (ROLE_DOMAIN_MEMBER)
>[2009/07/26 16:46:05, 10] auth/auth.c:262(check_ntlm_password)
>  check_ntlm_password: sam had nothing to say
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user AD+elatov
>[2009/07/26 16:46:05,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is ad+elatov
>[2009/07/26 16:46:05,  5] lib/username.c:85(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as given is AD+elatov
>[2009/07/26 16:46:05,  5] lib/username.c:95(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as uppercase is AD+ELATOV
>[2009/07/26 16:46:05,  5] lib/username.c:104(Get_Pwnam_internals)
>  Checking combinations of 0 uppercase letters in ad+elatov
>[2009/07/26 16:46:05,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals didn't find user [AD+elatov]!
>[2009/07/26 16:46:05,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user elatov
>[2009/07/26 16:46:05,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is elatov
>[2009/07/26 16:46:05,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [elatov]!
>[2009/07/26 16:46:05,  5] auth/auth_util.c:1517(fill_sam_account)
>  fill_sam_account: located username was [elatov]
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:572(pdb_set_username)
>  pdb_set_username: setting username elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:641(pdb_set_fullname)
>  pdb_set_full_name: setting full name Karim Elatov,,, , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:595(pdb_set_domain)
>  pdb_set_domain: setting domain MOXZ, was 
>[2009/07/26 16:46:05,  4] lib/substitute.c:504(automount_server)
>  Home server: moxz
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:687(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path \\moxz\elatov\profile, was 
>[2009/07/26 16:46:05,  4] lib/substitute.c:504(automount_server)
>  Home server: moxz
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:734(pdb_set_homedir)
>  pdb_set_homedir: setting home dir \\moxz\elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:710(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:664(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-3258327255-2435407998-1853397726-35454
>[2009/07/26 16:46:05, 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-3258327255-2435407998-1853397726-35454 from rid 35454
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:618(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:572(pdb_set_username)
>  pdb_set_username: setting username elatov, was elatov
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:595(pdb_set_domain)
>  pdb_set_domain: setting domain AD, was MOXZ
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-1275210071-492894223-682003330-111151
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-513, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-513
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:559(pdb_set_group_sid)
>  pdb_set_group_sid: setting group sid S-1-5-21-3258327255-2435407998-1853397726-513
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:641(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was Karim Elatov,,, 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:664(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:687(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was \\moxz\elatov\profile
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:734(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was \\moxz\elatov
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:710(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was 
>[2009/07/26 16:46:05,  3] auth/auth.c:271(check_ntlm_password)
>  check_ntlm_password: winbind authentication for user [elatov] succeeded
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/auth.c:297(check_ntlm_password)
>  check_ntlm_password:  PAM Account for user [elatov] succeeded
>[2009/07/26 16:46:05,  2] auth/auth.c:310(check_ntlm_password)
>  check_ntlm_password:  authentication for user [elatov] -> [elatov] -> [elatov] succeeded
>[2009/07/26 16:46:05,  5] auth/auth_util.c:2114(free_user_info)
>  attempting to free (and zero) a user_info structure
>[2009/07/26 16:46:05, 10] auth/auth_util.c:2118(free_user_info)
>  structure was created for elatov
>[2009/07/26 16:46:05, 10] auth/token_util.c:353(create_local_nt_token)
>  Create local NT token for S-1-5-21-1275210071-492894223-682003330-111151
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-32-544, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-32-544
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-32-544, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-32-544
>[2009/07/26 16:46:05, 10] groupdb/mapping.c:765(pdb_create_builtin_alias)
>  Trying to create builtin alias 544
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:953(lookup_sid)
>  lookup_sid called for SID 'S-1-5-32-544'
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:708(check_dom_sid_to_level)
>  Accepting SID S-1-5-32 in level 1
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:468(lookup_rids)
>  lookup_rids called for domain sid 'S-1-5-32'
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:988(lookup_sid)
>  Sid S-1-5-32-544 -> BUILTIN\Administrators(4)
>[2009/07/26 16:46:05,  3] groupdb/mapping.c:786(pdb_create_builtin_alias)
>  pdb_create_builtin_alias: Could not get a gid out of winbind
>[2009/07/26 16:46:05,  5] auth/token_util.c:303(create_builtin_administrators)
>  create_builtin_administrators: Failed to create Administrators
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  2] auth/token_util.c:450(create_local_nt_token)
>  WARNING: Failed to create BUILTIN\Administrators group!  Can Winbind allocate gids?
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-32-545, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-32-545
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-32-545, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-32-545
>[2009/07/26 16:46:05, 10] groupdb/mapping.c:765(pdb_create_builtin_alias)
>  Trying to create builtin alias 545
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:953(lookup_sid)
>  lookup_sid called for SID 'S-1-5-32-545'
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:708(check_dom_sid_to_level)
>  Accepting SID S-1-5-32 in level 1
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:468(lookup_rids)
>  lookup_rids called for domain sid 'S-1-5-32'
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:988(lookup_sid)
>  Sid S-1-5-32-545 -> BUILTIN\Users(4)
>[2009/07/26 16:46:05,  3] groupdb/mapping.c:786(pdb_create_builtin_alias)
>  pdb_create_builtin_alias: Could not get a gid out of winbind
>[2009/07/26 16:46:05,  5] auth/token_util.c:274(create_builtin_users)
>  create_builtin_users: Failed to create Users
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  2] auth/token_util.c:474(create_local_nt_token)
>  WARNING: Failed to create BUILTIN\Users group! Can Winbind allocate gids?
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-111151]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-513]
>[2009/07/26 16:46:05,  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
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-211599]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-177858]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-168222]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-213707]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-204977]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-260187]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-204975]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-261495]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-233995]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-172794]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-199817]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-168519]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-172276]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-172171]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-177859]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-145299]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-212520]
>[2009/07/26 16:46:05,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1275210071-492894223-682003330-259494]
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-513, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-513
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-513 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-1-0, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-1-0
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-1-0 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-2, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-2
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-2 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-11, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-11
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-11 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-211599, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-211599
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-211599 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-177858, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-177858
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-177858 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-168222, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-168222
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-168222 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-213707, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-213707
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-213707 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-204977, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-204977
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-204977 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-260187, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-260187
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-260187 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-204975, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-204975
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-204975 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-261495, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-261495
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-261495 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-233995, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-233995
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-233995 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-172794, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-172794
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-172794 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-199817, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-199817
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-199817 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-168519, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-168519
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-168519 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-172276, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-172276
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-172276 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-172171, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-172171
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-172171 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-177859, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-177859
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-177859 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-145299, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-145299
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-145299 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-212520, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-212520
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-212520 to gid, ignoring it
>[2009/07/26 16:46:05, 10] lib/gencache.c:208(gencache_get)
>  Returning valid cache entry: key = IDMAP/SID2GID/S-1-5-21-1275210071-492894223-682003330-259494, value = -1, timeout = Sun Jul 26 16:46:45 2009
>[2009/07/26 16:46:05, 10] passdb/lookup_sid.c:1274(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-1275210071-492894223-682003330-259494
>[2009/07/26 16:46:05, 10] auth/auth_util.c:750(create_local_token)
>  Could not convert SID S-1-5-21-1275210071-492894223-682003330-259494 to gid, ignoring it
>[2009/07/26 16:46:05, 10] auth/token_util.c:528(debug_nt_user_token)
>  NT user token of user S-1-5-21-1275210071-492894223-682003330-111151
>  contains 24 SIDs
>  SID[  0]: S-1-5-21-1275210071-492894223-682003330-111151
>  SID[  1]: S-1-5-21-1275210071-492894223-682003330-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-1275210071-492894223-682003330-211599
>  SID[  6]: S-1-5-21-1275210071-492894223-682003330-177858
>  SID[  7]: S-1-5-21-1275210071-492894223-682003330-168222
>  SID[  8]: S-1-5-21-1275210071-492894223-682003330-213707
>  SID[  9]: S-1-5-21-1275210071-492894223-682003330-204977
>  SID[ 10]: S-1-5-21-1275210071-492894223-682003330-260187
>  SID[ 11]: S-1-5-21-1275210071-492894223-682003330-204975
>  SID[ 12]: S-1-5-21-1275210071-492894223-682003330-261495
>  SID[ 13]: S-1-5-21-1275210071-492894223-682003330-233995
>  SID[ 14]: S-1-5-21-1275210071-492894223-682003330-172794
>  SID[ 15]: S-1-5-21-1275210071-492894223-682003330-199817
>  SID[ 16]: S-1-5-21-1275210071-492894223-682003330-168519
>  SID[ 17]: S-1-5-21-1275210071-492894223-682003330-172276
>  SID[ 18]: S-1-5-21-1275210071-492894223-682003330-172171
>  SID[ 19]: S-1-5-21-1275210071-492894223-682003330-177859
>  SID[ 20]: S-1-5-21-1275210071-492894223-682003330-145299
>  SID[ 21]: S-1-5-21-1275210071-492894223-682003330-212520
>  SID[ 22]: S-1-5-21-1275210071-492894223-682003330-259494
>  SID[ 23]: S-1-22-1-17227
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2009/07/26 16:46:05, 10] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 17227
>  Primary group is 150 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] auth/auth_ntlmssp.c:139(auth_ntlmssp_check_password)
>  Got NT session key of length 16
>[2009/07/26 16:46:05, 10] libsmb/ntlmssp.c:813(ntlmssp_server_auth)
>  ntlmssp_server_auth: Created NTLM2 session key.
>[2009/07/26 16:46:05,  3] libsmb/ntlmssp_sign.c:342(ntlmssp_sign_init)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2009/07/26 16:46:05,  3] libsmb/ntlmssp.c:62(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
>[2009/07/26 16:46:05, 10] smbd/password.c:265(register_existing_vuid)
>  register_existing_vuid: (17227,150) elatov elatov AD guest=0
>[2009/07/26 16:46:05,  3] smbd/password.c:269(register_existing_vuid)
>  register_existing_vuid: User name: elatov	Real name: 
>[2009/07/26 16:46:05,  3] smbd/password.c:279(register_existing_vuid)
>  register_existing_vuid: UNIX uid 17227 is UNIX user elatov, and will be vuid 100
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 49442F373039302F3130
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x884a540
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 49442F373039302F3130
>[2009/07/26 16:46:05,  7] param/loadparm.c:9251(lp_servicenumber)
>  lp_servicenumber: couldn't find elatov
>[2009/07/26 16:46:05,  3] smbd/password.c:211(register_homes_share)
>  Adding homes service for user 'elatov' using home directory: '/home/pitserver/elatov'
>[2009/07/26 16:46:05,  8] param/loadparm.c:6024(add_a_service)
>  add_a_service: Creating snum = 3 for elatov
>[2009/07/26 16:46:05, 10] param/loadparm.c:6071(hash_a_service)
>  hash_a_service: hashing index 3 for service name elatov
>[2009/07/26 16:46:05,  3] param/loadparm.c:6121(lp_add_home)
>  adding home's share [elatov] for user 'elatov' at '/home/pitserver/elatov'
>[2009/07/26 16:46:05,  6] param/loadparm.c:6989(lp_file_list_changed)
>  lp_file_list_changed()
>  file /usr/local/adm/config/samba/smb.conf -> /usr/local/adm/config/samba/smb.conf  last mod_time: Sun Jul 26 16:43:45 2009
>  
>[2009/07/26 16:46:05,  5] lib/util.c:632(show_msg)
>[2009/07/26 16:46:05,  5] lib/util.c:642(show_msg)
>  size=92
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=7089
>  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=49
>[2009/07/26 16:46:05, 10] ../lib/util/util.c:304(_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 34 00 2E 00 30   00 00 00 41 00 44 00 00   ...4...0 ...A.D..
>  [0030] 00                                                . 
>[2009/07/26 16:46:05, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
>  got smb length of 72
>[2009/07/26 16:46:05,  6] smbd/process.c:1430(process_smb)
>  got message type 0x0 of len 0x48
>[2009/07/26 16:46:05,  3] smbd/process.c:1433(process_smb)
>  Transaction 3 of length 76 (0 toread)
>[2009/07/26 16:46:05,  5] lib/util.c:632(show_msg)
>[2009/07/26 16:46:05,  5] lib/util.c:642(show_msg)
>  size=72
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=8
>  smb_flg2=51201
>  smb_tid=65535
>  smb_pid=7089
>  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=29
>[2009/07/26 16:46:05, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 5C 00 5C 00 4D 00 4F   00 58 00 5A 00 5C 00 49   .\.\.M.O .X.Z.\.I
>  [0010] 00 50 00 43 00 24 00 00   00 49 50 43 00           .P.C.$.. .IPC.
>[2009/07/26 16:46:05,  3] smbd/process.c:1251(switch_message)
>  switch message SMBtconX (pid 7090) conn 0x0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/07/26 16:46:05,  4] smbd/reply.c:675(reply_tcon_and_X)
>  Client requested device type [IPC] for share [IPC$]
>[2009/07/26 16:46:05,  5] smbd/service.c:1198(make_connection)
>  making a connection to 'normal' service ipc$
>[2009/07/26 16:46:05,  3] lib/access.c:362(only_ipaddrs_in_list)
>  only_ipaddrs_in_list: list has non-ip address (128.138.)
>[2009/07/26 16:46:05,  3] lib/access.c:396(check_access)
>  check_access: hostnames in host allow/deny list.
>[2009/07/26 16:46:05,  2] lib/access.c:406(check_access)
>  Allowed connection from 128.138.196.24 (128.138.196.24)
>[2009/07/26 16:46:05, 10] smbd/share_access.c:234(user_ok_token)
>  user_ok_token: share IPC$ is ok for unix user elatov
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:572(pdb_set_username)
>  pdb_set_username: setting username elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:595(pdb_set_domain)
>  pdb_set_domain: setting domain AD, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:618(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:641(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:734(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:710(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:664(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:687(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:777(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-3258327255-2435407998-1853397726-0
>[2009/07/26 16:46:05, 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-3258327255-2435407998-1853397726-0 from rid 0
>[2009/07/26 16:46:05,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user elatov
>[2009/07/26 16:46:05,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is elatov
>[2009/07/26 16:46:05,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [elatov]!
>[2009/07/26 16:46:05, 10] smbd/service.c:158(set_conn_connectpath)
>  set_conn_connectpath: service IPC$, connectpath = /tmp
>[2009/07/26 16:46:05,  3] smbd/service.c:803(make_connection_snum)
>  Connect path is '/tmp' for service [IPC$]
>[2009/07/26 16:46:05, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2009/07/26 16:46:05,  3] smbd/vfs.c:95(vfs_init_default)
>  Initialising default vfs hooks
>[2009/07/26 16:46:05, 10] smbd/vfs.c:47(vfs_find_backend_entry)
>  vfs_find_backend_entry called for /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:85(smb_register_vfs)
>  Successfully added vfs backend '/[Default VFS]/'
>[2009/07/26 16:46:05, 10] smbd/vfs.c:47(vfs_find_backend_entry)
>  vfs_find_backend_entry called for solarisacl
>[2009/07/26 16:46:05,  5] smbd/vfs.c:85(smb_register_vfs)
>  Successfully added vfs backend 'solarisacl'
>[2009/07/26 16:46:05,  3] smbd/vfs.c:129(vfs_init_custom)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2009/07/26 16:46:05, 10] smbd/vfs.c:47(vfs_find_backend_entry)
>  vfs_find_backend_entry called for /[Default VFS]/
>  Successfully loaded vfs module [/[Default VFS]/] with the new modules system
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #0 (type 0, layer 0)
>  Making operation type 0 opaque [module /[Default VFS]/]
>  Accepting operation type 0 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #1 (type 1, layer 0)
>  Making operation type 1 opaque [module /[Default VFS]/]
>  Accepting operation type 1 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #2 (type 2, layer 0)
>  Making operation type 2 opaque [module /[Default VFS]/]
>  Accepting operation type 2 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #3 (type 3, layer 0)
>  Making operation type 3 opaque [module /[Default VFS]/]
>  Accepting operation type 3 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #4 (type 4, layer 0)
>  Making operation type 4 opaque [module /[Default VFS]/]
>  Accepting operation type 4 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #5 (type 5, layer 0)
>  Making operation type 5 opaque [module /[Default VFS]/]
>  Accepting operation type 5 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #6 (type 6, layer 0)
>  Making operation type 6 opaque [module /[Default VFS]/]
>  Accepting operation type 6 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #7 (type 7, layer 0)
>  Making operation type 7 opaque [module /[Default VFS]/]
>  Accepting operation type 7 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #8 (type 8, layer 0)
>  Making operation type 8 opaque [module /[Default VFS]/]
>  Accepting operation type 8 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #9 (type 9, layer 0)
>  Making operation type 9 opaque [module /[Default VFS]/]
>  Accepting operation type 9 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #10 (type 10, layer 0)
>  Making operation type 10 opaque [module /[Default VFS]/]
>  Accepting operation type 10 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #11 (type 11, layer 0)
>  Making operation type 11 opaque [module /[Default VFS]/]
>  Accepting operation type 11 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #12 (type 12, layer 0)
>  Making operation type 12 opaque [module /[Default VFS]/]
>  Accepting operation type 12 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #13 (type 13, layer 0)
>  Making operation type 13 opaque [module /[Default VFS]/]
>  Accepting operation type 13 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #14 (type 14, layer 0)
>  Making operation type 14 opaque [module /[Default VFS]/]
>  Accepting operation type 14 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #15 (type 15, layer 0)
>  Making operation type 15 opaque [module /[Default VFS]/]
>  Accepting operation type 15 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #16 (type 16, layer 0)
>  Making operation type 16 opaque [module /[Default VFS]/]
>  Accepting operation type 16 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #17 (type 17, layer 0)
>  Making operation type 17 opaque [module /[Default VFS]/]
>  Accepting operation type 17 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #18 (type 18, layer 0)
>  Making operation type 18 opaque [module /[Default VFS]/]
>  Accepting operation type 18 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #19 (type 19, layer 0)
>  Making operation type 19 opaque [module /[Default VFS]/]
>  Accepting operation type 19 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #20 (type 20, layer 0)
>  Making operation type 20 opaque [module /[Default VFS]/]
>  Accepting operation type 20 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #21 (type 21, layer 0)
>  Making operation type 21 opaque [module /[Default VFS]/]
>  Accepting operation type 21 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #22 (type 22, layer 0)
>  Making operation type 22 opaque [module /[Default VFS]/]
>  Accepting operation type 22 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #23 (type 23, layer 0)
>  Making operation type 23 opaque [module /[Default VFS]/]
>  Accepting operation type 23 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #24 (type 24, layer 0)
>  Making operation type 24 opaque [module /[Default VFS]/]
>  Accepting operation type 24 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #25 (type 25, layer 0)
>  Making operation type 25 opaque [module /[Default VFS]/]
>  Accepting operation type 25 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #26 (type 26, layer 0)
>  Making operation type 26 opaque [module /[Default VFS]/]
>  Accepting operation type 26 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #27 (type 27, layer 0)
>  Making operation type 27 opaque [module /[Default VFS]/]
>  Accepting operation type 27 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #28 (type 28, layer 0)
>  Making operation type 28 opaque [module /[Default VFS]/]
>  Accepting operation type 28 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #29 (type 29, layer 0)
>  Making operation type 29 opaque [module /[Default VFS]/]
>  Accepting operation type 29 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #30 (type 30, layer 0)
>  Making operation type 30 opaque [module /[Default VFS]/]
>  Accepting operation type 30 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #31 (type 31, layer 0)
>  Making operation type 31 opaque [module /[Default VFS]/]
>  Accepting operation type 31 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #32 (type 32, layer 0)
>  Making operation type 32 opaque [module /[Default VFS]/]
>  Accepting operation type 32 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #33 (type 33, layer 0)
>  Making operation type 33 opaque [module /[Default VFS]/]
>  Accepting operation type 33 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #34 (type 34, layer 0)
>  Making operation type 34 opaque [module /[Default VFS]/]
>  Accepting operation type 34 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #35 (type 35, layer 0)
>  Making operation type 35 opaque [module /[Default VFS]/]
>  Accepting operation type 35 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #36 (type 36, layer 0)
>  Making operation type 36 opaque [module /[Default VFS]/]
>  Accepting operation type 36 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #37 (type 37, layer 0)
>  Making operation type 37 opaque [module /[Default VFS]/]
>  Accepting operation type 37 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #38 (type 38, layer 0)
>  Making operation type 38 opaque [module /[Default VFS]/]
>  Accepting operation type 38 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #39 (type 39, layer 0)
>  Making operation type 39 opaque [module /[Default VFS]/]
>  Accepting operation type 39 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #40 (type 40, layer 0)
>  Making operation type 40 opaque [module /[Default VFS]/]
>  Accepting operation type 40 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #41 (type 41, layer 0)
>  Making operation type 41 opaque [module /[Default VFS]/]
>  Accepting operation type 41 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #42 (type 42, layer 0)
>  Making operation type 42 opaque [module /[Default VFS]/]
>  Accepting operation type 42 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #43 (type 43, layer 0)
>  Making operation type 43 opaque [module /[Default VFS]/]
>  Accepting operation type 43 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #44 (type 44, layer 0)
>  Making operation type 44 opaque [module /[Default VFS]/]
>  Accepting operation type 44 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #45 (type 45, layer 0)
>  Making operation type 45 opaque [module /[Default VFS]/]
>  Accepting operation type 45 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #46 (type 46, layer 0)
>  Making operation type 46 opaque [module /[Default VFS]/]
>  Accepting operation type 46 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #47 (type 47, layer 0)
>  Making operation type 47 opaque [module /[Default VFS]/]
>  Accepting operation type 47 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #48 (type 48, layer 0)
>  Making operation type 48 opaque [module /[Default VFS]/]
>  Accepting operation type 48 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #49 (type 49, layer 0)
>  Making operation type 49 opaque [module /[Default VFS]/]
>  Accepting operation type 49 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #50 (type 50, layer 0)
>  Making operation type 50 opaque [module /[Default VFS]/]
>  Accepting operation type 50 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #51 (type 51, layer 0)
>  Making operation type 51 opaque [module /[Default VFS]/]
>  Accepting operation type 51 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #52 (type 52, layer 0)
>  Making operation type 52 opaque [module /[Default VFS]/]
>  Accepting operation type 52 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #53 (type 53, layer 0)
>  Making operation type 53 opaque [module /[Default VFS]/]
>  Accepting operation type 53 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #54 (type 54, layer 0)
>  Making operation type 54 opaque [module /[Default VFS]/]
>  Accepting operation type 54 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #55 (type 55, layer 0)
>  Making operation type 55 opaque [module /[Default VFS]/]
>  Accepting operation type 55 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #56 (type 56, layer 0)
>  Making operation type 56 opaque [module /[Default VFS]/]
>  Accepting operation type 56 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #57 (type 57, layer 0)
>  Making operation type 57 opaque [module /[Default VFS]/]
>  Accepting operation type 57 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #58 (type 58, layer 0)
>  Making operation type 58 opaque [module /[Default VFS]/]
>  Accepting operation type 58 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #59 (type 59, layer 0)
>  Making operation type 59 opaque [module /[Default VFS]/]
>  Accepting operation type 59 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #60 (type 60, layer 0)
>  Making operation type 60 opaque [module /[Default VFS]/]
>  Accepting operation type 60 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #61 (type 61, layer 0)
>  Making operation type 61 opaque [module /[Default VFS]/]
>  Accepting operation type 61 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #62 (type 62, layer 0)
>  Making operation type 62 opaque [module /[Default VFS]/]
>  Accepting operation type 62 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #63 (type 63, layer 0)
>  Making operation type 63 opaque [module /[Default VFS]/]
>  Accepting operation type 63 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #64 (type 64, layer 0)
>  Making operation type 64 opaque [module /[Default VFS]/]
>  Accepting operation type 64 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #65 (type 65, layer 0)
>  Making operation type 65 opaque [module /[Default VFS]/]
>  Accepting operation type 65 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #66 (type 66, layer 0)
>  Making operation type 66 opaque [module /[Default VFS]/]
>  Accepting operation type 66 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #67 (type 67, layer 0)
>  Making operation type 67 opaque [module /[Default VFS]/]
>  Accepting operation type 67 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #68 (type 68, layer 0)
>  Making operation type 68 opaque [module /[Default VFS]/]
>  Accepting operation type 68 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #69 (type 69, layer 0)
>  Making operation type 69 opaque [module /[Default VFS]/]
>  Accepting operation type 69 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #70 (type 70, layer 0)
>  Making operation type 70 opaque [module /[Default VFS]/]
>  Accepting operation type 70 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #71 (type 71, layer 0)
>  Making operation type 71 opaque [module /[Default VFS]/]
>  Accepting operation type 71 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #72 (type 72, layer 0)
>  Making operation type 72 opaque [module /[Default VFS]/]
>  Accepting operation type 72 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #73 (type 73, layer 0)
>  Making operation type 73 opaque [module /[Default VFS]/]
>  Accepting operation type 73 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #74 (type 74, layer 0)
>  Making operation type 74 opaque [module /[Default VFS]/]
>  Accepting operation type 74 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #75 (type 75, layer 0)
>  Making operation type 75 opaque [module /[Default VFS]/]
>  Accepting operation type 75 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #76 (type 76, layer 0)
>  Making operation type 76 opaque [module /[Default VFS]/]
>  Accepting operation type 76 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #77 (type 77, layer 0)
>  Making operation type 77 opaque [module /[Default VFS]/]
>  Accepting operation type 77 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #78 (type 78, layer 0)
>  Making operation type 78 opaque [module /[Default VFS]/]
>  Accepting operation type 78 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #79 (type 79, layer 0)
>  Making operation type 79 opaque [module /[Default VFS]/]
>  Accepting operation type 79 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #80 (type 80, layer 0)
>  Making operation type 80 opaque [module /[Default VFS]/]
>  Accepting operation type 80 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #81 (type 81, layer 0)
>  Making operation type 81 opaque [module /[Default VFS]/]
>  Accepting operation type 81 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #82 (type 82, layer 0)
>  Making operation type 82 opaque [module /[Default VFS]/]
>  Accepting operation type 82 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #83 (type 83, layer 0)
>  Making operation type 83 opaque [module /[Default VFS]/]
>  Accepting operation type 83 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #84 (type 84, layer 0)
>  Making operation type 84 opaque [module /[Default VFS]/]
>  Accepting operation type 84 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #85 (type 85, layer 0)
>  Making operation type 85 opaque [module /[Default VFS]/]
>  Accepting operation type 85 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #86 (type 86, layer 0)
>  Making operation type 86 opaque [module /[Default VFS]/]
>  Accepting operation type 86 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #87 (type 87, layer 0)
>  Making operation type 87 opaque [module /[Default VFS]/]
>  Accepting operation type 87 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #88 (type 88, layer 0)
>  Making operation type 88 opaque [module /[Default VFS]/]
>  Accepting operation type 88 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #89 (type 89, layer 0)
>  Making operation type 89 opaque [module /[Default VFS]/]
>  Accepting operation type 89 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #90 (type 90, layer 0)
>  Making operation type 90 opaque [module /[Default VFS]/]
>  Accepting operation type 90 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #91 (type 91, layer 0)
>  Making operation type 91 opaque [module /[Default VFS]/]
>  Accepting operation type 91 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #92 (type 92, layer 0)
>  Making operation type 92 opaque [module /[Default VFS]/]
>  Accepting operation type 92 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #93 (type 93, layer 0)
>  Making operation type 93 opaque [module /[Default VFS]/]
>  Accepting operation type 93 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #94 (type 94, layer 0)
>  Making operation type 94 opaque [module /[Default VFS]/]
>  Accepting operation type 94 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #95 (type 95, layer 0)
>  Making operation type 95 opaque [module /[Default VFS]/]
>  Accepting operation type 95 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #96 (type 96, layer 0)
>  Making operation type 96 opaque [module /[Default VFS]/]
>  Accepting operation type 96 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #97 (type 97, layer 0)
>  Making operation type 97 opaque [module /[Default VFS]/]
>  Accepting operation type 97 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #98 (type 98, layer 0)
>  Making operation type 98 opaque [module /[Default VFS]/]
>  Accepting operation type 98 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #99 (type 99, layer 0)
>  Making operation type 99 opaque [module /[Default VFS]/]
>  Accepting operation type 99 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #100 (type 100, layer 0)
>  Making operation type 100 opaque [module /[Default VFS]/]
>  Accepting operation type 100 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #101 (type 101, layer 0)
>  Making operation type 101 opaque [module /[Default VFS]/]
>  Accepting operation type 101 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #102 (type 102, layer 0)
>  Making operation type 102 opaque [module /[Default VFS]/]
>  Accepting operation type 102 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #103 (type 103, layer 0)
>  Making operation type 103 opaque [module /[Default VFS]/]
>  Accepting operation type 103 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #104 (type 104, layer 0)
>  Making operation type 104 opaque [module /[Default VFS]/]
>  Accepting operation type 104 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #105 (type 105, layer 0)
>  Making operation type 105 opaque [module /[Default VFS]/]
>  Accepting operation type 105 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #106 (type 106, layer 0)
>  Making operation type 106 opaque [module /[Default VFS]/]
>  Accepting operation type 106 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #107 (type 107, layer 0)
>  Making operation type 107 opaque [module /[Default VFS]/]
>  Accepting operation type 107 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #108 (type 108, layer 0)
>  Making operation type 108 opaque [module /[Default VFS]/]
>  Accepting operation type 108 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #109 (type 109, layer 0)
>  Making operation type 109 opaque [module /[Default VFS]/]
>  Accepting operation type 109 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #110 (type 110, layer 0)
>  Making operation type 110 opaque [module /[Default VFS]/]
>  Accepting operation type 110 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/connection.c:142(claim_connection)
>  claiming [IPC$]
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key B21B0000010000004950
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x884f598
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key B21B0000010000004950
>[2009/07/26 16:46:05, 10] smbd/share_access.c:234(user_ok_token)
>  user_ok_token: share IPC$ is ok for unix user elatov
>[2009/07/26 16:46:05, 10] smbd/share_access.c:279(is_share_read_only_for_token)
>  is_share_read_only_for_user: share IPC$ is read-only for unix user elatov
>[2009/07/26 16:46:05, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:572(pdb_set_username)
>  pdb_set_username: setting username elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:595(pdb_set_domain)
>  pdb_set_domain: setting domain AD, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:618(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:641(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:734(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:710(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:664(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:687(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:777(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-3258327255-2435407998-1853397726-0
>[2009/07/26 16:46:05, 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-3258327255-2435407998-1853397726-0 from rid 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (17227, 150) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/token_util.c:528(debug_nt_user_token)
>  NT user token of user S-1-5-21-1275210071-492894223-682003330-111151
>  contains 24 SIDs
>  SID[  0]: S-1-5-21-1275210071-492894223-682003330-111151
>  SID[  1]: S-1-5-21-1275210071-492894223-682003330-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-1275210071-492894223-682003330-211599
>  SID[  6]: S-1-5-21-1275210071-492894223-682003330-177858
>  SID[  7]: S-1-5-21-1275210071-492894223-682003330-168222
>  SID[  8]: S-1-5-21-1275210071-492894223-682003330-213707
>  SID[  9]: S-1-5-21-1275210071-492894223-682003330-204977
>  SID[ 10]: S-1-5-21-1275210071-492894223-682003330-260187
>  SID[ 11]: S-1-5-21-1275210071-492894223-682003330-204975
>  SID[ 12]: S-1-5-21-1275210071-492894223-682003330-261495
>  SID[ 13]: S-1-5-21-1275210071-492894223-682003330-233995
>  SID[ 14]: S-1-5-21-1275210071-492894223-682003330-172794
>  SID[ 15]: S-1-5-21-1275210071-492894223-682003330-199817
>  SID[ 16]: S-1-5-21-1275210071-492894223-682003330-168519
>  SID[ 17]: S-1-5-21-1275210071-492894223-682003330-172276
>  SID[ 18]: S-1-5-21-1275210071-492894223-682003330-172171
>  SID[ 19]: S-1-5-21-1275210071-492894223-682003330-177859
>  SID[ 20]: S-1-5-21-1275210071-492894223-682003330-145299
>  SID[ 21]: S-1-5-21-1275210071-492894223-682003330-212520
>  SID[ 22]: S-1-5-21-1275210071-492894223-682003330-259494
>  SID[ 23]: S-1-22-1-17227
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 17227
>  Primary group is 150 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  5] smbd/uid.c:353(change_to_user)
>  change_to_user uid=(0,17227) gid=(0,150)
>[2009/07/26 16:46:05,  3] smbd/service.c:1043(make_connection_snum)
>  moxz (128.138.196.24) connect to service IPC$ initially as user elatov (uid=17227, gid=150) (pid 7090)
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/07/26 16:46:05,  3] smbd/reply.c:754(reply_tcon_and_X)
>  tconX service=IPC$ 
>[2009/07/26 16:46:05, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
>  got smb length of 96
>[2009/07/26 16:46:05,  6] smbd/process.c:1430(process_smb)
>  got message type 0x0 of len 0x60
>[2009/07/26 16:46:05,  3] smbd/process.c:1433(process_smb)
>  Transaction 4 of length 100 (0 toread)
>[2009/07/26 16:46:05,  5] lib/util.c:632(show_msg)
>[2009/07/26 16:46:05,  5] lib/util.c:642(show_msg)
>  size=96
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=8
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=7089
>  smb_uid=100
>  smb_mid=5
>  smt_wct=15
>  smb_vwv[ 0]=   28 (0x1C)
>  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]=   28 (0x1C)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=   96 (0x60)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=   16 (0x10)
>  smb_bcc=31
>[2009/07/26 16:46:05, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 44 20 03 00 5C 00 6D   00 6F 00 78 00 7A 00 5C   .D ..\.m .o.x.z.\
>  [0010] 00 65 00 6C 00 61 00 74   00 6F 00 76 00 00 00     .e.l.a.t .o.v...
>[2009/07/26 16:46:05,  3] smbd/process.c:1251(switch_message)
>  switch message SMBtrans2 (pid 7090) conn 0x8847ac8
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (17227, 150) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/token_util.c:528(debug_nt_user_token)
>  NT user token of user S-1-5-21-1275210071-492894223-682003330-111151
>  contains 24 SIDs
>  SID[  0]: S-1-5-21-1275210071-492894223-682003330-111151
>  SID[  1]: S-1-5-21-1275210071-492894223-682003330-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-1275210071-492894223-682003330-211599
>  SID[  6]: S-1-5-21-1275210071-492894223-682003330-177858
>  SID[  7]: S-1-5-21-1275210071-492894223-682003330-168222
>  SID[  8]: S-1-5-21-1275210071-492894223-682003330-213707
>  SID[  9]: S-1-5-21-1275210071-492894223-682003330-204977
>  SID[ 10]: S-1-5-21-1275210071-492894223-682003330-260187
>  SID[ 11]: S-1-5-21-1275210071-492894223-682003330-204975
>  SID[ 12]: S-1-5-21-1275210071-492894223-682003330-261495
>  SID[ 13]: S-1-5-21-1275210071-492894223-682003330-233995
>  SID[ 14]: S-1-5-21-1275210071-492894223-682003330-172794
>  SID[ 15]: S-1-5-21-1275210071-492894223-682003330-199817
>  SID[ 16]: S-1-5-21-1275210071-492894223-682003330-168519
>  SID[ 17]: S-1-5-21-1275210071-492894223-682003330-172276
>  SID[ 18]: S-1-5-21-1275210071-492894223-682003330-172171
>  SID[ 19]: S-1-5-21-1275210071-492894223-682003330-177859
>  SID[ 20]: S-1-5-21-1275210071-492894223-682003330-145299
>  SID[ 21]: S-1-5-21-1275210071-492894223-682003330-212520
>  SID[ 22]: S-1-5-21-1275210071-492894223-682003330-259494
>  SID[ 23]: S-1-22-1-17227
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 17227
>  Primary group is 150 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  5] smbd/uid.c:353(change_to_user)
>  change_to_user uid=(0,17227) gid=(0,150)
>[2009/07/26 16:46:05,  4] smbd/vfs.c:753(vfs_ChDir)
>  vfs_ChDir to /tmp
>[2009/07/26 16:46:05, 10] smbd/trans2.c:7387(call_trans2getdfsreferral)
>  call_trans2getdfsreferral
>[2009/07/26 16:46:05, 10] smbd/msdfs.c:107(parse_dfs_path)
>  parse_dfs_path: temp = |moxz\elatov| after trimming \'s
>[2009/07/26 16:46:05, 10] smbd/msdfs.c:132(parse_dfs_path)
>  parse_dfs_path: hostname: moxz
>[2009/07/26 16:46:05, 10] smbd/msdfs.c:174(parse_dfs_path)
>  parse_dfs_path: servicename: elatov
>[2009/07/26 16:46:05,  3] smbd/msdfs.c:828(get_referred_path)
>  get_referred_path: |elatov| in dfs path \moxz\elatov is not a dfs root.
>[2009/07/26 16:46:05,  3] smbd/error.c:60(error_packet_set)
>  error packet at smbd/trans2.c(7409) cmd=50 (SMBtrans2) NT_STATUS_NOT_FOUND
>[2009/07/26 16:46:05,  5] lib/util.c:632(show_msg)
>[2009/07/26 16:46:05,  5] lib/util.c:642(show_msg)
>  size=35
>  smb_com=0x32
>  smb_rcls=37
>  smb_reh=2
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=7089
>  smb_uid=100
>  smb_mid=5
>  smt_wct=0
>  smb_bcc=0
>[2009/07/26 16:46:05, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
>  got smb length of 35
>[2009/07/26 16:46:05,  6] smbd/process.c:1430(process_smb)
>  got message type 0x0 of len 0x23
>[2009/07/26 16:46:05,  3] smbd/process.c:1433(process_smb)
>  Transaction 5 of length 39 (0 toread)
>[2009/07/26 16:46:05,  5] lib/util.c:632(show_msg)
>[2009/07/26 16:46:05,  5] lib/util.c:642(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=7089
>  smb_uid=100
>  smb_mid=6
>  smt_wct=0
>  smb_bcc=0
>[2009/07/26 16:46:05,  3] smbd/process.c:1251(switch_message)
>  switch message SMBtdis (pid 7090) conn 0x8847ac8
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/07/26 16:46:05,  3] smbd/service.c:1222(close_cnum)
>  moxz (128.138.196.24) closed connection to service IPC$
>[2009/07/26 16:46:05,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to IPC$
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key B21B0000010000004950
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x8849018
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key B21B0000010000004950
>[2009/07/26 16:46:05,  4] smbd/vfs.c:753(vfs_ChDir)
>  vfs_ChDir to /
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/07/26 16:46:05,  5] lib/util.c:632(show_msg)
>[2009/07/26 16:46:05,  5] lib/util.c:642(show_msg)
>  size=35
>  smb_com=0x71
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=7089
>  smb_uid=100
>  smb_mid=6
>  smt_wct=0
>  smb_bcc=0
>[2009/07/26 16:46:05, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
>  got smb length of 78
>[2009/07/26 16:46:05,  6] smbd/process.c:1430(process_smb)
>  got message type 0x0 of len 0x4e
>[2009/07/26 16:46:05,  3] smbd/process.c:1433(process_smb)
>  Transaction 6 of length 82 (0 toread)
>[2009/07/26 16:46:05,  5] lib/util.c:632(show_msg)
>[2009/07/26 16:46:05,  5] lib/util.c:642(show_msg)
>  size=78
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=8
>  smb_flg2=51201
>  smb_tid=65535
>  smb_pid=7089
>  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=35
>[2009/07/26 16:46:05, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 5C 00 5C 00 4D 00 4F   00 58 00 5A 00 5C 00 45   .\.\.M.O .X.Z.\.E
>  [0010] 00 4C 00 41 00 54 00 4F   00 56 00 00 00 3F 3F 3F   .L.A.T.O .V...???
>  [0020] 3F 3F 00                                          ??. 
>[2009/07/26 16:46:05,  3] smbd/process.c:1251(switch_message)
>  switch message SMBtconX (pid 7090) conn 0x0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/07/26 16:46:05,  4] smbd/reply.c:675(reply_tcon_and_X)
>  Client requested device type [?????] for share [ELATOV]
>[2009/07/26 16:46:05,  5] smbd/service.c:1161(make_connection)
>  making a connection to 'homes' service [ELATOV] created at session setup time
>[2009/07/26 16:46:05,  3] lib/access.c:362(only_ipaddrs_in_list)
>  only_ipaddrs_in_list: list has non-ip address (128.138.)
>[2009/07/26 16:46:05,  3] lib/access.c:396(check_access)
>  check_access: hostnames in host allow/deny list.
>[2009/07/26 16:46:05,  2] lib/access.c:406(check_access)
>  Allowed connection from 128.138.196.24 (128.138.196.24)
>[2009/07/26 16:46:05, 10] smbd/share_access.c:234(user_ok_token)
>  user_ok_token: share elatov is ok for unix user elatov
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:572(pdb_set_username)
>  pdb_set_username: setting username elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:595(pdb_set_domain)
>  pdb_set_domain: setting domain AD, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:618(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:641(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:734(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:710(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:664(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:687(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:777(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-3258327255-2435407998-1853397726-0
>[2009/07/26 16:46:05, 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-3258327255-2435407998-1853397726-0 from rid 0
>[2009/07/26 16:46:05,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user elatov
>[2009/07/26 16:46:05,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is elatov
>[2009/07/26 16:46:05,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [elatov]!
>[2009/07/26 16:46:05, 10] smbd/service.c:158(set_conn_connectpath)
>  set_conn_connectpath: service elatov, connectpath = /home/pitserver/elatov
>[2009/07/26 16:46:05,  3] smbd/service.c:803(make_connection_snum)
>  Connect path is '/home/pitserver/elatov' for service [elatov]
>[2009/07/26 16:46:05, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2009/07/26 16:46:05,  3] smbd/vfs.c:95(vfs_init_default)
>  Initialising default vfs hooks
>[2009/07/26 16:46:05,  3] smbd/vfs.c:129(vfs_init_custom)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2009/07/26 16:46:05, 10] smbd/vfs.c:47(vfs_find_backend_entry)
>  vfs_find_backend_entry called for /[Default VFS]/
>  Successfully loaded vfs module [/[Default VFS]/] with the new modules system
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #0 (type 0, layer 0)
>  Making operation type 0 opaque [module /[Default VFS]/]
>  Accepting operation type 0 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #1 (type 1, layer 0)
>  Making operation type 1 opaque [module /[Default VFS]/]
>  Accepting operation type 1 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #2 (type 2, layer 0)
>  Making operation type 2 opaque [module /[Default VFS]/]
>  Accepting operation type 2 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #3 (type 3, layer 0)
>  Making operation type 3 opaque [module /[Default VFS]/]
>  Accepting operation type 3 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #4 (type 4, layer 0)
>  Making operation type 4 opaque [module /[Default VFS]/]
>  Accepting operation type 4 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #5 (type 5, layer 0)
>  Making operation type 5 opaque [module /[Default VFS]/]
>  Accepting operation type 5 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #6 (type 6, layer 0)
>  Making operation type 6 opaque [module /[Default VFS]/]
>  Accepting operation type 6 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #7 (type 7, layer 0)
>  Making operation type 7 opaque [module /[Default VFS]/]
>  Accepting operation type 7 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #8 (type 8, layer 0)
>  Making operation type 8 opaque [module /[Default VFS]/]
>  Accepting operation type 8 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #9 (type 9, layer 0)
>  Making operation type 9 opaque [module /[Default VFS]/]
>  Accepting operation type 9 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #10 (type 10, layer 0)
>  Making operation type 10 opaque [module /[Default VFS]/]
>  Accepting operation type 10 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #11 (type 11, layer 0)
>  Making operation type 11 opaque [module /[Default VFS]/]
>  Accepting operation type 11 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #12 (type 12, layer 0)
>  Making operation type 12 opaque [module /[Default VFS]/]
>  Accepting operation type 12 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #13 (type 13, layer 0)
>  Making operation type 13 opaque [module /[Default VFS]/]
>  Accepting operation type 13 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #14 (type 14, layer 0)
>  Making operation type 14 opaque [module /[Default VFS]/]
>  Accepting operation type 14 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #15 (type 15, layer 0)
>  Making operation type 15 opaque [module /[Default VFS]/]
>  Accepting operation type 15 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #16 (type 16, layer 0)
>  Making operation type 16 opaque [module /[Default VFS]/]
>  Accepting operation type 16 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #17 (type 17, layer 0)
>  Making operation type 17 opaque [module /[Default VFS]/]
>  Accepting operation type 17 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #18 (type 18, layer 0)
>  Making operation type 18 opaque [module /[Default VFS]/]
>  Accepting operation type 18 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #19 (type 19, layer 0)
>  Making operation type 19 opaque [module /[Default VFS]/]
>  Accepting operation type 19 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #20 (type 20, layer 0)
>  Making operation type 20 opaque [module /[Default VFS]/]
>  Accepting operation type 20 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #21 (type 21, layer 0)
>  Making operation type 21 opaque [module /[Default VFS]/]
>  Accepting operation type 21 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #22 (type 22, layer 0)
>  Making operation type 22 opaque [module /[Default VFS]/]
>  Accepting operation type 22 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #23 (type 23, layer 0)
>  Making operation type 23 opaque [module /[Default VFS]/]
>  Accepting operation type 23 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #24 (type 24, layer 0)
>  Making operation type 24 opaque [module /[Default VFS]/]
>  Accepting operation type 24 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #25 (type 25, layer 0)
>  Making operation type 25 opaque [module /[Default VFS]/]
>  Accepting operation type 25 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #26 (type 26, layer 0)
>  Making operation type 26 opaque [module /[Default VFS]/]
>  Accepting operation type 26 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #27 (type 27, layer 0)
>  Making operation type 27 opaque [module /[Default VFS]/]
>  Accepting operation type 27 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #28 (type 28, layer 0)
>  Making operation type 28 opaque [module /[Default VFS]/]
>  Accepting operation type 28 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #29 (type 29, layer 0)
>  Making operation type 29 opaque [module /[Default VFS]/]
>  Accepting operation type 29 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #30 (type 30, layer 0)
>  Making operation type 30 opaque [module /[Default VFS]/]
>  Accepting operation type 30 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #31 (type 31, layer 0)
>  Making operation type 31 opaque [module /[Default VFS]/]
>  Accepting operation type 31 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #32 (type 32, layer 0)
>  Making operation type 32 opaque [module /[Default VFS]/]
>  Accepting operation type 32 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #33 (type 33, layer 0)
>  Making operation type 33 opaque [module /[Default VFS]/]
>  Accepting operation type 33 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #34 (type 34, layer 0)
>  Making operation type 34 opaque [module /[Default VFS]/]
>  Accepting operation type 34 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #35 (type 35, layer 0)
>  Making operation type 35 opaque [module /[Default VFS]/]
>  Accepting operation type 35 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #36 (type 36, layer 0)
>  Making operation type 36 opaque [module /[Default VFS]/]
>  Accepting operation type 36 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #37 (type 37, layer 0)
>  Making operation type 37 opaque [module /[Default VFS]/]
>  Accepting operation type 37 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #38 (type 38, layer 0)
>  Making operation type 38 opaque [module /[Default VFS]/]
>  Accepting operation type 38 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #39 (type 39, layer 0)
>  Making operation type 39 opaque [module /[Default VFS]/]
>  Accepting operation type 39 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #40 (type 40, layer 0)
>  Making operation type 40 opaque [module /[Default VFS]/]
>  Accepting operation type 40 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #41 (type 41, layer 0)
>  Making operation type 41 opaque [module /[Default VFS]/]
>  Accepting operation type 41 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #42 (type 42, layer 0)
>  Making operation type 42 opaque [module /[Default VFS]/]
>  Accepting operation type 42 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #43 (type 43, layer 0)
>  Making operation type 43 opaque [module /[Default VFS]/]
>  Accepting operation type 43 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #44 (type 44, layer 0)
>  Making operation type 44 opaque [module /[Default VFS]/]
>  Accepting operation type 44 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #45 (type 45, layer 0)
>  Making operation type 45 opaque [module /[Default VFS]/]
>  Accepting operation type 45 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #46 (type 46, layer 0)
>  Making operation type 46 opaque [module /[Default VFS]/]
>  Accepting operation type 46 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #47 (type 47, layer 0)
>  Making operation type 47 opaque [module /[Default VFS]/]
>  Accepting operation type 47 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #48 (type 48, layer 0)
>  Making operation type 48 opaque [module /[Default VFS]/]
>  Accepting operation type 48 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #49 (type 49, layer 0)
>  Making operation type 49 opaque [module /[Default VFS]/]
>  Accepting operation type 49 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #50 (type 50, layer 0)
>  Making operation type 50 opaque [module /[Default VFS]/]
>  Accepting operation type 50 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #51 (type 51, layer 0)
>  Making operation type 51 opaque [module /[Default VFS]/]
>  Accepting operation type 51 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #52 (type 52, layer 0)
>  Making operation type 52 opaque [module /[Default VFS]/]
>  Accepting operation type 52 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #53 (type 53, layer 0)
>  Making operation type 53 opaque [module /[Default VFS]/]
>  Accepting operation type 53 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #54 (type 54, layer 0)
>  Making operation type 54 opaque [module /[Default VFS]/]
>  Accepting operation type 54 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #55 (type 55, layer 0)
>  Making operation type 55 opaque [module /[Default VFS]/]
>  Accepting operation type 55 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #56 (type 56, layer 0)
>  Making operation type 56 opaque [module /[Default VFS]/]
>  Accepting operation type 56 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #57 (type 57, layer 0)
>  Making operation type 57 opaque [module /[Default VFS]/]
>  Accepting operation type 57 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #58 (type 58, layer 0)
>  Making operation type 58 opaque [module /[Default VFS]/]
>  Accepting operation type 58 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #59 (type 59, layer 0)
>  Making operation type 59 opaque [module /[Default VFS]/]
>  Accepting operation type 59 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #60 (type 60, layer 0)
>  Making operation type 60 opaque [module /[Default VFS]/]
>  Accepting operation type 60 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #61 (type 61, layer 0)
>  Making operation type 61 opaque [module /[Default VFS]/]
>  Accepting operation type 61 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #62 (type 62, layer 0)
>  Making operation type 62 opaque [module /[Default VFS]/]
>  Accepting operation type 62 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #63 (type 63, layer 0)
>  Making operation type 63 opaque [module /[Default VFS]/]
>  Accepting operation type 63 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #64 (type 64, layer 0)
>  Making operation type 64 opaque [module /[Default VFS]/]
>  Accepting operation type 64 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #65 (type 65, layer 0)
>  Making operation type 65 opaque [module /[Default VFS]/]
>  Accepting operation type 65 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #66 (type 66, layer 0)
>  Making operation type 66 opaque [module /[Default VFS]/]
>  Accepting operation type 66 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #67 (type 67, layer 0)
>  Making operation type 67 opaque [module /[Default VFS]/]
>  Accepting operation type 67 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #68 (type 68, layer 0)
>  Making operation type 68 opaque [module /[Default VFS]/]
>  Accepting operation type 68 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #69 (type 69, layer 0)
>  Making operation type 69 opaque [module /[Default VFS]/]
>  Accepting operation type 69 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #70 (type 70, layer 0)
>  Making operation type 70 opaque [module /[Default VFS]/]
>  Accepting operation type 70 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #71 (type 71, layer 0)
>  Making operation type 71 opaque [module /[Default VFS]/]
>  Accepting operation type 71 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #72 (type 72, layer 0)
>  Making operation type 72 opaque [module /[Default VFS]/]
>  Accepting operation type 72 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #73 (type 73, layer 0)
>  Making operation type 73 opaque [module /[Default VFS]/]
>  Accepting operation type 73 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #74 (type 74, layer 0)
>  Making operation type 74 opaque [module /[Default VFS]/]
>  Accepting operation type 74 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #75 (type 75, layer 0)
>  Making operation type 75 opaque [module /[Default VFS]/]
>  Accepting operation type 75 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #76 (type 76, layer 0)
>  Making operation type 76 opaque [module /[Default VFS]/]
>  Accepting operation type 76 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #77 (type 77, layer 0)
>  Making operation type 77 opaque [module /[Default VFS]/]
>  Accepting operation type 77 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #78 (type 78, layer 0)
>  Making operation type 78 opaque [module /[Default VFS]/]
>  Accepting operation type 78 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #79 (type 79, layer 0)
>  Making operation type 79 opaque [module /[Default VFS]/]
>  Accepting operation type 79 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #80 (type 80, layer 0)
>  Making operation type 80 opaque [module /[Default VFS]/]
>  Accepting operation type 80 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #81 (type 81, layer 0)
>  Making operation type 81 opaque [module /[Default VFS]/]
>  Accepting operation type 81 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #82 (type 82, layer 0)
>  Making operation type 82 opaque [module /[Default VFS]/]
>  Accepting operation type 82 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #83 (type 83, layer 0)
>  Making operation type 83 opaque [module /[Default VFS]/]
>  Accepting operation type 83 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #84 (type 84, layer 0)
>  Making operation type 84 opaque [module /[Default VFS]/]
>  Accepting operation type 84 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #85 (type 85, layer 0)
>  Making operation type 85 opaque [module /[Default VFS]/]
>  Accepting operation type 85 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #86 (type 86, layer 0)
>  Making operation type 86 opaque [module /[Default VFS]/]
>  Accepting operation type 86 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #87 (type 87, layer 0)
>  Making operation type 87 opaque [module /[Default VFS]/]
>  Accepting operation type 87 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #88 (type 88, layer 0)
>  Making operation type 88 opaque [module /[Default VFS]/]
>  Accepting operation type 88 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #89 (type 89, layer 0)
>  Making operation type 89 opaque [module /[Default VFS]/]
>  Accepting operation type 89 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #90 (type 90, layer 0)
>  Making operation type 90 opaque [module /[Default VFS]/]
>  Accepting operation type 90 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #91 (type 91, layer 0)
>  Making operation type 91 opaque [module /[Default VFS]/]
>  Accepting operation type 91 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #92 (type 92, layer 0)
>  Making operation type 92 opaque [module /[Default VFS]/]
>  Accepting operation type 92 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #93 (type 93, layer 0)
>  Making operation type 93 opaque [module /[Default VFS]/]
>  Accepting operation type 93 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #94 (type 94, layer 0)
>  Making operation type 94 opaque [module /[Default VFS]/]
>  Accepting operation type 94 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #95 (type 95, layer 0)
>  Making operation type 95 opaque [module /[Default VFS]/]
>  Accepting operation type 95 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #96 (type 96, layer 0)
>  Making operation type 96 opaque [module /[Default VFS]/]
>  Accepting operation type 96 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #97 (type 97, layer 0)
>  Making operation type 97 opaque [module /[Default VFS]/]
>  Accepting operation type 97 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #98 (type 98, layer 0)
>  Making operation type 98 opaque [module /[Default VFS]/]
>  Accepting operation type 98 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #99 (type 99, layer 0)
>  Making operation type 99 opaque [module /[Default VFS]/]
>  Accepting operation type 99 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #100 (type 100, layer 0)
>  Making operation type 100 opaque [module /[Default VFS]/]
>  Accepting operation type 100 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #101 (type 101, layer 0)
>  Making operation type 101 opaque [module /[Default VFS]/]
>  Accepting operation type 101 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #102 (type 102, layer 0)
>  Making operation type 102 opaque [module /[Default VFS]/]
>  Accepting operation type 102 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #103 (type 103, layer 0)
>  Making operation type 103 opaque [module /[Default VFS]/]
>  Accepting operation type 103 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #104 (type 104, layer 0)
>  Making operation type 104 opaque [module /[Default VFS]/]
>  Accepting operation type 104 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #105 (type 105, layer 0)
>  Making operation type 105 opaque [module /[Default VFS]/]
>  Accepting operation type 105 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #106 (type 106, layer 0)
>  Making operation type 106 opaque [module /[Default VFS]/]
>  Accepting operation type 106 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #107 (type 107, layer 0)
>  Making operation type 107 opaque [module /[Default VFS]/]
>  Accepting operation type 107 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #108 (type 108, layer 0)
>  Making operation type 108 opaque [module /[Default VFS]/]
>  Accepting operation type 108 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #109 (type 109, layer 0)
>  Making operation type 109 opaque [module /[Default VFS]/]
>  Accepting operation type 109 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/vfs.c:192(vfs_init_custom)
>  Checking operation #110 (type 110, layer 0)
>  Making operation type 110 opaque [module /[Default VFS]/]
>  Accepting operation type 110 from module /[Default VFS]/
>[2009/07/26 16:46:05,  5] smbd/connection.c:142(claim_connection)
>  claiming [elatov]
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key B21B000001000000656C
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x884f5f8
>[2009/07/26 16:46:05, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key B21B000001000000656C
>[2009/07/26 16:46:05, 10] smbd/share_access.c:234(user_ok_token)
>  user_ok_token: share elatov is ok for unix user elatov
>[2009/07/26 16:46:05, 10] smbd/share_access.c:279(is_share_read_only_for_token)
>  is_share_read_only_for_user: share elatov is read-write for unix user elatov
>[2009/07/26 16:46:05, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2009/07/26 16:46:05, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:572(pdb_set_username)
>  pdb_set_username: setting username elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:595(pdb_set_domain)
>  pdb_set_domain: setting domain AD, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:618(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username elatov, was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:641(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:734(pdb_set_homedir)
>  pdb_set_homedir: setting home dir , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:710(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:664(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:687(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path , was 
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:777(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-3258327255-2435407998-1853397726-0
>[2009/07/26 16:46:05, 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-3258327255-2435407998-1853397726-0 from rid 0
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (17227, 150) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/token_util.c:528(debug_nt_user_token)
>  NT user token of user S-1-5-21-1275210071-492894223-682003330-111151
>  contains 24 SIDs
>  SID[  0]: S-1-5-21-1275210071-492894223-682003330-111151
>  SID[  1]: S-1-5-21-1275210071-492894223-682003330-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-1275210071-492894223-682003330-211599
>  SID[  6]: S-1-5-21-1275210071-492894223-682003330-177858
>  SID[  7]: S-1-5-21-1275210071-492894223-682003330-168222
>  SID[  8]: S-1-5-21-1275210071-492894223-682003330-213707
>  SID[  9]: S-1-5-21-1275210071-492894223-682003330-204977
>  SID[ 10]: S-1-5-21-1275210071-492894223-682003330-260187
>  SID[ 11]: S-1-5-21-1275210071-492894223-682003330-204975
>  SID[ 12]: S-1-5-21-1275210071-492894223-682003330-261495
>  SID[ 13]: S-1-5-21-1275210071-492894223-682003330-233995
>  SID[ 14]: S-1-5-21-1275210071-492894223-682003330-172794
>  SID[ 15]: S-1-5-21-1275210071-492894223-682003330-199817
>  SID[ 16]: S-1-5-21-1275210071-492894223-682003330-168519
>  SID[ 17]: S-1-5-21-1275210071-492894223-682003330-172276
>  SID[ 18]: S-1-5-21-1275210071-492894223-682003330-172171
>  SID[ 19]: S-1-5-21-1275210071-492894223-682003330-177859
>  SID[ 20]: S-1-5-21-1275210071-492894223-682003330-145299
>  SID[ 21]: S-1-5-21-1275210071-492894223-682003330-212520
>  SID[ 22]: S-1-5-21-1275210071-492894223-682003330-259494
>  SID[ 23]: S-1-22-1-17227
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 17227
>  Primary group is 150 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  5] smbd/uid.c:353(change_to_user)
>  change_to_user uid=(0,17227) gid=(0,150)
>[2009/07/26 16:46:05,  1] smbd/service.c:1043(make_connection_snum)
>  moxz (128.138.196.24) connect to service elatov initially as user elatov (uid=17227, gid=150) (pid 7090)
>[2009/07/26 16:46:05,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/07/26 16:46:05,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/07/26 16:46:05,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/07/26 16:46:05,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/07/26 16:46:05,  3] smbd/reply.c:754(reply_tcon_and_X)
>  tconX service=ELATOV

Actions: View

Attachments on bug 6574: 4472 | 4473