autobuild[sn-devel-184]: intermittent test failure detected

Fri May 31 15:50:02 UTC 2019

The autobuild test system (on sn-devel-184) has detected an intermittent failing test in 
the current master tree.

The autobuild log of the failure is available here:

   https://git.samba.org/autobuild.flakey.sn-devel-184/2019-05-31-1550/flakey.log

The failure seems to be in the "samba-ad-member" suite, whose build logs are available here:

   https://git.samba.org/autobuild.flakey.sn-devel-184/2019-05-31-1550/samba-ad-member.stderr
   https://git.samba.org/autobuild.flakey.sn-devel-184/2019-05-31-1550/samba-ad-member.stdout

The top commit at the time of the failure was:

commit b5bf1b66d55f40862ece83ef013793fb303acca7
Author: Joe Guo <joeg at catalyst.net.nz>
Date:   Wed May 29 15:35:08 2019 +1200

    .gitlab-ci.yml: avoid using != to compare variables to support old versions

    `!=` was only introduced after 11.11, according to doc at:

    https://docs.gitlab.com/ee/ci/variables/README.html#environment-variables-expressions

    For private gitlab instance, the version may not be new enough.
    Use `==` to make it backward compatible.

    Signed-off-by: Joe Guo <joeg at catalyst.net.nz>
    Reviewed-by: Ralph Boehme <slow at samba.org>
    Reviewed-by: Andrew Bartlett <abartlet at samba.org>

    Autobuild-User(master): Ralph Böhme <slow at samba.org>
    Autobuild-Date(master): Fri May 31 09:48:37 UTC 2019 on sn-devel-184

and the last 50 lines of the stdout log were:

