[Samba] Trouble with ldapsam on OpenBSD

John Danks john.danks at gmail.com
Tue Mar 8 18:13:41 MST 2011


I'm trying to setup a basic Samba 3 domain with LDAP on OpenBSD. When
using the ldapsam passdb, the smbd processes terminate. I've poured
over the log files and tried everything I can think of to troubleshoot
it. The odd thing I've noticed is that if I start smbd and don't cause
any network connections to it for about 20 minutes, then it works
perfectly. I'm really hoping that I just missed something stupid and
someone here can point it out to me.

I have an isolated network of one OpenBSD router, one OpenBSD server
running Samba and OpenLDAP, and one laptop running XP. I set up ypldap
on OpenBSD to connect YP to LDAP (OpenBSD doesn't have PAM or NSS). I
used smbldap-populate to populate the directory.

These are the software versions I'm using:

Windows XP SP3
OpenBSD 4.8 (i386)
OpenLDAP 2.3.43
Samba 3.5.4 (samba-3.5.4p3-cups-ldap)
smbldap-tools 0.9.6
(I saw the same problem in limited testing on OpenBSD 4.9 with Samba 3.5.6)

This is the process I'm following to produce the error:

* Start smbd and nmbd
* On the XP laptop, browse to the domain in Windows Explorer (The
laptop is not joined to the domain, and the logged in user name is
mapped to root with a password that matches the root user stored in
LDAP)
* The first attempt works, but I see in the log "Server exit (failed
to receive smb request)"
* When I press F5 to refresh the view, I get the error message "The
specified network name is no longer available."
* In the log for smbd, I see the following lines:

[2011/03/08 16:25:31,  3, pid=26585]
/usr/ports/pobj/samba-3.5.4-cups-ldap/samba-3.5.4/source3/smbd/server.c:259
Unclean shutdown of pid 16692
[2011/03/08 16:25:31,  1, pid=26585]   Scheduled cleanup of brl and
lock database after unclean shutdown
[2011/03/08 16:25:34,  3, pid=26585]
/usr/ports/pobj/samba-3.5.4-cups-ldap/samba-3.5.4/source3/smbd/server.c:259
Unclean shutdown of pid 11079
[2011/03/08 16:25:51,  1, pid=26585]   Cleaning up brl and lock
database after unclean shutdown
[2011/03/08 16:25:51,  2, pid=26585]   message to process 11079 failed
- No such process
[2011/03/08 16:25:51,  2, pid=26585]   pid 11079 doesn't exist -
deleting messages record
[2011/03/08 16:25:51,  2, pid=26585]   pid 11079 doesn't exist -
deleting connections -1 []
[2011/03/08 16:25:51,  2, pid=26585]   message to process 4966 failed
- No such process
[2011/03/08 16:25:51,  2, pid=26585]   pid 4966 doesn't exist -
deleting messages record
[2011/03/08 16:25:51,  2, pid=26585]   pid 4966 doesn't exist -
deleting connections -1 []
[2011/03/08 16:25:51,  2, pid=26585]   message to process 16692 failed
- No such process
[2011/03/08 16:25:51,  2, pid=26585]   pid 16692 doesn't exist -
deleting messages record
[2011/03/08 16:25:51,  2, pid=26585]   pid 16692 doesn't exist -
deleting connections -1 []
[2011/03/08 16:25:51,  2, pid=26585]   message to process 14006 failed
- No such process
[2011/03/08 16:25:51,  2, pid=26585]   pid 14006 doesn't exist -
deleting messages record
[2011/03/08 16:25:51,  2, pid=26585]   pid 14006 doesn't exist -
deleting connections -1 []
[2011/03/08 16:27:20,  3, pid=4145]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:27:20,  3, pid=4145]   Yielding connection to
[2011/03/08 16:27:20,  3, pid=4145]   deleting connection record
returned NT_STATUS_NOT_FOUND
[2011/03/08 16:27:20,  3, pid=4145]   Server exit (normal exit)

Two smbd processes still appear in the process list on the server,
until that last line when they exit.

I'm including my smb.conf, slapd.conf, and samba log files. Please let
me know if you need different log levels, log options, or a wireshark
capture.

--- smb.conf ---

[global]
        workgroup = PCMHC
        server string = PDC
        security = user

        log file = /var/log/samba/smbd.%m
        max log size = 5000
        debug class = yes
        debug pid = yes
        debug prefix timestamp = yes
        debug hires timestamp = no
        log level = 3 passdb:100 auth:100 tdb:3 sam:3 vfs:1
        ldap debug level = 100
        ldap debug threshold = 1

        passdb backend = ldapsam:ldap://ldap.pcmhc.org
        guest account = nobody
        username map = /etc/samba/smbusers

        domain logons = yes
        domain master = yes
        preferred master = yes

        ldap ssl = start tls

        ldap suffix = dc=pcmhc,dc=org
        ldap machine suffix = ou=Computers
        ldap user suffix = ou=Users
        ldap group suffix = ou=Group
        ldap idmap suffix = ou=Idmap
        ldap admin dn = cn=Manager,dc=pcmhc,dc=org

--- slapd.conf ---

include /etc/openldap/schema/core.schema
include /etc/openldap/schema/cosine.schema
include /etc/openldap/schema/inetorgperson.schema
include /etc/openldap/schema/nis.schema
include /etc/openldap/schema/samba.schema

pidfile         /var/run/openldap/slapd.pid
argsfile        /var/run/openldap/slapd.args

database        ldbm
suffix          "dc=pcmhc,dc=org"
directory       /var/openldap-data
rootdn          "cn=Manager,dc=pcmhc,dc=org"
rootpw          {SSHA}...

TLSCipherSuite  HIGH:MEDIUM:+SSLv3
TLSCACertificateFile    /etc/ssl/ca.crt
TLSCertificateFile      /etc/openldap/ssl/server.crt
TLSCertificateKeyFile   /etc/openldap/ssl/private/server.key

index   objectClass             eq
index   cn                      pres,sub,eq
index   sn                      pres,sub,eq
index   uid                     pres,sub,eq
index   displayName             pres,sub,eq
index   uidNumber               eq
index   gidNumber               eq
index   memberUid               eq
index   sambaSID                eq
index   sambaPrimaryGroupSID    eq
index   sambaDomainName         eq
index   sambaGroupType          eq
index   sambasidlist            eq
index   default                 eq

access to attrs=userpassword,sambaLMPassword,sambaNTPassword
        by anonymous auth
        by self write
        by * none
access to *
        by self write
        by * read


I use log file = smbd.%m, so there are separate files for the main
smbd process and each workstation.

--- Log file for main smbd ---

[2011/03/08 16:37:58,  3, pid=27928]   lp_load_ex: refreshing parameters
Initialising global parameters
rlimit_max: rlimit_max (128) below minimum Windows limit (16384)
[2011/03/08 16:37:58.041114,  3]
/usr/ports/pobj/samba-3.5.4-cups-ldap/samba-3.5.4/source3/../lib/util/params.c:550(pm_process)
  params.c:pm_process() - Processing configuration file "/etc/samba/smb.conf"
[2011/03/08 16:37:58.041315,  3]
/usr/ports/pobj/samba-3.5.4-cups-ldap/samba-3.5.4/source3/param/loadparm.c:7842(do_section)
  Processing section "[global]"
