samba - Sep 2012 - smbd starts but terminates with - failed to receive smb request

Hello,

I start smbd interactively from the command line and then attempt to issue an
smbtree command from another terminal and smbd immediately terminates.

Here is the output. It looks like the initial authentication is working, but the
termination happens after "string_to_sid: SID root is not in a valid
format"

Any idea what might be going on here?

Thanks

[root]# /usr/local/samba/sbin/smbd -i -d 3
Maximum core file size limits now 16777216(soft) -1(hard)
smbd version 3.6.7 started.
Copyright Andrew Tridgell and the Samba Team 1992-2011
uid=0 gid=0 euid=0 egid=0
lp_load_ex: refreshing parameters
Initialising global parameters
rlimit_max: increasing rlimit_max (1024) to minimum Windows limit (16384)
params.c:pm_process() - Processing configuration file
"/etc/samba/smb.conf"
Processing section "[global]"
Registered MSG_REQ_POOL_USAGE
Registered MSG_REQ_DMALLOC_MARK and LOG_CHANGED
lp_load_ex: refreshing parameters
Initialising global parameters
rlimit_max: increasing rlimit_max (1024) to minimum Windows limit (16384)
params.c:pm_process() - Processing configuration file
"/etc/samba/smb.conf"
Processing section "[global]"
Processing section "[homes]"
Processing section "[data]"
loaded services
smbldap_search_domain_info: Searching
for:[(&(objectClass=sambaDomain)(sambaDomainName=BLAH))]
StartTLS issued: using a TLS connection
smbldap_open_connection: connection opened
ldap_connect_system: successful connection to the LDAP server
Forcing Primary Group to 'Domain Users' for root
init_group_from_ldap: Entry found for group: 544
Forcing Primary Group to 'Domain Users' for nobody
init_group_from_ldap: Entry found for group: 513
Initialise the svcctl registry keys if needed.
Closed policy
Closed policy
Closed policy
Closed policy
Closed policy
Closed policy
Closed policy
Closed policy
Closed policy
Initialise the eventlog registry keys if needed.
Closed policy
reloading printcap cache
reload status: ok
waiting for connections
Printcap cache time expired.
reloading printcap cache
reload status: ok
Allowed connection from xxx.xxx.xxx.xxx (xxx.xxx.xxx.xxx)
init_oplocks: initializing messages.
Linux kernel oplocks enabled
Transaction 0 of length 72 (0 toread)
Transaction 0 of length 194 (0 toread)
switch message SMBnegprot (pid 7116) conn 0x0
Requested protocol [PC NETWORK PROGRAM 1.0]
Requested protocol [MICROSOFT NETWORKS 1.03]
Requested protocol [MICROSOFT NETWORKS 3.0]
Requested protocol [LANMAN1.0]
Requested protocol [LM1.2X002]
Requested protocol [DOS LANMAN2.1]
Requested protocol [LANMAN2.1]
Requested protocol [Samba]
Requested protocol [NT LANMAN 1.0]
Requested protocol [NT LM 0.12]
using SPNEGO
Selected protocol NT LANMAN 1.0
Transaction 1 of length 164 (0 toread)
switch message SMBsesssetupX (pid 7116) conn 0x0
wct=12 flg2=0xc801
Doing spnego session setup
NativeOS=[Unix] NativeLanMan=[Samba] PrimaryDomain=[]
reply_spnego_negotiate: Got secblob of size 44
Got NTLMSSP neg_flags=0x60088215
Transaction 2 of length 264 (0 toread)
switch message SMBsesssetupX (pid 7116) conn 0x0
wct=12 flg2=0xc801
Doing spnego session setup
NativeOS=[Unix] NativeLanMan=[Samba] PrimaryDomain=[]
Got user=[classen] domain=[BLAH] workstation=[BLAHBLAH] len1=24 len2=24
check_ntlm_password:  Checking password for unmapped user
[BLAH]\[classen]@[BLAHBLAH] with the new password interface
check_ntlm_password:  mapped user is: [BLAH]\[classen]@[BLAHBLAH]
StartTLS issued: using a TLS connection
smbldap_open_connection: connection opened
ldap_connect_system: successful connection to the LDAP server
init_sam_from_ldap: Entry found for user: classen
Forcing Primary Group to 'Domain Users' for classen
check_ntlm_password: sam authentication for user [classen] succeeded
check_ntlm_password:  authentication for user [classen] -> [classen] ->
[classen] succeeded
NTLMSSP Sign/Seal - Initialising with flags:
Got NTLMSSP neg_flags=0x60088215
register_existing_vuid: User name: classen	Real name: The Classen
register_existing_vuid: UNIX uid 5555 is UNIX user classen, and will be vuid 100
Adding homes service for user 'classen' using home directory:
'/home/classen'
adding home's share [classen] for user 'classen' at
'/home/classen'
Transaction 3 of length 90 (0 toread)
switch message SMBtconX (pid 7116) conn 0x0
Allowed connection from 131.243.78.105 (131.243.78.105)
string_to_sid: SID root is not in a valid format
Connect path is '/tmp' for service [IPC$]
Initialising default vfs hooks
Initialising custom vfs hooks from [/[Default VFS]/]
string_to_sid: SID root is not in a valid format
myserver (xxx.xxx.xxx.xxx) connect to service IPC$ initially as user classen
(uid=5555, gid=1234) (pid 7116)
tconX service=IPC$ 
myserver (131.243.78.105) closed connection to service IPC$
Yielding connection to IPC$
Server exit (failed to receive smb request)
Terminated

On Mon, Sep 10, 2012 at 11:27:32AM -0700, Scott Classen
wrote:
> Hello,
> 
> I start smbd interactively from the command line and then attempt to issue
an smbtree command from another terminal and smbd immediately terminates.
> 
> Here is the output. It looks like the initial authentication is working,
but the termination happens after "string_to_sid: SID root is not in a
valid format"
> 
> Any idea what might be going on here?
That's expected. smbtree expects to be able to open multiple
connections to the IPC$ share. When you're running smbd -i
you only get one smbd and when get_workgroups() has completed
in smbtree the connection is abandoned and will time out (it
really should properly close it, but that's a patch for
another day).

Jeremy.