FC8/Samba 3.0.28: Could not receive trustdoms

[Geekasaurus]

I've been using Samba for a number of years, but this is becoming IMPOSSIBLE to set up and maintain.   The setup is simple: 2 computers, 1 Windows XP SP2 with all the latest patches and such [hostname doofus], 1 Fedora Core 8 running Samba 3.0.28 [hostname dumbee].

I am able to make it work after a fashion.  I can browse to the /mnt1 filesystem [ext3, if anyone cares] and read/write files to it.  I am now trying to get Norton Ghost to work, and their help desk is worse than useless.  The goal is to backup the XP system to the Linux system.

Ghost did work for a week, but recently I've been getting errors.  It does seem to be a problem with Samba.  At the time I get the Ghost errors, I get the an error in the  log.wb-WORKGROUP file, with a timestamp that matches the attempt on the XP machine.  The error is: libsmb/clientgen.c:cli_receive_smb(112)   Receiving SMB: Server stopped responding.  

At the same moment, in the log.winbindd file, I get another error with the same timestamp, and the mesage:
nsswitch/winbindd_util.c:trustdom_recv(229)   Could not receive trustdoms.

With the same timestamp, in the log.$IP_ADDY file I see:
auth/auth_util.c:create_token_from_username(1116)   sid_to_uid for cvail (S-1-5-21-1203031067-1067078206-1052721417-2000) failed

The results of # net user
root
cvail

So the cvail user is in the database.

cvail is the username I am using on the XP host, doofus.  It exists on both the Linux and XP systems.

I cannot say that I am a clueless newby...I've been doing *nix administration for 25 years, and
Samba since version 2 something.  But if anyone can offer some clues, I will post back here what, if any, solution I find.