[2011/03/08 16:37:58,  3, pid=27928]   adding IPC service
[2011/03/08 16:37:58,  3, pid=27928]   reloading printcap cache
[2011/03/08 16:37:58,  0, pid=14666]   Unable to connect to CUPS
server localhost:631 - Connection refused
[2011/03/08 16:37:58,  2, pid=27928]   cups_async_callback: failed to
read a new printer list
[2011/03/08 16:37:58,  3, pid=27928]   reload status: error
[2011/03/08 16:37:58,  3, pid=27928]   reloading printcap cache
[2011/03/08 16:37:58,  0, pid=23205]   Unable to connect to CUPS
server localhost:631 - Connection refused
[2011/03/08 16:37:58,  2, pid=27928]   cups_async_callback: failed to
read a new printer list
[2011/03/08 16:37:58,  3, pid=27928]   reload status: error
[2011/03/08 16:37:58,  2, pid=27928]   added interface fxp0
ip=fe80:1::203:47ff:fea3:c8b3 bcast=fe80:1::ffff:ffff:ffff:ffff
netmask=ffff:ffff:ffff:ffff::
[2011/03/08 16:37:58,  2, pid=27928]   added interface fxp0
ip=192.168.123.2 bcast=192.168.123.255 netmask=
[2011/03/08 16:37:58,  1, pid=27928]   file_init: Information only:
requested 16384 open files, 7010 are available.
[2011/03/08 16:37:58,  3, pid=27928]   loaded services
[2011/03/08 16:37:58,  0, pid=27928]   standard input is not a socket,
assuming -D option
[2011/03/08 16:37:58,  3, pid=27928]   Becoming a daemon.
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Attempting to
register passdb backend ldapsam
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Successfully
added passdb backend 'ldapsam'
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Attempting to
register passdb backend ldapsam_compat
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Successfully
added passdb backend 'ldapsam_compat'
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Attempting to
register passdb backend NDS_ldapsam
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Successfully
added passdb backend 'NDS_ldapsam'
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Attempting to
register passdb backend NDS_ldapsam_compat
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Successfully
added passdb backend 'NDS_ldapsam_compat'
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Attempting to
register passdb backend smbpasswd
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Successfully
added passdb backend 'smbpasswd'
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Attempting to
register passdb backend tdbsam
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Successfully
added passdb backend 'tdbsam'
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Attempting to
register passdb backend wbc_sam
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Successfully
added passdb backend 'wbc_sam'
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Attempting to
find a passdb backend to match ldapsam:ldap://ldap.pcmhc.org (ldapsam)
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Found pdb backend ldapsam
[2011/03/08 16:37:58,  2, pid=16184]   smbldap_search_domain_info:
Searching for:[(&(objectClass=sambaDomain)(sambaDomainName=PCMHC))]
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 1
[2011/03/08 16:37:58,  3, pid=16184]   StartTLS issued: using a TLS connection
[2011/03/08 16:37:58,  2, pid=16184]   smbldap_open_connection:
connection opened
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 2
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     supportedControl
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 3
[2011/03/08 16:37:58,  3, pid=16184]   ldap_connect_system: successful
connection to the LDAP server
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaDomainName
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaNextRid
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaNextUserRid
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaNextGroupRid
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaAlgorithmicRidBase
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     objectClass
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 4
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   pdb backend
ldapsam:ldap://ldap.pcmhc.org has a valid init
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 2
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 2
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     uid
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     uidNumber
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     gidNumber
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     homeDirectory
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaPwdLastSet
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaPwdCanChange
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaPwdMustChange
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaLogonTime
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaLogoffTime
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaKickoffTime
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     cn
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sn
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     displayName
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaHomeDrive
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaHomePath
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaLogonScript
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaProfilePath
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     description
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaUserWorkstations
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaPrimaryGroupSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaLMPassword
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaNTPassword
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaDomainName
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     objectClass
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaAcctFlags
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaMungedDial
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaBadPasswordCount
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaBadPasswordTime
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaPasswordHistory
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     modifyTimestamp
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaLogonHours
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     modifyTimestamp
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     uidNumber
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     gidNumber
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     homeDirectory
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     loginShell
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     gecos
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 5
[2011/03/08 16:37:58,  2, pid=16184, class=passdb]
init_sam_from_ldap: Entry found for user: root
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]   pdb_set_username:
setting username root, was
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 12 -> now SET
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]   pdb_set_domain:
setting domain PCMHC, was
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 14 -> now DEFAULT
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]
pdb_set_nt_username: setting nt username root, was
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 15 -> now SET
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]
pdb_set_user_sid_from_string: setting user sid
S-1-5-21-857253083-2910702812-3594639990-500
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]   pdb_set_user_sid:
setting user sid S-1-5-21-857253083-2910702812-3594639990-500
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 18 -> now SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 18: SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 21 -> now SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 5 -> now SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 6 -> now SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 7 -> now SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 9 -> now SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 10 -> now SET
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]
pdb_set_full_name: setting full name root, was
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 13 -> now SET
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]
pdb_set_dir_drive: setting dir drive H:, was NULL
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 3 -> now SET
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]   pdb_set_homedir:
setting home dir \\pdc\root, was
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 1 -> now SET
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]
pdb_set_logon_script: setting logon script , was
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 4 -> now DEFAULT
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]
pdb_set_profile_path: setting profile path \\pdc\root\.roamingprofile,
was
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 2 -> now SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 31 -> now SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 32 -> now SET
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 3
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 2
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 3
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]
ldapsam_get_account_policy: got valid value from cache
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 2
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 20 -> now SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 16 -> now SET
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 17 -> now SET
[2011/03/08 16:37:58,  5, pid=16184, class=passdb]   Opening cache
file at /var/spool/samba/login_cache.tdb
[2011/03/08 16:37:58,  7, pid=16184, class=passdb]   Looking up login
cache for user root
[2011/03/08 16:37:58,  7, pid=16184, class=passdb]   No cache entry found
[2011/03/08 16:37:58,  9, pid=16184, class=passdb]   No cache entry,
bad count = 0, bad time = 0
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 34 -> now CHANGED
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     gidNumber
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaGroupType
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSIDList
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     description
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     displayName
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     cn
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     objectClass
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 6
[2011/03/08 16:37:58,  2, pid=16184, class=passdb]
init_group_from_ldap: Entry found for group: 0
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     gidNumber
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaGroupType
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSIDList
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     description
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     displayName
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     cn
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     objectClass
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 7
[2011/03/08 16:37:58,  2, pid=16184, class=passdb]
init_group_from_ldap: Entry found for group: 545
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 8
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   get_privileges: No privileges
assigned to SID [S-1-5-21-857253083-2910702812-3594639990-500]
[2011/03/08 16:37:58,  3, pid=16184]   get_privileges: No privileges
assigned to SID [S-1-5-2]
[2011/03/08 16:37:58,  3, pid=16184]   get_privileges: No privileges
assigned to SID [S-1-5-11]
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  6, pid=16184, class=passdb]   pdb_getsampwsid:
Building guest account
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]   pdb_set_username:
setting username nobody, was
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 12 -> now SET
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]
pdb_set_full_name: setting full name Unprivileged user, was
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 13 -> now SET
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]   pdb_set_domain:
setting domain PCMHC, was
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 14 -> now DEFAULT
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]   pdb_set_user_sid:
setting user sid S-1-5-21-857253083-2910702812-3594639990-501
[2011/03/08 16:37:58, 11, pid=16184, class=passdb]   element 18 -> now DEFAULT
[2011/03/08 16:37:58, 10, pid=16184, class=passdb]   pdb_set_user_sid_from_rid:
  	setting user sid S-1-5-21-857253083-2910702812-3594639990-501 from rid 501
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     gidNumber
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaGroupType
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSIDList
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     description
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     displayName
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     cn
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     objectClass
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 9
[2011/03/08 16:37:58,  4, pid=16184, class=passdb]   ldapsam_getgroup:
Did not find group, filter was
(&(objectClass=sambaGroupMapping)(gidNumber=32767))
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  5, pid=16184, class=auth]
make_server_info_sam: made server info for user nobody -> nobody
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 10
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   get_privileges: No privileges
assigned to SID [S-1-5-21-857253083-2910702812-3594639990-501]
[2011/03/08 16:37:58,  3, pid=16184]   get_privileges: No privileges
assigned to SID [S-1-22-2-32767]
[2011/03/08 16:37:58,  3, pid=16184]   get_privileges: No privileges
assigned to SID [S-1-5-2]
[2011/03/08 16:37:58,  3, pid=16184]   get_privileges: No privileges
assigned to SID [S-1-5-32-546]
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     gidNumber
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaGroupType
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSIDList
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     description
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     displayName
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     cn
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     objectClass
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 11
[2011/03/08 16:37:58,  4, pid=16184, class=passdb]   ldapsam_getgroup:
Did not find group, filter was
(&(objectClass=sambaGroupMapping)(sambaSID=S-1-1-0))
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:37:58, 10, pid=16184, class=auth]   Could not convert
SID S-1-1-0 to gid, ignoring it
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     gidNumber
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaGroupType
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSIDList
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     description
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     displayName
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     cn
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     objectClass
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 12
[2011/03/08 16:37:58,  4, pid=16184, class=passdb]   ldapsam_getgroup:
Did not find group, filter was
(&(objectClass=sambaGroupMapping)(sambaSID=S-1-5-2))
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:37:58, 10, pid=16184, class=auth]   Could not convert
SID S-1-5-2 to gid, ignoring it
[2011/03/08 16:37:58,  3, pid=16184]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  3, pid=16184]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:37:58,  3, pid=16184]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     gidNumber
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSID
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaGroupType
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     sambaSIDList
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     description
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     displayName
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     cn
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP]     objectClass
[2011/03/08 16:37:58,  1, pid=16184]   [LDAP] request done: ld
0x7b134900 msgid 13
[2011/03/08 16:37:58,  2, pid=16184, class=passdb]
init_group_from_ldap: Entry found for group: 546
[2011/03/08 16:37:58,  3, pid=16184]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:37:58, 10, pid=16184]   NT user token of user
S-1-5-21-857253083-2910702812-3594639990-501
  contains 7 SIDs
  SID[  0]: S-1-5-21-857253083-2910702812-3594639990-501
  SID[  1]: S-1-22-2-32767
  SID[  2]: S-1-1-0
  SID[  3]: S-1-5-2
  SID[  4]: S-1-5-32-546
  SID[  5]: S-1-22-1-32767
  SID[  6]: S-1-22-2-546
  SE_PRIV  0x0 0x0 0x0 0x0
