Attachment 3099 Details for Bug 5204 – net ads join -d 10 -U Administrator with IPv6

net ads join -d 10 -U Administrator with IPv6

netadsjoin-d10_trace_w2008_with_ipv6.txt (text/plain), 31.11 KB, created by David Holder on 2008-01-16 06:57:47 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: David Holder

Created: 2008-01-16 06:57:47 UTC

Size: 31.11 KB

patch

obsolete

>[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

Actions: View

Attachments on bug 5204: 3099 | 3101 | 3102 | 3103 | 3105