Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Robustness etcd traffic client #16092

Merged
merged 1 commit into from
Jun 19, 2023
Merged

Robustness etcd traffic client #16092

merged 1 commit into from
Jun 19, 2023

Conversation

serathius
Copy link
Member

No description provided.

@serathius serathius marked this pull request as draft June 16, 2023 09:23
@serathius serathius changed the title Robustness etcdctl traffic client Robustness etcd traffic client Jun 16, 2023
@serathius serathius force-pushed the robustness-etcdctl-traffic-client branch from af48b66 to 8373e1c Compare June 16, 2023 11:36
@serathius
tests/robustness: Refactor etcd traffic client
09b9f88 
Signed-off-by: Marek Siarkowicz <siarkowicz@google.com>
@serathius serathius force-pushed the robustness-etcdctl-traffic-client branch from 8373e1c to 09b9f88 Compare June 19, 2023 10:08
@serathius serathius marked this pull request as ready for review June 19, 2023 10:09
@serathius
Copy link
Member Author

cc @ahrtr @ptabor @jmhbnz @chaochn47 @fuweid

fuweid
fuweid approved these changes Jun 19, 2023
View reviewed changes
Copy link
Member

@fuweid fuweid left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

LGTM

@fuweid
Copy link
Member

fuweid commented Jun 19, 2023

The failure isn't related to this change.

