getent passwd rowland rowland:*:10000:10000::/home/SAMDOM/rowland:/bin/false [2017/03/26 12:29:42.035366, 6, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:919(new_connection) accepted socket 30 [2017/03/26 12:29:42.035404, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:761(process_request) process_request: request fn INTERFACE_VERSION [2017/03/26 12:29:42.035410, 3, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:395(winbindd_interface_version) [29912]: request interface version (version = 28) [2017/03/26 12:29:42.035421, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:864(winbind_client_response_written) winbind_client_response_written[29912:INTERFACE_VERSION]: delivered response to client [2017/03/26 12:29:42.035457, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:761(process_request) process_request: request fn WINBINDD_PRIV_PIPE_DIR [2017/03/26 12:29:42.035462, 3, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:428(winbindd_priv_pipe_dir) [29912]: request location of privileged pipe [2017/03/26 12:29:42.035487, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:864(winbind_client_response_written) winbind_client_response_written[29912:WINBINDD_PRIV_PIPE_DIR]: delivered response to client [2017/03/26 12:29:42.035585, 6, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:919(new_connection) accepted socket 32 [2017/03/26 12:29:42.035633, 6, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:968(winbind_client_request_read) closing socket 30, client exited [2017/03/26 12:29:42.035646, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:734(process_request) process_request: Handling async request 29912:GETPWNAM [2017/03/26 12:29:42.035651, 3, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_getpwnam.c:56(winbindd_getpwnam_send) getpwnam rowland [2017/03/26 12:29:42.035665, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:471(fetch_cache_seqnum) fetch_cache_seqnum: timeout [SAMDOM][12373 @ 1490527021] [2017/03/26 12:29:42.035670, 3, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_ads.c:1302(sequence_number) ads: fetch sequence_number for SAMDOM [2017/03/26 12:29:42.035675, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_ads.c:233(ads_cached_connection) ads_cached_connection [2017/03/26 12:29:42.035679, 7, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_ads.c:63(ads_cached_connection_reuse) Current tickets expire in 35239 seconds (at 1490563021, time is now 1490527782) [2017/03/26 12:29:42.038702, 5, pid=27649, effective(0, 0), real(0, 0)] ../source3/libads/ldap_utils.c:81(ads_do_search_retry_internal) Search for (objectclass=*) in <> gave 1 replies [2017/03/26 12:29:42.038750, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:510(wcache_store_seqnum) wcache_store_seqnum: success [SAMDOM][12375 @ 1490527782] [2017/03/26 12:29:42.038756, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:596(refresh_sequence_number) refresh_sequence_number: SAMDOM seq number is now 12375 [2017/03/26 12:29:42.038782, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:642(centry_expired) centry_expired: Key NSS/AN/ROWLAND for domain SAMDOM expired [2017/03/26 12:29:42.038786, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:737(wcache_fetch) wcache_fetch: entry NSS/AN/ROWLAND expired for domain SAMDOM [2017/03/26 12:29:42.038800, 5, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1259(resolve_alias_to_username) resolve_alias_to_username: backend query returned NT_STATUS_NOT_IMPLEMENTED [2017/03/26 12:29:42.038815, 1, pid=27649, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:450(ndr_print_function_debug) wbint_LookupName: struct wbint_LookupName in: struct wbint_LookupName domain : * domain : 'SAMDOM' name : * name : 'ROWLAND' flags : 0x00000008 (8) [2017/03/26 12:29:42.038839, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4860(wcache_fetch_ndr) Entry has wrong sequence number: 70699 [2017/03/26 12:29:42.050812, 1, pid=27649, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:450(ndr_print_function_debug) wbint_LookupName: struct wbint_LookupName out: struct wbint_LookupName type : * type : SID_NAME_USER (1) sid : * sid : S-1-5-21-1768301897-3342589593-1064908849-1107 result : NT_STATUS_OK [2017/03/26 12:29:42.050844, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/wb_sids2xids.c:113(wb_sids2xids_send) SID 0: S-1-5-21-1768301897-3342589593-1064908849-1107 [2017/03/26 12:29:42.050857, 10, pid=27649, effective(0, 0), real(0, 0)] ../source3/lib/idmap_cache.c:56(idmap_cache_find_sid2unixid) Parsing value for key [IDMAP/SID2XID/S-1-5-21-1768301897-3342589593-1064908849-1107]: value=[10000:U] [2017/03/26 12:29:42.050862, 10, pid=27649, effective(0, 0), real(0, 0)] ../source3/lib/idmap_cache.c:75(idmap_cache_find_sid2unixid) Parsing value for key [IDMAP/SID2XID/S-1-5-21-1768301897-3342589593-1064908849-1107]: id=[10000], endptr=[:U] [2017/03/26 12:29:42.050870, 10, pid=27649, effective(0, 0), real(0, 0)] ../source3/libsmb/samlogon_cache.c:242(netsamlogon_cache_get) netsamlogon_cache_get: SID [S-1-5-21-1768301897-3342589593-1064908849-1107] [2017/03/26 12:29:42.050886, 1, pid=27649, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:413(ndr_print_debug) &r: struct netsamlogoncache_entry timestamp : Fri Mar 24 20:17:19 2017 GMT info3: struct netr_SamInfo3 base: struct netr_SamBaseInfo logon_time : Fri Mar 24 20:17:19 2017 GMT logoff_time : Thu Sep 14 03:48:05 30828 BST kickoff_time : Thu Sep 14 03:48:05 30828 BST last_password_change : Mon Nov 9 09:38:21 2015 GMT allow_password_change : Mon Nov 9 09:38:21 2015 GMT force_password_change : Thu Sep 14 03:48:05 30828 BST account_name: struct lsa_String length : 0x000e (14) size : 0x000e (14) string : * string : 'rowland' full_name: struct lsa_String length : 0x001a (26) size : 0x001a (26) string : * string : 'Rowland Penny' logon_script: struct lsa_String length : 0x0000 (0) size : 0x0000 (0) string : * string : '' profile_path: struct lsa_String length : 0x0000 (0) size : 0x0000 (0) string : * string : '' home_directory: struct lsa_String length : 0x002c (44) size : 0x002c (44) string : * string : '\\MEMBER1\home\rowland' home_drive: struct lsa_String length : 0x0004 (4) size : 0x0004 (4) string : * string : 'H:' logon_count : 0x01ca (458) bad_password_count : 0x0000 (0) rid : 0x00000453 (1107) primary_gid : 0x00000201 (513) groups: struct samr_RidWithAttributeArray count : 0x00000006 (6) rids : * rids: ARRAY(6) rids: struct samr_RidWithAttribute rid : 0x0000044e (1102) attributes : 0x00000007 (7) 1: SE_GROUP_MANDATORY 1: SE_GROUP_ENABLED_BY_DEFAULT 1: SE_GROUP_ENABLED 0: SE_GROUP_OWNER 0: SE_GROUP_USE_FOR_DENY_ONLY 0: SE_GROUP_RESOURCE 0x00: SE_GROUP_LOGON_ID (0) rids: struct samr_RidWithAttribute rid : 0x00000477 (1143) attributes : 0x00000007 (7) 1: SE_GROUP_MANDATORY 1: SE_GROUP_ENABLED_BY_DEFAULT 1: SE_GROUP_ENABLED 0: SE_GROUP_OWNER 0: SE_GROUP_USE_FOR_DENY_ONLY 0: SE_GROUP_RESOURCE 0x00: SE_GROUP_LOGON_ID (0) rids: struct samr_RidWithAttribute rid : 0x00000480 (1152) attributes : 0x00000007 (7) 1: SE_GROUP_MANDATORY 1: SE_GROUP_ENABLED_BY_DEFAULT 1: SE_GROUP_ENABLED 0: SE_GROUP_OWNER 0: SE_GROUP_USE_FOR_DENY_ONLY 0: SE_GROUP_RESOURCE 0x00: SE_GROUP_LOGON_ID (0) rids: struct samr_RidWithAttribute rid : 0x0000084a (2122) attributes : 0x00000007 (7) 1: SE_GROUP_MANDATORY 1: SE_GROUP_ENABLED_BY_DEFAULT 1: SE_GROUP_ENABLED 0: SE_GROUP_OWNER 0: SE_GROUP_USE_FOR_DENY_ONLY 0: SE_GROUP_RESOURCE 0x00: SE_GROUP_LOGON_ID (0) rids: struct samr_RidWithAttribute rid : 0x00000200 (512) attributes : 0x00000007 (7) 1: SE_GROUP_MANDATORY 1: SE_GROUP_ENABLED_BY_DEFAULT 1: SE_GROUP_ENABLED 0: SE_GROUP_OWNER 0: SE_GROUP_USE_FOR_DENY_ONLY 0: SE_GROUP_RESOURCE 0x00: SE_GROUP_LOGON_ID (0) rids: struct samr_RidWithAttribute rid : 0x0000023c (572) attributes : 0x00000007 (7) 1: SE_GROUP_MANDATORY 1: SE_GROUP_ENABLED_BY_DEFAULT 1: SE_GROUP_ENABLED 0: SE_GROUP_OWNER 0: SE_GROUP_USE_FOR_DENY_ONLY 0: SE_GROUP_RESOURCE 0x00: SE_GROUP_LOGON_ID (0) user_flags : 0x00000004 (4) 0: NETLOGON_GUEST 0: NETLOGON_NOENCRYPTION 1: NETLOGON_CACHED_ACCOUNT 0: NETLOGON_USED_LM_PASSWORD 0: NETLOGON_EXTRA_SIDS 0: NETLOGON_SUBAUTH_SESSION_KEY 0: NETLOGON_SERVER_TRUST_ACCOUNT 0: NETLOGON_NTLMV2_ENABLED 0: NETLOGON_RESOURCE_GROUPS 0: NETLOGON_PROFILE_PATH_RETURNED 0: NETLOGON_GRACE_LOGON key: struct netr_UserSessionKey key : 00000000000000000000000000000000 logon_server: struct lsa_StringLarge length : 0x000e (14) size : 0x0010 (16) string : * string : 'MEMBER1' logon_domain: struct lsa_StringLarge length : 0x000c (12) size : 0x000e (14) string : * string : 'SAMDOM' domain_sid : * domain_sid : S-1-5-21-1768301897-3342589593-1064908849 LMSessKey: struct netr_LMSessionKey key : 0000000000000000 acct_flags : 0x00000210 (528) 0: ACB_DISABLED 0: ACB_HOMDIRREQ 0: ACB_PWNOTREQ 0: ACB_TEMPDUP 1: ACB_NORMAL 0: ACB_MNS 0: ACB_DOMTRUST 0: ACB_WSTRUST 0: ACB_SVRTRUST 1: ACB_PWNOEXP 0: ACB_AUTOLOCK 0: ACB_ENC_TXT_PWD_ALLOWED 0: ACB_SMARTCARD_REQUIRED 0: ACB_TRUSTED_FOR_DELEGATION 0: ACB_NOT_DELEGATED 0: ACB_USE_DES_KEY_ONLY 0: ACB_DONT_REQUIRE_PREAUTH 0: ACB_PW_EXPIRED 0: ACB_TRUSTED_TO_AUTHENTICATE_FOR_DELEGATION 0: ACB_NO_AUTH_DATA_REQD 0: ACB_PARTIAL_SECRETS_ACCOUNT 0: ACB_USE_AES_KEYS sub_auth_status : 0x00000000 (0) last_successful_logon : NTTIME(0) last_failed_logon : NTTIME(0) failed_logon_count : 0x00000000 (0) reserved : 0x00000000 (0) sidcount : 0x00000000 (0) sids : NULL [2017/03/26 12:29:42.051225, 1, pid=27649, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:450(ndr_print_function_debug) wbint_GetNssInfo: struct wbint_GetNssInfo in: struct wbint_GetNssInfo info : * info: struct wbint_userinfo domain_name : * domain_name : 'SAMDOM' acct_name : * acct_name : 'rowland' full_name : * full_name : 'Rowland Penny' homedir : * homedir : '/home/%D/%U' shell : * shell : '/bin/false' uid : 0x0000000000002710 (10000) primary_gid : 0x00000000ffffffff (4294967295) primary_group_name : NULL user_sid : S-1-5-21-1768301897-3342589593-1064908849-1107 group_sid : S-1-5-21-1768301897-3342589593-1064908849-513 [2017/03/26 12:29:42.051623, 1, pid=27649, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:450(ndr_print_function_debug) wbint_GetNssInfo: struct wbint_GetNssInfo out: struct wbint_GetNssInfo info : * info: struct wbint_userinfo domain_name : * domain_name : 'SAMDOM' acct_name : * acct_name : 'rowland' full_name : * full_name : 'Rowland Penny' homedir : * homedir : '/home/%D/%U' shell : * shell : '/bin/false' uid : 0x0000000000002710 (10000) primary_gid : 0x00000000ffffffff (4294967295) primary_group_name : NULL user_sid : S-1-5-21-1768301897-3342589593-1064908849-1107 group_sid : S-1-5-21-1768301897-3342589593-1064908849-513 result : NT code 0xf2000051 [2017/03/26 12:29:42.051703, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/wb_sids2xids.c:113(wb_sids2xids_send) SID 0: S-1-5-21-1768301897-3342589593-1064908849-513 [2017/03/26 12:29:42.051714, 10, pid=27649, effective(0, 0), real(0, 0)] ../source3/lib/idmap_cache.c:56(idmap_cache_find_sid2unixid) Parsing value for key [IDMAP/SID2XID/S-1-5-21-1768301897-3342589593-1064908849-513]: value=[10000:G] [2017/03/26 12:29:42.051718, 10, pid=27649, effective(0, 0), real(0, 0)] ../source3/lib/idmap_cache.c:75(idmap_cache_find_sid2unixid) Parsing value for key [IDMAP/SID2XID/S-1-5-21-1768301897-3342589593-1064908849-513]: id=[10000], endptr=[:G] [2017/03/26 12:29:42.051725, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:1037(find_lookup_domain_from_sid) find_lookup_domain_from_sid(S-1-5-21-1768301897-3342589593-1064908849-513) [2017/03/26 12:29:42.051734, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:1047(find_lookup_domain_from_sid) calling find_our_domain [2017/03/26 12:29:42.051740, 1, pid=27649, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:450(ndr_print_function_debug) wbint_LookupSid: struct wbint_LookupSid in: struct wbint_LookupSid sid : * sid : S-1-5-21-1768301897-3342589593-1064908849-513 [2017/03/26 12:29:42.051754, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4860(wcache_fetch_ndr) Entry has wrong sequence number: 70699 [2017/03/26 12:29:42.062648, 1, pid=27649, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:450(ndr_print_function_debug) wbint_LookupSid: struct wbint_LookupSid out: struct wbint_LookupSid type : * type : SID_NAME_DOM_GRP (2) domain : * domain : * domain : 'SAMDOM' name : * name : * name : 'Domain_Users' result : NT_STATUS_OK [2017/03/26 12:29:42.062697, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:796(wb_request_done) wb_request_done[29912:GETPWNAM]: NT_STATUS_OK [2017/03/26 12:29:42.062713, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:864(winbind_client_response_written) winbind_client_response_written[29912:GETPWNAM]: delivered response to client [2017/03/26 12:29:42.062815, 6, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:968(winbind_client_request_read) closing socket 32, client exited