[2011/03/08 16:37:58, 10, pid=16184]   UNIX token of user 32767
  Primary group is 32767 and contains 2 supplementary groups
  Group[  0]: 32767
  Group[  1]: 546
[2011/03/08 16:37:58,  3, pid=16184]   start_background_queue:
Starting background LPQ thread
[2011/03/08 16:37:58,  2, pid=16184]   waiting for connections
[2011/03/08 16:38:05,  3, pid=6494] [2011/03/08 16:38:05,  3,
pid=16184]   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:05,  3, pid=16184]   Yielding connection to
[2011/03/08 16:38:05,  3, pid=16184]   Server exit (termination signal)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:05,  3, pid=6494]   Yielding connection to
[2011/03/08 16:38:05,  3, pid=6494]   deleting connection record
returned NT_STATUS_NOT_FOUND
[2011/03/08 16:38:05,  3, pid=6494]   Server exit (termination signal)
[2011/03/08 16:38:11,  3, pid=2301]   lp_load_ex: refreshing parameters
Initialising global parameters
rlimit_max: rlimit_max (128) below minimum Windows limit (16384)
[2011/03/08 16:38:11.350784,  3]
/usr/ports/pobj/samba-3.5.4-cups-ldap/samba-3.5.4/source3/../lib/util/params.c:550(pm_process)
  params.c:pm_process() - Processing configuration file "/etc/samba/smb.conf"
[2011/03/08 16:38:11.352055,  3]
/usr/ports/pobj/samba-3.5.4-cups-ldap/samba-3.5.4/source3/param/loadparm.c:7842(do_section)
  Processing section "[global]"
