[2007/05/02 07:44:12, 4] nsswitch/winbindd_dual.c:fork_domain_child(809) child daemon request 42 [2007/05/02 07:44:12, 10] nsswitch/winbindd_dual.c:child_process_request(395) process_request: request fn DUAL_SID2UID [2007/05/02 07:44:12, 3] nsswitch/winbindd_async.c:winbindd_dual_sid2uid(201) [20754]: sid to uid S-1-5-21-1783225922-323520374-3920701801-1187 [2007/05/02 07:44:12, 10] sam/idmap_util.c:idmap_sid_to_uid(70) idmap_sid_to_uid: sid = [S-1-5-21-1783225922-323520374-3920701801-1187] [2007/05/02 07:44:12, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-1187 of type 0x1 [2007/05/02 07:44:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(187) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-1187 not found [2007/05/02 07:44:12, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-1187 of type 0x2 [2007/05/02 07:44:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(187) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-1187 not found [2007/05/02 07:44:12, 6] libads/ldap.c:ads_find_dc(224) ads_find_dc: looking for realm 'IPR.UNI-KARLSRUHE.DE' [2007/05/02 07:44:12, 8] libsmb/namequery.c:get_sorted_dc_list(1551) get_sorted_dc_list: attempting lookup using [ads] [2007/05/02 07:44:12, 10] lib/gencache.c:gencache_get(329) Cache entry with key = SAF/DOMAIN/IPR.UNI-KARLSRUHE.DE couldn't be found [2007/05/02 07:44:12, 5] libsmb/namequery.c:saf_fetch(105) saf_fetch: failed to find server for "IPR.UNI-KARLSRUHE.DE" domain [2007/05/02 07:44:12, 3] libsmb/namequery.c:get_dc_list(1426) get_dc_list: preferred server list: ", i60pdc.ipr.uni-karlsruhe.de" [2007/05/02 07:44:12, 10] libsmb/namequery.c:internal_resolve_name(1132) internal_resolve_name: looking up i60pdc.ipr.uni-karlsruhe.de#20 [2007/05/02 07:44:12, 10] lib/gencache.c:gencache_get(304) Returning valid cache entry: key = NBT/I60PDC.IPR.UNI-KARLSRUHE.DE#20, value = 141.3.80.198:0, timeout = Wed May 2 07:46:37 2007 [2007/05/02 07:44:12, 5] libsmb/namecache.c:namecache_fetch(201) name i60pdc.ipr.uni-karlsruhe.de#20 found. [2007/05/02 07:44:12, 10] libsmb/namequery.c:remove_duplicate_addrs2(408) remove_duplicate_addrs2: looking for duplicate address/port pairs [2007/05/02 07:44:12, 4] libsmb/namequery.c:get_dc_list(1529) get_dc_list: returning 1 ip addresses in an ordered list [2007/05/02 07:44:12, 4] libsmb/namequery.c:get_dc_list(1530) get_dc_list: 141.3.80.198:389 [2007/05/02 07:44:12, 5] libads/ldap.c:ads_try_connect(127) ads_try_connect: sending CLDAP request to 141.3.80.198 (realm: IPR.UNI-KARLSRUHE.DE) [2007/05/02 07:44:12, 10] libsmb/namequery.c:saf_store(71) saf_store: domain = [IPR-OFFICE], server = [141.3.80.198], expire = [1178085552] [2007/05/02 07:44:12, 10] lib/gencache.c:gencache_set(140) Adding cache entry with key = SAF/DOMAIN/IPR-OFFICE; value = 141.3.80.198 and timeout = Wed May 2 07:59:12 2007 (900 seconds ahead) [2007/05/02 07:44:12, 3] libads/ldap.c:ads_connect(287) Connected to LDAP server 141.3.80.198 [2007/05/02 07:44:12, 4] libads/ldap.c:ads_current_time(2296) time offset is -194 seconds [2007/05/02 07:44:12, 4] libads/sasl.c:ads_sasl_bind(468) Found SASL mechanism GSS-SPNEGO [2007/05/02 07:44:12, 3] libads/sasl.c:ads_sasl_spnego_bind(210) ads_sasl_spnego_bind: got OID=1 2 840 48018 1 2 2 [2007/05/02 07:44:12, 3] libads/sasl.c:ads_sasl_spnego_bind(210) ads_sasl_spnego_bind: got OID=1 2 840 113554 1 2 2 [2007/05/02 07:44:12, 3] libads/sasl.c:ads_sasl_spnego_bind(210) ads_sasl_spnego_bind: got OID=1 2 840 113554 1 2 2 3 [2007/05/02 07:44:12, 3] libads/sasl.c:ads_sasl_spnego_bind(210) ads_sasl_spnego_bind: got OID=1 3 6 1 4 1 311 2 2 10 [2007/05/02 07:44:12, 3] libads/sasl.c:ads_sasl_spnego_bind(219) ads_sasl_spnego_bind: got server principal name =i60pdc$@IPR.UNI-KARLSRUHE.DE [2007/05/02 07:44:20, 1] libsmb/clikrb5.c:ads_krb5_mk_req(560) ads_krb5_mk_req: krb5_get_credentials failed for i60pdc$@IPR.UNI-KARLSRUHE.DE (Ticket expired) [2007/05/02 07:44:20, 10] libads/kerberos.c:kerberos_kinit_password_ext(89) kerberos_kinit_password: using MEMORY:winbind_ccache as ccache [2007/05/02 07:44:44, 3] libsmb/clikrb5.c:ads_cleanup_expired_creds(488) ads_cleanup_expired_creds: Ticket in ccache[MEMORY:winbind_ccache] expiration Mi, 02 Mai 2007 07:51:06 CEST [2007/05/02 07:44:44, 10] libsmb/clikrb5.c:ads_krb5_mk_req(581) ads_krb5_mk_req: Ticket (i60pdc$@IPR.UNI-KARLSRUHE.DE) in ccache (MEMORY:winbind_ccache) is valid until: (Mi, 02 Mai 2007 07:51:06 CEST - 1178085066) [2007/05/02 07:44:44, 10] libsmb/clikrb5.c:get_krb5_smb_session_key(685) Got KRB5 session key of length 16 [2007/05/02 07:44:44, 10] libads/ldap_schema.c:ads_check_posix_schema_mapping(208) ads_check_posix_schema_mapping [2007/05/02 07:44:44, 5] libads/ldap_utils.c:ads_do_search_retry_internal(63) Search for (|(attributeId=1.3.6.1.1.1.1.0)(attributeId=1.3.6.1.1.1.1.1)(attributeId=1.3.6.1.1.1.1.3)(attributeId=1.3.6.1.1.1.1.4)(attributeId=1.3.6.1.1.1.1.2)) gave 5 replies [2007/05/02 07:44:44, 10] libads/ldap_schema.c:ads_check_posix_schema_mapping(261) ads_check_posix_schema_mapping: query succeeded, identified: rfc2307 OID 1.3.6.1.1.1.1.0 has name: uidNumber OID 1.3.6.1.1.1.1.1 has name: gidNumber OID 1.3.6.1.1.1.1.2 has name: gecos OID 1.3.6.1.1.1.1.3 has name: unixHomeDirectory OID 1.3.6.1.1.1.1.4 has name: loginShell [2007/05/02 07:44:44, 5] libads/ldap_utils.c:ads_do_search_retry_internal(63) Search for (objectSid=\01\05\00\00\00\00\00\05\15\00\00\00\42\DE\49\6A\76\87\48\13\69\29\B1\E9\A3\04\00\00) gave 1 replies [2007/05/02 07:44:44, 1] sam/idmap_ad.c:ad_idmap_get_id_from_sid(318) ad_idmap_get_id_from_sid mapped SID [S-1-5-21-1783225922-323520374-3920701801-1187] to POSIX UID 6190 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_set_mapping(376) db_set_mapping: id_type = 0x1 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_set_mapping(407) db_set_mapping: fetching S-1-5-21-1783225922-323520374-3920701801-1187 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_set_mapping(436) db_set_mapping: stored S-1-5-21-1783225922-323520374-3920701801-1187 -> UID 6190 and UID 6190 -> S-1-5-21-1783225922-323520374-3920701801-1187 [2007/05/02 07:44:44, 10] sam/idmap_util.c:idmap_sid_to_uid(77) idmap_sid_to_uid: uid = [6190] [2007/05/02 07:44:44, 10] nsswitch/winbindd_cache.c:cache_store_response(1966) Storing response for pid 22020, len 3240 [2007/05/02 07:44:44, 4] nsswitch/winbindd_dual.c:fork_domain_child(809) child daemon request 42 [2007/05/02 07:44:44, 10] nsswitch/winbindd_dual.c:child_process_request(395) process_request: request fn DUAL_SID2UID [2007/05/02 07:44:44, 3] nsswitch/winbindd_async.c:winbindd_dual_sid2uid(201) [20754]: sid to uid S-1-5-21-1783225922-323520374-3920701801-1187 [2007/05/02 07:44:44, 10] sam/idmap_util.c:idmap_sid_to_uid(70) idmap_sid_to_uid: sid = [S-1-5-21-1783225922-323520374-3920701801-1187] [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-1187 of type 0x1 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(190) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-1187 -> UID 6190 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(205) internal_get_id_from_sid: ID_USERID fetching record S-1-5-21-1783225922-323520374-3920701801-1187 -> UID 6190 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_sid_from_id(152) internal_get_sid_from_id: fetching record UID 6190 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_sid_from_id(158) internal_get_sid_from_id: fetching record UID 6190 -> S-1-5-21-1783225922-323520374-3920701801-1187 [2007/05/02 07:44:44, 10] sam/idmap_util.c:idmap_sid_to_uid(77) idmap_sid_to_uid: uid = [6190] [2007/05/02 07:44:44, 10] nsswitch/winbindd_cache.c:cache_store_response(1966) Storing response for pid 22020, len 3240 [2007/05/02 07:44:44, 4] nsswitch/winbindd_dual.c:fork_domain_child(809) child daemon request 43 [2007/05/02 07:44:44, 10] nsswitch/winbindd_dual.c:child_process_request(395) process_request: request fn DUAL_SID2GID [2007/05/02 07:44:44, 3] nsswitch/winbindd_async.c:winbindd_dual_sid2gid(384) [20754]: sid to gid S-1-5-21-1783225922-323520374-3920701801-513 [2007/05/02 07:44:44, 10] sam/idmap_util.c:idmap_sid_to_gid(99) sid_to_gid: sid = [S-1-5-21-1783225922-323520374-3920701801-513] [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-513 of type 0x2 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(187) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-513 not found [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-513 of type 0x1 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(187) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-513 not found [2007/05/02 07:44:44, 7] sam/idmap_ad.c:ad_idmap_cached_connection(92) Current tickets expire at 1178085066, time is now 1178084684 [2007/05/02 07:44:44, 5] libads/ldap_utils.c:ads_do_search_retry_internal(63) Search for (objectSid=\01\05\00\00\00\00\00\05\15\00\00\00\42\DE\49\6A\76\87\48\13\69\29\B1\E9\01\02\00\00) gave 1 replies [2007/05/02 07:44:44, 1] sam/idmap_ad.c:ad_idmap_get_id_from_sid(309) ad_idmap_get_id_from_sid: ads_pull_uint32: could not read attribute 'gidNumber' [2007/05/02 07:44:44, 10] nsswitch/winbindd_cache.c:cache_store_response(1966) Storing response for pid 22020, len 3240 [2007/05/02 07:44:44, 4] nsswitch/winbindd_dual.c:fork_domain_child(809) child daemon request 43 [2007/05/02 07:44:44, 10] nsswitch/winbindd_dual.c:child_process_request(395) process_request: request fn DUAL_SID2GID [2007/05/02 07:44:44, 3] nsswitch/winbindd_async.c:winbindd_dual_sid2gid(384) [20754]: sid to gid S-1-5-21-1783225922-323520374-3920701801-513 [2007/05/02 07:44:44, 10] sam/idmap_util.c:idmap_sid_to_gid(99) sid_to_gid: sid = [S-1-5-21-1783225922-323520374-3920701801-513] [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-513 of type 0x2 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(187) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-513 not found [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-513 of type 0x1 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(187) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-513 not found [2007/05/02 07:44:44, 7] sam/idmap_ad.c:ad_idmap_cached_connection(92) Current tickets expire at 1178085066, time is now 1178084684 [2007/05/02 07:44:44, 5] libads/ldap_utils.c:ads_do_search_retry_internal(63) Search for (objectSid=\01\05\00\00\00\00\00\05\15\00\00\00\42\DE\49\6A\76\87\48\13\69\29\B1\E9\01\02\00\00) gave 1 replies [2007/05/02 07:44:44, 1] sam/idmap_ad.c:ad_idmap_get_id_from_sid(309) ad_idmap_get_id_from_sid: ads_pull_uint32: could not read attribute 'gidNumber' [2007/05/02 07:44:44, 10] nsswitch/winbindd_cache.c:cache_store_response(1966) Storing response for pid 22020, len 3240 [2007/05/02 07:44:44, 4] nsswitch/winbindd_dual.c:fork_domain_child(809) child daemon request 43 [2007/05/02 07:44:44, 10] nsswitch/winbindd_dual.c:child_process_request(395) process_request: request fn DUAL_SID2GID [2007/05/02 07:44:44, 3] nsswitch/winbindd_async.c:winbindd_dual_sid2gid(384) [20754]: sid to gid S-1-5-21-1783225922-323520374-3920701801-513 [2007/05/02 07:44:44, 10] sam/idmap_util.c:idmap_sid_to_gid(99) sid_to_gid: sid = [S-1-5-21-1783225922-323520374-3920701801-513] [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-513 of type 0x2 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(187) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-513 not found [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-513 of type 0x1 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(187) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-513 not found [2007/05/02 07:44:44, 7] sam/idmap_ad.c:ad_idmap_cached_connection(92) Current tickets expire at 1178085066, time is now 1178084684 [2007/05/02 07:44:44, 5] libads/ldap_utils.c:ads_do_search_retry_internal(63) Search for (objectSid=\01\05\00\00\00\00\00\05\15\00\00\00\42\DE\49\6A\76\87\48\13\69\29\B1\E9\01\02\00\00) gave 1 replies [2007/05/02 07:44:44, 1] sam/idmap_ad.c:ad_idmap_get_id_from_sid(309) ad_idmap_get_id_from_sid: ads_pull_uint32: could not read attribute 'gidNumber' [2007/05/02 07:44:44, 10] nsswitch/winbindd_cache.c:cache_store_response(1966) Storing response for pid 22020, len 3240 [2007/05/02 07:44:44, 4] nsswitch/winbindd_dual.c:fork_domain_child(809) child daemon request 43 [2007/05/02 07:44:44, 10] nsswitch/winbindd_dual.c:child_process_request(395) process_request: request fn DUAL_SID2GID [2007/05/02 07:44:44, 3] nsswitch/winbindd_async.c:winbindd_dual_sid2gid(384) [20754]: sid to gid S-1-5-21-1783225922-323520374-3920701801-513 [2007/05/02 07:44:44, 10] sam/idmap_util.c:idmap_sid_to_gid(99) sid_to_gid: sid = [S-1-5-21-1783225922-323520374-3920701801-513] [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-513 of type 0x2 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(187) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-513 not found [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:db_get_id_from_sid(277) db_get_id_from_sid [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(183) internal_get_id_from_sid: fetching record S-1-5-21-1783225922-323520374-3920701801-513 of type 0x1 [2007/05/02 07:44:44, 10] sam/idmap_tdb.c:internal_get_id_from_sid(187) internal_get_id_from_sid: record S-1-5-21-1783225922-323520374-3920701801-513 not found [2007/05/02 07:44:44, 7] sam/idmap_ad.c:ad_idmap_cached_connection(92) Current tickets expire at 1178085066, time is now 1178084684 [2007/05/02 07:44:44, 5] libads/ldap_utils.c:ads_do_search_retry_internal(63) Search for (objectSid=\01\05\00\00\00\00\00\05\15\00\00\00\42\DE\49\6A\76\87\48\13\69\29\B1\E9\01\02\00\00) gave 1 replies [2007/05/02 07:44:44, 1] sam/idmap_ad.c:ad_idmap_get_id_from_sid(309) ad_idmap_get_id_from_sid: ads_pull_uint32: could not read attribute 'gidNumber' [2007/05/02 07:44:44, 10] nsswitch/winbindd_cache.c:cache_store_response(1966) Storing response for pid 22020, len 3240