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

Mon Jul 23 17:01:50 UTC 2018

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/2018-07-23-1901/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/2018-07-23-1901/samba-nt4.stderr
   https://git.samba.org/autobuild.flakey.sn-devel-144/2018-07-23-1901/samba-nt4.stdout

The top commit at the time of the failure was:

commit 582ce5d6b599516d6d8d619529a2aa809139a175
Author: Jeremy Allison <jra at samba.org>
Date:   Wed Jul 18 15:49:29 2018 -0700

    s3: smbd: Fix AIX sendfile() for SMB2. Ensure we don't spin on EAGAIN.

    For SMB2 the socket is set non-blocking. Ensure sendfile()
    calls complete if they return EAGAIN by saving the socket state,
    setting it blocking, doing the sendfile until completion and then
    restoring the socket state.

    BUG: https://bugzilla.samba.org/show_bug.cgi?id=13537

    Signed-off-by: Jeremy Allison <jra at samba.org>
    Reviewed-by: Volker Lendecke <vl at samba.org>

    Autobuild-User(master): Volker Lendecke <vl at samba.org>
    Autobuild-Date(master): Fri Jul 20 15:14:24 CEST 2018 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: Wed Aug 22 18:42:54 2018 CEST(131794297740000000) => Sun Oct 21 18:42:54 2018 CEST(131846137740000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:54 2018 CEST(131794297740000000) => Sun Oct 21 18:42:54 2018 CEST(131846137740000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:54 2018 CEST(131794297740000000) => Sun Oct 21 18:42:54 2018 CEST(131846137740000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:54 2018 CEST(131794297740000000) => Sun Oct 21 18:42:54 2018 CEST(131846137740000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:54 2018 CEST(131794297740000000) => Sun Oct 21 18:42:54 2018 CEST(131846137740000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:54 2018 CEST(131794297740000000) => Sun Oct 21 18:42:54 2018 CEST(131846137740000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:54 2018 CEST(131794297740000000) => Sun Oct 21 18:42:54 2018 CEST(131846137740000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:54 2018 CEST(131794297740000000) => Sun Oct 21 18:42:54 2018 CEST(131846137740000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:56 2018 CEST(131794297760000000) => Sun Oct 21 18:42:56 2018 CEST(131846137760000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:56 2018 CEST(131794297760000000) => Sun Oct 21 18:42:56 2018 CEST(131846137760000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:56 2018 CEST(131794297760000000) => Sun Oct 21 18:42:56 2018 CEST(131846137760000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:56 2018 CEST(131794297760000000) => Sun Oct 21 18:42:56 2018 CEST(131846137760000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:56 2018 CEST(131794297760000000) => Sun Oct 21 18:42:56 2018 CEST(131846137760000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:56 2018 CEST(131794297760000000) => Sun Oct 21 18:42:56 2018 CEST(131846137760000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:56 2018 CEST(131794297760000000) => Sun Oct 21 18:42:56 2018 CEST(131846137760000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:56 2018 CEST(131794297760000000) => Sun Oct 21 18:42:56 2018 CEST(131846137760000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:56 2018 CEST(131794297760000000) => Sun Oct 21 18:42:56 2018 CEST(131846137760000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:58 2018 CEST(131794297780000000) => Sun Oct 21 18:42:58 2018 CEST(131846137780000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:58 2018 CEST(131794297780000000) => Sun Oct 21 18:42:58 2018 CEST(131846137780000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:58 2018 CEST(131794297780000000) => Sun Oct 21 18:42:58 2018 CEST(131846137780000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:58 2018 CEST(131794297780000000) => Sun Oct 21 18:42:58 2018 CEST(131846137780000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:58 2018 CEST(131794297780000000) => Sun Oct 21 18:42:58 2018 CEST(131846137780000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:58 2018 CEST(131794297780000000) => Sun Oct 21 18:42:58 2018 CEST(131846137780000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:58 2018 CEST(131794297780000000) => Sun Oct 21 18:42:58 2018 CEST(131846137780000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:58 2018 CEST(131794297780000000) => Sun Oct 21 18:42:58 2018 CEST(131846137780000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:42:58 2018 CEST(131794297780000000) => Sun Oct 21 18:42:58 2018 CEST(131846137780000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:43:00 2018 CEST(131794297800000000) => Sun Oct 21 18:43:00 2018 CEST(131846137800000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:43:00 2018 CEST(131794297800000000) => Sun Oct 21 18:43:00 2018 CEST(131846137800000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:43:00 2018 CEST(131794297800000000) => Sun Oct 21 18:43:00 2018 CEST(131846137800000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:43:00 2018 CEST(131794297800000000) => Sun Oct 21 18:43:00 2018 CEST(131846137800000000)
(../source4/torture/smb2/util.c:158) change_time not setup correctly: Wed Aug 22 18:43:00 2018 CEST(131794297800000000) => Sun Oct 21 18:43:00 2018 CEST(131846137800000000)
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) -> 102
samba3.raw.search(nt4_dc) -> 62
samba3.raw.oplock(nt4_dc) -> 46
samba3.blackbox.net.rpc.conf(nt4_dc) -> 45
samba3.raw.lock(nt4_dc) -> 33
samba3.smbtorture_s3.crypt_client(nt4_dc).OPLOCK1(nt4_dc) -> 30
samba3.blackbox.net.local.conf(nt4_dc:local) -> 29
samba3.smbtorture_s3.crypt_client(nt4_dc).LOCK1(nt4_dc) -> 20
samba3.base.defer_open(nt4_dc) -> 16
samba3.base.lock(nt4_dc) -> 15
ERROR: test failed with exit code 1