[2011/03/08 16:38:11,  3, pid=2301]   adding IPC service
[2011/03/08 16:38:11,  3, pid=2301]   reloading printcap cache
[2011/03/08 16:38:11,  0, pid=28934]   Unable to connect to CUPS
server localhost:631 - Connection refused
[2011/03/08 16:38:11,  2, pid=2301]   cups_async_callback: failed to
read a new printer list
[2011/03/08 16:38:11,  3, pid=2301]   reload status: error
[2011/03/08 16:38:11,  3, pid=2301]   reloading printcap cache
[2011/03/08 16:38:11,  0, pid=16035]   Unable to connect to CUPS
server localhost:631 - Connection refused
[2011/03/08 16:38:11,  2, pid=2301]   cups_async_callback: failed to
read a new printer list
[2011/03/08 16:38:11,  3, pid=2301]   reload status: error
[2011/03/08 16:38:11,  2, pid=2301]   added interface fxp0
ip=fe80:1::203:47ff:fea3:c8b3 bcast=fe80:1::ffff:ffff:ffff:ffff
netmask=ffff:ffff:ffff:ffff::
[2011/03/08 16:38:11,  2, pid=2301]   added interface fxp0
ip=192.168.123.2 bcast=192.168.123.255 netmask=
[2011/03/08 16:38:11,  1, pid=2301]   file_init: Information only:
requested 16384 open files, 7010 are available.
[2011/03/08 16:38:11,  3, pid=2301]   loaded services
[2011/03/08 16:38:11,  0, pid=2301]   standard input is not a socket,
assuming -D option
[2011/03/08 16:38:11,  3, pid=2301]   Becoming a daemon.
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Attempting to
register passdb backend ldapsam
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Successfully
added passdb backend 'ldapsam'
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Attempting to
register passdb backend ldapsam_compat
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Successfully
added passdb backend 'ldapsam_compat'
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Attempting to
register passdb backend NDS_ldapsam
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Successfully
added passdb backend 'NDS_ldapsam'
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Attempting to
register passdb backend NDS_ldapsam_compat
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Successfully
added passdb backend 'NDS_ldapsam_compat'
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Attempting to
register passdb backend smbpasswd
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Successfully
added passdb backend 'smbpasswd'
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Attempting to
register passdb backend tdbsam
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Successfully
added passdb backend 'tdbsam'
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Attempting to
register passdb backend wbc_sam
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Successfully
added passdb backend 'wbc_sam'
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Attempting to
find a passdb backend to match ldapsam:ldap://ldap.pcmhc.org (ldapsam)
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Found pdb backend ldapsam
[2011/03/08 16:38:11,  2, pid=22468]   smbldap_search_domain_info:
Searching for:[(&(objectClass=sambaDomain)(sambaDomainName=PCMHC))]
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 1
[2011/03/08 16:38:11,  3, pid=22468]   StartTLS issued: using a TLS connection
[2011/03/08 16:38:11,  2, pid=22468]   smbldap_open_connection:
connection opened
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 2
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     supportedControl
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 3
[2011/03/08 16:38:11,  3, pid=22468]   ldap_connect_system: successful
connection to the LDAP server
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaDomainName
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaNextRid
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaNextUserRid
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaNextGroupRid
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaAlgorithmicRidBase
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     objectClass
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 4
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   pdb backend
ldapsam:ldap://ldap.pcmhc.org has a valid init
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 2
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 2
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     uid
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     uidNumber
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     gidNumber
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     homeDirectory
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaPwdLastSet
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaPwdCanChange
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaPwdMustChange
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaLogonTime
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaLogoffTime
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaKickoffTime
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     cn
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sn
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     displayName
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaHomeDrive
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaHomePath
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaLogonScript
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaProfilePath
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     description
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaUserWorkstations
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaPrimaryGroupSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaLMPassword
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaNTPassword
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaDomainName
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     objectClass
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaAcctFlags
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaMungedDial
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaBadPasswordCount
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaBadPasswordTime
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaPasswordHistory
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     modifyTimestamp
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaLogonHours
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     modifyTimestamp
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     uidNumber
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     gidNumber
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     homeDirectory
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     loginShell
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     gecos
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 5
[2011/03/08 16:38:11,  2, pid=22468, class=passdb]
init_sam_from_ldap: Entry found for user: root
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]   pdb_set_username:
setting username root, was
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 12 -> now SET
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]   pdb_set_domain:
setting domain PCMHC, was
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 14 -> now DEFAULT
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]
pdb_set_nt_username: setting nt username root, was
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 15 -> now SET
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]
pdb_set_user_sid_from_string: setting user sid
S-1-5-21-857253083-2910702812-3594639990-500
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]   pdb_set_user_sid:
setting user sid S-1-5-21-857253083-2910702812-3594639990-500
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 18 -> now SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 18: SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 21 -> now SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 5 -> now SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 6 -> now SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 7 -> now SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 9 -> now SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 10 -> now SET
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]
pdb_set_full_name: setting full name root, was
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 13 -> now SET
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]
pdb_set_dir_drive: setting dir drive H:, was NULL
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 3 -> now SET
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]   pdb_set_homedir:
setting home dir \\pdc\root, was
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 1 -> now SET
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]
pdb_set_logon_script: setting logon script , was
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 4 -> now DEFAULT
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]
pdb_set_profile_path: setting profile path \\pdc\root\.roamingprofile,
was
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 2 -> now SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 31 -> now SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 32 -> now SET
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 3
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 2
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 3
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]
ldapsam_get_account_policy: got valid value from cache
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 2
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 20 -> now SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 16 -> now SET
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 17 -> now SET
[2011/03/08 16:38:11,  5, pid=22468, class=passdb]   Opening cache
file at /var/spool/samba/login_cache.tdb
[2011/03/08 16:38:11,  7, pid=22468, class=passdb]   Looking up login
cache for user root
[2011/03/08 16:38:11,  7, pid=22468, class=passdb]   No cache entry found
[2011/03/08 16:38:11,  9, pid=22468, class=passdb]   No cache entry,
bad count = 0, bad time = 0
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 34 -> now CHANGED
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     gidNumber
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaGroupType
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSIDList
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     description
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     displayName
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     cn
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     objectClass
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 6
[2011/03/08 16:38:11,  2, pid=22468, class=passdb]
init_group_from_ldap: Entry found for group: 0
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     gidNumber
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaGroupType
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSIDList
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     description
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     displayName
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     cn
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     objectClass
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 7
[2011/03/08 16:38:11,  2, pid=22468, class=passdb]
init_group_from_ldap: Entry found for group: 545
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 8
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   get_privileges: No privileges
assigned to SID [S-1-5-21-857253083-2910702812-3594639990-500]
[2011/03/08 16:38:11,  3, pid=22468]   get_privileges: No privileges
assigned to SID [S-1-5-2]
[2011/03/08 16:38:11,  3, pid=22468]   get_privileges: No privileges
assigned to SID [S-1-5-11]
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  6, pid=22468, class=passdb]   pdb_getsampwsid:
Building guest account
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]   pdb_set_username:
setting username nobody, was
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 12 -> now SET
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]
pdb_set_full_name: setting full name Unprivileged user, was
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 13 -> now SET
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]   pdb_set_domain:
setting domain PCMHC, was
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 14 -> now DEFAULT
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]   pdb_set_user_sid:
setting user sid S-1-5-21-857253083-2910702812-3594639990-501
[2011/03/08 16:38:11, 11, pid=22468, class=passdb]   element 18 -> now DEFAULT
[2011/03/08 16:38:11, 10, pid=22468, class=passdb]   pdb_set_user_sid_from_rid:
  	setting user sid S-1-5-21-857253083-2910702812-3594639990-501 from rid 501
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     gidNumber
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaGroupType
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSIDList
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     description
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     displayName
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     cn
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     objectClass
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 9
[2011/03/08 16:38:11,  4, pid=22468, class=passdb]   ldapsam_getgroup:
Did not find group, filter was
(&(objectClass=sambaGroupMapping)(gidNumber=32767))
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  5, pid=22468, class=auth]
make_server_info_sam: made server info for user nobody -> nobody
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 10
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   get_privileges: No privileges
assigned to SID [S-1-5-21-857253083-2910702812-3594639990-501]
[2011/03/08 16:38:11,  3, pid=22468]   get_privileges: No privileges
assigned to SID [S-1-22-2-32767]
[2011/03/08 16:38:11,  3, pid=22468]   get_privileges: No privileges
assigned to SID [S-1-5-2]
[2011/03/08 16:38:11,  3, pid=22468]   get_privileges: No privileges
assigned to SID [S-1-5-32-546]
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     gidNumber
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaGroupType
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSIDList
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     description
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     displayName
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     cn
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     objectClass
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 11
[2011/03/08 16:38:11,  4, pid=22468, class=passdb]   ldapsam_getgroup:
Did not find group, filter was
(&(objectClass=sambaGroupMapping)(sambaSID=S-1-1-0))
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:11, 10, pid=22468, class=auth]   Could not convert
SID S-1-1-0 to gid, ignoring it
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     gidNumber
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaGroupType
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSIDList
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     description
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     displayName
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     cn
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     objectClass
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 12
[2011/03/08 16:38:11,  4, pid=22468, class=passdb]   ldapsam_getgroup:
Did not find group, filter was
(&(objectClass=sambaGroupMapping)(sambaSID=S-1-5-2))
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:11, 10, pid=22468, class=auth]   Could not convert
SID S-1-5-2 to gid, ignoring it
[2011/03/08 16:38:11,  3, pid=22468]   push_sec_ctx(0, 0) :
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  3, pid=22468]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:11,  3, pid=22468]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     gidNumber
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSID
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaGroupType
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     sambaSIDList
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     description
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     displayName
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     cn
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP]     objectClass
[2011/03/08 16:38:11,  1, pid=22468]   [LDAP] request done: ld
0x8a135800 msgid 13
[2011/03/08 16:38:11,  2, pid=22468, class=passdb]
init_group_from_ldap: Entry found for group: 546
[2011/03/08 16:38:11,  3, pid=22468]   pop_sec_ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:11, 10, pid=22468]   NT user token of user
S-1-5-21-857253083-2910702812-3594639990-501
  contains 7 SIDs
  SID[  0]: S-1-5-21-857253083-2910702812-3594639990-501
  SID[  1]: S-1-22-2-32767
  SID[  2]: S-1-1-0
  SID[  3]: S-1-5-2
  SID[  4]: S-1-5-32-546
  SID[  5]: S-1-22-1-32767
  SID[  6]: S-1-22-2-546
  SE_PRIV  0x0 0x0 0x0 0x0
[2011/03/08 16:38:11, 10, pid=22468]   UNIX token of user 32767
  Primary group is 32767 and contains 2 supplementary groups
  Group[  0]: 32767
  Group[  1]: 546
