go: "TestKVWithEmptyValue" of etcd-io/etcd fails with master go
What version of Go are you using (go version
)?
go version devel go1.17-5daefc5363 Thu Apr 22 01:40:02 2021 +0000 linux/ppc64le
Does this issue reproduce with the latest release?
No
What operating system and processor architecture are you using (go env
)?
Linux ppc64le
What did you do?
go test go.etcd.io/etcd/tests/v3/integration
Ran integration tests of etcd-io/etcd
What did you expect to see?
Should Pass successfully. NOTE: It passed with the stable release.
[root@rajalakshmi1-workspace etcd]# go test go.etcd.io/etcd/tests/v3/integration
ok go.etcd.io/etcd/tests/v3/integration 191.044s
[root@rajalakshmi1-workspace etcd]# go version
go version go1.16 linux/ppc64le
[root@rajalakshmi1-workspace etcd]
What did you see instead?
Following Error for TestKVWithEmptyValue:
--- FAIL: TestKVWithEmptyValue (0.12s)
logger.go:130: 2021-04-22T02:16:41.750Z INFO m0 LISTEN GRPC {"member": "m0", "m.grpcAddr": "localhost:m0", "m.Name": "m0"}
logger.go:130: 2021-04-22T02:16:41.751Z INFO m0 launching a member {"member": "m0", "name": "m0", "advertise-peer-urls": ["unix://127.0.0.1:215174179541"], "listen-client-urls": ["unix://127.0.0.1:215184179541"], "grpc-address": "unix://localhost:m00"}
logger.go:130: 2021-04-22T02:16:41.752Z INFO m0 opened backend db {"member": "m0", "path": "/tmp/TestKVWithEmptyValue3985847728/002/etcd4041780374/member/snap/db", "took": "1.166028ms"}
logger.go:130: 2021-04-22T02:16:41.753Z INFO m0 starting local member {"member": "m0", "local-member-id": "fa19bbaf425c7e1c", "cluster-id": "5ed47e72b9de8e47"}
logger.go:130: 2021-04-22T02:16:41.753Z INFO m0.raft fa19bbaf425c7e1c switched to configuration voters=() {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.753Z INFO m0.raft fa19bbaf425c7e1c became follower at term 0 {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.753Z INFO m0.raft newRaft fa19bbaf425c7e1c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0] {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.753Z INFO m0.raft fa19bbaf425c7e1c became follower at term 1 {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.753Z INFO m0.raft fa19bbaf425c7e1c switched to configuration voters=(18021641745306779164) {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.754Z WARN m0 simple token is not cryptographically signed {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.756Z INFO m0 starting etcd server {"member": "m0", "local-member-id": "fa19bbaf425c7e1c", "local-server-version": "3.5.0-alpha.0", "cluster-version": "to_be_decided"}
logger.go:130: 2021-04-22T02:16:41.757Z INFO m0 started as single-node; fast-forwarding election ticks {"member": "m0", "local-member-id": "fa19bbaf425c7e1c", "forward-ticks": 9, "forward-duration": "90ms", "election-ticks": 10, "election-timeout": "100ms"}
logger.go:130: 2021-04-22T02:16:41.757Z INFO m0.raft fa19bbaf425c7e1c switched to configuration voters=(18021641745306779164) {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.757Z INFO m0 added member {"member": "m0", "cluster-id": "5ed47e72b9de8e47", "local-member-id": "fa19bbaf425c7e1c", "added-peer-id": "fa19bbaf425c7e1c", "added-peer-peer-urls": ["unix://127.0.0.1:215174179541"]}
logger.go:130: 2021-04-22T02:16:41.758Z INFO m0 launched a member {"member": "m0", "name": "m0", "advertise-peer-urls": ["unix://127.0.0.1:215174179541"], "listen-client-urls": ["unix://127.0.0.1:215184179541"], "grpc-address": "unix://localhost:m00"}
logger.go:130: 2021-04-22T02:16:41.854Z INFO m0.raft fa19bbaf425c7e1c is starting a new election at term 1 {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.854Z INFO m0.raft fa19bbaf425c7e1c became candidate at term 2 {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.854Z INFO m0.raft fa19bbaf425c7e1c received MsgVoteResp from fa19bbaf425c7e1c at term 2 {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.854Z INFO m0.raft fa19bbaf425c7e1c became leader at term 2 {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.854Z INFO m0.raft raft.node: fa19bbaf425c7e1c elected leader fa19bbaf425c7e1c at term 2 {"member": "m0"}
logger.go:130: 2021-04-22T02:16:41.854Z INFO m0 published local member to cluster through raft {"member": "m0", "local-member-id": "fa19bbaf425c7e1c", "local-member-attributes": "{Name:m0 ClientURLs:[unix://127.0.0.1:215184179541]}", "request-path": "/0/members/fa19bbaf425c7e1c/attributes", "cluster-id": "5ed47e72b9de8e47", "publish-timeout": "5.2s"}
logger.go:130: 2021-04-22T02:16:41.854Z INFO m0 setting up initial cluster version {"member": "m0", "cluster-version": "3.5"}
logger.go:130: 2021-04-22T02:16:41.854Z INFO m0 set initial cluster version {"member": "m0", "cluster-id": "5ed47e72b9de8e47", "local-member-id": "fa19bbaf425c7e1c", "cluster-version": "3.5"}
logger.go:130: 2021-04-22T02:16:41.854Z INFO m0 cluster version is updated {"member": "m0", "cluster-version": "3.5"}
cluster.go:251: - m0 -> fa19bbaf425c7e1c (unix://localhost:m00)
logger.go:130: 2021-04-22T02:16:41.858Z INFO grpc [[core] parsed scheme: "etcd-endpoints"]
logger.go:130: 2021-04-22T02:16:41.858Z INFO grpc [[core] ccResolverWrapper: sending update to cc: {[{unix:localhost:m00 localhost <nil> 0 <nil>}] 0xc00adc6ba0 <nil>}]
logger.go:130: 2021-04-22T02:16:41.858Z INFO grpc [[core] ClientConn switching balancer to "round_robin"]
logger.go:130: 2021-04-22T02:16:41.858Z INFO grpc [[core] Channel switches to new LB policy "round_robin"]
logger.go:130: 2021-04-22T02:16:41.858Z INFO grpc [[balancer] base.baseBalancer: got new ClientConn state: {{[{unix:localhost:m00 localhost <nil> 0 <nil>}] 0xc00adc6ba0 <nil>} <nil>}]
logger.go:130: 2021-04-22T02:16:41.858Z INFO grpc [[core] Subchannel Connectivity change to CONNECTING]
logger.go:130: 2021-04-22T02:16:41.858Z INFO grpc [[core] Subchannel picks a new address "unix:localhost:m00" to connect]
logger.go:130: 2021-04-22T02:16:41.858Z INFO grpc [[balancer] base.baseBalancer: handle SubConn state change: 0xc0137e3ab0, CONNECTING]
logger.go:130: 2021-04-22T02:16:41.858Z INFO grpc [[core] Channel Connectivity change to CONNECTING]
logger.go:130: 2021-04-22T02:16:41.861Z INFO grpc [[core] Subchannel Connectivity change to READY]
logger.go:130: 2021-04-22T02:16:41.861Z INFO grpc [[balancer] base.baseBalancer: handle SubConn state change: 0xc0137e3ab0, READY]
logger.go:130: 2021-04-22T02:16:41.861Z INFO grpc [[roundrobin] roundrobinPicker: newPicker called with info: {map[0xc0137e3ab0:{{unix:localhost:m00 localhost <nil> 0 <nil>}}]}]
logger.go:130: 2021-04-22T02:16:41.861Z INFO grpc [[core] Channel Connectivity change to READY]
v3_kv_test.go:39: my-namespace/foobar = data
v3_kv_test.go:39: my-namespace/foobar1 = data
v3_kv_test.go:39: namespace/foobar1 = data
v3_kv_test.go:46: etcdserver: key is not provided
logger.go:130: 2021-04-22T02:16:41.865Z INFO grpc [[core] Channel Connectivity change to SHUTDOWN]
logger.go:130: 2021-04-22T02:16:41.865Z INFO grpc [[core] Subchannel Connectivity change to SHUTDOWN]
logger.go:130: 2021-04-22T02:16:41.865Z INFO m0 terminating a member {"member": "m0", "name": "m0", "advertise-peer-urls": ["unix://127.0.0.1:215174179541"], "listen-client-urls": ["unix://127.0.0.1:215184179541"], "grpc-address": "unix://localhost:m00"}
logger.go:130: 2021-04-22T02:16:41.865Z INFO grpc [[transport] transport: loopyWriter.run returning. connection error: desc = "transport is closing"]
logger.go:130: 2021-04-22T02:16:41.865Z INFO grpc [[transport] transport: loopyWriter.run returning. connection error: desc = "transport is closing"]
Please find the attached log etcd.log
About this issue
- Original URL
- State: closed
- Created 3 years ago
- Comments: 17 (13 by maintainers)
That doesn’t imply it is a Go bug. The code might depend on buggy or internal behavior of earlier versions of Go, which may change.