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

autobuild autobuild at samba.org
Sun Feb 10 00:18:43 UTC 2019


The autobuild test system (on sn-devel-144) 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-144/2019-02-10-0118/flakey.log

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

   https://git.samba.org/autobuild.flakey.sn-devel-144/2019-02-10-0118/samba-nt4.stderr
   https://git.samba.org/autobuild.flakey.sn-devel-144/2019-02-10-0118/samba-nt4.stdout
  
The top commit at the time of the failure was:

commit 584dfc15fd33f665a0bd2287a6a981568032ec74
Author: Aurelien Aptel <aaptel at suse.com>
Date:   Fri Feb 8 12:13:25 2019 +0100

    libsmb,s3/smbd: dump SMB3+ session keys if debug parm is set
    
    Use of previously added smb.conf global param.
    
    Sample usage:
    
    $ smbclient //localhost/scratch --option='debugencryption=yes' \
                                     -e -mSMB3 -U aaptel%aaptel -c quit
    debug encryption: dumping generated session keys
    Session Id    [0000] 26 48 BF FD 00 00 00 00                             &H......
    Session Key   [0000] 63 D6 CA BC 08 C8 4A D2   45 F6 AE 35 AB 4A B3 3B   c.....J. E..5.J.;
    Signing Key   [0000] 4E FE 35 92 AC 13 14 FC   C9 17 62 B1 82 20 A4 12   N.5..... ..b.. ..
    App Key       [0000] A5 0F F4 8B 2F FB 0D FF   F2 BF EE 39 E6 6D F5 0A   ..../... ...9.m..
    ServerIn Key  [0000] 2A 02 7E E1 D3 58 D8 12   4C 63 76 AE 59 17 5A E4   *.~..X.. Lcv.Y.Z.
    ServerOut Key [0000] 59 F2 5B 7F 66 8F 31 A0   A5 E4 A8 D8 2F BA 00 38   Y.[.f.1. ..../..8
    
    We can now simply pass -ouat:smb2_seskey_list:<sesid>,<seskey> to
    wireshark or tshark:
    
    $ tshark -ouat:smb2_seskey_list:2648BFFD00000000,63D6CABC08C84AD245F6AE35AB4AB33B \
              -Y smb2 -r capture.pcap -Tfields -e _ws.col.Info
    Negotiate Protocol Response
    Negotiate Protocol Request
    Negotiate Protocol Response
    Session Setup Request, NTLMSSP_NEGOTIATE
    Session Setup Response, Error: STATUS_MORE_PROCESSING_REQUIRED, NTLMSSP_CHALLENGE
    Session Setup Request, NTLMSSP_AUTH, User: WORKGROUP\aaptel
    Session Setup Response
    Tree Connect Request Tree: \\localhost\IPC$
    Tree Connect Response
    Decrypted SMB3;Ioctl Request FSCTL_DFS_GET_REFERRALS, File: \localhost\scratch
    Decrypted SMB3;Ioctl Response, Error: STATUS_NOT_FOUND
    Decrypted SMB3;Tree Disconnect Request
    Decrypted SMB3;Tree Disconnect Response
    Decrypted SMB3;Tree Connect Request Tree: \\localhost\scratch
    Decrypted SMB3;Tree Connect Response
    Decrypted SMB3;Tree Disconnect Request
    Decrypted SMB3;Tree Disconnect Response
    
    For more info on Wireshark decryption support see
    https://wiki.samba.org/index.php/Wireshark_Decryption
    
    Signed-off-by: Aurelien Aptel <aaptel at suse.com>
    Reviewed-by: Noel Power <npower at samba.org>
    Reviewed-by: Ralph Boehme <slow at samba.org>
    Reviewed-by: David Disseldorp <ddiss at samba.org>
    
    Autobuild-User(master): David Disseldorp <ddiss at samba.org>
    Autobuild-Date(master): Sat Feb  9 21:43:25 CET 2019 on sn-devel-144

and the last 50 lines of the stdout log were:

(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:20 2019 CET(131968235000000000) => Sat May 11 02:18:20 2019 CEST(132020075000000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:20 2019 CET(131968235000000000) => Sat May 11 02:18:20 2019 CEST(132020075000000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:20 2019 CET(131968235000000000) => Sat May 11 02:18:20 2019 CEST(132020075000000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:20 2019 CET(131968235000000000) => Sat May 11 02:18:20 2019 CEST(132020075000000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:20 2019 CET(131968235000000000) => Sat May 11 02:18:20 2019 CEST(132020075000000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:20 2019 CET(131968235000000000) => Sat May 11 02:18:20 2019 CEST(132020075000000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:20 2019 CET(131968235000000000) => Sat May 11 02:18:20 2019 CEST(132020075000000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:22 2019 CET(131968235020000000) => Sat May 11 02:18:22 2019 CEST(132020075020000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:22 2019 CET(131968235020000000) => Sat May 11 02:18:22 2019 CEST(132020075020000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:22 2019 CET(131968235020000000) => Sat May 11 02:18:22 2019 CEST(132020075020000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:22 2019 CET(131968235020000000) => Sat May 11 02:18:22 2019 CEST(132020075020000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:22 2019 CET(131968235020000000) => Sat May 11 02:18:22 2019 CEST(132020075020000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:22 2019 CET(131968235020000000) => Sat May 11 02:18:22 2019 CEST(132020075020000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:22 2019 CET(131968235020000000) => Sat May 11 02:18:22 2019 CEST(132020075020000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:22 2019 CET(131968235020000000) => Sat May 11 02:18:22 2019 CEST(132020075020000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:22 2019 CET(131968235020000000) => Sat May 11 02:18:22 2019 CEST(132020075020000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:24 2019 CET(131968235040000000) => Sat May 11 02:18:24 2019 CEST(132020075040000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:24 2019 CET(131968235040000000) => Sat May 11 02:18:24 2019 CEST(132020075040000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:24 2019 CET(131968235040000000) => Sat May 11 02:18:24 2019 CEST(132020075040000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:24 2019 CET(131968235040000000) => Sat May 11 02:18:24 2019 CEST(132020075040000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:24 2019 CET(131968235040000000) => Sat May 11 02:18:24 2019 CEST(132020075040000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:24 2019 CET(131968235040000000) => Sat May 11 02:18:24 2019 CEST(132020075040000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:24 2019 CET(131968235040000000) => Sat May 11 02:18:24 2019 CEST(132020075040000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:24 2019 CET(131968235040000000) => Sat May 11 02:18:24 2019 CEST(132020075040000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:24 2019 CET(131968235040000000) => Sat May 11 02:18:24 2019 CEST(132020075040000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:26 2019 CET(131968235060000000) => Sat May 11 02:18:26 2019 CEST(132020075060000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:26 2019 CET(131968235060000000) => Sat May 11 02:18:26 2019 CEST(132020075060000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:26 2019 CET(131968235060000000) => Sat May 11 02:18:26 2019 CEST(132020075060000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:26 2019 CET(131968235060000000) => Sat May 11 02:18:26 2019 CEST(132020075060000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:26 2019 CET(131968235060000000) => Sat May 11 02:18:26 2019 CEST(132020075060000000)
(../../source4/torture/smb2/util.c:158) change_time not setup correctly: Tue Mar 12 01:18:26 2019 CET(131968235060000000) => Sat May 11 02:18:26 2019 CEST(132020075060000000)
UNEXPECTED(failure): samba3.smb2.notify.mask(nt4_dc)
REASON: Exception: Exception: ERROR: nchanges=1 action=2 expectedAction=1 filter=0x00000002

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.raw.notify(nt4_dc) -> 108
samba3.blackbox.net.rpc.conf(nt4_dc) -> 64
samba3.raw.search(nt4_dc) -> 62
samba3.blackbox.net.local.conf(nt4_dc:local) -> 47
samba3.raw.oplock(nt4_dc) -> 45
samba3.base.dir2(nt4_dc) -> 45
samba3.raw.lock(nt4_dc) -> 34
samba3.smbtorture_s3.crypt_client.OPLOCK1(nt4_dc) -> 30
samba3.blackbox.smbclient_s3.SMB3.sign (nt4_dc)(nt4_dc) -> 21
samba3.blackbox.smbclient_s3.NT1.crypt (nt4_dc)(nt4_dc) -> 20
ERROR: test failed with exit code 1



More information about the samba-cvs mailing list