[73(632)/87 at 7m3s] samba.wbinfo_simple.domain-groups(ad_member:local)
[74(633)/87 at 7m3s] samba.wbinfo_simple.name-to-sid=$DC_USERNAME(ad_member:local)
[75(634)/87 at 7m3s] samba.wbinfo_simple.name-to-sid=$DOMAIN/$DC_USERNAME(ad_member:local)
[76(635)/87 at 7m3s] samba.wbinfo_simple.user-info=$DOMAIN/$DC_USERNAME(ad_member:local)
[77(636)/87 at 7m4s] samba.wbinfo_simple.user-groups=$DOMAIN/$DC_USERNAME(ad_member:local)
[78(637)/87 at 7m4s] samba.wbinfo_simple.authenticate=$DOMAIN/$DC_USERNAME%$DC_PASSWORD(ad_member:local)
[79(638)/87 at 7m4s] samba.wbinfo_simple.allocate-uid(ad_member:local)
[80(639)/87 at 7m5s] samba.wbinfo_simple.allocate-gid(ad_member:local)
[81(640)/87 at 7m5s] samba.wbinfo_sids2xids.(ad_member:local)(ad_member:local)
[82(641)/87 at 7m18s] samba.tests.ntlm_auth(ad_member:local)
[83(654)/87 at 7m22s] samba.nss.test using winbind(ad_member:local)(ad_member:local)
[84(655)/87 at 7m22s] samba.tests.auth_log_winbind(ad_member:local)
WARNING: The "server schannel" option is deprecated
INFO: Received PING message from server 3443225.8 []
{"timestamp": "2019-05-31T15:49:40.607811+0000", "type": "Authentication", "Authentication": {"version": {"major": 1, "minor": 2}, "eventId": 4624, "logonId": "1d013c1a1b2cdca2", "logonType": 3, "status": "NT_STATUS_OK", "localAddress": "ipv4:127.0.0.30:1026", "remoteAddress": "ipv4:127.0.0.29:55947", "serviceDescription": "SamLogon", "authDescription": "network", "clientDomain": "ADDOMAIN", "clientAccount": "Administrator", "workstation": "\\\\LOCALADMEMBER", "becameAccount": "Administrator", "becameDomain": "ADDOMAIN", "becameSid": "S-1-5-21-3889413970-689953836-2330354403-500", "mappedAccount": "Administrator", "mappedDomain": "ADDOMAIN", "netlogonComputer": "LOCALADMEMBER", "netlogonTrustAccount": "LOCALADMEMBER$", "netlogonNegotiateFlags": "0x610FFFFF", "netlogonSecureChannelType": 2, "netlogonTrustAccountSid": "S-1-5-21-3889413970-689953836-2330354403-1109", "passwordType": "NTLMv1", "duration": 9884}}
INFO: Received PING message from server 3445900.3498982116 []
{"timestamp": "2019-05-31T15:49:40.589030+0000", "type": "Authentication", "Authentication": {"version": {"major": 1, "minor": 2}, "eventId": 4625, "logonId": "97bfa3751dd3a944", "logonType": 2, "status": "NT_STATUS_NO_SUCH_USER", "localAddress": "unix:", "remoteAddress": "unix:", "serviceDescription": "winbind", "authDescription": "PASSDB, wbinfo, 3445866", "clientDomain": "", "clientAccount": "Administrator", "workstation": "LOCALADMEMBER", "becameAccount": null, "becameDomain": null, "becameSid": null, "mappedAccount": "Administrator", "mappedDomain": "", "netlogonComputer": null, "netlogonTrustAccount": null, "netlogonNegotiateFlags": "0x00000000", "netlogonSecureChannelType": 0, "netlogonTrustAccountSid": null, "passwordType": "NTLMv2", "duration": 178}}
INFO: Received PING message from server 3445900.1940279545 []
{"timestamp": "2019-05-31T15:49:40.595642+0000", "type": "Authentication", "Authentication": {"version": {"major": 1, "minor": 2}, "eventId": 4625, "logonId": "97bfa3751dd3a944", "logonType": 8, "status": "NT_STATUS_INVALID_HANDLE", "localAddress": "unix:", "remoteAddress": "unix:", "serviceDescription": "winbind", "authDescription": "PAM_AUTH, wbinfo, 3445866", "clientDomain": "", "clientAccount": "Administrator", "workstation": null, "becameAccount": "", "becameDomain": "", "becameSid": null, "mappedAccount": null, "mappedDomain": null, "netlogonComputer": null, "netlogonTrustAccount": null, "netlogonNegotiateFlags": "0x00000000", "netlogonSecureChannelType": 0, "netlogonTrustAccountSid": null, "passwordType": "Plaintext", "duration": 6899}}
INFO: Received PING message from server 3445884.1182063507 []
{"timestamp": "2019-05-31T15:49:40.609210+0000", "type": "Authentication", "Authentication": {"version": {"major": 1, "minor": 2}, "eventId": 4624, "logonId": "1d013c1a1b2cdca2", "logonType": 3, "status": "NT_STATUS_OK", "localAddress": "unix:", "remoteAddress": "unix:", "serviceDescription": "winbind", "authDescription": "NTLM_AUTH, wbinfo, 3445866", "clientDomain": "ADDOMAIN", "clientAccount": "Administrator", "workstation": "LOCALADMEMBER", "becameAccount": "Administrator", "becameDomain": "ADDOMAIN", "becameSid": "S-1-5-21-3889413970-689953836-2330354403-500", "mappedAccount": null, "mappedDomain": null, "netlogonComputer": null, "netlogonTrustAccount": null, "netlogonNegotiateFlags": "0x00000000", "netlogonSecureChannelType": 0, "netlogonTrustAccountSid": null, "passwordType": "NTLMv1", "duration": 12084}}
INFO: Received PING message from server 3443246 []
{"timestamp": "2019-05-31T15:49:40.807691+0000", "type": "Authentication", "Authentication": {"version": {"major": 1, "minor": 2}, "eventId": 4624, "logonId": "4fddc5e9bc5c3c8f", "logonType": 3, "status": "NT_STATUS_OK", "localAddress": null, "remoteAddress": "ipv4:127.0.0.20:34180", "serviceDescription": "Kerberos KDC", "authDescription": "ENC-TS Pre-authentication", "clientDomain": null, "clientAccount": "IDMAPRIDMEMBER$@ADDOM.SAMBA.EXAMPLE.COM", "workstation": null, "becameAccount": "idmapridmember$", "becameDomain": "ADDOMAIN", "becameSid": "S-1-5-21-3889413970-689953836-2330354403-1113", "mappedAccount": "idmapridmember$", "mappedDomain": "ADDOMAIN", "netlogonComputer": null, "netlogonTrustAccount": null, "netlogonNegotiateFlags": "0x00000000", "netlogonSecureChannelType": 0, "netlogonTrustAccountSid": null, "passwordType": "aes256-cts-hmac-sha1-96", "duration": 5617}}
INFO: Received PING message from server 3443261 []
{"timestamp": "2019-05-31T15:49:40.818051+0000", "type": "Authentication", "Authentication": {"version": {"major": 1, "minor": 2}, "eventId": 4624, "logonId": "4e0621af808cbcf8", "logonType": 3, "status": "NT_STATUS_OK", "localAddress": null, "remoteAddress": "ipv4:127.0.0.20:34181", "serviceDescription": "Kerberos KDC", "authDescription": "ENC-TS Pre-authentication", "clientDomain": null, "clientAccount": "IDMAPRIDMEMBER$@ADDOM.SAMBA.EXAMPLE.COM", "workstation": null, "becameAccount": "idmapridmember$", "becameDomain": "ADDOMAIN", "becameSid": "S-1-5-21-3889413970-689953836-2330354403-1113", "mappedAccount": "idmapridmember$", "mappedDomain": "ADDOMAIN", "netlogonComputer": null, "netlogonTrustAccount": null, "netlogonNegotiateFlags": "0x00000000", "netlogonSecureChannelType": 0, "netlogonTrustAccountSid": null, "passwordType": "aes256-cts-hmac-sha1-96", "duration": 5386}}
UNEXPECTED(failure): samba.tests.auth_log_winbind.samba.tests.auth_log_winbind.AuthLogTestsWinbind.test_wbinfo_ntlmv1(ad_member:local)
REASON: Exception: Exception: Traceback (most recent call last):
  File "bin/python/samba/tests/auth_log_winbind.py", line 438, in test_wbinfo_ntlmv1
    self.assertEquals(logon_id, msg["Authentication"]["logonId"])
