[Samba] The user name could not be found when joining a samba domain

Thu Apr 29 19:34:47 MDT 2010

Using LDAP and the smbldap-tools.  When attempting to join the domain  
with an administrative user, the computer gets added to the Computers  
list in LDAP with the following attributes:

  cn:  workstation75$
  description:  Computer
  gecos:  Computer
  gidNumber:  515
  homeDirectory:  /dev/null
  loginShell:  /bin/false
  objectClass:  top, account, posixAccount
  uid:  workstation75$
  uidNumber:  1068

Then the workstation displays this message:

"the following error occurred attempting to join the domain "falm"

the user name could not be found"

Then the workstation log outputs this at log level 3

[2010/04/29 19:52:33.724539,  3] smbd/process.c:1485(process_smb)
   Transaction 0 of length 137 (0 toread)
[2010/04/29 19:52:33.724570,  3] smbd/process.c:1294(switch_message)
   switch message SMBnegprot (pid 1986) conn 0x0
[2010/04/29 19:52:33.724593,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/04/29 19:52:33.724661,  3] smbd/negprot.c:586(reply_negprot)
   Requested protocol [PC NETWORK PROGRAM 1.0]
[2010/04/29 19:52:33.724679,  3] smbd/negprot.c:586(reply_negprot)
   Requested protocol [LANMAN1.0]
[2010/04/29 19:52:33.724692,  3] smbd/negprot.c:586(reply_negprot)
   Requested protocol [Windows for Workgroups 3.1a]
[2010/04/29 19:52:33.724706,  3] smbd/negprot.c:586(reply_negprot)
   Requested protocol [LM1.2X002]
[2010/04/29 19:52:33.724724,  3] smbd/negprot.c:586(reply_negprot)
   Requested protocol [LANMAN2.1]
[2010/04/29 19:52:33.724742,  3] smbd/negprot.c:586(reply_negprot)
   Requested protocol [NT LM 0.12]
[2010/04/29 19:52:33.724846,  3] smbd/negprot.c:404(reply_nt1)
   using SPNEGO
[2010/04/29 19:52:33.724862,  3] smbd/negprot.c:691(reply_negprot)
   Selected protocol NT LM 0.12
[2010/04/29 19:52:33.736749,  3] smbd/process.c:1485(process_smb)
   Transaction 1 of length 240 (0 toread)
[2010/04/29 19:52:33.736799,  3] smbd/process.c:1294(switch_message)
   switch message SMBsesssetupX (pid 1986) conn 0x0
[2010/04/29 19:52:33.736880,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/04/29 19:52:33.736930,  3] smbd/sesssetup.c:1435 
(reply_sesssetup_and_X)
   wct=12 flg2=0xc807
[2010/04/29 19:52:33.736952,  2] smbd/sesssetup.c:1390 
(setup_new_vc_session)
   setup_new_vc_session: New VC == 0, if NT4.x compatible we would  
close all old resources.
[2010/04/29 19:52:33.737021,  3] smbd/sesssetup.c:1189 
(reply_sesssetup_and_X_spnego)
   Doing spnego session setup
[2010/04/29 19:52:33.737086,  3] smbd/sesssetup.c:1231 
(reply_sesssetup_and_X_spnego)
   NativeOS=[Windows 2002 Service Pack 3 2600] NativeLanMan=[Windows  
2002 5.1] PrimaryDomain=[]
[2010/04/29 19:52:33.737157,  3] smbd/sesssetup.c:805 
(reply_spnego_negotiate)
   reply_spnego_negotiate: Got secblob of size 40
[2010/04/29 19:52:33.737254,  3] libsmb/ntlmssp.c:65 
(debug_ntlmssp_flags)
   Got NTLMSSP neg_flags=0xa2088207
[2010/04/29 19:52:33.738057,  3] smbd/process.c:1485(process_smb)
   Transaction 2 of length 358 (0 toread)
[2010/04/29 19:52:33.738121,  3] smbd/process.c:1294(switch_message)
   switch message SMBsesssetupX (pid 1986) conn 0x0