[2011/03/08 16:38:11,  3, pid=22468]   start_background_queue:
Starting background LPQ thread
[2011/03/08 16:38:11,  2, pid=22468]   waiting for connections
[2011/03/08 16:39:07,  3, pid=22468]
/usr/ports/pobj/samba-3.5.4-cups-ldap/samba-3.5.4/source3/smbd/server.c:259
Unclean shutdown of pid 8076
[2011/03/08 16:39:07,  1, pid=22468]   Scheduled cleanup of brl and
lock database after unclean shutdown
[2011/03/08 16:39:10,  3, pid=22468]
/usr/ports/pobj/samba-3.5.4-cups-ldap/samba-3.5.4/source3/smbd/server.c:259
Unclean shutdown of pid 23614
[2011/03/08 16:39:27,  1, pid=22468]   Cleaning up brl and lock
database after unclean shutdown
[2011/03/08 16:39:27,  2, pid=22468]   message to process 14234 failed
- No such process
[2011/03/08 16:39:27,  2, pid=22468]   pid 14234 doesn't exist -
deleting messages record
[2011/03/08 16:39:27,  2, pid=22468]   pid 14234 doesn't exist -
deleting connections -1 []
[2011/03/08 16:39:27,  2, pid=22468]   message to process 30480 failed
- No such process
[2011/03/08 16:39:27,  2, pid=22468]   pid 30480 doesn't exist -
deleting messages record
[2011/03/08 16:39:27,  2, pid=22468]   pid 30480 doesn't exist -
deleting connections -1 []
[2011/03/08 16:39:27,  2, pid=22468]   message to process 23614 failed
- No such process
[2011/03/08 16:39:27,  2, pid=22468]   pid 23614 doesn't exist -
deleting messages record
[2011/03/08 16:39:27,  2, pid=22468]   pid 23614 doesn't exist -
deleting connections -1 []
[2011/03/08 16:39:27,  2, pid=22468]   message to process 6494 failed
- No such process
[2011/03/08 16:39:27,  2, pid=22468]   pid 6494 doesn't exist -
deleting messages record
[2011/03/08 16:39:27,  2, pid=22468]   pid 6494 doesn't exist -
deleting connections -1 []
[2011/03/08 16:39:27,  2, pid=22468]   message to process 8076 failed
- No such process
[2011/03/08 16:39:27,  2, pid=22468]   pid 8076 doesn't exist -
deleting messages record
[2011/03/08 16:39:27,  2, pid=22468]   pid 8076 doesn't exist -
deleting connections -1 []
[2011/03/08 16:39:27,  2, pid=22468]   message to process 13524 failed
- No such process
[2011/03/08 16:39:27,  2, pid=22468]   pid 13524 doesn't exist -
deleting messages record
[2011/03/08 16:39:27,  2, pid=22468]   pid 13524 doesn't exist -
deleting connections -1 []
[2011/03/08 16:40:41,  3, pid=23848]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:40:41,  3, pid=23848]   Yielding connection to
[2011/03/08 16:40:41,  3, pid=23848]   deleting connection record
returned NT_STATUS_NOT_FOUND
[2011/03/08 16:40:41,  3, pid=23848]   Server exit (normal exit)

--- Log file for workstation ---

[2011/03/08 16:38:26,  3, pid=3508]   Transaction 0 of length 137 (0 toread)
[2011/03/08 16:38:26,  3, pid=3508]   switch message SMBnegprot (pid
3508) conn 0x0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   Requested protocol [PC NETWORK
PROGRAM 1.0]
[2011/03/08 16:38:26,  3, pid=3508]   Requested protocol [LANMAN1.0]
[2011/03/08 16:38:26,  3, pid=3508]   Requested protocol [Windows for
Workgroups 3.1a]
[2011/03/08 16:38:26,  3, pid=3508]   Requested protocol [LM1.2X002]
[2011/03/08 16:38:26,  3, pid=3508]   Requested protocol [LANMAN2.1]
[2011/03/08 16:38:26,  3, pid=3508]   Requested protocol [NT LM 0.12]
[2011/03/08 16:38:26,  3, pid=3508]   using SPNEGO
[2011/03/08 16:38:26,  3, pid=3508]   Selected protocol NT LM 0.12
[2011/03/08 16:38:26,  3, pid=3508]   Transaction 1 of length 240 (0 toread)
[2011/03/08 16:38:26,  3, pid=3508]   switch message SMBsesssetupX
(pid 3508) conn 0x0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   wct=12 flg2=0xc807
[2011/03/08 16:38:26,  2, pid=3508]   setup_new_vc_session: New VC ==
0, if NT4.x compatible we would close all old resources.
[2011/03/08 16:38:26,  3, pid=3508]   Doing spnego session setup
[2011/03/08 16:38:26,  3, pid=3508]   NativeOS=[Windows 2002 Service
Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
[2011/03/08 16:38:26,  3, pid=3508]   reply_spnego_negotiate: Got
secblob of size 40
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Making default auth
method list for DC, security=user, encrypt passwords = yes
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Attempting to
register auth backend sam
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Successfully added
auth method 'sam'
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Attempting to
register auth backend sam_ignoredomain
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Successfully added
auth method 'sam_ignoredomain'
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Attempting to
register auth backend unix
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Successfully added
auth method 'unix'
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Attempting to
register auth backend winbind
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Successfully added
auth method 'winbind'
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Attempting to
register auth backend wbc
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Successfully added
auth method 'wbc'
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Attempting to
register auth backend smbserver
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Successfully added
auth method 'smbserver'
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Attempting to
register auth backend trustdomain
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Successfully added
auth method 'trustdomain'
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Attempting to
register auth backend ntdomain
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Successfully added
auth method 'ntdomain'
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Attempting to
register auth backend guest
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Successfully added
auth method 'guest'
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Attempting to
register auth backend netlogond
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Successfully added
auth method 'netlogond'
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   load_auth_module:
Attempting to find an auth method to match guest
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   load_auth_module:
auth method guest has a valid init
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   load_auth_module:
Attempting to find an auth method to match sam
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   load_auth_module:
auth method sam has a valid init
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   load_auth_module:
Attempting to find an auth method to match winbind:trustdomain
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   load_auth_module:
Attempting to find an auth method to match trustdomain
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   load_auth_module:
auth method trustdomain has a valid init
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   load_auth_module:
auth method winbind has a valid init
[2011/03/08 16:38:26,  3, pid=3508]   Got NTLMSSP neg_flags=0xa2088207
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   auth_get_challenge:
module guest did not want to specify a challenge
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   auth_get_challenge:
module sam did not want to specify a challenge
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   auth_get_challenge:
module winbind did not want to specify a challenge
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   auth_context
challenge created by random
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   challenge is:
[2011/03/08 16:38:26,  3, pid=3508]   Transaction 2 of length 270 (0 toread)
[2011/03/08 16:38:26,  3, pid=3508]   switch message SMBsesssetupX
(pid 3508) conn 0x0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   wct=12 flg2=0xc807
[2011/03/08 16:38:26,  2, pid=3508]   setup_new_vc_session: New VC ==
0, if NT4.x compatible we would close all old resources.
[2011/03/08 16:38:26,  3, pid=3508]   Doing spnego session setup
[2011/03/08 16:38:26,  3, pid=3508]   NativeOS=[Windows 2002 Service
Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
[2011/03/08 16:38:26,  3, pid=3508]   Got user=[] domain=[]
workstation=[PCMHCKVBZP86] len1=1 len2=0
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Mapping user []\[]
from workstation [PCMHCKVBZP86]
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   Mapped domain from
[] to [PCMHC] for user [] from workstation [PCMHCKVBZP86]
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   attempting to make a
user_info for  ()
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   making strings for
's user_info struct
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   making blobs for 's
user_info struct
[2011/03/08 16:38:26, 10, pid=3508, class=auth]   made an encrypted
user_info for  ()
[2011/03/08 16:38:26,  3, pid=3508, class=auth]   check_ntlm_password:
 Checking password for unmapped user []\[]@[PCMHCKVBZP86] with the new
password interface
[2011/03/08 16:38:26,  3, pid=3508, class=auth]   check_ntlm_password:
 mapped user is: [PCMHC]\[]@[PCMHCKVBZP86]
[2011/03/08 16:38:26, 10, pid=3508, class=auth]   check_ntlm_password:
auth_context challenge created by random
[2011/03/08 16:38:26, 10, pid=3508, class=auth]   challenge is:
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] request done: ld 0x83c34800 msgid 1
[2011/03/08 16:38:26,  3, pid=3508]   StartTLS issued: using a TLS connection
[2011/03/08 16:38:26,  2, pid=3508]   smbldap_open_connection: connection opened
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] request done: ld 0x83c34800 msgid 2
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     supportedControl
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] request done: ld 0x83c34800 msgid 3
[2011/03/08 16:38:26,  3, pid=3508]   ldap_connect_system: successful
connection to the LDAP server
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     gidNumber
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaSID
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaGroupType
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaSIDList
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     description
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     displayName
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     cn
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     objectClass
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] request done: ld 0x83c34800 msgid 4
[2011/03/08 16:38:26,  4, pid=3508, class=passdb]   ldapsam_getgroup:
Did not find group, filter was
(&(objectClass=sambaGroupMapping)(gidNumber=32767))
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 3: DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 1: DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 4: DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 2: DEFAULT
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]
ldapsam_get_account_policy: got valid value from cache
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 5 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 6 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 7 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 8 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 9 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 10 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 21 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_username:
setting username nobody, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 12 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_domain:
setting domain PCMHC, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 14 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_nt_username: setting nt username , was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 15 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_full_name:
setting full name Unprivileged user, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 13 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_homedir:
setting home dir \\hobbes\nobody, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 1 -> now DEFAULT
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_dir_drive:
setting dir drive , was NULL
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 3 -> now DEFAULT
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_logon_script: setting logon script , was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 4 -> now DEFAULT
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_profile_path: setting profile path \\hobbes\nobody\profile,
was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 2 -> now DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 22 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 24 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_workstations: setting workstations , was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 23 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 25 -> now SET
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]
ldapsam_get_account_policy: got valid value from cache
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 33 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_user_sid:
setting user sid S-1-5-21-857253083-2910702812-3594639990-501
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 18 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_user_sid_from_rid:
  	setting user sid S-1-5-21-857253083-2910702812-3594639990-501 from rid 501
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 16 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 28 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 29 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 30 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 20 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 17 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 26 -> now SET
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  5, pid=3508, class=passdb]
lookup_global_sam_rid: looking up RID 513.
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 2
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     uid
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     uidNumber
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     gidNumber
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     homeDirectory
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaPwdLastSet
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaPwdCanChange
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaPwdMustChange
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaLogonTime
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaLogoffTime
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaKickoffTime
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     cn
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sn
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     displayName
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaHomeDrive
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaHomePath
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaLogonScript
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaProfilePath
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     description
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaUserWorkstations
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaSID
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaPrimaryGroupSID
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaLMPassword
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaNTPassword
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaDomainName
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     objectClass
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaAcctFlags
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaMungedDial
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaBadPasswordCount
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaBadPasswordTime
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaPasswordHistory
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     modifyTimestamp
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaLogonHours
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     modifyTimestamp
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     uidNumber
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     gidNumber
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     homeDirectory
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     loginShell
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     gecos
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] request done: ld 0x83c34800 msgid 5
[2011/03/08 16:38:26,  4, pid=3508, class=passdb]
ldapsam_getsampwsid: Unable to locate SID
[S-1-5-21-857253083-2910702812-3594639990-513] count=0
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     gidNumber
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaSID
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaGroupType
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaSIDList
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     description
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     displayName
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     cn
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     objectClass
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] request done: ld 0x83c34800 msgid 6
[2011/03/08 16:38:26,  2, pid=3508, class=passdb]
init_group_from_ldap: Entry found for group: 513
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_group_sid:
setting group sid S-1-5-21-857253083-2910702812-3594639990-513
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 19 -> now SET
[2011/03/08 16:38:26,  3, pid=3508, class=auth]   check_ntlm_password:
guest authentication for user [] succeeded
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   check_ntlm_password:
 guest authentication for user [] -> [] -> [nobody] succeeded
