[2008/01/16 12:05:54, 5] lib/debug.c:debug_dump_status(391) INFO: Current debug levels: all: True/10 tdb: False/0 printdrivers: False/0 lanman: False/0 smb: False/0 rpc_parse: False/0 rpc_srv: False/0 rpc_cli: False/0 passdb: False/0 sam: False/0 auth: False/0 winbind: False/0 vfs: False/0 idmap: False/0 quota: False/0 acls: False/0 locking: False/0 msdfs: False/0 dmapi: False/0 registry: False/0 [2008/01/16 12:05:54, 3] param/loadparm.c:lp_load(5612) lp_load: refreshing parameters [2008/01/16 12:05:54, 3] param/loadparm.c:init_globals(1457) Initialising global parameters [2008/01/16 12:05:54, 3] param/params.c:pm_process(571) params.c:pm_process() - Processing configuration file "/usr/local/samba/lib/smb.conf" [2008/01/16 12:05:54, 3] param/loadparm.c:do_section(4333) Processing section "[global]" doing parameter log level = 10 doing parameter workgroup = S32IP6 doing parameter server string = IPv6 Samba Server doing parameter security = ads doing parameter log file = /usr/local/samba/var/log.%m doing parameter max log size = 50 doing parameter realm = S32IP6.COM doing parameter dns proxy = no doing parameter idmap uid = 10000-20000 doing parameter idmap gid = 10000-20000 doing parameter winbind use default domain = yes doing parameter winbind nested groups = yes [2008/01/16 12:05:54, 4] param/loadparm.c:lp_load(5643) pm_process() returned Yes [2008/01/16 12:05:54, 7] param/loadparm.c:lp_servicenumber(5781) lp_servicenumber: couldn't find homes [2008/01/16 12:05:54, 10] param/loadparm.c:set_server_role(4889) set_server_role: role = ROLE_DOMAIN_MEMBER [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(104) Attempting to register new charset UCS-2LE [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(112) Registered charset UCS-2LE [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(104) Attempting to register new charset UTF-16LE [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(112) Registered charset UTF-16LE [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(104) Attempting to register new charset UCS-2BE [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(112) Registered charset UCS-2BE [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(104) Attempting to register new charset UTF-16BE [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(112) Registered charset UTF-16BE [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(104) Attempting to register new charset UTF8 [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(112) Registered charset UTF8 [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(104) Attempting to register new charset UTF-8 [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(112) Registered charset UTF-8 [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(104) Attempting to register new charset ASCII [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(112) Registered charset ASCII [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(104) Attempting to register new charset 646 [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(112) Registered charset 646 [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(104) Attempting to register new charset ISO-8859-1 [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(112) Registered charset ISO-8859-1 [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(104) Attempting to register new charset UCS2-HEX [2008/01/16 12:05:54, 5] lib/iconv.c:smb_register_charset(112) Registered charset UCS2-HEX [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/charcnv.c:charset_name(81) Substituting charset 'UTF-8' for LOCALE [2008/01/16 12:05:54, 5] lib/util.c:init_names(276) Netbios name list:- my_netbios_names[0]="FEDORA6S4" [2008/01/16 12:05:54, 2] lib/interface.c:add_interface(334) added interface eth0 ip=fe80::20c:29ff:fef1:925b%eth0 bcast=fe80::ffff:ffff:ffff:ffff%eth0 netmask=ffff:ffff:ffff:ffff:: [2008/01/16 12:05:54, 2] lib/interface.c:add_interface(334) added interface eth0 ip=192.168.108.132 bcast=192.168.108.255 netmask=255.255.255.0 [2008/01/16 12:05:54, 5] lib/gencache.c:gencache_init(62) Opening cache file at /usr/local/samba/var/locks/gencache.tdb [2008/01/16 12:05:54, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = AD_SITENAME/DOMAIN/S32IP6.COM, value = Default-First-Site-Name, timeout = Tue Jan 19 03:14:07 2038 [2008/01/16 12:05:54, 5] libads/dns.c:sitename_fetch(791) sitename_fetch: Returning sitename for S32IP6.COM: "Default-First-Site-Name" [2008/01/16 12:05:54, 4] libsmb/namequery_dc.c:ads_dc_name(73) ads_dc_name: domain=S32IP6 [2008/01/16 12:05:54, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = AD_SITENAME/DOMAIN/S32IP6.COM, value = Default-First-Site-Name, timeout = Tue Jan 19 03:14:07 2038 [2008/01/16 12:05:54, 5] libads/dns.c:sitename_fetch(791) sitename_fetch: Returning sitename for S32IP6.COM: "Default-First-Site-Name" [2008/01/16 12:05:54, 6] libads/ldap.c:ads_find_dc(299) ads_find_dc: looking for realm 'S32IP6.COM' [2008/01/16 12:05:54, 8] libsmb/namequery.c:get_sorted_dc_list(1949) get_sorted_dc_list: attempting lookup for name S32IP6.COM (sitename Default-First-Site-Name) using [ads] [2008/01/16 12:05:54, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = SAF/DOMAIN/S32IP6.COM, value = 192.168.108.2, timeout = Wed Jan 16 12:17:12 2008 [2008/01/16 12:05:54, 5] libsmb/namequery.c:saf_fetch(138) saf_fetch: Returning "192.168.108.2" for "S32IP6.COM" domain [2008/01/16 12:05:54, 3] libsmb/namequery.c:get_dc_list(1779) get_dc_list: preferred server list: "192.168.108.2, *" [2008/01/16 12:05:54, 10] libsmb/namequery.c:internal_resolve_name(1412) internal_resolve_name: looking up S32IP6.COM#1c (sitename Default-First-Site-Name) [2008/01/16 12:05:54, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = NBT/S32IP6.COM#1C, value = 192.168.108.2:389,[3000::2]:389, timeout = Wed Jan 16 12:13:12 2008 [2008/01/16 12:05:54, 5] libsmb/namecache.c:namecache_fetch(233) name S32IP6.COM#1C found. [2008/01/16 12:05:54, 8] libsmb/namequery.c:get_dc_list(1800) Adding 2 DC's from auto lookup [2008/01/16 12:05:54, 10] libsmb/namequery.c:remove_duplicate_addrs2(520) remove_duplicate_addrs2: looking for duplicate address/port pairs [2008/01/16 12:05:54, 4] libsmb/namequery.c:get_dc_list(1913) get_dc_list: returning 2 ip addresses in an ordered list [2008/01/16 12:05:54, 4] libsmb/namequery.c:get_dc_list(1914) get_dc_list: 192.168.108.2:389 3000::2:389 [2008/01/16 12:05:54, 5] libads/ldap.c:ads_try_connect(179) ads_try_connect: sending CLDAP request to 192.168.108.2 (realm: S32IP6.COM) [2008/01/16 12:05:54, 10] libads/dns.c:sitename_store(752) sitename_store: realm = [S32IP6.COM], sitename = [Default-First-Site-Name], expire = [2147483647] [2008/01/16 12:05:54, 10] lib/gencache.c:gencache_set(138) Adding cache entry with key = AD_SITENAME/DOMAIN/S32IP6.COM; value = Default-First-Site-Name and timeout = Tue Jan 19 03:14:07 2038 (946998493 seconds ahead) [2008/01/16 12:05:54, 3] libads/ldap.c:ads_connect(403) Connected to LDAP server 192.168.108.2 [2008/01/16 12:05:54, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = AD_SITENAME/DOMAIN/S32IP6.COM, value = Default-First-Site-Name, timeout = Tue Jan 19 03:14:07 2038 [2008/01/16 12:05:54, 5] libads/dns.c:sitename_fetch(791) sitename_fetch: Returning sitename for S32IP6.COM: "Default-First-Site-Name" [2008/01/16 12:05:54, 10] libads/ldap.c:ads_closest_dc(148) ads_closest_dc: ADS_CLOSEST flag set [2008/01/16 12:05:54, 10] libads/kerberos.c:create_local_private_krb5_conf_for_domain(752) create_local_private_krb5_conf_for_domain: fname = /usr/local/samba/var/locks/smb_krb5/krb5.conf.S32IP6, realm = S32IP6.COM, domain = S32IP6 [2008/01/16 12:05:54, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = SAF/DOMAIN/S32IP6.COM, value = 192.168.108.2, timeout = Wed Jan 16 12:17:12 2008 [2008/01/16 12:05:54, 5] libsmb/namequery.c:saf_fetch(138) saf_fetch: Returning "192.168.108.2" for "S32IP6.COM" domain [2008/01/16 12:05:54, 3] libsmb/namequery.c:get_dc_list(1779) get_dc_list: preferred server list: "192.168.108.2, *" [2008/01/16 12:05:54, 10] libsmb/namequery.c:internal_resolve_name(1412) internal_resolve_name: looking up S32IP6.COM#1c (sitename Default-First-Site-Name) [2008/01/16 12:05:54, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = NBT/S32IP6.COM#1C, value = 192.168.108.2:389,[3000::2]:389, timeout = Wed Jan 16 12:13:12 2008 [2008/01/16 12:05:54, 5] libsmb/namecache.c:namecache_fetch(233) name S32IP6.COM#1C found. [2008/01/16 12:05:54, 8] libsmb/namequery.c:get_dc_list(1800) Adding 2 DC's from auto lookup [2008/01/16 12:05:54, 10] libsmb/namequery.c:remove_duplicate_addrs2(520) remove_duplicate_addrs2: looking for duplicate address/port pairs [2008/01/16 12:05:54, 4] libsmb/namequery.c:get_dc_list(1913) get_dc_list: returning 2 ip addresses in an ordered list [2008/01/16 12:05:54, 4] libsmb/namequery.c:get_dc_list(1914) get_dc_list: 192.168.108.2:389 3000::2:389 [2008/01/16 12:05:54, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = SAF/DOMAIN/S32IP6.COM, value = 192.168.108.2, timeout = Wed Jan 16 12:17:12 2008 [2008/01/16 12:05:54, 5] libsmb/namequery.c:saf_fetch(138) saf_fetch: Returning "192.168.108.2" for "S32IP6.COM" domain [2008/01/16 12:05:54, 3] libsmb/namequery.c:get_dc_list(1779) get_dc_list: preferred server list: "192.168.108.2, *" [2008/01/16 12:05:54, 10] libsmb/namequery.c:internal_resolve_name(1412) internal_resolve_name: looking up S32IP6.COM#1c (sitename (null)) [2008/01/16 12:05:54, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = NBT/S32IP6.COM#1C, value = 192.168.108.2:389,[3000::2]:389, timeout = Wed Jan 16 12:13:12 2008 [2008/01/16 12:05:54, 5] libsmb/namecache.c:namecache_fetch(233) name S32IP6.COM#1C found. [2008/01/16 12:05:54, 8] libsmb/namequery.c:get_dc_list(1800) Adding 2 DC's from auto lookup [2008/01/16 12:05:54, 10] libsmb/namequery.c:remove_duplicate_addrs2(520) remove_duplicate_addrs2: looking for duplicate address/port pairs [2008/01/16 12:05:54, 4] libsmb/namequery.c:get_dc_list(1913) get_dc_list: returning 2 ip addresses in an ordered list [2008/01/16 12:05:54, 4] libsmb/namequery.c:get_dc_list(1914) get_dc_list: 192.168.108.2:389 3000::2:389 [2008/01/16 12:05:54, 10] libads/kerberos.c:get_kdc_ip_string(701) get_kdc_ip_string: Returning kdc = 192.168.108.2 kdc = [3000::2] kdc = [3000::2] [2008/01/16 12:05:54, 5] libads/kerberos.c:create_local_private_krb5_conf_for_domain(821) create_local_private_krb5_conf_for_domain: wrote file /usr/local/samba/var/locks/smb_krb5/krb5.conf.S32IP6 with realm S32IP6.COM KDC = 192.168.108.2 [2008/01/16 12:05:54, 4] libsmb/namequery_dc.c:ads_dc_name(143) ads_dc_name: using server='W2008RC1.S32IP6.COM' IP=192.168.108.2 [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = AD_SITENAME/DOMAIN/S32IP6.COM, value = Default-First-Site-Name, timeout = Tue Jan 19 03:14:07 2038 [2008/01/16 12:05:57, 5] libads/dns.c:sitename_fetch(791) sitename_fetch: Returning sitename for S32IP6.COM: "Default-First-Site-Name" [2008/01/16 12:05:57, 6] libads/ldap.c:ads_find_dc(299) ads_find_dc: looking for realm 'S32IP6.COM' [2008/01/16 12:05:57, 8] libsmb/namequery.c:get_sorted_dc_list(1949) get_sorted_dc_list: attempting lookup for name S32IP6.COM (sitename Default-First-Site-Name) using [ads] [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = SAF/DOMAIN/S32IP6.COM, value = 192.168.108.2, timeout = Wed Jan 16 12:17:12 2008 [2008/01/16 12:05:57, 5] libsmb/namequery.c:saf_fetch(138) saf_fetch: Returning "192.168.108.2" for "S32IP6.COM" domain [2008/01/16 12:05:57, 3] libsmb/namequery.c:get_dc_list(1779) get_dc_list: preferred server list: "192.168.108.2, *" [2008/01/16 12:05:57, 10] libsmb/namequery.c:internal_resolve_name(1412) internal_resolve_name: looking up S32IP6.COM#1c (sitename Default-First-Site-Name) [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = NBT/S32IP6.COM#1C, value = 192.168.108.2:389,[3000::2]:389, timeout = Wed Jan 16 12:13:12 2008 [2008/01/16 12:05:57, 5] libsmb/namecache.c:namecache_fetch(233) name S32IP6.COM#1C found. [2008/01/16 12:05:57, 8] libsmb/namequery.c:get_dc_list(1800) Adding 2 DC's from auto lookup [2008/01/16 12:05:57, 10] libsmb/namequery.c:remove_duplicate_addrs2(520) remove_duplicate_addrs2: looking for duplicate address/port pairs [2008/01/16 12:05:57, 4] libsmb/namequery.c:get_dc_list(1913) get_dc_list: returning 2 ip addresses in an ordered list [2008/01/16 12:05:57, 4] libsmb/namequery.c:get_dc_list(1914) get_dc_list: 192.168.108.2:389 3000::2:389 [2008/01/16 12:05:57, 5] libads/ldap.c:ads_try_connect(179) ads_try_connect: sending CLDAP request to 192.168.108.2 (realm: S32IP6.COM) [2008/01/16 12:05:57, 10] libads/dns.c:sitename_store(752) sitename_store: realm = [S32IP6.COM], sitename = [Default-First-Site-Name], expire = [2147483647] [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_set(138) Adding cache entry with key = AD_SITENAME/DOMAIN/S32IP6.COM; value = Default-First-Site-Name and timeout = Tue Jan 19 03:14:07 2038 (946998490 seconds ahead) [2008/01/16 12:05:57, 3] libads/ldap.c:ads_connect(403) Connected to LDAP server 192.168.108.2 [2008/01/16 12:05:57, 10] libads/ldap.c:ads_closest_dc(148) ads_closest_dc: ADS_CLOSEST flag set [2008/01/16 12:05:57, 10] libsmb/namequery.c:saf_store(75) saf_store: domain = [S32IP6], server = [192.168.108.2], expire = [1200486057] [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_set(138) Adding cache entry with key = SAF/DOMAIN/S32IP6; value = 192.168.108.2 and timeout = Wed Jan 16 12:20:57 2008 (900 seconds ahead) [2008/01/16 12:05:57, 10] libsmb/namequery.c:saf_store(75) saf_store: domain = [S32IP6.COM], server = [192.168.108.2], expire = [1200486057] [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_set(138) Adding cache entry with key = SAF/DOMAIN/S32IP6.COM; value = 192.168.108.2 and timeout = Wed Jan 16 12:20:57 2008 (900 seconds ahead) [2008/01/16 12:05:57, 4] libads/ldap.c:ads_current_time(2539) time offset is -5 seconds [2008/01/16 12:05:57, 4] libads/sasl.c:ads_sasl_bind(1110) Found SASL mechanism GSS-SPNEGO [2008/01/16 12:05:57, 3] libads/sasl.c:ads_sasl_spnego_bind(778) ads_sasl_spnego_bind: got OID=1 2 840 48018 1 2 2 [2008/01/16 12:05:57, 3] libads/sasl.c:ads_sasl_spnego_bind(778) ads_sasl_spnego_bind: got OID=1 2 840 113554 1 2 2 [2008/01/16 12:05:57, 3] libads/sasl.c:ads_sasl_spnego_bind(778) ads_sasl_spnego_bind: got OID=1 2 840 113554 1 2 2 3 [2008/01/16 12:05:57, 3] libads/sasl.c:ads_sasl_spnego_bind(778) ads_sasl_spnego_bind: got OID=1 3 6 1 4 1 311 2 2 10 [2008/01/16 12:05:57, 3] libads/sasl.c:ads_sasl_spnego_bind(787) ads_sasl_spnego_bind: got server principal name = not_defined_in_RFC4178@please_ignore [2008/01/16 12:05:57, 3] libsmb/clikrb5.c:ads_krb5_mk_req(657) ads_krb5_mk_req: krb5_cc_get_principal failed (No credentials cache found) [2008/01/16 12:05:57, 10] libads/sasl.c:ads_sasl_spnego_bind(808) ads_sasl_spnego_krb5_bind failed with: No credentials cache found, calling kinit [2008/01/16 12:05:57, 10] libads/kerberos.c:kerberos_kinit_password_ext(214) kerberos_kinit_password: using [MEMORY:net_ads] as ccache and config [/usr/local/samba/var/locks/smb_krb5/krb5.conf.S32IP6] [2008/01/16 12:05:57, 3] libsmb/clikrb5.c:ads_cleanup_expired_creds(592) ads_cleanup_expired_creds: Ticket in ccache[MEMORY:net_ads] expiration Wed, 16 Jan 2008 22:05:52 GMT [2008/01/16 12:05:57, 10] libsmb/clikrb5.c:ads_krb5_mk_req(688) ads_krb5_mk_req: Ticket (ldap/w2008rc1.s32ip6.com@S32IP6.COM) in ccache (MEMORY:net_ads) is valid until: (Wed, 16 Jan 2008 22:05:52 GMT - 1200521152) [2008/01/16 12:05:57, 10] libsmb/clikrb5.c:get_krb5_smb_session_key(799) Got KRB5 session key of length 16 [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_del(168) Deleting cache entry (key = NBT/S32IP6.COM#1C) [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_del(168) Deleting cache entry (key = NBT/S32IP6#1C) [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = AD_SITENAME/DOMAIN/S32IP6.COM, value = Default-First-Site-Name, timeout = Tue Jan 19 03:14:07 2038 [2008/01/16 12:05:57, 5] libads/dns.c:sitename_fetch(791) sitename_fetch: Returning sitename for S32IP6.COM: "Default-First-Site-Name" [2008/01/16 12:05:57, 6] libads/ldap.c:ads_find_dc(299) ads_find_dc: looking for realm 'S32IP6.COM' [2008/01/16 12:05:57, 8] libsmb/namequery.c:get_sorted_dc_list(1949) get_sorted_dc_list: attempting lookup for name S32IP6.COM (sitename Default-First-Site-Name) using [ads] [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_get(219) Returning valid cache entry: key = SAF/DOMAIN/S32IP6.COM, value = 192.168.108.2, timeout = Wed Jan 16 12:20:57 2008 [2008/01/16 12:05:57, 5] libsmb/namequery.c:saf_fetch(138) saf_fetch: Returning "192.168.108.2" for "S32IP6.COM" domain [2008/01/16 12:05:57, 3] libsmb/namequery.c:get_dc_list(1779) get_dc_list: preferred server list: "192.168.108.2, *" [2008/01/16 12:05:57, 10] libsmb/namequery.c:internal_resolve_name(1412) internal_resolve_name: looking up S32IP6.COM#1c (sitename Default-First-Site-Name) [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_get(205) Cache entry with key = NBT/S32IP6.COM#1C couldn't be found [2008/01/16 12:05:57, 5] libsmb/namecache.c:namecache_fetch(229) no entry for S32IP6.COM#1C found. [2008/01/16 12:05:57, 5] libsmb/namequery.c:resolve_ads(1306) resolve_ads: Attempting to resolve DCs for S32IP6.COM using DNS [2008/01/16 12:05:57, 4] libads/dns.c:ads_dns_lookup_srv(406) ads_dns_lookup_srv: 1 records returned in the answer section. [2008/01/16 12:05:57, 10] libsmb/namequery.c:remove_duplicate_addrs2(520) remove_duplicate_addrs2: looking for duplicate address/port pairs [2008/01/16 12:05:57, 5] libsmb/namecache.c:namecache_store(143) namecache_store: storing 2 addresses for S32IP6.COM#1c: 192.168.108.2,[3000::2] [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_set(138) Adding cache entry with key = NBT/S32IP6.COM#1C; value = 192.168.108.2:389,[3000::2]:389 and timeout = Wed Jan 16 12:16:57 2008 (660 seconds ahead) [2008/01/16 12:05:57, 10] libsmb/namequery.c:internal_resolve_name(1563) internal_resolve_name: returning 2 addresses: 192.168.108.2:389 3000::2:389 [2008/01/16 12:05:57, 8] libsmb/namequery.c:get_dc_list(1800) Adding 2 DC's from auto lookup [2008/01/16 12:05:57, 10] libsmb/namequery.c:remove_duplicate_addrs2(520) remove_duplicate_addrs2: looking for duplicate address/port pairs [2008/01/16 12:05:57, 4] libsmb/namequery.c:get_dc_list(1913) get_dc_list: returning 2 ip addresses in an ordered list [2008/01/16 12:05:57, 4] libsmb/namequery.c:get_dc_list(1914) get_dc_list: 192.168.108.2:389 3000::2:389 [2008/01/16 12:05:57, 5] libads/ldap.c:ads_try_connect(179) ads_try_connect: sending CLDAP request to 192.168.108.2 (realm: S32IP6.COM) [2008/01/16 12:05:57, 10] libads/dns.c:sitename_store(752) sitename_store: realm = [S32IP6.COM], sitename = [Default-First-Site-Name], expire = [2147483647] [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_set(138) Adding cache entry with key = AD_SITENAME/DOMAIN/S32IP6.COM; value = Default-First-Site-Name and timeout = Tue Jan 19 03:14:07 2038 (946998490 seconds ahead) [2008/01/16 12:05:57, 3] libads/ldap.c:ads_connect(403) Connected to LDAP server 192.168.108.2 [2008/01/16 12:05:57, 10] libads/ldap.c:ads_closest_dc(148) ads_closest_dc: ADS_CLOSEST flag set [2008/01/16 12:05:57, 10] libsmb/namequery.c:saf_store(75) saf_store: domain = [S32IP6], server = [192.168.108.2], expire = [1200486057] [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_set(138) Adding cache entry with key = SAF/DOMAIN/S32IP6; value = 192.168.108.2 and timeout = Wed Jan 16 12:20:57 2008 (900 seconds ahead) [2008/01/16 12:05:57, 10] libsmb/namequery.c:saf_store(75) saf_store: domain = [S32IP6.COM], server = [192.168.108.2], expire = [1200486057] [2008/01/16 12:05:57, 10] lib/gencache.c:gencache_set(138) Adding cache entry with key = SAF/DOMAIN/S32IP6.COM; value = 192.168.108.2 and timeout = Wed Jan 16 12:20:57 2008 (900 seconds ahead) [2008/01/16 12:05:57, 4] libads/ldap.c:ads_current_time(2539) time offset is -5 seconds [2008/01/16 12:05:57, 4] libads/sasl.c:ads_sasl_bind(1110) Found SASL mechanism GSS-SPNEGO [2008/01/16 12:05:57, 3] libads/sasl.c:ads_sasl_spnego_bind(778) ads_sasl_spnego_bind: got OID=1 2 840 48018 1 2 2 [2008/01/16 12:05:57, 3] libads/sasl.c:ads_sasl_spnego_bind(778) ads_sasl_spnego_bind: got OID=1 2 840 113554 1 2 2 [2008/01/16 12:05:57, 3] libads/sasl.c:ads_sasl_spnego_bind(778) ads_sasl_spnego_bind: got OID=1 2 840 113554 1 2 2 3 [2008/01/16 12:05:57, 3] libads/sasl.c:ads_sasl_spnego_bind(778) ads_sasl_spnego_bind: got OID=1 3 6 1 4 1 311 2 2 10 [2008/01/16 12:05:57, 3] libads/sasl.c:ads_sasl_spnego_bind(787) ads_sasl_spnego_bind: got server principal name = not_defined_in_RFC4178@please_ignore [2008/01/16 12:05:57, 3] libsmb/clikrb5.c:ads_cleanup_expired_creds(592) ads_cleanup_expired_creds: Ticket in ccache[MEMORY:net_ads] expiration Wed, 16 Jan 2008 22:05:52 GMT [2008/01/16 12:05:57, 10] libsmb/clikrb5.c:ads_krb5_mk_req(688) ads_krb5_mk_req: Ticket (ldap/w2008rc1.s32ip6.com@S32IP6.COM) in ccache (MEMORY:net_ads) is valid until: (Wed, 16 Jan 2008 22:05:52 GMT - 1200521152) [2008/01/16 12:05:57, 10] libsmb/clikrb5.c:get_krb5_smb_session_key(799) Got KRB5 session key of length 16 [2008/01/16 12:05:57, 3] libsmb/cliconnect.c:cli_start_connection(1525) Connecting to host=W2008RC1.s32ip6.com [2008/01/16 12:05:57, 3] lib/util_sock.c:open_socket_out(1443) Connecting to 192.168.108.2 at port 445 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option SO_KEEPALIVE = 0 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option SO_REUSEADDR = 0 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option SO_BROADCAST = 0 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option TCP_NODELAY = 1 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option TCP_KEEPCNT = 9 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option TCP_KEEPIDLE = 7200 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option TCP_KEEPINTVL = 75 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option IPTOS_LOWDELAY = 0 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option IPTOS_THROUGHPUT = 0 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option SO_SNDBUF = 16384 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option SO_RCVBUF = 87380 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option SO_SNDLOWAT = 1 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option SO_RCVLOWAT = 1 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option SO_SNDTIMEO = 0 [2008/01/16 12:05:57, 5] lib/util_sock.c:print_socket_options(764) socket option SO_RCVTIMEO = 0 [2008/01/16 12:05:57, 6] libsmb/clientgen.c:write_socket(255) write_socket(7,194) [2008/01/16 12:05:57, 6] libsmb/clientgen.c:write_socket(258) write_socket(7,194) wrote 194 [2008/01/16 12:05:57, 10] lib/util_sock.c:read_smb_length_return_keepalive(1147) got smb length of 193 [2008/01/16 12:05:57, 5] lib/util.c:show_msg(475) [2008/01/16 12:05:57, 5] lib/util.c:show_msg(485) size=193 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5705 smb_uid=0 smb_mid=1 smt_wct=17 smb_vwv[ 0]= 9 (0x9) smb_vwv[ 1]=12815 (0x320F) smb_vwv[ 2]= 256 (0x100) smb_vwv[ 3]= 1024 (0x400) smb_vwv[ 4]= 17 (0x11) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 256 (0x100) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]= 499 (0x1F3) smb_vwv[11]=29312 (0x7280) smb_vwv[12]=15935 (0x3E3F) smb_vwv[13]=14372 (0x3824) smb_vwv[14]=51288 (0xC858) smb_vwv[15]= 1 (0x1) smb_vwv[16]= 0 (0x0) smb_bcc=124 [2008/01/16 12:05:57, 10] lib/util.c:dump_data(2065) [000] 19 BB DE C8 06 65 AA 4E 9B AF B8 5F 9B 3D C4 80 .....e.N ..._.=.. [010] 60 6A 06 06 2B 06 01 05 05 02 A0 60 30 5E A0 30 `j..+... ...`0^.0 [020] 30 2E 06 09 2A 86 48 82 F7 12 01 02 02 06 09 2A 0...*.H. .......* [030] 86 48 86 F7 12 01 02 02 06 0A 2A 86 48 86 F7 12 .H...... ..*.H... [040] 01 02 02 03 06 0A 2B 06 01 04 01 82 37 02 02 0A ......+. ....7... [050] A3 2A 30 28 A0 26 1B 24 6E 6F 74 5F 64 65 66 69 .*0(.&.$ not_defi [060] 6E 65 64 5F 69 6E 5F 52 46 43 34 31 37 38 40 70 ned_in_R FC4178@p [070] 6C 65 61 73 65 5F 69 67 6E 6F 72 65 lease_ig nore [2008/01/16 12:05:57, 5] lib/util.c:show_msg(475) [2008/01/16 12:05:57, 5] lib/util.c:show_msg(485) size=193 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5705 smb_uid=0 smb_mid=1 smt_wct=17 smb_vwv[ 0]= 9 (0x9) smb_vwv[ 1]=12815 (0x320F) smb_vwv[ 2]= 256 (0x100) smb_vwv[ 3]= 1024 (0x400) smb_vwv[ 4]= 17 (0x11) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 256 (0x100) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]= 499 (0x1F3) smb_vwv[11]=29312 (0x7280) smb_vwv[12]=15935 (0x3E3F) smb_vwv[13]=14372 (0x3824) smb_vwv[14]=51288 (0xC858) smb_vwv[15]= 1 (0x1) smb_vwv[16]= 0 (0x0) smb_bcc=124 [2008/01/16 12:05:57, 10] lib/util.c:dump_data(2065) [000] 19 BB DE C8 06 65 AA 4E 9B AF B8 5F 9B 3D C4 80 .....e.N ..._.=.. [010] 60 6A 06 06 2B 06 01 05 05 02 A0 60 30 5E A0 30 `j..+... ...`0^.0 [020] 30 2E 06 09 2A 86 48 82 F7 12 01 02 02 06 09 2A 0...*.H. .......* [030] 86 48 86 F7 12 01 02 02 06 0A 2A 86 48 86 F7 12 .H...... ..*.H... [040] 01 02 02 03 06 0A 2B 06 01 04 01 82 37 02 02 0A ......+. ....7... [050] A3 2A 30 28 A0 26 1B 24 6E 6F 74 5F 64 65 66 69 .*0(.&.$ not_defi [060] 6E 65 64 5F 69 6E 5F 52 46 43 34 31 37 38 40 70 ned_in_R FC4178@p [070] 6C 65 61 73 65 5F 69 67 6E 6F 72 65 lease_ig nore [2008/01/16 12:05:57, 3] libsmb/cliconnect.c:cli_session_setup_spnego(790) Doing spnego session setup (blob length=124) [2008/01/16 12:05:57, 3] libsmb/cliconnect.c:cli_session_setup_spnego(815) got OID=1 2 840 48018 1 2 2 [2008/01/16 12:05:57, 3] libsmb/cliconnect.c:cli_session_setup_spnego(815) got OID=1 2 840 113554 1 2 2 [2008/01/16 12:05:57, 3] libsmb/cliconnect.c:cli_session_setup_spnego(815) got OID=1 2 840 113554 1 2 2 3 [2008/01/16 12:05:57, 3] libsmb/cliconnect.c:cli_session_setup_spnego(815) got OID=1 3 6 1 4 1 311 2 2 10 [2008/01/16 12:05:57, 3] libsmb/cliconnect.c:cli_session_setup_spnego(823) got principal=not_defined_in_RFC4178@please_ignore [2008/01/16 12:05:57, 10] libads/kerberos.c:kerberos_kinit_password_ext(214) kerberos_kinit_password: using [MEMORY:cliconnect] as ccache and config [/usr/local/samba/var/locks/smb_krb5/krb5.conf.S32IP6] [2008/01/16 12:05:57, 2] libsmb/cliconnect.c:cli_session_setup_kerberos(610) Doing kerberos session setup [2008/01/16 12:05:57, 1] libsmb/clikrb5.c:ads_krb5_mk_req(666) ads_krb5_mk_req: krb5_get_credentials failed for not_defined_in_RFC4178@please_ignore (Server not found in Kerberos database) [2008/01/16 12:05:57, 1] libsmb/cliconnect.c:cli_session_setup_kerberos(617) cli_session_setup_kerberos: spnego_gen_negTokenTarg failed: Server not found in Kerberos database [2008/01/16 12:05:57, 3] libsmb/cliconnect.c:cli_session_setup(958) SPNEGO login failed: Server not found in Kerberos database [2008/01/16 12:05:57, 1] libsmb/cliconnect.c:cli_full_connection(1625) failed session setup with NT_STATUS_INVALID_ACCOUNT_NAME [2008/01/16 12:05:57, 1] utils/net.c:connect_to_ipc_krb5(293) Cannot connect to server using kerberos. Error was NT_STATUS_INVALID_ACCOUNT_NAME [2008/01/16 12:05:57, 1] utils/net_ads.c:net_ads_join(1578) call of net_join_domain failed: Improperly formed account name [2008/01/16 12:05:57, 10] intl/lang_tdb.c:lang_tdb_init(134) lang_tdb_init: /usr/local/samba/lib/en_US.UTF-8.msg: No such file or directory [2008/01/16 12:05:57, 2] utils/net.c:main(1116) return code = -1