Attachment 1571 Details for Bug 3262 – Level 10 Log of executing attrib V:\4\1.txt

Level 10 Log of executing attrib V:\4\1.txt

log.virtual (text/plain), 58.85 KB, created by Alex Masterov on 2005-11-15 01:24:57 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Alex Masterov

Created: 2005-11-15 01:24:57 UTC

Size: 58.85 KB

patch

obsolete

>[2005/11/15 14:12:12, 6] param/loadparm.c:lp_file_list_changed(2940)
>  lp_file_list_changed()
>  file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf  last mod_time: Tue Nov 15 10:05:47 2005
>  
>[2005/11/15 14:12:12, 5] smbd/reply.c:reply_special(537)
>  init msg_type=0x81 msg_flags=0x0
>[2005/11/15 14:12:12, 10] smbd/process.c:setup_select_timeout(1372)
>  change_notify_timeout: 60
>[2005/11/15 14:12:12, 10] smbd/process.c:run_events(299)
>  run_events: No events
>[2005/11/15 14:12:12, 10] lib/util_sock.c:read_data(517)
>  read_data: read of 4 returned 0. Error = Unknown error: 0
>[2005/11/15 14:12:12, 10] lib/util_sock.c:receive_smb_raw(666)
>  receive_smb_raw: length < 0!
>[2005/11/15 14:12:12, 3] smbd/process.c:timeout_processing(1447)
>  timeout_processing: End of file from client (client has disconnected).
>[2005/11/15 14:12:12, 5] lib/gencache.c:gencache_shutdown(88)
>  Closing cache file
>[2005/11/15 14:12:12, 5] libsmb/namecache.c:namecache_shutdown(79)
>  namecache_shutdown: netbios namecache closed successfully.
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:12, 5] smbd/uid.c:change_to_root_user(319)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2005/11/15 14:12:12, 2] smbd/server.c:exit_server(614)
>  Closing connections
>[2005/11/15 14:12:12, 3] smbd/connection.c:yield_connection(69)
>  Yielding connection to 
>[2005/11/15 14:12:12, 3] smbd/connection.c:yield_connection(76)
>  yield_connection: tdb_delete for name  failed with error Record does not exist.
>[2005/11/15 14:12:12, 3] smbd/server.c:exit_server(655)
>  Server exit (normal exit)
>[2005/11/15 14:12:12, 6] param/loadparm.c:lp_file_list_changed(2940)
>  lp_file_list_changed()
>  file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf  last mod_time: Tue Nov 15 10:05:47 2005
>  
>[2005/11/15 14:12:12, 5] auth/auth_util.c:make_user_info_map(193)
>  make_user_info_map: Mapping user [HELMI]\[rc20] from workstation [VIRTUAL]
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:push_sec_ctx(256)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2005/11/15 14:12:12, 3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:12, 5] auth/auth_util.c:is_trusted_domain(1645)
>  is_trusted_domain: Checking for domain trust with [HELMI]
>[2005/11/15 14:12:12, 5] passdb/secrets.c:secrets_fetch_trusted_domain_password(339)
>  secrets_fetch failed!
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 10] lib/gencache.c:gencache_get(291)
>  Cache entry with key = TDOM/HELMI couldn't be found
>[2005/11/15 14:12:12, 5] libsmb/trustdom_cache.c:trustdom_cache_fetch(184)
>  no entry for trusted domain HELMI found.
>[2005/11/15 14:12:12, 5] auth/auth_util.c:make_user_info(99)
>  attempting to make a user_info for rc20 (rc20)
>[2005/11/15 14:12:12, 5] auth/auth_util.c:make_user_info(109)
>  making strings for rc20's user_info struct
>[2005/11/15 14:12:12, 5] auth/auth_util.c:make_user_info(151)
>  making blobs for rc20's user_info struct
>[2005/11/15 14:12:12, 10] auth/auth_util.c:make_user_info(169)
>  made an encrypted user_info for rc20 (rc20)
>[2005/11/15 14:12:12, 3] auth/auth.c:check_ntlm_password(219)
>  check_ntlm_password:  Checking password for unmapped user [HELMI]\[rc20]@[VIRTUAL] with the new password interface
>[2005/11/15 14:12:12, 3] auth/auth.c:check_ntlm_password(222)
>  check_ntlm_password:  mapped user is: [TESTD]\[rc20]@[VIRTUAL]
>[2005/11/15 14:12:12, 10] auth/auth.c:check_ntlm_password(231)
>  check_ntlm_password: auth_context challenge created by NTLMSSP callback (NTLM2)
>[2005/11/15 14:12:12, 10] auth/auth.c:check_ntlm_password(233)
>  challenge is: 
>[2005/11/15 14:12:12, 5] lib/util.c:dump_data(2058)
>  [000] 53 3D 35 50 90 E6 CF BB                           S=5P.... 
>[2005/11/15 14:12:12, 10] auth/auth.c:check_ntlm_password(259)
>  check_ntlm_password: guest had nothing to say
>[2005/11/15 14:12:12, 8] lib/util.c:is_myname(1879)
>  is_myname("TESTD") returns 0
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:push_sec_ctx(256)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2005/11/15 14:12:12, 3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:12, 5] lib/smbldap.c:smbldap_search_ext(984)
>  smbldap_search_ext: base => [dc=testd,dc=org], filter => [(&(uid=rc20)(objectclass=sambaSamAccount))], scope => [2]
>[2005/11/15 14:12:12, 5] lib/smbldap.c:smbldap_close(893)
>  The connection to the LDAP server was closed
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_open_connection(538)
>  smbldap_open_connection: ldaps://localhost
>[2005/11/15 14:12:12, 2] lib/smbldap.c:smbldap_open_connection(634)
>  smbldap_open_connection: connection opened
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_connect_system(766)
>  ldap_connect_system: Binding to ldap server ldaps://localhost as "cn=Manager,dc=testd,dc=org"
>[2005/11/15 14:12:12, 3] lib/smbldap.c:smbldap_connect_system(809)
>  ldap_connect_system: succesful connection to the LDAP server
>  ldap_connect_system: LDAP server does support paged results
>[2005/11/15 14:12:12, 4] lib/smbldap.c:smbldap_open(873)
>  The LDAP server is succesfully connected
>[2005/11/15 14:12:12, 2] passdb/pdb_ldap.c:init_sam_from_ldap(639)
>  init_sam_from_ldap: Entry found for user: rc20
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_username(617)
>  pdb_set_username: setting username rc20, was 
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_domain(644)
>  pdb_set_domain: setting domain TESTD, was 
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_nt_username(671)
>  pdb_set_nt_username: setting nt username rc20, was 
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_user_sid_from_string(557)
>  pdb_set_user_sid_from_string: setting user sid S-1-5-21-3244338754-2153890023-3124348023-3018
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_user_sid(544)
>  pdb_set_user_sid: setting user sid S-1-5-21-3244338754-2153890023-3124348023-3018
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_group_sid_from_string(592)
>  pdb_set_group_sid_from_string: setting group sid S-1-5-21-3244338754-2153890023-3124348023-3025
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_group_sid(580)
>  pdb_set_group_sid: setting group sid S-1-5-21-3244338754-2153890023-3124348023-3025
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaLogonTime] = [<does not exist>]
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaLogoffTime] = [<does not exist>]
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaKickoffTime] = [<does not exist>]
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_fullname(698)
>  pdb_set_full_name: setting full name User &, was 
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaHomeDrive] = [<does not exist>]
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(779)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaHomePath] = [<does not exist>]
>[2005/11/15 14:12:12, 4] lib/substitute.c:automount_server(359)
>  Home server: testbsd
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_homedir(806)
>  pdb_set_homedir: setting home dir \\testbsd\rc20, was 
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaLogonScript] = [<does not exist>]
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_logon_script(725)
>  pdb_set_logon_script: setting logon script logon.bat, was 
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaProfilePath] = [<does not exist>]
>[2005/11/15 14:12:12, 4] lib/substitute.c:automount_server(359)
>  Home server: testbsd
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_profile_path(752)
>  pdb_set_profile_path: setting profile path \\testbsd\rc20\profile, was 
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [description] = [<does not exist>]
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaUserWorkstations] = [<does not exist>]
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaMungedDial] = [<does not exist>]
>[2005/11/15 14:12:12, 10] lib/account_pol.c:account_policy_cache_timestamp(187)
>  failed to get last set timestamp of cache
>[2005/11/15 14:12:12, 10] lib/account_pol.c:cache_account_policy_get(396)
>  cache_account_policy_get: failed to get latest cache update timestamp
>[2005/11/15 14:12:12, 10] passdb/pdb_ldap.c:ldapsam_get_account_policy_from_ldap(3395)
>  ldapsam_get_account_policy_from_ldap
>[2005/11/15 14:12:12, 5] lib/smbldap.c:smbldap_search_ext(984)
>  smbldap_search_ext: base => [sambaDomainName=TESTD,dc=testd,dc=org], filter => [(objectclass=*)], scope => [0]
>[2005/11/15 14:12:12, 10] passdb/pdb_ldap.c:ldapsam_get_account_policy(3474)
>  ldapsam_get_account_policy: failed to retrieve from ldap, returning default.
>[2005/11/15 14:12:12, 10] passdb/pdb_ldap.c:ldapsam_set_account_policy(3337)
>  ldapsam_set_account_policy
>[2005/11/15 14:12:12, 5] lib/smbldap.c:smbldap_modify(1158)
>  smbldap_modify: dn => [sambaDomainName=TESTD,dc=testd,dc=org]
>[2005/11/15 14:12:12, 0] passdb/pdb_ldap.c:ldapsam_set_account_policy(3367)
>  ldapsam_set_account_policy: Could not set account policy for sambaDomainName=TESTD,dc=testd,dc=org, error: Undefined attribute type (sambaPwdHistoryLength: attribute type undefined)
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaBadPasswordCount] = [<does not exist>]
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaBadPasswordTime] = [<does not exist>]
>[2005/11/15 14:12:12, 10] lib/smbldap.c:smbldap_get_single_attribute(297)
>  smbldap_get_single_attribute: [sambaLogonHours] = [<does not exist>]
>[2005/11/15 14:12:12, 7] passdb/login_cache.c:login_cache_read(83)
>  Looking up login cache for user rc20
>[2005/11/15 14:12:12, 7] passdb/login_cache.c:login_cache_read(97)
>  No cache entry found
>[2005/11/15 14:12:12, 9] passdb/pdb_ldap.c:init_sam_from_ldap(994)
>  No cache entry, bad count = 0, bad time = 0
>[2005/11/15 14:12:12, 10] lib/account_pol.c:account_policy_cache_timestamp(187)
>  failed to get last set timestamp of cache
>[2005/11/15 14:12:12, 10] lib/account_pol.c:cache_account_policy_get(396)
>  cache_account_policy_get: failed to get latest cache update timestamp
>[2005/11/15 14:12:12, 10] passdb/pdb_ldap.c:ldapsam_get_account_policy_from_ldap(3395)
>  ldapsam_get_account_policy_from_ldap
>[2005/11/15 14:12:12, 5] lib/smbldap.c:smbldap_search_ext(984)
>  smbldap_search_ext: base => [sambaDomainName=TESTD,dc=testd,dc=org], filter => [(objectclass=*)], scope => [0]
>[2005/11/15 14:12:12, 10] passdb/pdb_ldap.c:ldapsam_get_account_policy(3474)
>  ldapsam_get_account_policy: failed to retrieve from ldap, returning default.
>[2005/11/15 14:12:12, 10] passdb/pdb_ldap.c:ldapsam_set_account_policy(3337)
>  ldapsam_set_account_policy
>[2005/11/15 14:12:12, 5] lib/smbldap.c:smbldap_modify(1158)
>  smbldap_modify: dn => [sambaDomainName=TESTD,dc=testd,dc=org]
>[2005/11/15 14:12:12, 0] passdb/pdb_ldap.c:ldapsam_set_account_policy(3367)
>  ldapsam_set_account_policy: Could not set account policy for sambaDomainName=TESTD,dc=testd,dc=org, error: Undefined attribute type (sambaPwdHistoryLength: attribute type undefined)
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_username(617)
>  pdb_set_username: setting username rc20, was 
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_domain(644)
>  pdb_set_domain: setting domain TESTD, was 
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_nt_username(671)
>  pdb_set_nt_username: setting nt username rc20, was 
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_fullname(698)
>  pdb_set_full_name: setting full name User &, was 
>[2005/11/15 14:12:12, 4] lib/substitute.c:automount_server(359)
>  Home server: testbsd
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_homedir(806)
>  pdb_set_homedir: setting home dir \\testbsd\rc20, was 
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(779)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_logon_script(725)
>  pdb_set_logon_script: setting logon script logon.bat, was 
>[2005/11/15 14:12:12, 4] lib/substitute.c:automount_server(359)
>  Home server: testbsd
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_profile_path(752)
>  pdb_set_profile_path: setting profile path \\testbsd\rc20\profile, was 
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_workstations(885)
>  pdb_set_workstations: setting workstations , was 
>[2005/11/15 14:12:12, 10] lib/account_pol.c:account_policy_cache_timestamp(187)
>  failed to get last set timestamp of cache
>[2005/11/15 14:12:12, 10] lib/account_pol.c:cache_account_policy_get(396)
>  cache_account_policy_get: failed to get latest cache update timestamp
>[2005/11/15 14:12:12, 10] passdb/pdb_ldap.c:ldapsam_get_account_policy_from_ldap(3395)
>  ldapsam_get_account_policy_from_ldap
>[2005/11/15 14:12:12, 5] lib/smbldap.c:smbldap_search_ext(984)
>  smbldap_search_ext: base => [sambaDomainName=TESTD,dc=testd,dc=org], filter => [(objectclass=*)], scope => [0]
>[2005/11/15 14:12:12, 10] passdb/pdb_ldap.c:ldapsam_get_account_policy(3474)
>  ldapsam_get_account_policy: failed to retrieve from ldap, returning default.
>[2005/11/15 14:12:12, 10] passdb/pdb_ldap.c:ldapsam_set_account_policy(3337)
>  ldapsam_set_account_policy
>[2005/11/15 14:12:12, 5] lib/smbldap.c:smbldap_modify(1158)
>  smbldap_modify: dn => [sambaDomainName=TESTD,dc=testd,dc=org]
>[2005/11/15 14:12:12, 0] passdb/pdb_ldap.c:ldapsam_set_account_policy(3367)
>  ldapsam_set_account_policy: Could not set account policy for sambaDomainName=TESTD,dc=testd,dc=org, error: Undefined attribute type (sambaPwdHistoryLength: attribute type undefined)
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_user_sid(544)
>  pdb_set_user_sid: setting user sid S-1-5-21-3244338754-2153890023-3124348023-3018
>[2005/11/15 14:12:12, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(73)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3244338754-2153890023-3124348023-3018 from rid 3018
>[2005/11/15 14:12:12, 10] passdb/pdb_get_set.c:pdb_set_group_sid(580)
>  pdb_set_group_sid: setting group sid S-1-5-21-3244338754-2153890023-3124348023-3025
>[2005/11/15 14:12:12, 10] passdb/pdb_compat.c:pdb_set_group_sid_from_rid(100)
>  pdb_set_group_sid_from_rid:
>  	setting group sid S-1-5-21-3244338754-2153890023-3124348023-3025 from rid 3025
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 9] passdb/passdb.c:pdb_update_autolock_flag(2333)
>  pdb_update_autolock_flag: Account rc20 not autolocked, no check needed
>[2005/11/15 14:12:12, 4] libsmb/ntlm_check.c:ntlm_password_check(326)
>  ntlm_password_check: Checking NT MD4 password
>[2005/11/15 14:12:12, 4] auth/auth_sam.c:sam_account_ok(122)
>  sam_account_ok: Checking SMB password for user rc20
>[2005/11/15 14:12:12, 5] auth/auth_sam.c:logon_hours_ok(104)
>  logon_hours_ok: user rc20 allowed to logon at this time (Tue Nov 15 14:12:12 2005
>  )
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:push_sec_ctx(256)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2005/11/15 14:12:12, 3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:12, 10] lib/system_smbd.c:sys_getgrouplist(167)
>  sys_getgrouplist: user [rc20]
>[2005/11/15 14:12:12, 10] lib/system_smbd.c:sys_getgrouplist(176)
>  sys_getgrouplist(): disabled winbindd for group lookup [user == rc20]
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:push_sec_ctx(256)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2005/11/15 14:12:12, 3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:12, 5] lib/smbldap.c:smbldap_search_ext(984)
>  smbldap_search_ext: base => [ou=Groups,dc=testd,dc=org], filter => [(&(objectClass=sambaGroupMapping)(gidNumber=1012))], scope => [2]
>[2005/11/15 14:12:12, 4] passdb/pdb_ldap.c:ldapsam_getgroup(2295)
>  ldapsam_getgroup: Did not find group
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2005/11/15 14:12:12, 10] passdb/passdb.c:local_gid_to_sid(1250)
>  local_gid_to_sid: Fall back to algorithmic mapping: 1012 -> S-1-5-21-3244338754-2153890023-3124348023-3025
>[2005/11/15 14:12:12, 10] passdb/lookup_sid.c:gid_to_sid(379)
>  gid_to_sid: local 1012 -> S-1-5-21-3244338754-2153890023-3124348023-3025
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 10] auth/auth_util.c:debug_nt_user_token(468)
>  NT user token of user S-1-5-21-3244338754-2153890023-3124348023-3018
>  contains 5 SIDs
>  SID[  0]: S-1-5-21-3244338754-2153890023-3124348023-3018
>  SID[  1]: S-1-5-21-3244338754-2153890023-3124348023-3025
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2005/11/15 14:12:12, 5] auth/auth_util.c:make_server_info_sam(878)
>  make_server_info_sam: made server info for user rc20 -> rc20
>[2005/11/15 14:12:12, 3] auth/auth.c:check_ntlm_password(268)
>  check_ntlm_password: sam authentication for user [rc20] succeeded
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:push_sec_ctx(256)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2005/11/15 14:12:12, 3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 5] auth/auth.c:check_ntlm_password(294)
>  check_ntlm_password:  PAM Account for user [rc20] succeeded
>[2005/11/15 14:12:12, 2] auth/auth.c:check_ntlm_password(307)
>  check_ntlm_password:  authentication for user [rc20] -> [rc20] -> [rc20] succeeded
>[2005/11/15 14:12:12, 5] auth/auth_util.c:free_user_info(1465)
>  attempting to free (and zero) a user_info structure
>[2005/11/15 14:12:12, 10] auth/auth_util.c:free_user_info(1468)
>  structure was created for rc20
>[2005/11/15 14:12:12, 10] auth/auth_ntlmssp.c:auth_ntlmssp_check_password(120)
>  Got NT session key of length 16
>[2005/11/15 14:12:12, 10] auth/auth_ntlmssp.c:auth_ntlmssp_check_password(127)
>  Got LM session key of length 16
>[2005/11/15 14:12:12, 10] libsmb/ntlmssp.c:ntlmssp_server_auth(708)
>  ntlmssp_server_auth: Created NTLM2 session key.
>[2005/11/15 14:12:12, 3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(332)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2005/11/15 14:12:12, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
>  Got NTLMSSP neg_flags=0x60088235
>    NTLMSSP_NEGOTIATE_UNICODE
>    NTLMSSP_REQUEST_TARGET
>    NTLMSSP_NEGOTIATE_SIGN
>    NTLMSSP_NEGOTIATE_SEAL
>    NTLMSSP_NEGOTIATE_NTLM
>    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
>    NTLMSSP_NEGOTIATE_NTLM2
>    NTLMSSP_NEGOTIATE_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>[2005/11/15 14:12:12, 10] smbd/password.c:register_vuid(182)
>  register_vuid: allocated vuid = 101
>[2005/11/15 14:12:12, 10] lib/util_pw.c:getpwnam_alloc(98)
>  Got rc20 from pwnam_cache
>[2005/11/15 14:12:12, 10] smbd/password.c:register_vuid(255)
>  register_vuid: (1009,1012) rc20 rc20 TESTD guest=0
>[2005/11/15 14:12:12, 3] smbd/password.c:register_vuid(257)
>  User name: rc20	Real name: User &
>[2005/11/15 14:12:12, 3] smbd/password.c:register_vuid(276)
>  UNIX uid 1009 is UNIX user rc20, and will be vuid 101
>[2005/11/15 14:12:12, 7] param/loadparm.c:lp_servicenumber(4329)
>  lp_servicenumber: couldn't find rc20
>[2005/11/15 14:12:12, 3] smbd/password.c:register_vuid(305)
>  Adding homes service for user 'rc20' using home directory: '/nonexistent'
>[2005/11/15 14:12:12, 8] param/loadparm.c:add_a_service(2439)
>  add_a_service: Creating snum = 9 for rc20
>[2005/11/15 14:12:12, 10] param/loadparm.c:hash_a_service(2486)
>  hash_a_service: hashing index 9 for service name rc20
>[2005/11/15 14:12:12, 3] param/loadparm.c:lp_add_home(2532)
>  adding home's share [rc20] for user 'rc20' at '/nonexistent'
>[2005/11/15 14:12:12, 6] param/loadparm.c:lp_file_list_changed(2940)
>  lp_file_list_changed()
>  file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf  last mod_time: Tue Nov 15 10:05:47 2005
>  
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=132
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=1664
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_bcc=89
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 00 55 00 6E 00 69 00 78  00 00 00 53 00 61 00 6D  .U.n.i.x ...S.a.m
>  [010] 00 62 00 61 00 20 00 33  00 2E 00 30 00 2E 00 32  .b.a. .3 ...0...2
>  [020] 00 31 00 70 00 72 00 65  00 33 00 2D 00 53 00 56  .1.p.r.e .3.-.S.V
>  [030] 00 4E 00 2D 00 62 00 75  00 69 00 6C 00 64 00 2D  .N.-.b.u .i.l.d.-
>  [040] 00 31 00 31 00 37 00 32  00 39 00 00 00 54 00 45  .1.1.7.2 .9...T.E
>  [050] 00 53 00 54 00 44 00 00  00                       .S.T.D.. .
>[2005/11/15 14:12:12, 10] smbd/process.c:setup_select_timeout(1372)
>  change_notify_timeout: 60
>[2005/11/15 14:12:12, 10] smbd/process.c:run_events(299)
>  run_events: No events
>[2005/11/15 14:12:12, 10] lib/util_sock.c:read_smb_length_return_keepalive(615)
>  got smb length of 82
>[2005/11/15 14:12:12, 6] smbd/process.c:process_smb(1193)
>  got message type 0x0 of len 0x52
>[2005/11/15 14:12:12, 3] smbd/process.c:process_smb(1194)
>  Transaction 3 of length 86
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=82
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=1728
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=   82 (0x52)
>  smb_vwv[ 2]=    8 (0x8)
>  smb_vwv[ 3]=    1 (0x1)
>  smb_bcc=39
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 00 5C 00 5C 00 54 00 45  00 53 00 54 00 42 00 53  .\.\.T.E .S.T.B.S
>  [010] 00 44 00 5C 00 53 00 48  00 41 00 52 00 45 00 00  .D.\.S.H .A.R.E..
>  [020] 00 3F 3F 3F 3F 3F 00                              .?????. 
>[2005/11/15 14:12:12, 3] smbd/process.c:switch_message(993)
>  switch message SMBtconX (pid 34295) conn 0x0
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:12, 5] smbd/uid.c:change_to_root_user(319)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2005/11/15 14:12:12, 4] smbd/reply.c:reply_tcon_and_X(660)
>  Client requested device type [?????] for share [SHARE]
>[2005/11/15 14:12:12, 5] smbd/service.c:make_connection(815)
>  making a connection to 'normal' service share
>[2005/11/15 14:12:12, 5] lib/username.c:Get_Pwnam_alloc(313)
>  Finding user rc20
>[2005/11/15 14:12:12, 5] lib/username.c:Get_Pwnam_internals(262)
>  Trying _Get_Pwnam(), username as lowercase is rc20
>[2005/11/15 14:12:12, 10] lib/util_pw.c:getpwnam_alloc(98)
>  Got rc20 from pwnam_cache
>[2005/11/15 14:12:12, 5] lib/username.c:Get_Pwnam_internals(290)
>  Get_Pwnam_internals did find user [rc20]!
>[2005/11/15 14:12:12, 3] smbd/service.c:make_connection_snum(478)
>  Connect path is '/shared' for service [share]
>[2005/11/15 14:12:12, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(218)
>  get_share_security: using default secdesc for share
>[2005/11/15 14:12:12, 10] lib/util_seaccess.c:se_map_generic(176)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2005/11/15 14:12:12, 10] lib/util_seaccess.c:se_access_check(233)
>  se_access_check: requested access 0x00000002, for NT token with 5 entries and first sid S-1-5-21-3244338754-2153890023-3124348023-3018.
>[2005/11/15 14:12:12, 3] lib/util_seaccess.c:se_access_check(250)
>[2005/11/15 14:12:12, 3] lib/util_seaccess.c:se_access_check(251)
>  se_access_check: user sid is S-1-5-21-3244338754-2153890023-3124348023-3018
>  se_access_check: also S-1-5-21-3244338754-2153890023-3124348023-3025
>  se_access_check: also S-1-1-0
>  se_access_check: also S-1-5-2
>  se_access_check: also S-1-5-11
>  se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-1-0 mask = 101f01ff, current desired = 2
>[2005/11/15 14:12:12, 5] lib/util_seaccess.c:se_access_check(308)
>  se_access_check: access (2) granted.
>[2005/11/15 14:12:12, 3] smbd/vfs.c:vfs_init_default(216)
>  Initialising default vfs hooks
>[2005/11/15 14:12:12, 5] smbd/connection.c:claim_connection(170)
>  claiming share 0
>[2005/11/15 14:12:12, 10] smbd/uid.c:is_share_read_only_for_user(122)
>  is_share_read_only_for_user: share share is read-write for unix user rc20
>[2005/11/15 14:12:12, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(218)
>  get_share_security: using default secdesc for share
>[2005/11/15 14:12:12, 10] lib/util_seaccess.c:se_map_generic(176)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2005/11/15 14:12:12, 10] lib/util_seaccess.c:se_access_check(233)
>  se_access_check: requested access 0x00000002, for NT token with 5 entries and first sid S-1-5-21-3244338754-2153890023-3124348023-3018.
>[2005/11/15 14:12:12, 3] lib/util_seaccess.c:se_access_check(250)
>[2005/11/15 14:12:12, 3] lib/util_seaccess.c:se_access_check(251)
>  se_access_check: user sid is S-1-5-21-3244338754-2153890023-3124348023-3018
>  se_access_check: also S-1-5-21-3244338754-2153890023-3124348023-3025
>  se_access_check: also S-1-1-0
>  se_access_check: also S-1-5-2
>  se_access_check: also S-1-5-11
>  se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-1-0 mask = 101f01ff, current desired = 2
>[2005/11/15 14:12:12, 5] lib/util_seaccess.c:se_access_check(308)
>  se_access_check: access (2) granted.
>[2005/11/15 14:12:12, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(218)
>  get_share_security: using default secdesc for share
>[2005/11/15 14:12:12, 10] lib/util_seaccess.c:se_map_generic(176)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2005/11/15 14:12:12, 10] lib/util_seaccess.c:se_access_check(233)
>  se_access_check: requested access 0x00000002, for NT token with 5 entries and first sid S-1-5-21-3244338754-2153890023-3124348023-3018.
>[2005/11/15 14:12:12, 3] lib/util_seaccess.c:se_access_check(250)
>[2005/11/15 14:12:12, 3] lib/util_seaccess.c:se_access_check(251)
>  se_access_check: user sid is S-1-5-21-3244338754-2153890023-3124348023-3018
>  se_access_check: also S-1-5-21-3244338754-2153890023-3124348023-3025
>  se_access_check: also S-1-1-0
>  se_access_check: also S-1-5-2
>  se_access_check: also S-1-5-11
>  se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-1-0 mask = 101f01ff, current desired = 2
>[2005/11/15 14:12:12, 5] lib/util_seaccess.c:se_access_check(308)
>  se_access_check: access (2) granted.
>[2005/11/15 14:12:12, 10] lib/username.c:user_in_list(583)
>  user_in_list: checking user rc20 in list
>[2005/11/15 14:12:12, 10] lib/username.c:user_in_list(587)
>  user_in_list: checking user |rc20| against |alex|
>[2005/11/15 14:12:12, 10] lib/username.c:user_in_list(587)
>  user_in_list: checking user |rc20| against |vovchik|
>[2005/11/15 14:12:12, 10] lib/username.c:user_in_list(587)
>  user_in_list: checking user |rc20| against |bit1|
>[2005/11/15 14:12:12, 10] lib/username.c:user_in_list(587)
>  user_in_list: checking user |rc20| against |bit2|
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (1009, 1012) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_nt_user_token(468)
>  NT user token of user S-1-5-21-3244338754-2153890023-3124348023-3018
>  contains 5 SIDs
>  SID[  0]: S-1-5-21-3244338754-2153890023-3124348023-3018
>  SID[  1]: S-1-5-21-3244338754-2153890023-3124348023-3025
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 1009
>  Primary group is 1012 and contains 1 supplementary groups
>  Group[  0]: 1012
>[2005/11/15 14:12:12, 5] smbd/uid.c:change_to_user(304)
>  change_to_user uid=(1009,1009) gid=(0,1012)
>[2005/11/15 14:12:12, 1] smbd/service.c:make_connection_snum(666)
>  virtual (192.168.1.178) connect to service share initially as user rc20 (uid=1009, gid=1012) (pid 34295)
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:12, 5] smbd/uid.c:change_to_root_user(319)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2005/11/15 14:12:12, 3] smbd/reply.c:reply_tcon_and_X(708)
>  tconX service=SHARE 
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=54
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=1728
>  smt_wct=3
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    1 (0x1)
>  smb_bcc=13
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 41 3A 00 4E 00 54 00 46  00 53 00 00 00           A:.N.T.F .S...
>[2005/11/15 14:12:12, 10] smbd/process.c:setup_select_timeout(1372)
>  change_notify_timeout: 60
>[2005/11/15 14:12:12, 10] smbd/process.c:run_events(299)
>  run_events: No events
>[2005/11/15 14:12:12, 10] lib/util_sock.c:read_smb_length_return_keepalive(615)
>  got smb length of 76
>[2005/11/15 14:12:12, 6] smbd/process.c:process_smb(1193)
>  got message type 0x0 of len 0x4c
>[2005/11/15 14:12:12, 3] smbd/process.c:process_smb(1194)
>  Transaction 4 of length 80
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=76
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=3120
>  smb_uid=101
>  smb_mid=1792
>  smt_wct=15
>  smb_vwv[ 0]=    8 (0x8)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=   40 (0x28)
>  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]=    8 (0x8)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    5 (0x5)
>  smb_bcc=11
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 00 00 00 EC 03 00 00 00  00 00 00                 ........ ...
>[2005/11/15 14:12:12, 3] smbd/process.c:switch_message(993)
>  switch message SMBtrans2 (pid 34295) conn 0x8345028
>[2005/11/15 14:12:12, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (1009, 1012) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_nt_user_token(468)
>  NT user token of user S-1-5-21-3244338754-2153890023-3124348023-3018
>  contains 5 SIDs
>  SID[  0]: S-1-5-21-3244338754-2153890023-3124348023-3018
>  SID[  1]: S-1-5-21-3244338754-2153890023-3124348023-3025
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2005/11/15 14:12:12, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 1009
>  Primary group is 1012 and contains 1 supplementary groups
>  Group[  0]: 1012
>[2005/11/15 14:12:12, 5] smbd/uid.c:change_to_user(304)
>  change_to_user uid=(1009,1009) gid=(0,1012)
>[2005/11/15 14:12:12, 4] smbd/vfs.c:vfs_ChDir(738)
>  vfs_ChDir to /shared
>[2005/11/15 14:12:12, 3] smbd/trans2.c:call_trans2qfilepathinfo(2859)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
>[2005/11/15 14:12:12, 5] smbd/filename.c:unix_convert(108)
>  unix_convert called on file ""
>[2005/11/15 14:12:12, 5] smbd/filename.c:unix_convert(137)
>  conversion finished "" -> .
>[2005/11/15 14:12:12, 3] smbd/trans2.c:call_trans2qfilepathinfo(2911)
>  call_trans2qfilepathinfo . (fnum = -1) level=1004 call=5 total_data=0
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode(299)
>  dos_mode: .
>[2005/11/15 14:12:12, 1] smbd/dosmode.c:get_ea_dos_attribute(200)
>  get_ea_dos_attributes: Cannot get attribute from EA on file .: Error = Result too large
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode_from_sbuf(167)
>  dos_mode_from_sbuf returning d
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode(333)
>  dos_mode returning d
>[2005/11/15 14:12:12, 10] smbd/trans2.c:call_trans2qfilepathinfo(3106)
>  call_trans2qfilepathinfo: SMB_FILE_BASIC_INFORMATION
>[2005/11/15 14:12:12, 5] smbd/trans2.c:call_trans2qfilepathinfo(3116)
>  SMB_QFBI - create: Wed Oct 26 09:17:04 2005
>   access: Tue Nov 15 14:10:54 2005
>   write: Wed Oct 26 09:17:04 2005
>   change: Wed Oct 26 09:17:04 2005
>   mode: 10
>[2005/11/15 14:12:12, 9] smbd/trans2.c:send_trans2_replies(685)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 40, useable_space = 131010
>[2005/11/15 14:12:12, 9] smbd/trans2.c:send_trans2_replies(687)
>  t2_rep: params_to_send = 2, data_to_send = 40, paramsize = 2, datasize = 40
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=100
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51265
>  smb_tid=1
>  smb_pid=3120
>  smb_uid=101
>  smb_mid=1792
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   40 (0x28)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   40 (0x28)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=45
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 00 00 00 00 00 00 D0 1E  5B D3 D9 C5 01 00 BB 72  ........ [......r
>  [010] 19 BC E9 C5 01 00 D0 1E  5B D3 D9 C5 01 00 D0 1E  ........ [.......
>  [020] 5B D3 D9 C5 01 10 00 00  00 00 00 00 00           [....... .....
>[2005/11/15 14:12:12, 10] smbd/process.c:setup_select_timeout(1372)
>  change_notify_timeout: 60
>[2005/11/15 14:12:12, 10] smbd/process.c:run_events(299)
>  run_events: No events
>[2005/11/15 14:12:12, 10] lib/util_sock.c:read_smb_length_return_keepalive(615)
>  got smb length of 86
>[2005/11/15 14:12:12, 6] smbd/process.c:process_smb(1193)
>  got message type 0x0 of len 0x56
>[2005/11/15 14:12:12, 3] smbd/process.c:process_smb(1194)
>  Transaction 5 of length 90
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=86
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=3120
>  smb_uid=101
>  smb_mid=1856
>  smt_wct=15
>  smb_vwv[ 0]=   18 (0x12)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=   10 (0xA)
>  smb_vwv[ 3]=16384 (0x4000)
>  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]=   18 (0x12)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    1 (0x1)
>  smb_bcc=21
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 00 00 00 16 00 56 05 07  00 04 01 00 00 00 00 5C  .....V.. .......\
>  [010] 00 34 00 00 00                                    .4... 
>[2005/11/15 14:12:12, 3] smbd/process.c:switch_message(993)
>  switch message SMBtrans2 (pid 34295) conn 0x8345028
>[2005/11/15 14:12:12, 4] smbd/uid.c:change_to_user(217)
>  change_to_user: Skipping user change - already user
>[2005/11/15 14:12:12, 3] smbd/trans2.c:call_trans2findfirst(1632)
>  call_trans2findfirst: dirtype = 16, maxentries = 1366, close_after_first=1, close_if_end = 2 requires_resume_key = 4 level = 0x104, max_data_bytes = 16384
>[2005/11/15 14:12:12, 5] smbd/filename.c:unix_convert(108)
>  unix_convert called on file "4"
>[2005/11/15 14:12:12, 10] smbd/statcache.c:stat_cache_lookup(215)
>  stat_cache_lookup: lookup failed for name [4]
>[2005/11/15 14:12:12, 5] smbd/statcache.c:stat_cache_add(140)
>  stat_cache_add: Added entry (833dc30:size2) 4 -> 4
>[2005/11/15 14:12:12, 5] smbd/filename.c:unix_convert(170)
>  conversion finished 4 -> 4
>[2005/11/15 14:12:12, 5] smbd/trans2.c:call_trans2findfirst(1688)
>  dir=./, mask = 4
>[2005/11/15 14:12:12, 5] smbd/dir.c:dptr_create(391)
>  dptr_create dir=./
>[2005/11/15 14:12:12, 3] smbd/dir.c:dptr_create(511)
>  creating new dirptr 256 for path ./, expect_close = 1
>[2005/11/15 14:12:12, 4] smbd/trans2.c:call_trans2findfirst(1745)
>  dptr_num is 256, wcard = 4, attr = 22
>[2005/11/15 14:12:12, 8] smbd/trans2.c:call_trans2findfirst(1750)
>  dirpath=<./> dontdescend=<>
>[2005/11/15 14:12:12, 5] smbd/open.c:open_directory(1815)
>  open_directory: opening directory .//4, access_mask = 0x20000, share_access = 0x3 create_options = 0x0, create_disposition = 0x1
>[2005/11/15 14:12:12, 5] smbd/files.c:file_new(128)
>  allocated file structure 1163, fnum = 5259 (1 used)
>[2005/11/15 14:12:12, 10] locking/locking.c:unparse_share_modes(527)
>  unparse_share_modes: del: 0, num: 1
>[2005/11/15 14:12:12, 10] locking/locking.c:print_share_mode_table(413)
>  print_share_mode_table: share_mode_entry[0]: pid = 34295, share_access = 0x3, private_options = 0x0, access_mask = 0x20000, mid = 0x0, type= 0x0, file_id = 1, dev = 0x416, inode = 423939
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:get_nt_acl(2736)
>  get_nt_acl: called for file .//4
>[2005/11/15 14:12:12, 5] smbd/posix_acls.c:get_nt_acl(2773)
>  get_nt_acl : file ACL absent, directory ACL absent
>[2005/11/15 14:12:12, 3] passdb/lookup_sid.c:fetch_sid_from_uid_cache(166)
>  fetch sid from uid cache 0 -> S-1-5-21-3244338754-2153890023-3124348023-1000
>[2005/11/15 14:12:12, 3] passdb/lookup_sid.c:fetch_sid_from_gid_cache(240)
>  fetch sid from gid cache 0 -> S-1-5-21-3244338754-2153890023-3124348023-1001
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:canonicalise_acl(2213)
>  canonicalise_acl: Access ace entries before arrange :
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:canonicalise_acl(2226)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER perms r-x
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:canonicalise_acl(2226)
>  canon_ace index 1. Type = allow SID = S-1-5-21-3244338754-2153890023-3124348023-1001 gid 0 (wheel) SMB_ACL_GROUP_OBJ perms r-x
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:canonicalise_acl(2226)
>  canon_ace index 2. Type = allow SID = S-1-5-21-3244338754-2153890023-3124348023-1000 uid 0 (root) SMB_ACL_USER_OBJ perms rwx
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:print_canon_ace_list(593)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-3244338754-2153890023-3124348023-1000 uid 0 (root) SMB_ACL_USER_OBJ perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-3244338754-2153890023-3124348023-1001 gid 0 (wheel) SMB_ACL_GROUP_OBJ perms r-x
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER perms r-x
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:map_canon_ace_perms(844)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:map_canon_ace_perms(844)
>  map_canon_ace_perms: Mapped (UNIX) 140 to (NT) 1200a9
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:map_canon_ace_perms(844)
>  map_canon_ace_perms: Mapped (UNIX) 140 to (NT) 1200a9
>[2005/11/15 14:12:12, 10] locking/locking.c:parse_share_modes(438)
>  parse_share_modes: delete_on_close: 0, num_share_modes: 1
>[2005/11/15 14:12:12, 10] locking/locking.c:parse_share_modes(479)
>  parse_share_modes: share_mode_entry[0]: pid = 34295, share_access = 0x3, private_options = 0x0, access_mask = 0x20000, mid = 0x0, type= 0x0, file_id = 1, dev = 0x416, inode = 423939
>[2005/11/15 14:12:12, 5] smbd/files.c:file_free(450)
>  freed files structure 5259 (0 used)
>[2005/11/15 14:12:12, 10] lib/util_seaccess.c:se_access_check(233)
>  se_access_check: requested access 0x00000001, for NT token with 5 entries and first sid S-1-5-21-3244338754-2153890023-3124348023-3018.
>[2005/11/15 14:12:12, 3] lib/util_seaccess.c:se_access_check(250)
>[2005/11/15 14:12:12, 3] lib/util_seaccess.c:se_access_check(251)
>  se_access_check: user sid is S-1-5-21-3244338754-2153890023-3124348023-3018
>  se_access_check: also S-1-5-21-3244338754-2153890023-3124348023-3025
>  se_access_check: also S-1-1-0
>  se_access_check: also S-1-5-2
>  se_access_check: also S-1-5-11
>  se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-5-21-3244338754-2153890023-3124348023-1000 mask = 1f01ff, current desired = 1
>  se_access_check: ACE 1: type 0, flags = 0x00, SID = S-1-5-21-3244338754-2153890023-3124348023-1001 mask = 1200a9, current desired = 1
>  se_access_check: ACE 2: type 0, flags = 0x00, SID = S-1-1-0 mask = 1200a9, current desired = 1
>[2005/11/15 14:12:12, 5] lib/util_seaccess.c:se_access_check(308)
>  se_access_check: access (1) granted.
>[2005/11/15 14:12:12, 8] smbd/trans2.c:get_lanman2_dir_entry(1079)
>  get_lanman2_dir_entry:readdir on dirptr 0x830f140 now at offset -1
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode(299)
>  dos_mode: ./4
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode_from_sbuf(167)
>  dos_mode_from_sbuf returning d
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode(333)
>  dos_mode returning d
>[2005/11/15 14:12:12, 5] smbd/trans2.c:get_lanman2_dir_entry(1169)
>  get_lanman2_dir_entry found ./4 fname=4
>[2005/11/15 14:12:12, 10] smbd/trans2.c:get_lanman2_dir_entry(1310)
>  get_lanman2_dir_entry: SMB_FIND_FILE_BOTH_DIRECTORY_INFO
>[2005/11/15 14:12:12, 5] smbd/trans2.c:call_trans2findfirst(1798)
>  call_trans2findfirst - (2) closing dptr_num 256
>[2005/11/15 14:12:12, 4] smbd/dir.c:dptr_close_internal(238)
>  closing dptr key 256
>[2005/11/15 14:12:12, 9] smbd/trans2.c:send_trans2_replies(685)
>  t2_rep: params_sent_thistime = 10, data_sent_thistime = 96, useable_space = 131010
>[2005/11/15 14:12:12, 9] smbd/trans2.c:send_trans2_replies(687)
>  t2_rep: params_to_send = 10, data_to_send = 96, paramsize = 10, datasize = 96
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=164
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51265
>  smb_tid=1
>  smb_pid=3120
>  smb_uid=101
>  smb_mid=1856
>  smt_wct=10
>  smb_vwv[ 0]=   10 (0xA)
>  smb_vwv[ 1]=   96 (0x60)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=   10 (0xA)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   96 (0x60)
>  smb_vwv[ 7]=   68 (0x44)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=109
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 00 FD FF 01 00 01 00 00  00 00 00 00 00 60 00 00  ........ .....`..
>  [010] 00 00 00 00 00 80 B5 CC  10 B9 E9 C5 01 00 BB 72  ........ .......r
>  [020] 19 BC E9 C5 01 80 B5 CC  10 B9 E9 C5 01 80 B5 CC  ........ ........
>  [030] 10 B9 E9 C5 01 00 00 00  00 00 00 00 00 00 00 00  ........ ........
>  [040] 00 00 00 00 00 10 00 00  00 02 00 00 00 00 00 00  ........ ........
>  [050] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
>  [060] 00 00 00 00 00 00 00 00  00 00 00 34 00           ........ ...4.
>[2005/11/15 14:12:12, 4] smbd/trans2.c:call_trans2findfirst(1834)
>  SMBtrans2 mask=4 directory=./ dirtype=22 numentries=1
>[2005/11/15 14:12:12, 10] smbd/process.c:setup_select_timeout(1372)
>  change_notify_timeout: 60
>[2005/11/15 14:12:12, 10] smbd/process.c:run_events(299)
>  run_events: No events
>[2005/11/15 14:12:12, 10] lib/util_sock.c:read_smb_length_return_keepalive(615)
>  got smb length of 80
>[2005/11/15 14:12:12, 6] smbd/process.c:process_smb(1193)
>  got message type 0x0 of len 0x50
>[2005/11/15 14:12:12, 3] smbd/process.c:process_smb(1194)
>  Transaction 6 of length 84
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=80
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=3120
>  smb_uid=101
>  smb_mid=1920
>  smt_wct=15
>  smb_vwv[ 0]=   12 (0xC)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=   40 (0x28)
>  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]=   12 (0xC)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    5 (0x5)
>  smb_bcc=15
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 00 00 00 EC 03 00 00 00  00 5C 00 34 00 00 00     ........ .\.4...
>[2005/11/15 14:12:12, 3] smbd/process.c:switch_message(993)
>  switch message SMBtrans2 (pid 34295) conn 0x8345028
>[2005/11/15 14:12:12, 4] smbd/uid.c:change_to_user(217)
>  change_to_user: Skipping user change - already user
>[2005/11/15 14:12:12, 3] smbd/trans2.c:call_trans2qfilepathinfo(2859)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
>[2005/11/15 14:12:12, 5] smbd/filename.c:unix_convert(108)
>  unix_convert called on file "4"
>[2005/11/15 14:12:12, 10] smbd/statcache.c:stat_cache_lookup(248)
>  stat_cache_lookup: lookup succeeded for name [4] -> [4]
>[2005/11/15 14:12:12, 3] smbd/trans2.c:call_trans2qfilepathinfo(2911)
>  call_trans2qfilepathinfo 4 (fnum = -1) level=1004 call=5 total_data=0
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode(299)
>  dos_mode: 4
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode_from_sbuf(167)
>  dos_mode_from_sbuf returning d
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode(333)
>  dos_mode returning d
>[2005/11/15 14:12:12, 10] smbd/trans2.c:call_trans2qfilepathinfo(3106)
>  call_trans2qfilepathinfo: SMB_FILE_BASIC_INFORMATION
>[2005/11/15 14:12:12, 5] smbd/trans2.c:call_trans2qfilepathinfo(3116)
>  SMB_QFBI - create: Tue Nov 15 13:49:11 2005
>   access: Tue Nov 15 14:10:54 2005
>   write: Tue Nov 15 13:49:11 2005
>   change: Tue Nov 15 13:49:11 2005
>   mode: 10
>[2005/11/15 14:12:12, 9] smbd/trans2.c:send_trans2_replies(685)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 40, useable_space = 131010
>[2005/11/15 14:12:12, 9] smbd/trans2.c:send_trans2_replies(687)
>  t2_rep: params_to_send = 2, data_to_send = 40, paramsize = 2, datasize = 40
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=100
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51265
>  smb_tid=1
>  smb_pid=3120
>  smb_uid=101
>  smb_mid=1920
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   40 (0x28)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   40 (0x28)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=45
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 00 00 00 00 00 80 B5 CC  10 B9 E9 C5 01 00 BB 72  ........ .......r
>  [010] 19 BC E9 C5 01 80 B5 CC  10 B9 E9 C5 01 80 B5 CC  ........ ........
>  [020] 10 B9 E9 C5 01 10 00 00  00 00 00 00 00           ........ .....
>[2005/11/15 14:12:12, 10] smbd/process.c:setup_select_timeout(1372)
>  change_notify_timeout: 60
>[2005/11/15 14:12:12, 10] smbd/process.c:run_events(299)
>  run_events: No events
>[2005/11/15 14:12:12, 10] lib/util_sock.c:read_smb_length_return_keepalive(615)
>  got smb length of 98
>[2005/11/15 14:12:12, 6] smbd/process.c:process_smb(1193)
>  got message type 0x0 of len 0x62
>[2005/11/15 14:12:12, 3] smbd/process.c:process_smb(1194)
>  Transaction 7 of length 102
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=98
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=3120
>  smb_uid=101
>  smb_mid=1984
>  smt_wct=15
>  smb_vwv[ 0]=   30 (0x1E)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=   10 (0xA)
>  smb_vwv[ 3]=16384 (0x4000)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=    0 (0x0)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=   30 (0x1E)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    1 (0x1)
>  smb_bcc=33
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 00 00 00 16 00 56 05 07  00 04 01 00 00 00 00 5C  .....V.. .......\
>  [010] 00 34 00 5C 00 31 00 2E  00 74 00 78 00 74 00 00  .4.\.1.. .t.x.t..
>  [020] 00                                                . 
>[2005/11/15 14:12:12, 3] smbd/process.c:switch_message(993)
>  switch message SMBtrans2 (pid 34295) conn 0x8345028
>[2005/11/15 14:12:12, 4] smbd/uid.c:change_to_user(217)
>  change_to_user: Skipping user change - already user
>[2005/11/15 14:12:12, 3] smbd/trans2.c:call_trans2findfirst(1632)
>  call_trans2findfirst: dirtype = 16, maxentries = 1366, close_after_first=1, close_if_end = 2 requires_resume_key = 4 level = 0x104, max_data_bytes = 16384
>[2005/11/15 14:12:12, 5] smbd/filename.c:unix_convert(108)
>  unix_convert called on file "4/1.txt"
>[2005/11/15 14:12:12, 10] smbd/statcache.c:stat_cache_lookup(215)
>  stat_cache_lookup: lookup failed for name [4/1.TXT]
>[2005/11/15 14:12:12, 10] smbd/statcache.c:stat_cache_lookup(248)
>  stat_cache_lookup: lookup succeeded for name [4] -> [4]
>[2005/11/15 14:12:12, 5] smbd/statcache.c:stat_cache_add(140)
>  stat_cache_add: Added entry (833dc30:size8) 4/1.TXT -> 4/1.txt
>[2005/11/15 14:12:12, 5] smbd/filename.c:unix_convert(170)
>  conversion finished 4/1.txt -> 4/1.txt
>[2005/11/15 14:12:12, 5] smbd/trans2.c:call_trans2findfirst(1688)
>  dir=4, mask = 1.txt
>[2005/11/15 14:12:12, 5] smbd/dir.c:dptr_create(391)
>  dptr_create dir=4
>[2005/11/15 14:12:12, 3] smbd/dir.c:dptr_create(511)
>  creating new dirptr 256 for path 4, expect_close = 1
>[2005/11/15 14:12:12, 4] smbd/trans2.c:call_trans2findfirst(1745)
>  dptr_num is 256, wcard = 1.txt, attr = 22
>[2005/11/15 14:12:12, 8] smbd/trans2.c:call_trans2findfirst(1750)
>  dirpath=<4> dontdescend=<>
>[2005/11/15 14:12:12, 5] smbd/files.c:file_new(128)
>  allocated file structure 1164, fnum = 5260 (1 used)
>[2005/11/15 14:12:12, 5] smbd/open.c:open_file_stat(2014)
>  open_file_stat: 'opening' file 4/1.txt
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:get_nt_acl(2736)
>  get_nt_acl: called for file 4/1.txt
>[2005/11/15 14:12:12, 5] smbd/posix_acls.c:get_nt_acl(2773)
>  get_nt_acl : file ACL absent, directory ACL absent
>[2005/11/15 14:12:12, 3] passdb/lookup_sid.c:fetch_sid_from_uid_cache(166)
>  fetch sid from uid cache 0 -> S-1-5-21-3244338754-2153890023-3124348023-1000
>[2005/11/15 14:12:12, 3] passdb/lookup_sid.c:fetch_sid_from_gid_cache(240)
>  fetch sid from gid cache 0 -> S-1-5-21-3244338754-2153890023-3124348023-1001
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:canonicalise_acl(2213)
>  canonicalise_acl: Access ace entries before arrange :
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:canonicalise_acl(2226)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER perms r--
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:canonicalise_acl(2226)
>  canon_ace index 1. Type = allow SID = S-1-5-21-3244338754-2153890023-3124348023-1001 gid 0 (wheel) SMB_ACL_GROUP_OBJ perms r--
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:canonicalise_acl(2226)
>  canon_ace index 2. Type = allow SID = S-1-5-21-3244338754-2153890023-3124348023-1000 uid 0 (root) SMB_ACL_USER_OBJ perms rwx
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:print_canon_ace_list(593)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-3244338754-2153890023-3124348023-1000 uid 0 (root) SMB_ACL_USER_OBJ perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-3244338754-2153890023-3124348023-1001 gid 0 (wheel) SMB_ACL_GROUP_OBJ perms r--
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER perms r--
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:map_canon_ace_perms(844)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:map_canon_ace_perms(844)
>  map_canon_ace_perms: Mapped (UNIX) 100 to (NT) 120089
>[2005/11/15 14:12:12, 10] smbd/posix_acls.c:map_canon_ace_perms(844)
>  map_canon_ace_perms: Mapped (UNIX) 100 to (NT) 120089
>[2005/11/15 14:12:12, 5] smbd/files.c:file_free(450)
>  freed files structure 5260 (0 used)
>[2005/11/15 14:12:12, 10] lib/util_seaccess.c:se_access_check(233)
>  se_access_check: requested access 0x00000001, for NT token with 5 entries and first sid S-1-5-21-3244338754-2153890023-3124348023-3018.
>[2005/11/15 14:12:12, 3] lib/util_seaccess.c:se_access_check(250)
>[2005/11/15 14:12:12, 3] lib/util_seaccess.c:se_access_check(251)
>  se_access_check: user sid is S-1-5-21-3244338754-2153890023-3124348023-3018
>  se_access_check: also S-1-5-21-3244338754-2153890023-3124348023-3025
>  se_access_check: also S-1-1-0
>  se_access_check: also S-1-5-2
>  se_access_check: also S-1-5-11
>  se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-5-21-3244338754-2153890023-3124348023-1000 mask = 1f01ff, current desired = 1
>  se_access_check: ACE 1: type 0, flags = 0x00, SID = S-1-5-21-3244338754-2153890023-3124348023-1001 mask = 120089, current desired = 1
>  se_access_check: ACE 2: type 0, flags = 0x00, SID = S-1-1-0 mask = 120089, current desired = 1
>[2005/11/15 14:12:12, 5] lib/util_seaccess.c:se_access_check(308)
>  se_access_check: access (1) granted.
>[2005/11/15 14:12:12, 8] smbd/trans2.c:get_lanman2_dir_entry(1079)
>  get_lanman2_dir_entry:readdir on dirptr 0x830f140 now at offset -1
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode(299)
>  dos_mode: 4/1.txt
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode_from_sbuf(167)
>  dos_mode_from_sbuf returning a
>[2005/11/15 14:12:12, 8] smbd/dosmode.c:dos_mode(333)
>  dos_mode returning a
>[2005/11/15 14:12:12, 5] smbd/trans2.c:get_lanman2_dir_entry(1169)
>  get_lanman2_dir_entry found 4/1.txt fname=1.txt
>[2005/11/15 14:12:12, 10] smbd/trans2.c:get_lanman2_dir_entry(1310)
>  get_lanman2_dir_entry: SMB_FIND_FILE_BOTH_DIRECTORY_INFO
>[2005/11/15 14:12:12, 5] smbd/trans2.c:call_trans2findfirst(1798)
>  call_trans2findfirst - (2) closing dptr_num 256
>[2005/11/15 14:12:12, 4] smbd/dir.c:dptr_close_internal(238)
>  closing dptr key 256
>[2005/11/15 14:12:12, 9] smbd/trans2.c:send_trans2_replies(685)
>  t2_rep: params_sent_thistime = 10, data_sent_thistime = 104, useable_space = 131010
>[2005/11/15 14:12:12, 9] smbd/trans2.c:send_trans2_replies(687)
>  t2_rep: params_to_send = 10, data_to_send = 104, paramsize = 10, datasize = 104
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(454)
>[2005/11/15 14:12:12, 5] lib/util.c:show_msg(464)
>  size=172
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51265
>  smb_tid=1
>  smb_pid=3120
>  smb_uid=101
>  smb_mid=1984
>  smt_wct=10
>  smb_vwv[ 0]=   10 (0xA)
>  smb_vwv[ 1]=  104 (0x68)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=   10 (0xA)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=  104 (0x68)
>  smb_vwv[ 7]=   68 (0x44)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=117
>[2005/11/15 14:12:12, 10] lib/util.c:dump_data(2058)
>  [000] 00 FD FF 01 00 01 00 00  00 00 00 00 00 68 00 00  ........ .....h..
>  [010] 00 00 00 00 00 00 A0 A9  9E BB E9 C5 01 00 BB 72  ........ .......r
>  [020] 19 BC E9 C5 01 00 A0 A9  9E BB E9 C5 01 00 A0 A9  ........ ........
>  [030] 9E BB E9 C5 01 00 00 00  00 00 00 00 00 00 00 10  ........ ........
>  [040] 00 00 00 00 00 20 00 00  00 0A 00 00 00 00 00 00  ..... .. ........
>  [050] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
>  [060] 00 00 00 00 00 00 00 00  00 00 00 31 00 2E 00 74  ........ ...1...t
>  [070] 00 78 00 74 00                                    .x.t. 
>[2005/11/15 14:12:12, 4] smbd/trans2.c:call_trans2findfirst(1834)
>  SMBtrans2 mask=1.txt directory=4 dirtype=22 numentries=1
>[2005/11/15 14:12:12, 10] smbd/process.c:setup_select_timeout(1372)
>  change_notify_timeout: 60
>[2005/11/15 14:12:12, 10] smbd/process.c:run_events(299)
>  run_events: No events
>[2005/11/15 14:12:21, 10] smbd/process.c:async_processing(403)
>  async_processing: Doing async processing.
>[2005/11/15 14:12:21, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:21, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:21, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:21, 5] smbd/uid.c:change_to_root_user(319)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2005/11/15 14:12:21, 2] smbd/server.c:exit_server(614)
>  Closing connections
>[2005/11/15 14:12:21, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:21, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:21, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:21, 5] smbd/uid.c:change_to_root_user(319)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2005/11/15 14:12:21, 1] smbd/service.c:close_cnum(839)
>  virtual (192.168.1.178) closed connection to service share
>[2005/11/15 14:12:21, 3] smbd/connection.c:yield_connection(69)
>  Yielding connection to share
>[2005/11/15 14:12:21, 4] smbd/vfs.c:vfs_ChDir(738)
>  vfs_ChDir to /
>[2005/11/15 14:12:21, 3] smbd/sec_ctx.c:set_sec_ctx(288)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2005/11/15 14:12:21, 5] auth/auth_util.c:debug_nt_user_token(463)
>  NT user token: (NULL)
>[2005/11/15 14:12:21, 5] auth/auth_util.c:debug_unix_user_token(484)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2005/11/15 14:12:21, 5] smbd/uid.c:change_to_root_user(319)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2005/11/15 14:12:21, 5] auth/auth_util.c:free_server_info(1491)
>  attempting to free (and zero) a server_info structure
>[2005/11/15 14:12:21, 3] smbd/connection.c:yield_connection(69)
>  Yielding connection to 
>[2005/11/15 14:12:21, 3] smbd/server.c:exit_server(655)
>  Server exit (Caught TERM signal)

Actions: View

Attachments on bug 3262: 1571 | 1574 | 1586 | 1587