[2011/03/08 16:38:26,  5, pid=3508, class=auth]   attempting to free
(and zero) a user_info structure
[2011/03/08 16:38:26, 10, pid=3508, class=auth]   structure was created for
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaSID
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] request done: ld 0x83c34800 msgid 7
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   get_privileges: No privileges
assigned to SID [S-1-5-21-857253083-2910702812-3594639990-501]
[2011/03/08 16:38:26,  3, pid=3508]   get_privileges: No privileges
assigned to SID [S-1-5-2]
[2011/03/08 16:38:26,  3, pid=3508]   get_privileges: No privileges
assigned to SID [S-1-5-32-546]
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     gidNumber
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaSID
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaGroupType
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaSIDList
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     description
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     displayName
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     cn
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     objectClass
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] request done: ld 0x83c34800 msgid 8
[2011/03/08 16:38:26,  4, pid=3508, class=passdb]   ldapsam_getgroup:
Did not find group, filter was
(&(objectClass=sambaGroupMapping)(sambaSID=S-1-1-0))
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26, 10, pid=3508, class=auth]   Could not convert
SID S-1-1-0 to gid, ignoring it
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] ldap_build_search_req ATTRS:
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     gidNumber
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaSID
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaGroupType
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     sambaSIDList
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     description
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     displayName
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     cn
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP]     objectClass
[2011/03/08 16:38:26,  1, pid=3508]   [LDAP] request done: ld 0x83c34800 msgid 9
[2011/03/08 16:38:26,  4, pid=3508, class=passdb]   ldapsam_getgroup:
Did not find group, filter was
(&(objectClass=sambaGroupMapping)(sambaSID=S-1-5-2))
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26, 10, pid=3508, class=auth]   Could not convert
SID S-1-5-2 to gid, ignoring it
[2011/03/08 16:38:26, 10, pid=3508]   NT user token of user
S-1-5-21-857253083-2910702812-3594639990-501
  contains 6 SIDs
  SID[  0]: S-1-5-21-857253083-2910702812-3594639990-501
  SID[  1]: S-1-1-0
  SID[  2]: S-1-5-2
  SID[  3]: S-1-5-32-546
  SID[  4]: S-1-22-1-32767
  SID[  5]: S-1-22-2-546
  SE_PRIV  0x0 0x0 0x0 0x0
[2011/03/08 16:38:26, 10, pid=3508]   UNIX token of user 32767
  Primary group is 32767 and contains 1 supplementary groups
  Group[  0]: 546