Here is the /etc/samba/smb.conf:
[In the interest of brevity, I have deleted most lines with a ; or # as the first byte].
#
#======================= Global Settings =====================================

[global]
        workgroup = Workgroup
        server string = Samba Server Version %v
        netbios name = dumbee
        interfaces = lo eth0
        hosts allow = 127.0.0.1 192.168.1.2 192.168.1.6 192.168.1.5 192.168.1.3
        log file = /var/log/samba/log.%m
        max log size = 50

        security = user
        passdb backend = tdbsam
        domain master = yes
        domain logons = yes
        local master = yes
        os level = 32
        preferred master = yes
        dns proxy = yes

        username map = /etc/samba/smbusers
        idmap uid = 100000-200000
        idmap gid = 100000-200000
        winbind uid = 10000-20000
        winbind gid = 10000-30000
        winbind use default domain = yes

        logon drive = R:
        logon home = \\%N\%U
        logon script = %u.bat
        logon path = \\%L\Profiles\%u

        template homedir = /dev/null
        template shell = /bin/false

        winbind enum users = yes
        winbind enum groups = yes
        winbind cache time = 600
        winbind nested groups = No
        client use spnego = no

        add user script = /usr/sbin/useradd "%u" -n -g users
        add group script = /usr/sbin/groupadd "%g"
        add machine script = /usr/sbin/useradd -n -c "Workstation (%u)" -M -d /nohome -s /bin/false "%u"
        delete user script = /usr/sbin/userdel "%u"
        delete user from group script = /usr/sbin/userdel "%u" "%g"
        delete group script = /usr/sbin/groupdel "%g"

#============================ Share Definitions ==============================


[homes]
        comment = Home Directories
        browseable = no
        writable = yes
        ;users = %S
        users = Workgroup\%S
        create mask = 0700
        force create mode = 0700
        directory mask =  0700
        force directory mode = 0700

[printers]
        comment = All Printers
        path = /var/spool/samba
        guest ok = no
        writable = no
        printable = yes

[mnt1]
        comment = 300gb Disk on Dumbee
        path = /mnt1
        public = yes
        writable = yes
        printable = no
        users = Workgroup\%S
        strict locking = no

[Volker Lendecke]

On Sat, May 31, 2008 at 05:08:00PM -0700, Geekasaurus wrote:
> Ghost did work for a week, but recently I've been getting errors.  It does
> seem to be a problem with Samba.  At the time I get the Ghost errors, I get
> the an error in the  log.wb-WORKGROUP file, with a timestamp that matches
> the attempt on the XP machine.  The error is:
> libsmb/clientgen.c:cli_receive_smb(112)   Receiving SMB: Server stopped
> responding.  

Well, this basically says that the dc of domain WORKGROUP
we're connected does not respond quickly enough for
winbind's taste. Without a debug level 10 log of winbind and
smbd and possibly a sniff it's very difficult to say what's
going wrong in your case.

Volker

[Geekasaurus]

Volker Lendecke wrote

On Sat, May 31, 2008 at 05:08:00PM -0700, Geekasaurus wrote:
> Ghost did work for a week, but recently I've been getting errors.  It does
> seem to be a problem with Samba.  At the time I get the Ghost errors, I get
> the an error in the  log.wb-WORKGROUP file, with a timestamp that matches
> the attempt on the XP machine.  The error is:
> libsmb/clientgen.c:cli_receive_smb(112)   Receiving SMB: Server stopped
> responding.  

Well, this basically says that the dc of domain WORKGROUP
we're connected does not respond quickly enough for
winbind's taste. Without a debug level 10 log of winbind and
smbd and possibly a sniff it's very difficult to say what's
going wrong in your case.

Volker

 

Thanks for your input, at least I know what questions to ask.  As you know, a debug level of 10 produces A LOT of output.  I have done what I can to shorten it.  But it IS a lot of data.  

I did not even attempt to run Ghost at this point.  The logs had plenty of errors even without it running.
I suspect that if we can take care of these errors, Ghost will also start to work.

# Contents of "/etc/nsswitch.conf|grep -v ^#|grep -v ^$"
passwd:     files winbind
shadow:     files
group:      files winbind
hosts:      files  dns winbind
bootparams: nisplus [NOTFOUND=return] files
ethers:     files
netmasks:   files
networks:   files
protocols:  files
rpc:        files
services:   files
netgroup:   nisplus
publickey:  nisplus
automount:  files nisplus
aliases:    files nisplus
#########################################################
In the interests of making brevity the following samba logs
were all piped through sort and uniq -f4 to
extract only the pertinent information.  

First is the output of "cat log.smbd|sort|uniq -f4" after
starting the smbd daemon with --debuglevel=10

[2008/05/11 00:01:57, 0] lib/util_sock.c:get_peer_addr(1232)
  Copyright Andrew Tridgell and the Samba Team 1992-2008
  getpeername failed. Error was Transport endpoint is not connected
  smbd version 3.0.28a-0.fc8 started.
#########################################################
Next is the output of "cat log.doofus|sort|uniq -f4"
[doofus is the name of the XP host]

[2008/06/03 16:12:53, 1] smbd/service.c:close_cnum(1230)
  doofus (192.168.1.2) closed connection to service mnt1
  doofus (192.168.1.2) connect to service mnt1 initially as user cvail (uid=500, gid=500) (pid 29154)
  doofus (192.168.1.2) connect to service mnt1 initially as user nobody (uid=99, gid=99) (pid 29344)

I do not understand why it connected as user cvail (me) and then
also connected as user nobody.

I have not loaded or executed Ghost at this time.
#########################################################
Next is the output from "cat log.winbindd|sort|uniq -f4".  
The winbindd daemon was started with --debuglevel=10 in the /etc/sysconfig/samba file

[2008/06/03 16:19:11, 10] lib/events.c:event_add_timed(129)
  [29344]: gid to sid 500
  Added timed event "async_request_timeout": b80d5038
  Destroying timed event b80d5038 "async_request_timeout"
  gid2sid: gid 500 has sid S-1-22-2-500
  process_request: request fn GID_TO_SID
  Retrieving response for pid 29289
  timed_events_timeout: 299/999908
  uid2sid: uid 500 has sid S-1-5-21-1203031067-1067078206-1052721417-2000
#########################################################
Next is the output from "cat log.192.168.1.5|sort|uniq -f4".
This is the IP addy of the FC8/Samba server.

[2008/06/03 16:14:11, 1] auth/auth_util.c:create_token_from_username(1116)
  sid_to_uid for cvail (S-1-5-21-1203031067-1067078206-1052721417-2000) failed

cvail is my user on the XP host, also a valid user on the Linux system.
#########################################################

 # cat log.winbindd-dc-connect|sort|uniq -f4
[2008/06/03 16:14:21, 10] lib/gencache.c:gencache_get(212)
  Adding 1 DC's from auto lookup
  Adding cache entry with key = NBT/WORKGROUP#1C; value = 192.168.1.5:0 and timeout = Tue Jun  3 16:35:59 2008
      answers   0 char ......   hex 8000C0A80105
      answers: nmb_name=WORKGROUP<1c> rr_type=32 rr_class=1 ttl=259200
  bind succeeded on port 0
  Cache entry with key = SAF/DOMAIN/WORKGROUP couldn't be found
  Could not test socket option TCP_KEEPCNT.
  Could not test socket option TCP_KEEPIDLE.
  Could not test socket option TCP_KEEPINTVL.
  Could not test socket option TCP_NODELAY.
  get_dc_list: 192.168.1.5:0
  get_dc_list: preferred server list: ", *"
  get_dc_list: returning 1 ip addresses in an unordered list
  getlmhostsent: lmhost entry: 127.0.0.1 localhost
  getlmhostsent: lmhost entry: 192.168.1.2 doofus
  getlmhostsent: lmhost entry: 192.168.1.6 dummee
  get_sorted_dc_list: attempting lookup for name WORKGROUP (sitename NULL) using [lmhosts wins host bcast]
  Got a positive name query response from 127.0.0.1 ( 192.168.1.5 )
      header: flags: bcast=No rec_avail=Yes rec_des=Yes trunc=No auth=Yes
      header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0
  internal_resolve_name: looking up WORKGROUP#1c (sitename (null))
  internal_resolve_name: returning 1 addresses: 192.168.1.5:0
  namecache_store: storing 1 address for WORKGROUP#1c: 192.168.1.5:0
  name_resolve_bcast: Attempting broadcast lookup for name WORKGROUP<0x1c>
  name WORKGROUP#1C found.
  nmb packet from 127.0.0.1(137) header: id=3792 opcode=Query(0) response=Yes
  no entry for WORKGROUP#1C found.
  parse_nmb: packet id = 3792
  read_udp_socket: lastip 127.0.0.1 lastport 137 read: 62
  Received a packet of len 62 from (127.0.0.1) port 137
  remove_duplicate_addrs2: looking for duplicate address/port pairs
  resolve_hosts: not appropriate for name type <0x1c>
  resolve_lmhosts: Attempting lmhosts lookup for name WORKGROUP<0x1c>
  resolve_wins: WINS server resolution selected and no WINS servers listed.
  Returning expired cache entry: key = NBT/WORKGROUP#1C, value = 192.168.1.5:0, timeout = Tue Jun  3 16:17:40 2008
  saf_fetch: failed to find server for "WORKGROUP" domain
  Sending a packet of len 50 to (127.255.255.255) on port 137
  socket option IPTOS_LOWDELAY = 0
  socket option SO_BROADCAST = 1
  socket option SO_KEEPALIVE = 0
  socket option SO_RCVBUF = 110592
  socket option SO_RCVLOWAT = 1
  socket option SO_RCVTIMEO = 0
  socket option SO_REUSEADDR = 1
  socket option SO_SNDBUF = 110592
  socket option SO_SNDLOWAT = 1
  socket option SO_SNDTIMEO = 0

#########################################################
cat log.winbindd-idmap|sort|uniq -f4
[2008/05/19 13:25:15, 0] nsswitch/idmap.c:idmap_init(388)
  [29284]: gid 500 to sid
  [29284]: retrieved sid: S-1-22-2-500
  [29284]: uid to sid 500
   (900 seconds ahead)
  account_policy_get: name: password history, val: 0
  Adding cache entry with key = IDMAP/SID/S-1-5-21-1203031067-1067078206-1052721417-2000; value =   1212528851/IDMAP/UID/500 and timeout = Tue Jun  3 16:34:11 2008
  Adding cache entry with key = IDMAP/UID/500; value =   1212528851/IDMAP/SID/S-1-5-21-1203031067-1067078206-1052721417-2000 and timeout = Tue Jun  3 16:34:11 2008
  async_request_timeout_handler: child pid 29286 is not responding. Closing connection to it.
  Attempting to find an passdb backend to match tdbsam (tdbsam)
  Attempting to register passdb backend ldapsam
  Attempting to register passdb backend ldapsam_compat
  Attempting to register passdb backend NDS_ldapsam
  Attempting to register passdb backend NDS_ldapsam_compat
  Attempting to register passdb backend smbpasswd
  Attempting to register passdb backend tdbsam
  Cache entry with key = IDMAP/GID/500 couldn't be found
  child daemon request 51
  Could not receive async reply from child pid 29286
  Could not receive trustdoms
  Destroying timed event b811eee0 "async_request_timeout"
  Domain init returned an error
  Domain WORKGROUP - Backend passdb - not default - readonly
  Found pdb backend tdbsam
  pdb backend tdbsam has a valid init
  pdb_set_dir_drive: setting dir drive R:, was NULL
  pdb_set_domain: setting domain GEEKAZOIDNET, was
  pdb_set_full_name: setting full name Chris Vail, was
  pdb_set_homedir: setting home dir \\dumbee\cvail, was
  pdb_set_logon_script: setting logon script %u.bat, was
  pdb_set_nt_username: setting nt username , was
  pdb_set_profile_path: setting profile path \\dumbee\Profiles\%u, was
  pdb_set_username: setting username cvail, was
  pdb_set_user_sid_from_rid:
  pdb_set_user_sid: setting user sid S-1-5-21-1203031067-1067078206-1052721417-2000
  pdb_set_workstations: setting workstations , was
  process_request: request fn DUAL_GID2SID
  Query backends to map ids->sids
  Query sids from domain WORKGROUP
  read_data: read failure for 2088. Error = Connection reset by peer
  Received child initialization response for domain WORKGROUP
  Returning expired cache entry: key = IDMAP/UID/500, value = IDMAP/SID/S-1-5-21-1203031067-1067078206-1052721417-2000, timeout = Tue Jun  3 16:13:30 2008
  Running event "async_request_timeout" b811eee0
  SE_PRIV  0x0 0x0 0x0 0x0
        setting user sid S-1-5-21-1203031067-1067078206-1052721417-2000 from rid 2000
  Storing response for pid 29289, len 3240
  Successfully added idmap alloc backend 'ldap'
  Successfully added idmap alloc backend 'tdb'
  Successfully added idmap backend 'ldap'
  Successfully added idmap backend 'nss'
  Successfully added idmap backend 'passdb'
  Successfully added idmap backend 'tdb'
  Successfully added passdb backend 'ldapsam'
  Successfully added passdb backend 'ldapsam_compat'
  Successfully added passdb backend 'NDS_ldapsam'
  Successfully added passdb backend 'NDS_ldapsam_compat'
  Successfully added passdb backend 'smbpasswd'
  Successfully added passdb backend 'tdbsam'
  tdbsam_close: Reference count is now 0.
  tdbsam_open: successfully opened /var/lib/samba/private/passdb.tdb
#########################################################
All lines previous to this are before I start and run ghost,
Next, I will connect from the XP host and list the directory /mnt1.
#########################################################
 cat log.winbindd|sort|uniq -f4
[2008/06/03 16:19:11, 10] lib/events.c:event_add_timed(129)
  [29344]: gid to sid 500
  [29344]: ping
  [29344]: sid to gid S-1-1-0
  [29344]: sid to gid S-1-5-11
  [29344]: sid to gid S-1-5-2
  [29344]: sid to gid S-1-5-32-546
  [29344]: uid to sid 500
  [29356]: ping
  [29356]: request location of privileged pipe
  [29356]: sid to uid S-1-5-21-1203031067-1067078206-1052721417-2000
  [29358]: gid to sid 500
  [29358]: ping
  [29358]: request location of privileged pipe
  [29358]: sid to uid S-1-5-21-1203031067-1067078206-1052721417-2000
  [29360]: gid to sid 500
  [29360]: ping
  [29360]: request location of privileged pipe
  [29360]: sid to uid S-1-5-21-1203031067-1067078206-1052721417-2000
  [29387]: gid to sid 500
  [29387]: ping
  [29387]: request location of privileged pipe
  [29387]: sid to uid S-1-5-21-1203031067-1067078206-1052721417-2000
  [29389]: gid to sid 500
  [29389]: ping
  [29389]: request location of privileged pipe
  [29389]: sid to uid S-1-5-21-1203031067-1067078206-1052721417-2000
  [29391]: gid to sid 500
  [29391]: ping
  [29391]: request location of privileged pipe
  [29391]: sid to uid S-1-5-21-1203031067-1067078206-1052721417-2000
  accepted socket 20
  Added timed event "async_request_timeout": b80c8038
  Added timed event "async_request_timeout": b80d5038
  calling find_domain_from_sid
  Could not find domain for sid S-1-1-0
  Could not find domain for sid S-1-5-11
  Could not find domain for sid S-1-5-2
  Could not receive trustdoms
  Destroying timed event b80c8038 "async_request_timeout"
  find_lookup_domain_from_sid(S-1-1-0)
  gid2sid: gid 500 has sid S-1-22-2-500
  lookupsid returned an error
  Retrieving response for pid 29286
  Retrieving response for pid 29289
  Retrieving response for pid 29345
  sid2gid_lookupsid_recv: Could not convert get sid type for S-1-1-0
  sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-11
  sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-2
  sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-32-546
  sid2uid_lookupsid_recv Could not convert get sid type for S-1-5-21-1203031067-1067078206-1052721417-2000
  timed_events_timeout: 264/998686
  uid2sid: uid 500 has sid S-1-5-21-1203031067-1067078206-1052721417-2000
#########################################################
So now the failures are starting to show.  

# cat log.winbindd-dc-connect|sort|uniq
[2008/06/03 16:14:21, 10] lib/gencache.c:gencache_get(212)
[2008/06/03 16:14:21, 10] lib/gencache.c:gencache_get(226)
[2008/06/03 16:14:21, 10] libsmb/namequery.c:internal_resolve_name(1166)
[2008/06/03 16:14:21, 10] libsmb/namequery.c:remove_duplicate_addrs2(435)
[2008/06/03 16:14:21, 3] libsmb/namequery.c:get_dc_list(1489)
[2008/06/03 16:14:21, 4] libsmb/namequery.c:get_dc_list(1599)
[2008/06/03 16:14:21, 4] libsmb/namequery.c:get_dc_list(1600)
[2008/06/03 16:14:21, 5] libsmb/namecache.c:namecache_fetch(214)
[2008/06/03 16:14:21, 5] libsmb/namequery.c:saf_fetch(133)
[2008/06/03 16:14:21, 8] libsmb/namequery.c:get_dc_list(1505)
[2008/06/03 16:14:21, 8] libsmb/namequery.c:get_sorted_dc_list(1626)
[2008/06/03 16:24:59, 10] lib/gencache.c:gencache_get(212)
[2008/06/03 16:24:59, 10] lib/gencache.c:gencache_get(226)
[2008/06/03 16:24:59, 10] lib/gencache.c:gencache_set(140)
[2008/06/03 16:24:59, 10] libsmb/namequery.c:internal_resolve_name(1166)
[2008/06/03 16:24:59, 10] libsmb/namequery.c:internal_resolve_name(1293)
[2008/06/03 16:24:59, 10] libsmb/namequery.c:remove_duplicate_addrs2(435)
[2008/06/03 16:24:59, 10] libsmb/nmblib.c:parse_nmb(506)
[2008/06/03 16:24:59, 10] lib/util_sock.c:open_socket_in(839)
[2008/06/03 16:24:59, 10] lib/util_sock.c:read_udp_socket(294)
[2008/06/03 16:24:59, 2] libsmb/namequery.c:name_query(604)
[2008/06/03 16:24:59, 3] libsmb/namequery.c:get_dc_list(1489)
[2008/06/03 16:24:59, 3] libsmb/namequery.c:name_resolve_bcast(805)
[2008/06/03 16:24:59, 3] libsmb/namequery.c:resolve_lmhosts(966)
[2008/06/03 16:24:59, 3] libsmb/namequery.c:resolve_wins(863)
[2008/06/03 16:24:59, 3] libsmb/namequery.c:resolve_wins(866)
[2008/06/03 16:24:59, 4] libsmb/namequery.c:get_dc_list(1599)
[2008/06/03 16:24:59, 4] libsmb/namequery.c:get_dc_list(1600)
[2008/06/03 16:24:59, 4] libsmb/namequery.c:getlmhostsent(717)
[2008/06/03 16:24:59, 4] libsmb/nmblib.c:debug_nmb_packet(112)
[2008/06/03 16:24:59, 5] libsmb/namecache.c:namecache_fetch(210)
[2008/06/03 16:24:59, 5] libsmb/namecache.c:namecache_store(135)
[2008/06/03 16:24:59, 5] libsmb/namequery.c:resolve_hosts(1022)
[2008/06/03 16:24:59, 5] libsmb/namequery.c:saf_fetch(133)
[2008/06/03 16:24:59, 5] libsmb/nmblib.c:read_packet(757)
[2008/06/03 16:24:59, 5] libsmb/nmblib.c:send_udp(779)
[2008/06/03 16:24:59, 5] lib/util_sock.c:print_socket_options(204)
[2008/06/03 16:24:59, 5] lib/util_sock.c:print_socket_options(206)
[2008/06/03 16:24:59, 8] libsmb/namequery.c:get_dc_list(1505)
[2008/06/03 16:24:59, 8] libsmb/namequery.c:get_sorted_dc_list(1626)
[2008/06/03 16:35:35, 10] lib/gencache.c:gencache_get(212)
[2008/06/03 16:35:35, 10] lib/gencache.c:gencache_get(226)
[2008/06/03 16:35:35, 10] libsmb/namequery.c:internal_resolve_name(1166)
[2008/06/03 16:35:35, 10] libsmb/namequery.c:remove_duplicate_addrs2(435)
[2008/06/03 16:35:35, 3] libsmb/namequery.c:get_dc_list(1489)
[2008/06/03 16:35:35, 4] libsmb/namequery.c:get_dc_list(1599)
[2008/06/03 16:35:35, 4] libsmb/namequery.c:get_dc_list(1600)
[2008/06/03 16:35:35, 5] libsmb/namecache.c:namecache_fetch(214)
[2008/06/03 16:35:35, 5] libsmb/namequery.c:saf_fetch(133)
[2008/06/03 16:35:35, 8] libsmb/namequery.c:get_dc_list(1505)
[2008/06/03 16:35:35, 8] libsmb/namequery.c:get_sorted_dc_list(1626)
   (660 seconds ahead)
  Adding 1 DC's from auto lookup
  Adding cache entry with key = NBT/WORKGROUP#1C; value = 192.168.1.5:0 and timeout = Tue Jun  3 16:35:59 2008
      answers   0 char ......   hex 8000C0A80105
      answers: nmb_name=WORKGROUP<1c> rr_type=32 rr_class=1 ttl=259200
  bind succeeded on port 0
  Cache entry with key = SAF/DOMAIN/WORKGROUP couldn't be found
  Could not test socket option TCP_KEEPCNT.
  Could not test socket option TCP_KEEPIDLE.
  Could not test socket option TCP_KEEPINTVL.
  Could not test socket option TCP_NODELAY.
  get_dc_list: 192.168.1.5:0
  get_dc_list: preferred server list: ", *"
  get_dc_list: returning 1 ip addresses in an unordered list
  getlmhostsent: lmhost entry: 127.0.0.1 localhost
  getlmhostsent: lmhost entry: 192.168.1.2 doofus
  getlmhostsent: lmhost entry: 192.168.1.6 dummee
  get_sorted_dc_list: attempting lookup for name WORKGROUP (sitename NULL) using [lmhosts wins host bcast]
  Got a positive name query response from 127.0.0.1 ( 192.168.1.5 )
      header: flags: bcast=No rec_avail=Yes rec_des=Yes trunc=No auth=Yes
      header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0
  internal_resolve_name: looking up WORKGROUP#1c (sitename (null))
  internal_resolve_name: returning 1 addresses: 192.168.1.5:0
  namecache_store: storing 1 address for WORKGROUP#1c: 192.168.1.5:0
  name_resolve_bcast: Attempting broadcast lookup for name WORKGROUP<0x1c>
  name WORKGROUP#1C found.
  nmb packet from 127.0.0.1(137) header: id=3792 opcode=Query(0) response=Yes
  no entry for WORKGROUP#1C found.
  parse_nmb: packet id = 3792
  read_udp_socket: lastip 127.0.0.1 lastport 137 read: 62
  Received a packet of len 62 from (127.0.0.1) port 137
  remove_duplicate_addrs2: looking for duplicate address/port pairs
  resolve_hosts: not appropriate for name type <0x1c>
  resolve_lmhosts: Attempting lmhosts lookup for name WORKGROUP<0x1c>
  resolve_wins: Attempting wins lookup for name WORKGROUP<0x1c>
  resolve_wins: WINS server resolution selected and no WINS servers listed.
  Returning expired cache entry: key = NBT/WORKGROUP#1C, value = 192.168.1.5:0, timeout = Tue Jun  3 16:17:40 2008
  Returning valid cache entry: key = NBT/WORKGROUP#1C, value = 192.168.1.5:0, timeout = Tue Jun  3 16:17:40 2008
  Returning valid cache entry: key = NBT/WORKGROUP#1C, value = 192.168.1.5:0, timeout = Tue Jun  3 16:35:59 2008
  saf_fetch: failed to find server for "WORKGROUP" domain
  Sending a packet of len 50 to (127.255.255.255) on port 137
  socket option IPTOS_LOWDELAY = 0
  socket option IPTOS_THROUGHPUT = 0
  socket option SO_BROADCAST = 1
  socket option SO_KEEPALIVE = 0
  socket option SO_RCVBUF = 110592
  socket option SO_RCVLOWAT = 1
  socket option SO_RCVTIMEO = 0
  socket option SO_REUSEADDR = 1
  socket option SO_SNDBUF = 110592
  socket option SO_SNDLOWAT = 1
  socket option SO_SNDTIMEO = 0

#########################################################
cat log.winbindd-idmap

[2008/05/19 13:25:15, 1] nsswitch/idmap.c:idmap_init(377)
  Initializing idmap domains
[2008/05/19 13:25:15, 0] nsswitch/idmap.c:idmap_init(388)
  idmap_init: Ignoring domain WORKGROUP
[2008/05/20 13:35:30, 0] lib/util_sock.c:read_data(536)
  read_data: read failure for 2088. Error = Connection reset by peer
[2008/05/20 13:35:45, 1] nsswitch/idmap.c:idmap_init(377)
  Initializing idmap domains
[2008/05/20 13:35:45, 0] nsswitch/idmap.c:idmap_init(388)
  idmap_init: Ignoring domain WORKGROUP
[2008/05/20 13:42:34, 1] nsswitch/idmap.c:idmap_init(377)
  Initializing idmap domains
[2008/05/20 13:42:34, 0] nsswitch/idmap.c:idmap_init(388)
  idmap_init: Ignoring domain WORKGROUP
[2008/05/20 13:47:30, 0] nsswitch/winbindd_dual.c:async_request_timeout_handler(182)
  async_request_timeout_handler: child pid 6182 is not responding. Closing connection to it.
[2008/05/20 13:47:30, 1] nsswitch/winbindd_util.c:trustdom_recv(229)
  Could not receive trustdoms
[2008/05/24 22:01:42, 1] nsswitch/idmap.c:idmap_init(377)
  Initializing idmap domains
[2008/05/24 22:01:42, 0] nsswitch/idmap.c:idmap_init(388)
  idmap_init: Ignoring domain WORKGROUP
[2008/05/24 22:02:32, 1] nsswitch/idmap.c:idmap_init(377)
  Initializing idmap domains
[2008/05/24 22:02:32, 0] nsswitch/idmap.c:idmap_init(388)
  idmap_init: Ignoring domain WORKGROUP
[2008/05/24 22:02:37, 1] nsswitch/idmap.c:idmap_init(377)
  Initializing idmap domains
[2008/05/24 22:02:37, 0] nsswitch/idmap.c:idmap_init(388)
  idmap_init: Ignoring domain WORKGROUP
[2008/05/24 22:07:37, 0] nsswitch/winbindd_dual.c:async_request_timeout_handler(182)
  async_request_timeout_handler: child pid 9496 is not responding. Closing connection to it.
[2008/05/24 22:07:37, 1] nsswitch/winbindd_util.c:trustdom_recv(229)
  Could not receive trustdoms
[2008/05/31 18:54:56, 1] nsswitch/idmap.c:idmap_init(377)
  Initializing idmap domains
[2008/05/31 18:54:56, 0] nsswitch/idmap.c:idmap_init(388)
  idmap_init: Ignoring domain WORKGROUP
[2008/06/03 16:13:36, 10] lib/events.c:get_timed_events_timeout(295)
  timed_events_timeout: 295/322140
[2008/06/03 16:13:36, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:13:36, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:13:36, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:13:36, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:13:36, 5] nsswitch/idmap.c:smb_register_idmap_alloc(216)
  Successfully added idmap alloc backend 'ldap'
[2008/06/03 16:13:36, 5] nsswitch/idmap.c:smb_register_idmap(163)
  Successfully added idmap backend 'ldap'
[2008/06/03 16:13:36, 5] nsswitch/idmap.c:smb_register_idmap_alloc(216)
  Successfully added idmap alloc backend 'tdb'
[2008/06/03 16:13:36, 5] nsswitch/idmap.c:smb_register_idmap(163)
  Successfully added idmap backend 'tdb'
[2008/06/03 16:13:36, 5] nsswitch/idmap.c:smb_register_idmap(163)
  Successfully added idmap backend 'passdb'
[2008/06/03 16:13:36, 5] nsswitch/idmap.c:smb_register_idmap(163)
  Successfully added idmap backend 'nss'
[2008/06/03 16:13:36, 1] nsswitch/idmap.c:idmap_init(377)
  Initializing idmap domains
[2008/06/03 16:13:36, 0] nsswitch/idmap.c:idmap_init(388)
  idmap_init: Ignoring domain WORKGROUP
[2008/06/03 16:13:36, 10] nsswitch/idmap.c:idmap_init(627)
  Domain WORKGROUP - Backend passdb - not default - readonly
[2008/06/03 16:13:36, 3] nsswitch/idmap.c:idmap_init(657)
  Initializing idmap alloc module
[2008/06/03 16:13:36, 10] nsswitch/idmap_tdb.c:idmap_tdb_open_db(261)
  Opening tdbfile /var/lib/samba/winbindd_idmap.tdb
[2008/06/03 16:13:36, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:13:36, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:13:36, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(68)
  Attempting to register passdb backend ldapsam
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(81)
  Successfully added passdb backend 'ldapsam'
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(68)
  Attempting to register passdb backend ldapsam_compat
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(81)
  Successfully added passdb backend 'ldapsam_compat'
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(68)
  Attempting to register passdb backend NDS_ldapsam
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(81)
  Successfully added passdb backend 'NDS_ldapsam'
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(68)
  Attempting to register passdb backend NDS_ldapsam_compat
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(81)
  Successfully added passdb backend 'NDS_ldapsam_compat'
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(68)
  Attempting to register passdb backend smbpasswd
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(81)
  Successfully added passdb backend 'smbpasswd'
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(68)
  Attempting to register passdb backend tdbsam
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:smb_register_passdb(81)
  Successfully added passdb backend 'tdbsam'
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:make_pdb_method_name(121)
  Attempting to find an passdb backend to match tdbsam (tdbsam)
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:make_pdb_method_name(142)
  Found pdb backend tdbsam
[2008/06/03 16:13:36, 5] passdb/pdb_interface.c:make_pdb_method_name(153)
  pdb backend tdbsam has a valid init
[2008/06/03 16:13:36, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:13:36, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:13:36, 10] lib/events.c:get_timed_events_timeout(295)
  timed_events_timeout: 295/121753
[2008/06/03 16:13:49, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:13:49, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:13:49, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:13:49, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:13:49, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:13:49, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:13:49, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:13:49, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:13:49, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:13:49, 10] lib/events.c:get_timed_events_timeout(295)
  timed_events_timeout: 282/712203
[2008/06/03 16:14:01, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:14:01, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:14:01, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:14:01, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:14:01, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:14:01, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:14:01, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:14:01, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:14:01, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:14:01, 10] lib/events.c:get_timed_events_timeout(295)
  timed_events_timeout: 270/102155
[2008/06/03 16:14:24, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:14:24, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:14:24, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:14:24, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:14:24, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:14:24, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:14:24, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:14:24, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:14:24, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:14:24, 10] lib/events.c:get_timed_events_timeout(295)
  timed_events_timeout: 247/484817
[2008/06/03 16:14:36, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:14:36, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:14:36, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:14:36, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:14:36, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:14:36, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:14:36, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:14:36, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:14:36, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:14:36, 10] lib/events.c:get_timed_events_timeout(295)
  timed_events_timeout: 234/874088
[2008/06/03 16:14:49, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:14:49, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:14:49, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:14:49, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:14:49, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:14:49, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:14:49, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:14:49, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:14:49, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:14:49, 10] lib/events.c:get_timed_events_timeout(295)
  timed_events_timeout: 222/263899
[2008/06/03 16:18:31, 10] lib/events.c:run_events(240)
  Running event "async_request_timeout" b811eee0
[2008/06/03 16:18:31, 0] nsswitch/winbindd_dual.c:async_request_timeout_handler(182)
  async_request_timeout_handler: child pid 29286 is not responding. Closing connection to it.
[2008/06/03 16:18:31, 10] lib/events.c:timed_event_destructor(66)
  Destroying timed event b811eee0 "async_request_timeout"
[2008/06/03 16:18:31, 5] nsswitch/winbindd_dual.c:async_reply_recv(264)
  Could not receive async reply from child pid 29286
[2008/06/03 16:18:31, 5] nsswitch/winbindd_util.c:init_child_recv(419)
  Received child initialization response for domain WORKGROUP
[2008/06/03 16:18:31, 3] nsswitch/winbindd_util.c:init_child_recv(422)
  Could not init child
[2008/06/03 16:18:31, 5] nsswitch/winbindd_dual.c:domain_init_recv(403)
  Domain init returned an error
[2008/06/03 16:18:31, 1] nsswitch/winbindd_util.c:trustdom_recv(229)
  Could not receive trustdoms
[2008/06/03 16:19:11, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 51
[2008/06/03 16:19:11, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_UID2SID
[2008/06/03 16:19:11, 3] nsswitch/winbindd_async.c:winbindd_dual_uid2sid(1514)
  [29284]: uid to sid 500
[2008/06/03 16:19:11, 10] nsswitch/idmap_util.c:idmap_uid_to_sid(37)
  uid = [500]
[2008/06/03 16:19:11, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(521)
  Returning expired cache entry: key = IDMAP/UID/500, value = IDMAP/SID/S-1-5-21-1203031067-1067078206-1052721417-2000, timeout = Tue Jun  3 16:13:30 2008
[2008/06/03 16:19:11, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:19:11, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:19:11, 4] passdb/pdb_tdb.c:tdbsam_open(869)
  tdbsam_open: successfully opened /var/lib/samba/private/passdb.tdb
[2008/06/03 16:19:11, 10] passdb/pdb_get_set.c:pdb_set_username(581)
  pdb_set_username: setting username cvail, was
[2008/06/03 16:19:11, 10] passdb/pdb_get_set.c:pdb_set_domain(604)
  pdb_set_domain: setting domain GEEKAZOIDNET, was
[2008/06/03 16:19:11, 10] passdb/pdb_get_set.c:pdb_set_nt_username(627)
  pdb_set_nt_username: setting nt username , was
[2008/06/03 16:19:11, 10] passdb/pdb_get_set.c:pdb_set_fullname(650)
  pdb_set_full_name: setting full name Chris Vail, was
[2008/06/03 16:19:11, 4] lib/substitute.c:automount_server(407)
  Home server: dumbee
[2008/06/03 16:19:11, 10] passdb/pdb_get_set.c:pdb_set_homedir(743)
  pdb_set_homedir: setting home dir \\dumbee\cvail, was
[2008/06/03 16:19:11, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(719)
  pdb_set_dir_drive: setting dir drive R:, was NULL
[2008/06/03 16:19:11, 10] passdb/pdb_get_set.c:pdb_set_logon_script(673)
  pdb_set_logon_script: setting logon script %u.bat, was
[2008/06/03 16:19:11, 10] passdb/pdb_get_set.c:pdb_set_profile_path(696)
  pdb_set_profile_path: setting profile path \\dumbee\Profiles\%u, was
[2008/06/03 16:19:11, 10] passdb/pdb_get_set.c:pdb_set_workstations(786)
  pdb_set_workstations: setting workstations , was
[2008/06/03 16:19:11, 10] lib/privileges.c:grant_privilege(572)
  grant_privilege: S-1-1-0
  original privilege mask:
  SE_PRIV  0x0 0x0 0x0 0x0
  new privilege mask:
  SE_PRIV  0x0 0x0 0x0 0x0
[2008/06/03 16:19:11, 10] lib/privileges.c:grant_privilege(572)
  grant_privilege: S-1-5-32-548
  original privilege mask:
  SE_PRIV  0x0 0x0 0x0 0x0
  new privilege mask:
  SE_PRIV  0x0 0x0 0x0 0x0
[2008/06/03 16:19:11, 10] lib/privileges.c:grant_privilege(572)
  grant_privilege: S-1-5-32-549
  original privilege mask:
  SE_PRIV  0x0 0x0 0x0 0x0
  new privilege mask:
  SE_PRIV  0x0 0x0 0x0 0x0
[2008/06/03 16:19:11, 10] lib/privileges.c:grant_privilege(572)
  grant_privilege: S-1-5-32-550
  original privilege mask:
  SE_PRIV  0x0 0x0 0x0 0x0
  new privilege mask:
  SE_PRIV  0x0 0x0 0x0 0x0
[2008/06/03 16:19:11, 10] lib/privileges.c:grant_privilege(572)
  grant_privilege: S-1-5-32-551
  original privilege mask:
  SE_PRIV  0x0 0x0 0x0 0x0
  new privilege mask:
  SE_PRIV  0x0 0x0 0x0 0x0
[2008/06/03 16:19:11, 10] lib/privileges.c:grant_privilege(572)
  grant_privilege: S-1-5-32-544
  original privilege mask:
  SE_PRIV  0xff0 0x0 0x0 0x0
  new privilege mask:
  SE_PRIV  0xff0 0x0 0x0 0x0
[2008/06/03 16:19:11, 10] lib/account_pol.c:account_policy_get(294)
  account_policy_get: name: password history, val: 0
[2008/06/03 16:19:11, 10] passdb/pdb_get_set.c:pdb_set_user_sid(510)
  pdb_set_user_sid: setting user sid S-1-5-21-1203031067-1067078206-1052721417-2000
[2008/06/03 16:19:11, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(73)
  pdb_set_user_sid_from_rid:
  setting user sid S-1-5-21-1203031067-1067078206-1052721417-2000 from rid 2000
[2008/06/03 16:19:11, 8] passdb/pdb_tdb.c:tdbsam_close(882)
  tdbsam_close: Reference count is now 0.
[2008/06/03 16:19:11, 10] nsswitch/idmap_cache.c:idmap_cache_set(151)
  Adding cache entry with key = IDMAP/SID/S-1-5-21-1203031067-1067078206-1052721417-2000; value =   1212528851/IDMAP/UID/500 and timeout = Tue Jun  3 16:34:11 2008
   (900 seconds ahead)
[2008/06/03 16:19:11, 10] nsswitch/idmap_cache.c:idmap_cache_set(176)
  Adding cache entry with key = IDMAP/UID/500; value =   1212528851/IDMAP/SID/S-1-5-21-1203031067-1067078206-1052721417-2000 and timeout = Tue Jun  3 16:34:11 2008
   (900 seconds ahead)
[2008/06/03 16:19:11, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:19:11, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:19:11, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:19:11, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:19:11, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:19:11, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:19:11, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:19:11, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:19:11, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:19:11, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:19:12, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:19:12, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:19:12, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:19:12, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:19:12, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:19:12, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:19:12, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:19:12, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:19:12, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:19:12, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:19:12, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:19:12, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:19:12, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:19:12, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:19:12, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:19:12, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:19:12, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:19:12, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:19:12, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:19:12, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:19:12, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:19:12, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:19:12, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:19:12, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:19:12, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:19:12, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:19:12, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:24:59, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:24:59, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:24:59, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:24:59, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:24:59, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:24:59, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:24:59, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:24:59, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:24:59, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:25:12, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:25:12, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:25:12, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:25:12, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:25:12, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:25:12, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:25:12, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:25:12, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:25:12, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:25:25, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:25:25, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:25:25, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:25:25, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:25:25, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:25:25, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:25:25, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:25:25, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:25:25, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:35:35, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:35:35, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:35:35, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:35:35, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:35:35, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:35:35, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:35:35, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:35:35, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:35:35, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:35:47, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:35:47, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:35:47, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:35:47, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:35:47, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:35:47, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:35:47, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:35:47, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:35:47, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:36:00, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:36:00, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:36:00, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:36:00, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:36:00, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:36:00, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:36:00, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:36:00, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:36:00, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:38:16, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:38:16, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:38:16, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:38:16, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:38:16, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:38:16, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:38:16, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:38:16, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:38:16, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:38:16, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:38:16, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:38:16, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:38:16, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:38:16, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:38:16, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:38:16, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:38:16, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:38:16, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:46:10, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:46:10, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:46:10, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:46:10, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:46:10, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:46:10, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:46:10, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:46:10, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:46:10, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:46:23, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:46:23, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:46:23, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:46:23, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:46:23, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:46:23, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:46:23, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:46:23, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:46:23, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240
[2008/06/03 16:46:35, 4] nsswitch/winbindd_dual.c:fork_domain_child(1065)
  child daemon request 52
[2008/06/03 16:46:35, 10] nsswitch/winbindd_dual.c:child_process_request(480)
  process_request: request fn DUAL_GID2SID
[2008/06/03 16:46:35, 3] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1571)
  [29284]: gid 500 to sid
[2008/06/03 16:46:35, 10] nsswitch/idmap_util.c:idmap_gid_to_sid(71)
  gid = [500]
[2008/06/03 16:46:35, 10] nsswitch/idmap_cache.c:idmap_cache_map_id(499)
  Cache entry with key = IDMAP/GID/500 couldn't be found
[2008/06/03 16:46:35, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1029)
  Query backends to map ids->sids
[2008/06/03 16:46:35, 10] nsswitch/idmap.c:idmap_backends_unixids_to_sids(1041)
  Query sids from domain WORKGROUP
[2008/06/03 16:46:35, 10] nsswitch/winbindd_async.c:winbindd_dual_gid2sid(1580)
  [29284]: retrieved sid: S-1-22-2-500
[2008/06/03 16:46:35, 10] nsswitch/winbindd_cache.c:cache_store_response(2260)
  Storing response for pid 29289, len 3240

[tilolevante]

Hi,

I had just a similar problem and solved it by removing all samba databases:

  cd /var/lib/samba/
  /etc/init.d/samba stop
  /etc/init.d/winbind stop
  mkdir x
  mv * x
  /etc/init.d/samba start
  /etc/init.d/winbind start
  net join domain
  /etc/init.d/samba stop
  /etc/init.d/winbind stop
  /etc/init.d/winbind start
  /etc/init.d/samba start
  wbinfo -t
  -> success

maybe it help you

tilo

[Geekasaurus]

Thanks for the assistance.  It did not solve the problem.  I'll keep working on it and see if I can figure it out.  


Chris

tilolevante wrote

Hi,

I had just a similar problem and solved it by removing all samba databases:

  cd /var/lib/samba/
  /etc/init.d/samba stop
  /etc/init.d/winbind stop
  mkdir x
  mv * x
  /etc/init.d/samba start
  /etc/init.d/winbind start
  net join domain
  /etc/init.d/samba stop
  /etc/init.d/winbind stop
  /etc/init.d/winbind start
  /etc/init.d/samba start
  wbinfo -t
  -> success

maybe it help you

tilo