Attachment 13105 Details for Bug 12720 – log10 of when not working

log10 of when not working

working.txt (text/plain), 22.82 KB, created by Rowland Penny on 2017-03-26 12:02:06 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rowland Penny

Created: 2017-03-26 12:02:06 UTC

Size: 22.82 KB

patch

obsolete

>getent passwd rowland
>rowland:*:10000:10000:Rowland Penny:/home/rowland:/bin/bash
>
>[2017/03/26 11:35:13.825938,  6, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:919(new_connection)
>  accepted socket 26
>[2017/03/26 11:35:13.825993, 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 11:35:13.826005,  3, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:395(winbindd_interface_version)
>  [27844]: request interface version (version = 28)
>[2017/03/26 11:35:13.826017, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:864(winbind_client_response_written)
>  winbind_client_response_written[27844:INTERFACE_VERSION]: delivered response to client
>[2017/03/26 11:35:13.826047, 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 11:35:13.826052,  3, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:428(winbindd_priv_pipe_dir)
>  [27844]: request location of privileged pipe
>[2017/03/26 11:35:13.826064, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:864(winbind_client_response_written)
>  winbind_client_response_written[27844:WINBINDD_PRIV_PIPE_DIR]: delivered response to client
>[2017/03/26 11:35:13.826176,  6, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:919(new_connection)
>  accepted socket 28
>[2017/03/26 11:35:13.826204,  6, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:968(winbind_client_request_read)
>  closing socket 26, client exited
>[2017/03/26 11:35:13.826216, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:734(process_request)
>  process_request: Handling async request 27844:GETPWNAM
>[2017/03/26 11:35:13.826221,  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 11:35:13.826229,  5, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:180(get_cache)
>  get_cache: Setting ADS methods for domain SAMDOM
>[2017/03/26 11:35:13.826239, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:477(fetch_cache_seqnum)
>  fetch_cache_seqnum: success [SAMDOM][70699 @ 1490524241]
>[2017/03/26 11:35:13.826243, 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 70699
>[2017/03/26 11:35:13.826249, 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 11:35:13.826253, 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 11:35:13.826260,  5, pid=27649, effective(0, 0), real(0, 0)] ../source3/winbindd/nss_info.c:80(smb_register_idmap_nss)
>  smb_register_idmap_nss: Successfully added idmap nss backend 'template'
>[2017/03/26 11:35:13.826263, 10, pid=27649, effective(0, 0), real(0, 0)] ../source3/winbindd/nss_info.c:191(nss_init)
>  parsed backend = 'template', domain = '(null)'
>[2017/03/26 11:35:13.826267, 10, pid=27649, effective(0, 0), real(0, 0)] ../source3/winbindd/nss_info.c:231(nss_init)
>  nss_init: using 'template' as default backend.
>[2017/03/26 11:35:13.826271, 10, pid=27649, effective(0, 0), real(0, 0)] ../source3/winbindd/nss_info.c:147(nss_domain_list_add_domain)
>  Added domain '(null)' with backend 'template' to nss_domain_list.
>[2017/03/26 11:35:13.826275, 10, pid=27649, effective(0, 0), real(0, 0)] ../source3/winbindd/nss_info.c:147(nss_domain_list_add_domain)
>  Added domain 'SAMDOM' with backend 'template' to nss_domain_list.
>[2017/03/26 11:35:13.826278,  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 11:35:13.826304,  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 11:35:13.826339, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4865(wcache_fetch_ndr)
>  Entry has timed out
>[2017/03/26 11:35:13.850393,  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 11:35:13.850431, 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 11:35:13.850443,  5, pid=27649, effective(0, 0), real(0, 0), class=tdb] ../source3/lib/gencache.c:69(gencache_init)
>  Opening cache file at /var/cache/samba/gencache.tdb
>[2017/03/26 11:35:13.850485,  5, pid=27649, effective(0, 0), real(0, 0), class=tdb] ../source3/lib/gencache.c:120(gencache_init)
>  Opening cache file at /var/run/samba/gencache_notrans.tdb
>[2017/03/26 11:35:13.851293, 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 11:35:13.851306, 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 11:35:13.851320, 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 11:35:13.851358,  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 11:35:13.851702,  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 11:35:13.851754, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1419(fork_domain_child)
>  fork_domain_child called without domain.
>[2017/03/26 11:35:13.852080, 10, pid=27846, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1473(fork_domain_child)
>  Child process 27846
>[2017/03/26 11:35:13.852325, 10, pid=27846, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:157(msg_dgm_ref_destructor)
>  msg_dgm_ref_destructor: refs=0x7efcbf71a9e0
>[2017/03/26 11:35:13.852385, 10, pid=27846, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:79(messaging_dgm_ref)
>  messaging_dgm_ref: messaging_dgm_init returned Success
>[2017/03/26 11:35:13.852402, 10, pid=27846, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:108(messaging_dgm_ref)
>  messaging_dgm_ref: unique = 10370283891433301912
>[2017/03/26 11:35:14.315616,  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/rowland'
>                      shell                    : *
>                          shell                    : '/bin/bash'
>                      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_STATUS_OK
>[2017/03/26 11:35:14.315682, 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 11:35:14.315701, 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 11:35:14.315705, 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 11:35:14.315717, 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 11:35:14.315723, 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 11:35:14.315731,  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 11:35:14.315749, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4865(wcache_fetch_ndr)
>  Entry has timed out
>[2017/03/26 11:35:14.321775,  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 11:35:14.321817, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:796(wb_request_done)
>  wb_request_done[27844:GETPWNAM]: NT_STATUS_OK
>[2017/03/26 11:35:14.321832, 10, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:864(winbind_client_response_written)
>  winbind_client_response_written[27844:GETPWNAM]: delivered response to client
>[2017/03/26 11:35:14.321952,  6, pid=27649, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:968(winbind_client_request_read)
>  closing socket 28, client exited
>

Actions: View

Attachments on bug 12720: 13104 | 13105 | 13106 | 13108 | 13313 | 13314 | 13384