{"level":"warn","ts":"2026-05-11T00:57:06.545479Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:57:06.545571Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T00:57:06.546553Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:57:06.54657Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-11T00:57:06.54659Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T00:57:06.54735Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-05-11T00:57:06.54742Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"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":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T00:57:06.556813Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.190376ms"} {"level":"info","ts":"2026-05-11T00:57:06.570095Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-05-11T00:57:06.570242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T00:57:06.570283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-05-11T00:57:06.570309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T00:57:06.570324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-05-11T00:57:06.570375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-05-11T00:57:06.5826Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T00:57:06.584044Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T00:57:06.585901Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T00:57:06.590577Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T00:57:06.590698Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T00:57:06.590841Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T00:57:06.590922Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T00:57:06.590934Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T00:57:06.596766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-05-11T00:57:06.597008Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-11T00:57:06.597895Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T00:57:06.597991Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-11T00:57:06.598043Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-11T00:57:06.598261Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T00:57:06.598303Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T00:57:06.771014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T00:57:06.771187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T00:57:06.771256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-05-11T00:57:06.771315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-05-11T00:57:06.771353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-11T00:57:06.771401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-05-11T00:57:06.771439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-11T00:57:06.772781Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T00:57:06.772938Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:57:06.77297Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:57:06.773164Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:06.773224Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T00:57:06.773335Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T00:57:06.774263Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:06.77435Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:06.774378Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:06.776694Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-05-11T00:57:06.776948Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T00:57:19.763666Z","caller":"traceutil/trace.go:171","msg":"trace[716862211] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"168.645847ms","start":"2026-05-11T00:57:19.594953Z","end":"2026-05-11T00:57:19.763599Z","steps":["trace[716862211] 'process raft request' (duration: 168.310321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:57:36.531138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.726519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:57:36.531248Z","caller":"traceutil/trace.go:171","msg":"trace[1547539230] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:508; }","duration":"186.886443ms","start":"2026-05-11T00:57:36.344337Z","end":"2026-05-11T00:57:36.531224Z","steps":["trace[1547539230] 'range keys from in-memory index tree' (duration: 186.564637ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:37.428285Z","caller":"traceutil/trace.go:171","msg":"trace[890620459] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"141.431673ms","start":"2026-05-11T00:57:37.28679Z","end":"2026-05-11T00:57:37.428222Z","steps":["trace[890620459] 'process raft request' (duration: 132.27333ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:37.428391Z","caller":"traceutil/trace.go:171","msg":"trace[1301437736] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"109.35741ms","start":"2026-05-11T00:57:37.319004Z","end":"2026-05-11T00:57:37.428361Z","steps":["trace[1301437736] 'process raft request' (duration: 109.224128ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:37.428448Z","caller":"traceutil/trace.go:171","msg":"trace[1507525658] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"128.895079ms","start":"2026-05-11T00:57:37.299519Z","end":"2026-05-11T00:57:37.428414Z","steps":["trace[1507525658] 'process raft request' (duration: 128.533863ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:43.027267Z","caller":"traceutil/trace.go:171","msg":"trace[1548327967] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"208.099052ms","start":"2026-05-11T00:57:42.819141Z","end":"2026-05-11T00:57:43.02724Z","steps":["trace[1548327967] 'read index received' (duration: 207.110724ms)","trace[1548327967] 'applied index is now lower than readState.Index' (duration: 987.298µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:57:43.027372Z","caller":"traceutil/trace.go:171","msg":"trace[1111245976] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"208.922736ms","start":"2026-05-11T00:57:42.818392Z","end":"2026-05-11T00:57:43.027315Z","steps":["trace[1111245976] 'process raft request' (duration: 208.190283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:57:43.027478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.312866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w2jkp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-11T00:57:43.027521Z","caller":"traceutil/trace.go:171","msg":"trace[889429460] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w2jkp; range_end:; response_count:1; response_revision:616; }","duration":"208.419987ms","start":"2026-05-11T00:57:42.81909Z","end":"2026-05-11T00:57:43.02751Z","steps":["trace[889429460] 'agreement among raft nodes before linearized reading' (duration: 208.254074ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:43.344825Z","caller":"traceutil/trace.go:171","msg":"trace[1900121311] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"301.64483ms","start":"2026-05-11T00:57:43.043154Z","end":"2026-05-11T00:57:43.344799Z","steps":["trace[1900121311] 'process raft request' (duration: 301.507808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:57:43.345972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:57:43.043135Z","time spent":"301.761962ms","remote":"127.0.0.1:44028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:57:43.670679Z","caller":"traceutil/trace.go:171","msg":"trace[16823694] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"583.333774ms","start":"2026-05-11T00:57:43.087289Z","end":"2026-05-11T00:57:43.670623Z","steps":["trace[16823694] 'process raft request' (duration: 565.346723ms)","trace[16823694] 'compare' (duration: 17.793306ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:57:43.670845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:57:43.087264Z","time spent":"583.489417ms","remote":"127.0.0.1:44362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:57:43.670989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.978453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-11T00:57:43.67106Z","caller":"traceutil/trace.go:171","msg":"trace[1755704640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"576.115306ms","start":"2026-05-11T00:57:43.094932Z","end":"2026-05-11T00:57:43.671047Z","steps":["trace[1755704640] 'agreement among raft nodes before linearized reading' (duration: 575.923843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:57:43.671092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:57:43.094907Z","time spent":"576.175416ms","remote":"127.0.0.1:44106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T00:57:43.670661Z","caller":"traceutil/trace.go:171","msg":"trace[1492406165] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"575.603447ms","start":"2026-05-11T00:57:43.094994Z","end":"2026-05-11T00:57:43.670598Z","steps":["trace[1492406165] 'read index received' (duration: 249.661073ms)","trace[1492406165] 'applied index is now lower than readState.Index' (duration: 325.941284ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:57:45.089924Z","caller":"traceutil/trace.go:171","msg":"trace[96939532] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"150.471733ms","start":"2026-05-11T00:57:44.939416Z","end":"2026-05-11T00:57:45.089888Z","steps":["trace[96939532] 'process raft request' (duration: 144.09597ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:45.091529Z","caller":"traceutil/trace.go:171","msg":"trace[712128867] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"147.110064ms","start":"2026-05-11T00:57:44.944389Z","end":"2026-05-11T00:57:45.0915Z","steps":["trace[712128867] 'process raft request' (duration: 146.970111ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:33.219537Z","caller":"traceutil/trace.go:171","msg":"trace[31532076] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"126.22121ms","start":"2026-05-11T00:58:33.093292Z","end":"2026-05-11T00:58:33.219513Z","steps":["trace[31532076] 'process raft request' (duration: 125.735561ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:56.597732Z","caller":"traceutil/trace.go:171","msg":"trace[1598726721] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"314.470631ms","start":"2026-05-11T00:58:56.283233Z","end":"2026-05-11T00:58:56.597704Z","steps":["trace[1598726721] 'process raft request' (duration: 314.327949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:56.59788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:58:56.283181Z","time spent":"314.621775ms","remote":"127.0.0.1:44106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:58:56.761134Z","caller":"traceutil/trace.go:171","msg":"trace[1801285375] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"143.111756ms","start":"2026-05-11T00:58:56.617999Z","end":"2026-05-11T00:58:56.761111Z","steps":["trace[1801285375] 'process raft request' (duration: 117.410646ms)","trace[1801285375] 'compare' (duration: 25.572878ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:58:57.462913Z","caller":"traceutil/trace.go:171","msg":"trace[1733471952] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"161.812315ms","start":"2026-05-11T00:58:57.301082Z","end":"2026-05-11T00:58:57.462894Z","steps":["trace[1733471952] 'process raft request' (duration: 161.663912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:57.756225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.946409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:58:57.756426Z","caller":"traceutil/trace.go:171","msg":"trace[1681760158] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"288.936544ms","start":"2026-05-11T00:58:57.467468Z","end":"2026-05-11T00:58:57.756405Z","steps":["trace[1681760158] 'process raft request' (duration: 164.571897ms)","trace[1681760158] 'compare' (duration: 123.820047ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:58:58.936047Z","caller":"traceutil/trace.go:171","msg":"trace[825168918] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"159.562184ms","start":"2026-05-11T00:58:58.776463Z","end":"2026-05-11T00:58:58.936025Z","steps":["trace[825168918] 'read index received' (duration: 159.41616ms)","trace[825168918] 'applied index is now lower than readState.Index' (duration: 144.694µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:58:58.936139Z","caller":"traceutil/trace.go:171","msg":"trace[641090235] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"167.251138ms","start":"2026-05-11T00:58:58.768828Z","end":"2026-05-11T00:58:58.93608Z","steps":["trace[641090235] 'process raft request' (duration: 167.055113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:58.936482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.983661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:58:58.936577Z","caller":"traceutil/trace.go:171","msg":"trace[1273333524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"160.121974ms","start":"2026-05-11T00:58:58.776434Z","end":"2026-05-11T00:58:58.936556Z","steps":["trace[1273333524] 'agreement among raft nodes before linearized reading' (duration: 159.762628ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:11.587267Z","caller":"traceutil/trace.go:171","msg":"trace[462480734] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"552.293798ms","start":"2026-05-11T00:59:11.034952Z","end":"2026-05-11T00:59:11.587246Z","steps":["trace[462480734] 'process raft request' (duration: 552.184916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:11.58739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:11.034936Z","time spent":"552.38883ms","remote":"127.0.0.1:44106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:11.590666Z","caller":"traceutil/trace.go:171","msg":"trace[550441658] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"496.498326ms","start":"2026-05-11T00:59:11.094156Z","end":"2026-05-11T00:59:11.590654Z","steps":["trace[550441658] 'read index received' (duration: 493.635302ms)","trace[550441658] 'applied index is now lower than readState.Index' (duration: 2.862124ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:11.590804Z","caller":"traceutil/trace.go:171","msg":"trace[733257489] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"530.120554ms","start":"2026-05-11T00:59:11.060677Z","end":"2026-05-11T00:59:11.590797Z","steps":["trace[733257489] 'process raft request' (duration: 529.88731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:11.590851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:11.060653Z","time spent":"530.166745ms","remote":"127.0.0.1:44106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:11.591087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.938394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-11T00:59:11.591109Z","caller":"traceutil/trace.go:171","msg":"trace[1922127855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1175; }","duration":"496.989304ms","start":"2026-05-11T00:59:11.094113Z","end":"2026-05-11T00:59:11.591103Z","steps":["trace[1922127855] 'agreement among raft nodes before linearized reading' (duration: 496.908702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:11.591128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:11.094101Z","time spent":"497.022865ms","remote":"127.0.0.1:44106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T00:59:11.591273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.590195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T00:59:11.591291Z","caller":"traceutil/trace.go:171","msg":"trace[677660532] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1175; }","duration":"228.671086ms","start":"2026-05-11T00:59:11.362614Z","end":"2026-05-11T00:59:11.591285Z","steps":["trace[677660532] 'agreement among raft nodes before linearized reading' (duration: 228.594485ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:23.780603Z","caller":"traceutil/trace.go:171","msg":"trace[831524825] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"145.012252ms","start":"2026-05-11T00:59:23.635571Z","end":"2026-05-11T00:59:23.780583Z","steps":["trace[831524825] 'read index received' (duration: 144.90043ms)","trace[831524825] 'applied index is now lower than readState.Index' (duration: 111.072µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:23.780641Z","caller":"traceutil/trace.go:171","msg":"trace[36954328] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"154.563709ms","start":"2026-05-11T00:59:23.626047Z","end":"2026-05-11T00:59:23.78061Z","steps":["trace[36954328] 'process raft request' (duration: 154.398936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:23.780863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.817142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-11T00:59:23.780845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.256307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T00:59:23.780907Z","caller":"traceutil/trace.go:171","msg":"trace[1473015487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"140.912445ms","start":"2026-05-11T00:59:23.639987Z","end":"2026-05-11T00:59:23.780899Z","steps":["trace[1473015487] 'agreement among raft nodes before linearized reading' (duration: 140.758412ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:23.780932Z","caller":"traceutil/trace.go:171","msg":"trace[1355135681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"145.382379ms","start":"2026-05-11T00:59:23.63553Z","end":"2026-05-11T00:59:23.780913Z","steps":["trace[1355135681] 'agreement among raft nodes before linearized reading' (duration: 145.170495ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:28.081349Z","caller":"traceutil/trace.go:171","msg":"trace[1005680428] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"107.707564ms","start":"2026-05-11T00:59:27.973615Z","end":"2026-05-11T00:59:28.081323Z","steps":["trace[1005680428] 'process raft request' (duration: 107.53566ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:30.198923Z","caller":"traceutil/trace.go:171","msg":"trace[1302248901] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"108.422871ms","start":"2026-05-11T00:59:30.090471Z","end":"2026-05-11T00:59:30.198894Z","steps":["trace[1302248901] 'process raft request' (duration: 108.264578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:30.439614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.702521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T00:59:30.43975Z","caller":"traceutil/trace.go:171","msg":"trace[1150360092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"102.901245ms","start":"2026-05-11T00:59:30.336822Z","end":"2026-05-11T00:59:30.439723Z","steps":["trace[1150360092] 'range keys from in-memory index tree' (duration: 102.474307ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:31.123076Z","caller":"traceutil/trace.go:171","msg":"trace[1622825270] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"173.007904ms","start":"2026-05-11T00:59:30.950042Z","end":"2026-05-11T00:59:31.12305Z","steps":["trace[1622825270] 'process raft request' (duration: 172.526645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:31.452977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.814038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:59:31.453208Z","caller":"traceutil/trace.go:171","msg":"trace[721584522] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1324; }","duration":"163.885707ms","start":"2026-05-11T00:59:31.289301Z","end":"2026-05-11T00:59:31.453186Z","steps":["trace[721584522] 'read index received' (duration: 112.560221ms)","trace[721584522] 'applied index is now lower than readState.Index' (duration: 51.324116ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:31.453288Z","caller":"traceutil/trace.go:171","msg":"trace[311601191] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"324.673304ms","start":"2026-05-11T00:59:31.128569Z","end":"2026-05-11T00:59:31.453242Z","steps":["trace[311601191] 'process raft request' (duration: 147.460018ms)","trace[311601191] 'compare' (duration: 176.634304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:31.453356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.046269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T00:59:31.453406Z","caller":"traceutil/trace.go:171","msg":"trace[770136849] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1276; }","duration":"164.136562ms","start":"2026-05-11T00:59:31.289256Z","end":"2026-05-11T00:59:31.453392Z","steps":["trace[770136849] 'agreement among raft nodes before linearized reading' (duration: 163.996489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:31.453449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:31.128547Z","time spent":"324.825847ms","remote":"127.0.0.1:44106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:31.453774Z","caller":"traceutil/trace.go:171","msg":"trace[267700080] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"324.317576ms","start":"2026-05-11T00:59:31.129441Z","end":"2026-05-11T00:59:31.453759Z","steps":["trace[267700080] 'process raft request' (duration: 323.661504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:31.453867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:31.129431Z","time spent":"324.392158ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:31.627211Z","caller":"traceutil/trace.go:171","msg":"trace[539133483] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"163.205373ms","start":"2026-05-11T00:59:31.463977Z","end":"2026-05-11T00:59:31.627182Z","steps":["trace[539133483] 'process raft request' (duration: 147.093201ms)","trace[539133483] 'compare' (duration: 15.98999ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:31.650299Z","caller":"traceutil/trace.go:171","msg":"trace[2123523335] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"118.12022ms","start":"2026-05-11T00:59:31.53216Z","end":"2026-05-11T00:59:31.65028Z","steps":["trace[2123523335] 'process raft request' (duration: 117.997218ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:31.922494Z","caller":"traceutil/trace.go:171","msg":"trace[1602149704] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"105.301171ms","start":"2026-05-11T00:59:31.817163Z","end":"2026-05-11T00:59:31.922464Z","steps":["trace[1602149704] 'process raft request' (duration: 104.659739ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:31.973018Z","caller":"traceutil/trace.go:171","msg":"trace[2014618601] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1329; }","duration":"113.592132ms","start":"2026-05-11T00:59:31.8594Z","end":"2026-05-11T00:59:31.972992Z","steps":["trace[2014618601] 'read index received' (duration: 62.3833ms)","trace[2014618601] 'applied index is now lower than readState.Index' (duration: 51.207672ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:31.973281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.460884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-11T00:59:31.973306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.872447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T00:59:31.973565Z","caller":"traceutil/trace.go:171","msg":"trace[794607812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"114.112662ms","start":"2026-05-11T00:59:31.859353Z","end":"2026-05-11T00:59:31.973466Z","steps":["trace[794607812] 'agreement among raft nodes before linearized reading' (duration: 113.769105ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:31.973321Z","caller":"traceutil/trace.go:171","msg":"trace[1156291664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1281; }","duration":"105.550186ms","start":"2026-05-11T00:59:31.86776Z","end":"2026-05-11T00:59:31.97331Z","steps":["trace[1156291664] 'agreement among raft nodes before linearized reading' (duration: 105.453994ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:31.973142Z","caller":"traceutil/trace.go:171","msg":"trace[1187199865] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"130.848726ms","start":"2026-05-11T00:59:31.842268Z","end":"2026-05-11T00:59:31.973116Z","steps":["trace[1187199865] 'process raft request' (duration: 130.52192ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:42.447045Z","caller":"traceutil/trace.go:171","msg":"trace[1435481008] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"339.221725ms","start":"2026-05-11T00:59:42.107797Z","end":"2026-05-11T00:59:42.447019Z","steps":["trace[1435481008] 'read index received' (duration: 339.122603ms)","trace[1435481008] 'applied index is now lower than readState.Index' (duration: 97.882µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:42.44716Z","caller":"traceutil/trace.go:171","msg":"trace[647581538] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"409.510679ms","start":"2026-05-11T00:59:42.037611Z","end":"2026-05-11T00:59:42.447121Z","steps":["trace[647581538] 'process raft request' (duration: 409.254644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:42.447264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.45387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-11T00:59:42.44731Z","caller":"traceutil/trace.go:171","msg":"trace[1193798994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1343; }","duration":"339.618034ms","start":"2026-05-11T00:59:42.107677Z","end":"2026-05-11T00:59:42.447295Z","steps":["trace[1193798994] 'agreement among raft nodes before linearized reading' (duration: 339.466382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:42.44735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:42.107658Z","time spent":"339.681105ms","remote":"127.0.0.1:44106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T00:59:42.447352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:42.037586Z","time spent":"409.650781ms","remote":"127.0.0.1:44106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:42.483763Z","caller":"traceutil/trace.go:171","msg":"trace[757847432] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"312.657621ms","start":"2026-05-11T00:59:42.171079Z","end":"2026-05-11T00:59:42.483736Z","steps":["trace[757847432] 'process raft request' (duration: 310.009389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:42.483951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:42.171059Z","time spent":"312.790263ms","remote":"127.0.0.1:44106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:42.484002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.780866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T00:59:42.484119Z","caller":"traceutil/trace.go:171","msg":"trace[386141786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"371.951509ms","start":"2026-05-11T00:59:42.112141Z","end":"2026-05-11T00:59:42.484093Z","steps":["trace[386141786] 'agreement among raft nodes before linearized reading' (duration: 371.648884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:42.484182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:42.11212Z","time spent":"372.048352ms","remote":"127.0.0.1:44106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}