[2011/03/08 16:38:26,  3, pid=3508]   NTLMSSP Sign/Seal - Initialising
with flags:
[2011/03/08 16:38:26,  3, pid=3508]   Got NTLMSSP neg_flags=0xa2088205
[2011/03/08 16:38:26,  3, pid=3508]   register_existing_vuid: User
name: nobody	Real name: Unprivileged user
[2011/03/08 16:38:26,  3, pid=3508]   register_existing_vuid: UNIX uid
32767 is UNIX user nobody, and will be vuid 100
[2011/03/08 16:38:26,  3, pid=3508]   Transaction 3 of length 82 (0 toread)
[2011/03/08 16:38:26,  3, pid=3508]   switch message SMBtconX (pid
3508) conn 0x0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 3: DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 1: DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 4: DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 2: DEFAULT
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]
ldapsam_get_account_policy: got valid value from cache
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 5 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 6 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 7 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 8 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 9 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 10 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 21 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_username:
setting username nobody, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 12 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_domain:
setting domain PCMHC, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 14 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_nt_username: setting nt username , was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 15 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_full_name:
setting full name Unprivileged user, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 13 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_homedir:
setting home dir \\hobbes\nobody, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 1 -> now DEFAULT
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_dir_drive:
setting dir drive , was NULL
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 3 -> now DEFAULT
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_logon_script: setting logon script , was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 4 -> now DEFAULT
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_profile_path: setting profile path \\hobbes\nobody\profile,
was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 2 -> now DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 22 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 24 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_workstations: setting workstations , was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 23 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 25 -> now SET
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]
ldapsam_get_account_policy: got valid value from cache
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 33 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_user_sid:
setting user sid S-1-5-21-857253083-2910702812-3594639990-501
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 18 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_user_sid_from_rid:
  	setting user sid S-1-5-21-857253083-2910702812-3594639990-501 from rid 501
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 16 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 28 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 29 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 30 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 20 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 17 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 26 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_group_sid:
setting group sid S-1-5-21-857253083-2910702812-3594639990-513
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 19 -> now SET
[2011/03/08 16:38:26,  3, pid=3508]   Connect path is '/tmp' for service [IPC$]
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 3: DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 1: DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 4: DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 2: DEFAULT
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]
ldapsam_get_account_policy: got valid value from cache
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 5 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 6 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 7 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 8 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 9 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 10 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 21 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_username:
setting username nobody, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 12 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_domain:
setting domain PCMHC, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 14 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_nt_username: setting nt username , was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 15 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_full_name:
setting full name Unprivileged user, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 13 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_homedir:
setting home dir \\hobbes\nobody, was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 1 -> now DEFAULT
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_dir_drive:
setting dir drive , was NULL
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 3 -> now DEFAULT
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_logon_script: setting logon script , was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 4 -> now DEFAULT
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_profile_path: setting profile path \\hobbes\nobody\profile,
was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 2 -> now DEFAULT
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 22 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 24 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]
pdb_set_workstations: setting workstations , was
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 23 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 25 -> now SET
[2011/03/08 16:38:26,  3, pid=3508]   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26,  3, pid=3508]   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 1
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]
ldapsam_get_account_policy: got valid value from cache
[2011/03/08 16:38:26,  3, pid=3508]   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 33 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_user_sid:
setting user sid S-1-5-21-857253083-2910702812-3594639990-501
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 18 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_user_sid_from_rid:
  	setting user sid S-1-5-21-857253083-2910702812-3594639990-501 from rid 501
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 16 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 28 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 29 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 30 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 20 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 17 -> now SET
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 26 -> now SET
[2011/03/08 16:38:26, 10, pid=3508, class=passdb]   pdb_set_group_sid:
setting group sid S-1-5-21-857253083-2910702812-3594639990-513
[2011/03/08 16:38:26, 11, pid=3508, class=passdb]   element 19 -> now SET
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (32767, 32767) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   pcmhckvbzp86 (192.168.123.100)
connect to service IPC$ initially as user nobody (uid=32767,
gid=32767) (pid 3508)
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   tconX service=IPC$
[2011/03/08 16:38:26,  3, pid=3508]   Transaction 4 of length 128 (0 toread)
[2011/03/08 16:38:26,  3, pid=3508]   switch message SMBtrans (pid
3508) conn 0x890a2a30
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (32767, 32767) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   trans <\PIPE\LANMAN> data=0
params=32 setup=0
[2011/03/08 16:38:26,  3, pid=3508]   named pipe command on <LANMAN> name
[2011/03/08 16:38:26,  3, pid=3508]   Got API command 104 of form
<WrLehDz> <B16BBDz> (tdscnt=0,tpscnt=32,mdrcnt=4200,mprcnt=8)
[2011/03/08 16:38:26,  3, pid=3508]   Doing NetServerEnum2
[2011/03/08 16:38:26,  3, pid=3508]   NetServerEnum2 domain = PCMHC
uLevel=1 counted=1 total=1
[2011/03/08 16:38:26,  3, pid=3508]   Transaction 5 of length 43 (0 toread)
[2011/03/08 16:38:26,  3, pid=3508]   switch message SMBulogoffX (pid
3508) conn 0x0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   ulogoffX vuid=100
[2011/03/08 16:38:26,  3, pid=3508]   Transaction 6 of length 39 (0 toread)
[2011/03/08 16:38:26,  3, pid=3508]   switch message SMBtdis (pid
3508) conn 0x890a2a30
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   pcmhckvbzp86 (192.168.123.100)
closed connection to service IPC$
[2011/03/08 16:38:26,  3, pid=3508]   Yielding connection to IPC$
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:38:26,  3, pid=3508]   Yielding connection to
[2011/03/08 16:38:26,  3, pid=3508]   Server exit (failed to receive
smb request)
[2011/03/08 16:39:07,  3, pid=8076]   Transaction 0 of length 137 (0 toread)
[2011/03/08 16:39:07,  3, pid=8076]   switch message SMBnegprot (pid
8076) conn 0x0
[2011/03/08 16:39:07,  3, pid=8076]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:39:07,  3, pid=8076]   Requested protocol [PC NETWORK
PROGRAM 1.0]
[2011/03/08 16:39:07,  3, pid=8076]   Requested protocol [LANMAN1.0]
[2011/03/08 16:39:07,  3, pid=8076]   Requested protocol [Windows for
Workgroups 3.1a]
[2011/03/08 16:39:07,  3, pid=8076]   Requested protocol [LM1.2X002]
[2011/03/08 16:39:07,  3, pid=8076]   Requested protocol [LANMAN2.1]
[2011/03/08 16:39:07,  3, pid=8076]   Requested protocol [NT LM 0.12]
[2011/03/08 16:39:07,  3, pid=8076]   using SPNEGO
[2011/03/08 16:39:07,  3, pid=8076]   Selected protocol NT LM 0.12
[2011/03/08 16:39:07,  3, pid=8076]   Transaction 1 of length 240 (0 toread)
[2011/03/08 16:39:07,  3, pid=8076]   switch message SMBsesssetupX
(pid 8076) conn 0x0
[2011/03/08 16:39:07,  3, pid=8076]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:39:07,  3, pid=8076]   wct=12 flg2=0xc807
[2011/03/08 16:39:07,  2, pid=8076]   setup_new_vc_session: New VC ==
0, if NT4.x compatible we would close all old resources.
[2011/03/08 16:39:07,  3, pid=8076]   Doing spnego session setup
[2011/03/08 16:39:07,  3, pid=8076]   NativeOS=[Windows 2002 Service
Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
[2011/03/08 16:39:07,  3, pid=8076]   reply_spnego_negotiate: Got
secblob of size 40
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Making default auth
method list for DC, security=user, encrypt passwords = yes
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Attempting to
register auth backend sam
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Successfully added
auth method 'sam'
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Attempting to
register auth backend sam_ignoredomain
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Successfully added
auth method 'sam_ignoredomain'
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Attempting to
register auth backend unix
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Successfully added
auth method 'unix'
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Attempting to
register auth backend winbind
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Successfully added
auth method 'winbind'
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Attempting to
register auth backend wbc
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Successfully added
auth method 'wbc'
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Attempting to
register auth backend smbserver
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Successfully added
auth method 'smbserver'
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Attempting to
register auth backend trustdomain
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Successfully added
auth method 'trustdomain'
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Attempting to
register auth backend ntdomain
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Successfully added
auth method 'ntdomain'
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Attempting to
register auth backend guest
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Successfully added
auth method 'guest'
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Attempting to
register auth backend netlogond
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Successfully added
auth method 'netlogond'
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   load_auth_module:
Attempting to find an auth method to match guest
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   load_auth_module:
auth method guest has a valid init
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   load_auth_module:
Attempting to find an auth method to match sam
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   load_auth_module:
auth method sam has a valid init
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   load_auth_module:
Attempting to find an auth method to match winbind:trustdomain
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   load_auth_module:
Attempting to find an auth method to match trustdomain
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   load_auth_module:
auth method trustdomain has a valid init
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   load_auth_module:
auth method winbind has a valid init
[2011/03/08 16:39:07,  3, pid=8076]   Got NTLMSSP neg_flags=0xa2088207
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   auth_get_challenge:
module guest did not want to specify a challenge
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   auth_get_challenge:
module sam did not want to specify a challenge
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   auth_get_challenge:
module winbind did not want to specify a challenge
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   auth_context
challenge created by random
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   challenge is:
[2011/03/08 16:39:07,  3, pid=8076]   Transaction 2 of length 270 (0 toread)
[2011/03/08 16:39:07,  3, pid=8076]   switch message SMBsesssetupX
(pid 8076) conn 0x0
[2011/03/08 16:39:07,  3, pid=8076]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:39:07,  3, pid=8076]   wct=12 flg2=0xc807
[2011/03/08 16:39:07,  2, pid=8076]   setup_new_vc_session: New VC ==
0, if NT4.x compatible we would close all old resources.
[2011/03/08 16:39:07,  3, pid=8076]   Doing spnego session setup
[2011/03/08 16:39:07,  3, pid=8076]   NativeOS=[Windows 2002 Service
Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
[2011/03/08 16:39:07,  3, pid=8076]   Got user=[] domain=[]
workstation=[PCMHCKVBZP86] len1=1 len2=0
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Mapping user []\[]
from workstation [PCMHCKVBZP86]
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   Mapped domain from
[] to [PCMHC] for user [] from workstation [PCMHCKVBZP86]
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   attempting to make a
user_info for  ()
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   making strings for
's user_info struct
[2011/03/08 16:39:07,  5, pid=8076, class=auth]   making blobs for 's
user_info struct
[2011/03/08 16:39:07, 10, pid=8076, class=auth]   made an encrypted
user_info for  ()
[2011/03/08 16:39:07,  3, pid=8076, class=auth]   check_ntlm_password:
 Checking password for unmapped user []\[]@[PCMHCKVBZP86] with the new
password interface
[2011/03/08 16:39:07,  3, pid=8076, class=auth]   check_ntlm_password:
 mapped user is: [PCMHC]\[]@[PCMHCKVBZP86]
[2011/03/08 16:39:07, 10, pid=8076, class=auth]   check_ntlm_password:
auth_context challenge created by random
[2011/03/08 16:39:07, 10, pid=8076, class=auth]   challenge is:
[2011/03/08 16:39:10,  3, pid=23614]   Transaction 0 of length 137 (0 toread)
[2011/03/08 16:39:10,  3, pid=23614]   switch message SMBnegprot (pid
23614) conn 0x0
[2011/03/08 16:39:10,  3, pid=23614]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:39:10,  3, pid=23614]   Requested protocol [PC NETWORK
PROGRAM 1.0]
[2011/03/08 16:39:10,  3, pid=23614]   Requested protocol [LANMAN1.0]
[2011/03/08 16:39:10,  3, pid=23614]   Requested protocol [Windows for
Workgroups 3.1a]
[2011/03/08 16:39:10,  3, pid=23614]   Requested protocol [LM1.2X002]
[2011/03/08 16:39:10,  3, pid=23614]   Requested protocol [LANMAN2.1]
[2011/03/08 16:39:10,  3, pid=23614]   Requested protocol [NT LM 0.12]
[2011/03/08 16:39:10,  3, pid=23614]   using SPNEGO
[2011/03/08 16:39:10,  3, pid=23614]   Selected protocol NT LM 0.12
[2011/03/08 16:39:10,  3, pid=23614]   Transaction 1 of length 240 (0 toread)
[2011/03/08 16:39:10,  3, pid=23614]   switch message SMBsesssetupX
(pid 23614) conn 0x0
[2011/03/08 16:39:10,  3, pid=23614]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:39:10,  3, pid=23614]   wct=12 flg2=0xc807
[2011/03/08 16:39:10,  2, pid=23614]   setup_new_vc_session: New VC ==
0, if NT4.x compatible we would close all old resources.
[2011/03/08 16:39:10,  3, pid=23614]   Doing spnego session setup
[2011/03/08 16:39:10,  3, pid=23614]   NativeOS=[Windows 2002 Service
Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
[2011/03/08 16:39:10,  3, pid=23614]   reply_spnego_negotiate: Got
secblob of size 40
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Making default auth
method list for DC, security=user, encrypt passwords = yes
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Attempting to
register auth backend sam
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Successfully added
auth method 'sam'
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Attempting to
register auth backend sam_ignoredomain
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Successfully added
auth method 'sam_ignoredomain'
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Attempting to
register auth backend unix
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Successfully added
auth method 'unix'
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Attempting to
register auth backend winbind
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Successfully added
auth method 'winbind'
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Attempting to
register auth backend wbc
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Successfully added
auth method 'wbc'
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Attempting to
register auth backend smbserver
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Successfully added
auth method 'smbserver'
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Attempting to
register auth backend trustdomain
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Successfully added
auth method 'trustdomain'
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Attempting to
register auth backend ntdomain
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Successfully added
auth method 'ntdomain'
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Attempting to
register auth backend guest
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Successfully added
auth method 'guest'
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Attempting to
register auth backend netlogond
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Successfully added
auth method 'netlogond'
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   load_auth_module:
Attempting to find an auth method to match guest
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   load_auth_module:
auth method guest has a valid init
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   load_auth_module:
Attempting to find an auth method to match sam
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   load_auth_module:
auth method sam has a valid init
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   load_auth_module:
Attempting to find an auth method to match winbind:trustdomain
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   load_auth_module:
Attempting to find an auth method to match trustdomain
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   load_auth_module:
auth method trustdomain has a valid init
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   load_auth_module:
auth method winbind has a valid init
[2011/03/08 16:39:10,  3, pid=23614]   Got NTLMSSP neg_flags=0xa2088207
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   auth_get_challenge:
module guest did not want to specify a challenge
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   auth_get_challenge:
module sam did not want to specify a challenge
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   auth_get_challenge:
module winbind did not want to specify a challenge
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   auth_context
challenge created by random
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   challenge is:
[2011/03/08 16:39:10,  3, pid=23614]   Transaction 2 of length 270 (0 toread)
[2011/03/08 16:39:10,  3, pid=23614]   switch message SMBsesssetupX
(pid 23614) conn 0x0
[2011/03/08 16:39:10,  3, pid=23614]   setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
[2011/03/08 16:39:10,  3, pid=23614]   wct=12 flg2=0xc807
[2011/03/08 16:39:10,  2, pid=23614]   setup_new_vc_session: New VC ==
0, if NT4.x compatible we would close all old resources.
[2011/03/08 16:39:10,  3, pid=23614]   Doing spnego session setup
[2011/03/08 16:39:10,  3, pid=23614]   NativeOS=[Windows 2002 Service
Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
[2011/03/08 16:39:10,  3, pid=23614]   Got user=[] domain=[]
workstation=[PCMHCKVBZP86] len1=1 len2=0
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Mapping user []\[]
from workstation [PCMHCKVBZP86]
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   Mapped domain from
[] to [PCMHC] for user [] from workstation [PCMHCKVBZP86]
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   attempting to make
a user_info for  ()
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   making strings for
's user_info struct
[2011/03/08 16:39:10,  5, pid=23614, class=auth]   making blobs for 's
user_info struct
[2011/03/08 16:39:10, 10, pid=23614, class=auth]   made an encrypted
user_info for  ()
[2011/03/08 16:39:10,  3, pid=23614, class=auth]
check_ntlm_password:  Checking password for unmapped user
[]\[]@[PCMHCKVBZP86] with the new password interface
[2011/03/08 16:39:10,  3, pid=23614, class=auth]
check_ntlm_password:  mapped user is: [PCMHC]\[]@[PCMHCKVBZP86]
[2011/03/08 16:39:10, 10, pid=23614, class=auth]
check_ntlm_password: auth_context challenge created by random
[2011/03/08 16:39:10, 10, pid=23614, class=auth]   challenge is:


More information about the samba mailing list