AssertionError: '1d013c1a1b2cdca2' != '4e0621af808cbcf8'
- 1d013c1a1b2cdca2
+ 4e0621af808cbcf8

FAILED (1 failures, 0 errors and 0 unexpected successes in 0 testsuites)

A summary with detailed information can be found in:
  ./bin/ab/summary
TOP 10 slowest tests
samba3.smb2.session krb5(ad_member_idmap_rid) -> 94
idmap.rfc2307(ad_member_rfc2307) -> 49
samba3.blackbox.smbclient_s3.SMB3.plain (ad_member) member creds(ad_member) -> 14
samba.wbinfo_sids2xids.(ad_member:local)(ad_member:local) -> 13
samba3.blackbox.smbclient_s3.NT1.sign (ad_member) member creds(ad_member) -> 11
samba3.blackbox.smbclient_s3.NT1.plain (ad_member) member creds(ad_member) -> 11
samba3.blackbox.smbclient_s3.SMB3.sign (ad_member) member creds(ad_member) -> 11
idmap.ad(ad_member_idmap_ad) -> 6
samba4.blackbox.net_ads_dns(ad_member:local)(ad_member:local) -> 6
samba.tests.pam_winbind_chauthtok with options use_authtok(ad_member) -> 5
ERROR: test failed with exit code 1
Makefile:16: recipe for target 'test' failed