Log 
2023-06-19T10:20:14.5093779Z === RUN   TestAuthLeaseTimeToLive
2023-06-19T10:20:14.5094272Z     before.go:36: Changing working directory to: /tmp/TestAuthLeaseTimeToLive3060925805/001
2023-06-19T10:20:14.5094990Z     logger.go:130: 2023-06-19T10:20:14.494Z	INFO	starting server...	{"name": "TestAuthLeaseTimeToLive-test-0"}
2023-06-19T10:20:14.5100010Z     logger.go:130: 2023-06-19T10:20:14.494Z	INFO	spawning process	{"args": ["/home/runner/work/etcd/etcd/bin/etcd", "--name", "TestAuthLeaseTimeToLive-test-0", "--listen-client-urls", "http://localhost:20000", "--advertise-client-urls", "http://localhost:20000", "--listen-peer-urls", "http://localhost:20001", "--initial-advertise-peer-urls", "http://localhost:20001", "--initial-cluster-token", "new", "--data-dir", "/tmp/TestAuthLeaseTimeToLive3060925805/002", "--snapshot-count", "10000", "--strict-reconfig-check=false", "--auth-token", "jwt,pub-key=/home/runner/work/etcd/etcd/tests/fixtures/server.crt,priv-key=/home/runner/work/etcd/etcd/tests/fixtures/server.key.insecure,sign-method=RS256,ttl=1s", "--initial-cluster", "TestAuthLeaseTimeToLive-test-0=http://localhost:20001", "--initial-cluster-state", "new"], "working-dir": "/tmp/TestAuthLeaseTimeToLive3060925805/001", "name": "TestAuthLeaseTimeToLive-test-0", "environment-variables": ["ETCD_VERIFY=all", "EXPECT_DEBUG=true", "PATH=/opt/hostedtoolcache/go/1.19.10/x64/bin:/home/runner/go/bin:/opt/hostedtoolcache/go/1.19.10/x64/bin:/home/runner/.local/bin:/opt/pipx_bin:/home/runner/.cargo/bin:/home/runner/.config/composer/vendor/bin:/usr/local/.ghcup/bin:/home/runner/.dotnet/tools:/snap/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin", "ETCD_UNSUPPORTED_ARCH=386"]}
2023-06-19T10:20:14.5105682Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.507772Z","caller":"etcdmain/etcd.go:274","msg":"running etcd on unsupported architecture since ETCD_UNSUPPORTED_ARCH is set","arch":"386"}
2023-06-19T10:20:14.5106774Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"warn","ts":"2023-06-19T10:20:14.508199Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_VERIFY=all"}
2023-06-19T10:20:14.5107857Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"warn","ts":"2023-06-19T10:20:14.508295Z","caller":"embed/config.go:708","msg":"Running http and grpc server on single port. This is not recommended for production."}
2023-06-19T10:20:14.5150068Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.508332Z","caller":"etcdmain/etcd.go:64","msg":"Running: ","args":["/home/runner/work/etcd/etcd/bin/etcd","--name","TestAuthLeaseTimeToLive-test-0","--listen-client-urls","http://localhost:20000","--advertise-client-urls","http://localhost:20000","--listen-peer-urls","http://localhost:20001","--initial-advertise-peer-urls","http://localhost:20001","--initial-cluster-token","new","--data-dir","/tmp/TestAuthLeaseTimeToLive3060925805/002","--snapshot-count","10000","--strict-reconfig-check=false","--auth-token","jwt,pub-key=/home/runner/work/etcd/etcd/tests/fixtures/server.crt,priv-key=/home/runner/work/etcd/etcd/tests/fixtures/server.key.insecure,sign-method=RS256,ttl=1s","--initial-cluster","TestAuthLeaseTimeToLive-test-0=http://localhost:20001","--initial-cluster-state","new"]}
2023-06-19T10:20:14.5155965Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.508401Z","caller":"etcdmain/etcd.go:124","msg":"Initialize and start etcd server","data-dir":"/tmp/TestAuthLeaseTimeToLive3060925805/002","dir-type":"empty"}
2023-06-19T10:20:14.5157340Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"warn","ts":"2023-06-19T10:20:14.508558Z","caller":"embed/config.go:708","msg":"Running http and grpc server on single port. This is not recommended for production."}
2023-06-19T10:20:14.5158743Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.508577Z","caller":"embed/etcd.go:125","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:20001"]}
2023-06-19T10:20:14.5159899Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.509027Z","caller":"embed/etcd.go:133","msg":"configuring client listeners","listen-client-urls":["http://localhost:20000"]}
2023-06-19T10:20:14.5165592Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.509225Z","caller":"embed/etcd.go:313","msg":"starting an etcd server","etcd-version":"3.6.0-alpha.0","git-sha":"032b94f","go-version":"go1.19.10","go-os":"linux","go-arch":"386","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"TestAuthLeaseTimeToLive-test-0","data-dir":"/tmp/TestAuthLeaseTimeToLive3060925805/002","wal-dir":"","wal-dir-dedicated":"","member-dir":"/tmp/TestAuthLeaseTimeToLive3060925805/002/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","wait-cluster-ready-timeout":"5s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:20001"],"listen-peer-urls":["http://localhost:20001"],"advertise-client-urls":["http://localhost:20000"],"listen-client-urls":["http://localhost:20000"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"TestAuthLeaseTimeToLive-test-0=http://localhost:20001","initial-cluster-state":"new","initial-cluster-token":"new","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","discovery-token":"","discovery-endpoints":"","discovery-dial-timeout":"2s","discovery-request-timeout":"5s","discovery-keepalive-time":"2s","discovery-keepalive-timeout":"6s","discovery-insecure-transport":true,"discovery-insecure-skip-tls-verify":false,"discovery-cert":"","discovery-key":"","discovery-cacert":"","discovery-user":"","downgrade-check-interval":"5s","max-learners":1}
2023-06-19T10:20:14.5176433Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.51064Z","caller":"storage/backend.go:80","msg":"opened backend db","path":"/tmp/TestAuthLeaseTimeToLive3060925805/002/member/snap/db","took":"1.115908ms"}
2023-06-19T10:20:14.5177725Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.512772Z","caller":"etcdserver/bootstrap.go:493","msg":"starting local member","local-member-id":"ca50e9357181d758","cluster-id":"34f27e83b3bc2ff"}
2023-06-19T10:20:14.5213491Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.512917Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:1697","msg":"ca50e9357181d758 switched to configuration voters=()"}
2023-06-19T10:20:14.5215364Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.512961Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:728","msg":"ca50e9357181d758 became follower at term 0"}
2023-06-19T10:20:14.5216684Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.512983Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:382","msg":"newRaft ca50e9357181d758 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
2023-06-19T10:20:14.5218022Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.513007Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:728","msg":"ca50e9357181d758 became follower at term 1"}
2023-06-19T10:20:14.5219553Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.513054Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:1697","msg":"ca50e9357181d758 switched to configuration voters=(14578408409545168728)"}
2023-06-19T10:20:14.5220851Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.518821Z","caller":"mvcc/kvstore.go:402","msg":"kvstore restored","current-rev":1}
2023-06-19T10:20:14.5222293Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.520054Z","caller":"storage/quota.go:93","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
2023-06-19T10:20:14.5224018Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.520185Z","caller":"etcdserver/server.go:583","msg":"starting etcd server","local-member-id":"ca50e9357181d758","local-server-version":"3.6.0-alpha.0","cluster-version":"to_be_decided"}
2023-06-19T10:20:14.5226230Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.5204Z","caller":"embed/etcd.go:282","msg":"now serving peer/client/metrics","local-member-id":"ca50e9357181d758","initial-advertise-peer-urls":["http://localhost:20001"],"listen-peer-urls":["http://localhost:20001"],"advertise-client-urls":["http://localhost:20000"],"listen-client-urls":["http://localhost:20000"],"listen-metrics-urls":[]}
2023-06-19T10:20:14.5228187Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.520539Z","caller":"etcdserver/server.go:468","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca50e9357181d758","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
2023-06-19T10:20:14.5229651Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.520526Z","caller":"embed/etcd.go:607","msg":"serving peer traffic","address":"127.0.0.1:20001"}
2023-06-19T10:20:14.5230964Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.520696Z","caller":"embed/etcd.go:579","msg":"cmux::serve","address":"127.0.0.1:20001"}
2023-06-19T10:20:14.5232424Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.520717Z","caller":"fileutil/purge.go:44","msg":"started to purge file","dir":"/tmp/TestAuthLeaseTimeToLive3060925805/002/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
2023-06-19T10:20:14.5234003Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.520798Z","caller":"fileutil/purge.go:44","msg":"started to purge file","dir":"/tmp/TestAuthLeaseTimeToLive3060925805/002/member/snap","suffix":"snap","max":5,"interval":"30s"}
2023-06-19T10:20:14.5235381Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.520852Z","caller":"fileutil/purge.go:44","msg":"started to purge file","dir":"/tmp/TestAuthLeaseTimeToLive3060925805/002/member/wal","suffix":"wal","max":5,"interval":"30s"}
2023-06-19T10:20:14.8160511Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.521067Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:1697","msg":"ca50e9357181d758 switched to configuration voters=(14578408409545168728)"}
2023-06-19T10:20:14.8163053Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.521419Z","caller":"membership/cluster.go:403","msg":"added member","cluster-id":"34f27e83b3bc2ff","local-member-id":"ca50e9357181d758","added-peer-id":"ca50e9357181d758","added-peer-peer-urls":["http://localhost:20001"],"added-peer-is-learner":false}
2023-06-19T10:20:14.8164694Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.813593Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:819","msg":"ca50e9357181d758 is starting a new election at term 1"}
2023-06-19T10:20:14.8166182Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.813652Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:757","msg":"ca50e9357181d758 became pre-candidate at term 1"}
2023-06-19T10:20:14.8168048Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.813703Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:879","msg":"ca50e9357181d758 received MsgPreVoteResp from ca50e9357181d758 at term 1"}
2023-06-19T10:20:14.8169557Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.813728Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:741","msg":"ca50e9357181d758 became candidate at term 2"}
2023-06-19T10:20:14.8170999Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.81376Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:879","msg":"ca50e9357181d758 received MsgVoteResp from ca50e9357181d758 at term 2"}
2023-06-19T10:20:14.8172377Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.813976Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/raft.go:797","msg":"ca50e9357181d758 became leader at term 2"}
2023-06-19T10:20:14.8173639Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.814019Z","logger":"raft","caller":"v3@v3.0.0-20221201111702-eaa6808e1f7a/node.go:330","msg":"raft.node: ca50e9357181d758 elected leader ca50e9357181d758 at term 2"}
2023-06-19T10:20:14.8174798Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.814629Z","caller":"etcdserver/server.go:2303","msg":"setting up initial cluster version using v3 API","cluster-version":"3.6"}
2023-06-19T10:20:14.8176151Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.815102Z","caller":"membership/cluster.go:546","msg":"set initial cluster version","cluster-id":"34f27e83b3bc2ff","local-member-id":"ca50e9357181d758","cluster-version":"3.6"}
2023-06-19T10:20:14.8177916Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.815249Z","caller":"etcdserver/server.go:1738","msg":"published local member to cluster through raft","local-member-id":"ca50e9357181d758","local-member-attributes":"{Name:TestAuthLeaseTimeToLive-test-0 ClientURLs:[http://localhost:20000]}","cluster-id":"34f27e83b3bc2ff","publish-timeout":"7s"}
2023-06-19T10:20:14.8179402Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.815497Z","caller":"api/capability.go:77","msg":"enabled capabilities for version","cluster-version":"3.6"}
2023-06-19T10:20:14.8180483Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.815605Z","caller":"etcdserver/server.go:2323","msg":"cluster version is updated","cluster-version":"3.6"}
2023-06-19T10:20:15.1396139Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.815619Z","caller":"embed/serve.go:112","msg":"ready to serve client requests"}
2023-06-19T10:20:15.1398035Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.819736Z","caller":"version/monitor.go:116","msg":"cluster version differs from storage version.","cluster-version":"3.6.0","storage-version":"3.5.0"}
2023-06-19T10:20:15.1399404Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.819981Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
2023-06-19T10:20:15.1401011Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.820013Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
2023-06-19T10:20:15.1402051Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.820648Z","caller":"schema/migration.go:65","msg":"updated storage version","new-storage-version":"3.6.0"}
2023-06-19T10:20:15.1403530Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.822283Z","caller":"embed/serve.go:196","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"127.0.0.1:20000"}
2023-06-19T10:20:15.1404491Z     logger.go:130: 2023-06-19T10:20:14.824Z	INFO	started server.	{"name": "TestAuthLeaseTimeToLive-test-0", "pid": 29826}
2023-06-19T10:20:15.1405337Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.839734Z","caller":"auth/store.go:773","msg":"created a role","role-name":"test-role"}
2023-06-19T10:20:15.1406358Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_role_add_test-role_-w_json) (29833): {"header":{"cluster_id":238453183653593855,"member_id":14578408409545168728,"revision":1,"raft_term":2}}
2023-06-19T10:20:15.1409422Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.859836Z","caller":"auth/store.go:836","msg":"granted/updated a permission to a user","user-name":"test-role","permission-name":"READWRITE","key":"foo","range-end":""}
2023-06-19T10:20:15.1410673Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_role_grant-permission_test-role_READWRITE_foo__-w_json) (29838): {"header":{"cluster_id":238453183653593855,"member_id":14578408409545168728,"revision":1,"raft_term":2}}
2023-06-19T10:20:15.1411688Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.976198Z","caller":"auth/store.go:450","msg":"added a user","user-name":"root"}
2023-06-19T10:20:15.1412933Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_user_add_root:rootPassword_--interactive=false_-w_json) (29843): {"header":{"cluster_id":238453183653593855,"member_id":14578408409545168728,"revision":1,"raft_term":2}}
2023-06-19T10:20:15.1414090Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:14.994755Z","caller":"auth/store.go:561","msg":"granted a role to a user","user-name":"root","user-roles":["root"],"added-role-name":"root"}
2023-06-19T10:20:15.1415295Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_user_grant-role_root_root_-w_json) (29849): {"header":{"cluster_id":238453183653593855,"member_id":14578408409545168728,"revision":1,"raft_term":2}}
2023-06-19T10:20:15.1416310Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:15.114959Z","caller":"auth/store.go:450","msg":"added a user","user-name":"test-user"}
2023-06-19T10:20:15.1417408Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_user_add_test-user:pass_--interactive=false_-w_json) (29854): {"header":{"cluster_id":238453183653593855,"member_id":14578408409545168728,"revision":1,"raft_term":2}}
2023-06-19T10:20:16.1374252Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:15.139079Z","caller":"auth/store.go:561","msg":"granted a role to a user","user-name":"test-user","user-roles":["test-role"],"added-role-name":"test-role"}
2023-06-19T10:20:16.1375665Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_user_grant-role_test-user_test-role_-w_json) (29859): {"header":{"cluster_id":238453183653593855,"member_id":14578408409545168728,"revision":1,"raft_term":2}}
2023-06-19T10:20:16.1376780Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"info","ts":"2023-06-19T10:20:15.160764Z","caller":"auth/store.go:282","msg":"enabled authentication"}
2023-06-19T10:20:16.1378170Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_auth_enable) (29864): Authentication Enabled
2023-06-19T10:20:16.1379427Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_--user=test-user:pass_lease_grant_10_-w_json) (29869): {"cluster_id":238453183653593855,"member_id":14578408409545168728,"revision":1,"raft_term":2,"ID":6293930919797272848,"TTL":10,"Error":""}
2023-06-19T10:20:16.1380542Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_--user=test-user:pass_put_foo_bar_--lease_575888d32b5d3110) (29874): OK
2023-06-19T10:20:16.1381925Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_--user=test-user:pass_lease_timetolive_575888d32b5d3110_-w_json_--keys) (29879): {"cluster_id":238453183653593855,"member_id":14578408409545168728,"revision":2,"raft_term":2,"id":6293930919797272848,"ttl":9,"granted-ttl":10,"keys":["Zm9v"]}
2023-06-19T10:20:16.1383073Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--user=root:rootPassword_--endpoints=http://localhost:20000_put_bar_foo_--lease_575888d32b5d3110) (29885): OK
2023-06-19T10:20:16.1384185Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"warn","ts":"2023-06-19T10:20:16.001008Z","caller":"auth/jwt.go:63","msg":"failed to parse a JWT token","error":"Token is expired"}
2023-06-19T10:20:16.1388123Z /home/runner/work/etcd/etcd/bin/etcd (TestAuthLeaseTimeToLive-test-0) (29826): {"level":"warn","ts":"2023-06-19T10:20:16.001072Z","caller":"auth/store.go:1065","msg":"invalid auth token","token":"***"}
2023-06-19T10:20:16.1390276Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_--user=test-user:pass_lease_timetolive_575888d32b5d3110_-w_json_--keys) (29890): {"level":"warn","ts":"2023-06-19T10:20:16.001416Z","logger":"etcd-client","caller":"v3@v3.6.0-alpha.0/retry_interceptor.go:65","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xaaa0100/localhost:20000","method":"/etcdserverpb.Lease/LeaseTimeToLive","attempt":0,"error":"rpc error: code = Unauthenticated desc = etcdserver: invalid auth token"}
2023-06-19T10:20:16.1394500Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_--user=test-user:pass_lease_timetolive_575888d32b5d3110_-w_json_--keys) (29890): {"level":"warn","ts":"2023-06-19T10:20:16.135496Z","logger":"etcd-client","caller":"v3@v3.6.0-alpha.0/retry_interceptor.go:65","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xaaa0100/localhost:20000","method":"/etcdserverpb.Lease/LeaseTimeToLive","attempt":1,"error":"rpc error: code = PermissionDenied desc = etcdserver: permission denied"}
2023-06-19T10:20:16.1397665Z /home/runner/work/etcd/etcd/bin/etcdctl (/home/runner/work/etcd/etcd/bin/etcdctl_--endpoints=http://localhost:20000_--user=test-user:pass_lease_timetolive_575888d32b5d3110_-w_json_--keys) (29890): Error: etcdserver: permission denied
2023-06-19T10:20:16.1398894Z     auth_test.go:847: 
2023-06-19T10:20:16.1419775Z         	Error Trace:	/home/runner/work/etcd/etcd/tests/common/auth_test.go:847
2023-06-19T10:20:16.1420746Z         	            				/home/runner/work/etcd/etcd/tests/framework/testutils/execute.go:38
2023-06-19T10:20:16.1421583Z         	            				/opt/hostedtoolcache/go/1.19.10/x64/src/runtime/asm_386.s:1326
2023-06-19T10:20:16.1422041Z         	Error:      	An error is expected but got nil.
2023-06-19T10:20:16.1422440Z         	Test:       	TestAuthLeaseTimeToLive
2023-06-19T10:20:16.1423315Z         	Messages:   	test-user must not be able to access to the lease, because it's attached to the key bar
2023-06-19T10:20:16.1423907Z     logger.go:130: 2023-06-19T10:20:16.137Z	INFO	closing test cluster...
2023-06-19T10:20:16.1424938Z     logger.go:130: 2023-06-19T10:20:16.137Z	INFO	stopping server...	{"name": "TestAuthLeaseTimeToLive-test-0"}

@serathius serathius merged commit 1420292 into etcd-io:main Jun 19, 2023
@jmhbnz jmhbnz mentioned this pull request Sep 25, 2023
Closed
9 tasks
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Reviewers

@fuweid fuweid fuweid approved these changes

Assignees
No one assigned
Labels
None yet
Milestone
No milestone
Development

Successfully merging this pull request may close these issues.
2 participants
@serathius @fuweid