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