hashicorp / hashicorp/consul

Make TestAgentConnectCALeafCert_aclDefaultDeny determinstic

Open
#7,085 3 comments 0 reactions 0 assignees View on GitHub
theme/testing
Dominant language
Go
Stars
30.1k
Forks
4.6k
Avg merge
2d 6h
Merged PRs (30d)
43

Description

Failed locally during an unrelated test run. Passed on a subsequent run. Failing logs below

```
=== RUN TestAgentConnectCALeafCert_aclDefaultDeny
=== PAUSE TestAgentConnectCALeafCert_aclDefaultDeny
=== CONT TestAgentConnectCALeafCert_aclDefaultDeny
--- FAIL: TestAgentConnectCALeafCert_aclDefaultDeny (1.06s)
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.329055 [WARN] The 'acl_datacenter' field is deprecated. Use the 'primary_datacenter' field instead.
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.329072 [WARN] bootstrap = true: do not enable unless necessary
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.329215 [DEBUG] tlsutil: Update with version 1
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.329496 [DEBUG] tlsutil: OutgoingRPCWrapper with version 1
writer.go:21: 2020/01/17 11:50:41 [INFO] raft: Initial configuration (index=1): [{Suffrage:Voter ID:3a6cffc9-952e-799d-ca63-2d30536c456a Address:127.0.0.1:44994}]
writer.go:21: 2020/01/17 11:50:41 [INFO] raft: Node at 127.0.0.1:44994 [Follower] entering Follower state (Leader: "")
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.597597 [INFO] serf: EventMemberJoin: Node-3a6cffc9-952e-799d-ca63-2d30536c456a.dc1 127.0.0.1
writer.go:21: 2020/01/17 11:50:41 [WARN] raft: Heartbeat timeout from "" reached, starting election
writer.go:21: 2020/01/17 11:50:41 [INFO] raft: Node at 127.0.0.1:44994 [Candidate] entering Candidate state in term 2
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.618793 [INFO] serf: EventMemberJoin: Node-3a6cffc9-952e-799d-ca63-2d30536c456a 127.0.0.1
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.618943 [INFO] consul: Handled member-join event for server "Node-3a6cffc9-952e-799d-ca63-2d30536c456a.dc1" in area "wan"
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.618957 [INFO] consul: Adding LAN server Node-3a6cffc9-952e-799d-ca63-2d30536c456a (Addr: tcp/127.0.0.1:44994) (DC: dc1)
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.619253 [INFO] agent: Started DNS server 127.0.0.1:45007 (tcp)
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.619295 [INFO] agent: Started DNS server 127.0.0.1:45007 (udp)
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.619791 [INFO] agent: Started HTTP server on 127.0.0.1:45008 (tcp)
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.619824 [INFO] agent: started state syncer
writer.go:21: 2020/01/17 11:50:41 [INFO] raft: Election won. Tally: 1
writer.go:21: 2020/01/17 11:50:41 [INFO] raft: Node at 127.0.0.1:44994 [Leader] entering Leader state
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.718984 [INFO] consul: cluster leadership acquired
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.719209 [INFO] consul: New leader elected: Node-3a6cffc9-952e-799d-ca63-2d30536c456a
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.772385 [INFO] acl: initializing acls
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.803265 [INFO] acl: initializing acls
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.803513 [INFO] consul: Created ACL 'global-management' policy
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.803580 [WARN] consul: Configuring a non-UUID master token is deprecated
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.888724 [INFO] consul: Created ACL 'global-management' policy
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.888797 [WARN] consul: Configuring a non-UUID master token is deprecated
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.888872 [INFO] consul: Bootstrapped ACL master token from configuration
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.929944 [INFO] consul: Bootstrapped ACL master token from configuration
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.973229 [INFO] consul: Created ACL anonymous token from configuration
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.973263 [INFO] leader: started legacy ACL token upgrade routine
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.973273 [INFO] leader: started acl token reaping routine
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.973283 [DEBUG] acl: transitioning out of legacy ACL mode
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.973376 [INFO] serf: EventMemberUpdate: Node-3a6cffc9-952e-799d-ca63-2d30536c456a
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:41.973479 [INFO] serf: EventMemberUpdate: Node-3a6cffc9-952e-799d-ca63-2d30536c456a.dc1
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.006338 [INFO] consul: Created ACL anonymous token from configuration
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.006736 [INFO] serf: EventMemberUpdate: Node-3a6cffc9-952e-799d-ca63-2d30536c456a
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.007068 [INFO] serf: EventMemberUpdate: Node-3a6cffc9-952e-799d-ca63-2d30536c456a.dc1
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.076989 [INFO] agent: Synced node info
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.077074 [DEBUG] agent: Node info in sync
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.099559 [DEBUG] consul: dropping node "Node-3a6cffc9-952e-799d-ca63-2d30536c456a" from result due to ACLs
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.184638 [DEBUG] consul CA provider configured ID=07:80:c8:de:f6:41:86:29:8f:9c:b8:17:d6:48:c2:d5:c5:5c:7f:0c:03:f7:cf:97:5a:a7:c1:68:aa:23:ae:81 IsPrimary=true
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.285540 [INFO] agent: Synced service "test-id"
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.285642 [DEBUG] agent: Check "service:test-id" in sync
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.285677 [DEBUG] agent: Node info in sync
require.go:1159:
Error Trace: agent_endpoint_test.go:4710
Error: Should be true
Test: TestAgentConnectCALeafCert_aclDefaultDeny
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.286784 [INFO] agent: Requesting shutdown
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.286820 [INFO] consul: shutting down server
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.286837 [DEBUG] leader: stopping legacy ACL token upgrade routine
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.286851 [DEBUG] leader: stopping acl token reaping routine
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.286863 [WARN] serf: Shutdown without a Leave
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.286885 [DEBUG] leader: stopped legacy ACL token upgrade routine
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.286901 [DEBUG] leader: stopped acl token reaping routine
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.311081 [WARN] serf: Shutdown without a Leave
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.317677 [INFO] connect: initialized primary datacenter CA with provider "consul"
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.317753 [INFO] leader: started CA root pruning routine
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.317912 [DEBUG] consul: Skipping self join check for "Node-3a6cffc9-952e-799d-ca63-2d30536c456a" since the cluster is too small
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.317984 [INFO] consul: member 'Node-3a6cffc9-952e-799d-ca63-2d30536c456a' joined, marking health alive
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.346816 [INFO] manager: shutting down
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.366628 [ERR] consul: failed to reconcile member: {Node-3a6cffc9-952e-799d-ca63-2d30536c456a 127.0.0.1 45010 map[acls:1 bootstrap:1 build:1.7.0beta2: dc:dc1 id:3a6cffc9-952e-799d-ca63-2d30536c456a port:44994 raft_vsn:3 role:consul segment: vsn:2 vsn_max:3 vsn_min:2 wan_join_port:45011] alive 1 5 2 2 5 4}: leadership lost while committing log
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.367056 [DEBUG] leader: stopping CA root pruning routine
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.367144 [DEBUG] leader: stopped CA root pruning routine
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.367288 [INFO] agent: consul server down
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.367407 [INFO] agent: shutdown complete
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.367448 [INFO] agent: Stopping DNS server 127.0.0.1:45007 (tcp)
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.367621 [INFO] agent: Stopping DNS server 127.0.0.1:45007 (udp)
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.367819 [INFO] agent: Stopping HTTP server 127.0.0.1:45008 (tcp)
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.368270 [INFO] agent: Waiting for endpoints to shut down
log.go:172: TestAgentConnectCALeafCert_aclDefaultDeny - 2020/01/17 11:50:42.368338 [INFO] agent: Endpoints down
FAIL agent.TestAgentConnectCALeafCert_aclDefaultDeny (1.06s)
```

Contributor guide

Open the contributing guide

Assessment

This issue has not been assessed yet.

Get new issues in your inbox

A short digest of beginner-friendly GitHub issues.