[pkg-go] Bug#796469: golang-github-hashicorp-raft: Testsuite non-determistically FTBFS

Chris Lamb lamby at debian.org
Fri Aug 21 22:28:36 UTC 2015


Source: golang-github-hashicorp-raft
Version: 0.0~git20150728.9b586e2-1
Severity: serious
Justification: fails to build from source
User: reproducible-builds at lists.alioth.debian.org
Usertags: ftbfs
X-Debbugs-Cc: reproducible-builds at lists.alioth.debian.org

Dear Maintainer,

golang-github-hashicorp-raft's testsuite appears to
non-deterministically FTBFS. After building, you can run something like:

 $ while GOPATH=`pwd`/obj-* go test -v github.com/hashicorp/raft; do :;
 done

.. until it fails. Can't immediately see the cause, but it's usually
trying to do timing benchmarking.

  [..]

  2015/08/21 19:52:25 [DEBUG] raft: Node
  14ffb7d1-934e-1c54-963e-625fffcf5712 updated peer set (2):
  [14ffb7d1-934e-1c54-963e-625fffcf5712
  71336609-5fde-33cf-270f-0fa9e2b3f1bb
  1f994bc3-bf1b-babf-6163-223be2af4701]
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  14ffb7d1-934e-1c54-963e-625fffcf5712
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  1f994bc3-bf1b-babf-6163-223be2af4701
  2015/08/21 19:52:25 [ERR] raft: Failed to heartbeat to
  1f994bc3-bf1b-babf-6163-223be2af4701: command timed out
  --- PASS: TestRaft_ReJoinFollower (0.26s)
  === RUN TestRaft_Barrier
  2015/08/21 19:52:25 [WARN] Fully Connecting
  2015/08/21 19:52:25 [INFO] raft: Node at
  790a9412-886b-6160-5527-bdbde4d6be70 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [INFO] raft: Node at
  dd8a41b6-ddc6-3ac7-95f3-af641b85b839 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [INFO] raft: Node at
  2adc7425-6dc1-a7ff-f1a5-3cf2c4827768 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting
  election
  2015/08/21 19:52:25 [INFO] raft: Node at
  dd8a41b6-ddc6-3ac7-95f3-af641b85b839 [Candidate] entering Candidate
  state
  2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2
  2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2
  2015/08/21 19:52:25 [INFO] raft: Node at
  dd8a41b6-ddc6-3ac7-95f3-af641b85b839 [Leader] entering Leader state
  2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer
  790a9412-886b-6160-5527-bdbde4d6be70
  2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer
  2adc7425-6dc1-a7ff-f1a5-3cf2c4827768
  2015/08/21 19:52:25 [DEBUG] raft: Node
  dd8a41b6-ddc6-3ac7-95f3-af641b85b839 updated peer set (2):
  [dd8a41b6-ddc6-3ac7-95f3-af641b85b839
  790a9412-886b-6160-5527-bdbde4d6be70
  2adc7425-6dc1-a7ff-f1a5-3cf2c4827768]
  2015/08/21 19:52:25 [DEBUG] raft: Node
  790a9412-886b-6160-5527-bdbde4d6be70 updated peer set (2):
  [dd8a41b6-ddc6-3ac7-95f3-af641b85b839
  790a9412-886b-6160-5527-bdbde4d6be70
  2adc7425-6dc1-a7ff-f1a5-3cf2c4827768]
  2015/08/21 19:52:25 [DEBUG] raft: Node
  2adc7425-6dc1-a7ff-f1a5-3cf2c4827768 updated peer set (2):
  [dd8a41b6-ddc6-3ac7-95f3-af641b85b839
  790a9412-886b-6160-5527-bdbde4d6be70
  2adc7425-6dc1-a7ff-f1a5-3cf2c4827768]
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  790a9412-886b-6160-5527-bdbde4d6be70
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  2adc7425-6dc1-a7ff-f1a5-3cf2c4827768
  --- PASS: TestRaft_Barrier (0.09s)
  === RUN TestRaft_VerifyLeader
  2015/08/21 19:52:25 [WARN] Fully Connecting
  2015/08/21 19:52:25 [INFO] raft: Node at
  b1cd7c66-a0bf-222d-a086-2c0198eb5f79 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [INFO] raft: Node at
  54f89f50-7725-5a6c-6ea4-b26b5ed7ee9e [Follower] entering Follower
  state
  2015/08/21 19:52:25 [INFO] raft: Node at
  70450dd8-4161-87e8-77af-b53d5583ff67 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting
  election
  2015/08/21 19:52:25 [INFO] raft: Node at
  70450dd8-4161-87e8-77af-b53d5583ff67 [Candidate] entering Candidate
  state
  2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2
  2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2
  2015/08/21 19:52:25 [INFO] raft: Node at
  70450dd8-4161-87e8-77af-b53d5583ff67 [Leader] entering Leader state
  2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer
  b1cd7c66-a0bf-222d-a086-2c0198eb5f79
  2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer
  54f89f50-7725-5a6c-6ea4-b26b5ed7ee9e
  2015/08/21 19:52:25 [DEBUG] raft: Node
  70450dd8-4161-87e8-77af-b53d5583ff67 updated peer set (2):
  [70450dd8-4161-87e8-77af-b53d5583ff67
  b1cd7c66-a0bf-222d-a086-2c0198eb5f79
  54f89f50-7725-5a6c-6ea4-b26b5ed7ee9e]
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  b1cd7c66-a0bf-222d-a086-2c0198eb5f79
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  54f89f50-7725-5a6c-6ea4-b26b5ed7ee9e
  --- PASS: TestRaft_VerifyLeader (0.06s)
  === RUN TestRaft_VerifyLeader_Single
  2015/08/21 19:52:25 [WARN] Fully Connecting
  2015/08/21 19:52:25 [INFO] raft: Node at
  a96449c4-9bfc-ca7f-acb6-582bc0fcd678 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting
  election
  2015/08/21 19:52:25 [INFO] raft: Node at
  a96449c4-9bfc-ca7f-acb6-582bc0fcd678 [Candidate] entering Candidate
  state
  2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 1
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1
  2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 1
  2015/08/21 19:52:25 [INFO] raft: Node at
  a96449c4-9bfc-ca7f-acb6-582bc0fcd678 [Leader] entering Leader state
  2015/08/21 19:52:25 [INFO] raft: Disabling EnableSingleNode
  (bootstrap)
  2015/08/21 19:52:25 [DEBUG] raft: Node
  a96449c4-9bfc-ca7f-acb6-582bc0fcd678 updated peer set (2):
  [a96449c4-9bfc-ca7f-acb6-582bc0fcd678]
  --- PASS: TestRaft_VerifyLeader_Single (0.10s)
  === RUN TestRaft_VerifyLeader_Fail
  2015/08/21 19:52:25 [WARN] Fully Connecting
  2015/08/21 19:52:25 [INFO] raft: Node at
  53128c5a-caf4-67c1-bb47-18d2588ca006 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [INFO] raft: Node at
  1a8f0d37-4926-047d-5a1e-173da9fcb448 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting
  election
  2015/08/21 19:52:25 [INFO] raft: Node at
  1a8f0d37-4926-047d-5a1e-173da9fcb448 [Candidate] entering Candidate
  state
  2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2
  2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2
  2015/08/21 19:52:25 [INFO] raft: Node at
  1a8f0d37-4926-047d-5a1e-173da9fcb448 [Leader] entering Leader state
  2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer
  53128c5a-caf4-67c1-bb47-18d2588ca006
  2015/08/21 19:52:25 [DEBUG] raft: Node
  1a8f0d37-4926-047d-5a1e-173da9fcb448 updated peer set (2):
  [1a8f0d37-4926-047d-5a1e-173da9fcb448
  53128c5a-caf4-67c1-bb47-18d2588ca006]
  2015/08/21 19:52:25 [DEBUG] raft: Node
  53128c5a-caf4-67c1-bb47-18d2588ca006 updated peer set (2):
  [1a8f0d37-4926-047d-5a1e-173da9fcb448
  53128c5a-caf4-67c1-bb47-18d2588ca006]
  2015/08/21 19:52:25 [WARN] raft: New leader elected, stepping down
  2015/08/21 19:52:25 [INFO] raft: Node at
  1a8f0d37-4926-047d-5a1e-173da9fcb448 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  53128c5a-caf4-67c1-bb47-18d2588ca006
  --- PASS: TestRaft_VerifyLeader_Fail (0.08s)
  === RUN TestRaft_VerifyLeader_ParitalConnect
  2015/08/21 19:52:25 [WARN] Fully Connecting
  2015/08/21 19:52:25 [INFO] raft: Node at
  04e0b808-56df-9a2e-d6fe-b8952958f641 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [INFO] raft: Node at
  02f25c2e-afac-6a0c-da05-d2de959e8f4c [Follower] entering Follower
  state
  2015/08/21 19:52:25 [INFO] raft: Node at
  975c21e9-a021-6d28-b10b-5340f1fb8b36 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting
  election
  2015/08/21 19:52:25 [INFO] raft: Node at
  04e0b808-56df-9a2e-d6fe-b8952958f641 [Candidate] entering Candidate
  state
  2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2
  2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2
  2015/08/21 19:52:25 [INFO] raft: Node at
  04e0b808-56df-9a2e-d6fe-b8952958f641 [Leader] entering Leader state
  2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer
  02f25c2e-afac-6a0c-da05-d2de959e8f4c
  2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer
  975c21e9-a021-6d28-b10b-5340f1fb8b36
  2015/08/21 19:52:25 [DEBUG] raft: Node
  04e0b808-56df-9a2e-d6fe-b8952958f641 updated peer set (2):
  [04e0b808-56df-9a2e-d6fe-b8952958f641
  02f25c2e-afac-6a0c-da05-d2de959e8f4c
  975c21e9-a021-6d28-b10b-5340f1fb8b36]
  2015/08/21 19:52:25 [DEBUG] raft: Node
  975c21e9-a021-6d28-b10b-5340f1fb8b36 updated peer set (2):
  [04e0b808-56df-9a2e-d6fe-b8952958f641
  02f25c2e-afac-6a0c-da05-d2de959e8f4c
  975c21e9-a021-6d28-b10b-5340f1fb8b36]
  2015/08/21 19:52:25 [DEBUG] raft: Node
  02f25c2e-afac-6a0c-da05-d2de959e8f4c updated peer set (2):
  [04e0b808-56df-9a2e-d6fe-b8952958f641
  02f25c2e-afac-6a0c-da05-d2de959e8f4c
  975c21e9-a021-6d28-b10b-5340f1fb8b36]
  2015/08/21 19:52:25 [INFO] Disconnecting Node at
  02f25c2e-afac-6a0c-da05-d2de959e8f4c [Follower]
  2015/08/21 19:52:25 [WARN] Disconnecting
  02f25c2e-afac-6a0c-da05-d2de959e8f4c
  2015/08/21 19:52:25 [ERR] raft: Failed to pipeline AppendEntries to
  02f25c2e-afac-6a0c-da05-d2de959e8f4c: append pipeline closed
  2015/08/21 19:52:25 [ERR] raft: Failed to heartbeat to
  02f25c2e-afac-6a0c-da05-d2de959e8f4c: failed to connect to peer:
  02f25c2e-afac-6a0c-da05-d2de959e8f4c
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  02f25c2e-afac-6a0c-da05-d2de959e8f4c
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  975c21e9-a021-6d28-b10b-5340f1fb8b36
  --- PASS: TestRaft_VerifyLeader_ParitalConnect (0.09s)
  === RUN TestRaft_SettingPeers
  2015/08/21 19:52:25 [WARN] Fully Connecting
  2015/08/21 19:52:25 [INFO] raft: Node at
  d2ca38f8-08b9-98e8-ce62-1bb0a38dd449 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [INFO] raft: Node at
  536ef089-76e5-bea4-a180-0cbaacb89595 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [INFO] raft: Node at
  757afb5c-ca3a-c7f9-c9af-617ce13ca082 [Follower] entering Follower
  state
  2015/08/21 19:52:25 [WARN] raft: Heartbeat timeout reached, starting
  election
  2015/08/21 19:52:25 [INFO] raft: Node at
  757afb5c-ca3a-c7f9-c9af-617ce13ca082 [Candidate] entering Candidate
  state
  2015/08/21 19:52:25 [DEBUG] raft: Votes needed: 2
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 1
  2015/08/21 19:52:25 [DEBUG] raft: Vote granted. Tally: 2
  2015/08/21 19:52:25 [INFO] raft: Election won. Tally: 2
  2015/08/21 19:52:25 [INFO] raft: Node at
  757afb5c-ca3a-c7f9-c9af-617ce13ca082 [Leader] entering Leader state
  2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer
  d2ca38f8-08b9-98e8-ce62-1bb0a38dd449
  2015/08/21 19:52:25 [INFO] raft: pipelining replication to peer
  536ef089-76e5-bea4-a180-0cbaacb89595
  2015/08/21 19:52:25 [DEBUG] raft: Node
  757afb5c-ca3a-c7f9-c9af-617ce13ca082 updated peer set (2):
  [757afb5c-ca3a-c7f9-c9af-617ce13ca082
  d2ca38f8-08b9-98e8-ce62-1bb0a38dd449
  536ef089-76e5-bea4-a180-0cbaacb89595]
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  d2ca38f8-08b9-98e8-ce62-1bb0a38dd449
  2015/08/21 19:52:25 [INFO] raft: aborting pipeline replication to peer
  536ef089-76e5-bea4-a180-0cbaacb89595
  --- PASS: TestRaft_SettingPeers (0.08s)
  === RUN TestTCPTransport_BadAddr
  --- PASS: TestTCPTransport_BadAddr (0.00s)
  === RUN TestTCPTransport_WithAdvertise
  --- PASS: TestTCPTransport_WithAdvertise (0.00s)
  === RUN TestRandomTimeout
  --- PASS: TestRandomTimeout (0.00s)
  === RUN TestNewSeed
  --- PASS: TestNewSeed (0.00s)
  === RUN TestRandomTimeout_NoTime
  --- PASS: TestRandomTimeout_NoTime (0.00s)
  === RUN TestMin
  --- PASS: TestMin (0.00s)
  === RUN TestMax
  --- PASS: TestMax (0.00s)
  === RUN TestGenerateUUID
  --- PASS: TestGenerateUUID (0.03s)
  === RUN TestAsyncNotify
  --- PASS: TestAsyncNotify (0.00s)
  === RUN TestExcludePeer
  --- PASS: TestExcludePeer (0.00s)
  === RUN TestPeerContained
  --- PASS: TestPeerContained (0.00s)
  === RUN TestAddUniquePeer
  --- PASS: TestAddUniquePeer (0.00s)
  === RUN TestEncodeDecodePeers
  --- PASS: TestEncodeDecodePeers (0.00s)
  === RUN TestBackoff
  --- PASS: TestBackoff (0.00s)
  FAIL
  FAIL    github.com/hashicorp/raft       3.596s
  ?       github.com/hashicorp/raft/bench [no test files]
  dh_auto_test: go test -v github.com/hashicorp/raft
  github.com/hashicorp/raft/bench returned exit code 1
  debian/rules:9: recipe for target 'build' failed
  make: *** [build] Error 1
  dpkg-buildpackage: error: debian/rules build gave error exit status 2

  [..]

The full build log is attached or can be viewed here:

    https://reproducible.debian.net/logs/unstable/amd64/golang-github-hashicorp-raft_0.0~git20150728.9b586e2-1.build2.log.gz


Regards,

-- 
      ,''`.
     : :'  :     Chris Lamb
     `. `'`      lamby at debian.org / chris-lamb.co.uk
       `-
-------------- next part --------------
An embedded and charset-unspecified text was scrubbed...
Name: golang-github-hashicorp-raft.0.0~git20150728.9b586e2-1.unstable.amd64.log.txt
URL: <http://lists.alioth.debian.org/pipermail/pkg-go-maintainers/attachments/20150821/1fc1f368/attachment-0001.txt>


More information about the Pkg-go-maintainers mailing list