[2010/04/29 19:52:33.738185,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/04/29 19:52:33.738244,  3] smbd/sesssetup.c:1435 
(reply_sesssetup_and_X)
   wct=12 flg2=0xc807
[2010/04/29 19:52:33.738285,  2] smbd/sesssetup.c:1390 
(setup_new_vc_session)
   setup_new_vc_session: New VC == 0, if NT4.x compatible we would  
close all old resources.
[2010/04/29 19:52:33.738337,  3] smbd/sesssetup.c:1189 
(reply_sesssetup_and_X_spnego)
   Doing spnego session setup
[2010/04/29 19:52:33.738396,  3] smbd/sesssetup.c:1231 
(reply_sesssetup_and_X_spnego)
   NativeOS=[Windows 2002 Service Pack 3 2600] NativeLanMan=[Windows  
2002 5.1] PrimaryDomain=[]
[2010/04/29 19:52:33.738471,  3] libsmb/ntlmssp.c:747 
(ntlmssp_server_auth)
   Got user=[administrator] domain=[falm] workstation=[WORKSTATION75]  
len1=24 len2=24
[2010/04/29 19:52:33.738557,  3] auth/auth.c:216(check_ntlm_password)
   check_ntlm_password:  Checking password for unmapped user [falm]\ 
[administrator]@[WORKSTATION75] with the new password interface
[2010/04/29 19:52:33.738622,  3] auth/auth.c:219(check_ntlm_password)
   check_ntlm_password:  mapped user is: [falm]\[administrator]@ 
[WORKSTATION75]
[2010/04/29 19:52:33.738687,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.738728,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/04/29 19:52:33.738771,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.738960,  2] lib/smbldap.c:950 
(smbldap_open_connection)
   smbldap_open_connection: connection opened
[2010/04/29 19:52:33.739601,  3] lib/smbldap.c:1166 
(smbldap_connect_system)
   ldap_connect_system: successful connection to the LDAP server
[2010/04/29 19:52:33.740038,  2] passdb/pdb_ldap.c:572 
(init_sam_from_ldap)
   init_sam_from_ldap: Entry found for user: administrator
[2010/04/29 19:52:33.740168,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.740211,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/04/29 19:52:33.740252,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.740344,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.740469,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.740508,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/04/29 19:52:33.740548,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.740809,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.741718,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.741765,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/04/29 19:52:33.741803,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.742113,  2] passdb/pdb_ldap.c:2446 
(init_group_from_ldap)
   init_group_from_ldap: Entry found for group: 500
[2010/04/29 19:52:33.742196,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.742255,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.742299,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/04/29 19:52:33.742347,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.742393,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
[2010/04/29 19:52:33.742434,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 2
[2010/04/29 19:52:33.742480,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
[2010/04/29 19:52:33.743163,  2] passdb/pdb_ldap.c:2446 
(init_group_from_ldap)
   init_group_from_ldap: Entry found for group: 500
[2010/04/29 19:52:33.743221,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.743269,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.743309,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.743347,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/04/29 19:52:33.743395,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.743444,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.743512,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.743550,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/04/29 19:52:33.743590,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.743649,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.743708,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/04/29 19:52:33.743830,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.743868,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/04/29 19:52:33.743907,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.743968,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/04/29 19:52:33.744004,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.744044,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/04/29 19:52:33.744079,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/04/29 19:52:33.746497,  3] smbd/sec_ctx.c:210(push_sec_ctx)
   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.746517,  3] smbd/uid.c:429(push_conn_ctx)
   push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/04/29 19:52:33.746538,  3] smbd/sec_ctx.c:310(set_sec_ctx)
   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/04/29 19:52:33.747055,  2] passdb/pdb_ldap.c:2446 
(init_group_from_ldap)
   init_group_from_ldap: Entry found for group: 512

The problem is the log doesn't give me any information on what's going  
on and this happened out of nowhere.  Any help would really be  
appreciated.