{"level":"warn","ts":"2026-07-23T10:26:32.6978Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T10:26:32.69795Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-07-23T10:26:32.698043Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T10:26:32.698062Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-07-23T10:26:32.698091Z","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-07-23T10:26:32.698837Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-07-23T10:26:32.698925Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-07-23T10:26:32.709286Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.136141ms"} {"level":"info","ts":"2026-07-23T10:26:32.722756Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-07-23T10:26:32.72287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T10:26:32.722906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-07-23T10:26:32.722931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T10:26:32.722952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-07-23T10:26:32.723014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-07-23T10:26:32.736558Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T10:26:32.738258Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T10:26:32.739566Z","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-07-23T10:26:32.741982Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T10:26:32.742601Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T10:26:32.742874Z","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-07-23T10:26:32.743026Z","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-07-23T10:26:32.745054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-07-23T10:26:32.745162Z","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-07-23T10:26:32.745238Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-07-23T10:26:32.747388Z","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-07-23T10:26:32.747542Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-07-23T10:26:32.747562Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-07-23T10:26:32.748396Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T10:26:32.748421Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T10:26:33.62488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T10:26:33.625004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T10:26:33.625117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-07-23T10:26:33.625136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-07-23T10:26:33.625145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-07-23T10:26:33.625157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-07-23T10:26:33.625171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-07-23T10:26:33.62647Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T10:26:33.626508Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T10:26:33.626557Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T10:26:33.626793Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T10:26:33.628153Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T10:26:33.628201Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T10:26:33.628882Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T10:26:33.629085Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T10:26:33.62912Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T10:26:33.629437Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-07-23T10:26:33.632036Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T10:26:45.258445Z","caller":"traceutil/trace.go:171","msg":"trace[502349648] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"133.190792ms","start":"2026-07-23T10:26:45.125227Z","end":"2026-07-23T10:26:45.258418Z","steps":["trace[502349648] 'process raft request' (duration: 132.996415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:26:51.384822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021948592952988,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T10:26:51.661178Z","caller":"traceutil/trace.go:171","msg":"trace[1763986307] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"776.647582ms","start":"2026-07-23T10:26:50.884503Z","end":"2026-07-23T10:26:51.66115Z","steps":["trace[1763986307] 'read index received' (duration: 776.347831ms)","trace[1763986307] 'applied index is now lower than readState.Index' (duration: 296.2µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:26:51.661212Z","caller":"traceutil/trace.go:171","msg":"trace[1706619233] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"850.078506ms","start":"2026-07-23T10:26:50.811103Z","end":"2026-07-23T10:26:51.661182Z","steps":["trace[1706619233] 'process raft request' (duration: 849.866638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:26:51.66145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.672812ms","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-07-23T10:26:51.661374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.844179ms","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-07-23T10:26:51.661596Z","caller":"traceutil/trace.go:171","msg":"trace[293709159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"376.808037ms","start":"2026-07-23T10:26:51.284719Z","end":"2026-07-23T10:26:51.661527Z","steps":["trace[293709159] 'agreement among raft nodes before linearized reading' (duration: 376.633321ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:26:51.661591Z","caller":"traceutil/trace.go:171","msg":"trace[1856106953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"777.120788ms","start":"2026-07-23T10:26:50.88444Z","end":"2026-07-23T10:26:51.661561Z","steps":["trace[1856106953] 'agreement among raft nodes before linearized reading' (duration: 776.798747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:26:51.661635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:26:51.284706Z","time spent":"376.919411ms","remote":"127.0.0.1:50970","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-07-23T10:26:51.661381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.924446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T10:26:51.66184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:26:50.884394Z","time spent":"777.42844ms","remote":"127.0.0.1:50970","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-07-23T10:26:51.66184Z","caller":"traceutil/trace.go:171","msg":"trace[1559165215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"381.450585ms","start":"2026-07-23T10:26:51.280375Z","end":"2026-07-23T10:26:51.661825Z","steps":["trace[1559165215] 'agreement among raft nodes before linearized reading' (duration: 380.938826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:26:51.66217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:26:51.280353Z","time spent":"381.804718ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T10:26:51.662473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:26:50.811076Z","time spent":"850.76536ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:26:51.969394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.943105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T10:26:51.969621Z","caller":"traceutil/trace.go:171","msg":"trace[1972235527] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"302.971527ms","start":"2026-07-23T10:26:51.666627Z","end":"2026-07-23T10:26:51.969599Z","steps":["trace[1972235527] 'process raft request' (duration: 302.937535ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:26:51.969694Z","caller":"traceutil/trace.go:171","msg":"trace[1931943698] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"303.827337ms","start":"2026-07-23T10:26:51.665818Z","end":"2026-07-23T10:26:51.969645Z","steps":["trace[1931943698] 'process raft request' (duration: 68.411515ms)","trace[1931943698] 'compare' (duration: 234.819791ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:26:51.969787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:26:51.666616Z","time spent":"303.04904ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:26:51.96972Z","caller":"traceutil/trace.go:171","msg":"trace[80315392] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"303.500626ms","start":"2026-07-23T10:26:51.6662Z","end":"2026-07-23T10:26:51.9697Z","steps":["trace[80315392] 'process raft request' (duration: 303.31112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:26:51.969927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:26:51.665793Z","time spent":"304.023875ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:26:51.969994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:26:51.666181Z","time spent":"303.763355ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:26:53.091562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.191315ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T10:26:53.091716Z","caller":"traceutil/trace.go:171","msg":"trace[1832509666] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"1.032458332s","start":"2026-07-23T10:26:52.059234Z","end":"2026-07-23T10:26:53.091692Z","steps":["trace[1832509666] 'read index received' (duration: 35.152663ms)","trace[1832509666] 'applied index is now lower than readState.Index' (duration: 997.299799ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:26:53.091868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032576717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T10:26:53.091963Z","caller":"traceutil/trace.go:171","msg":"trace[899890710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"1.032721852s","start":"2026-07-23T10:26:52.059227Z","end":"2026-07-23T10:26:53.091949Z","steps":["trace[899890710] 'agreement among raft nodes before linearized reading' (duration: 1.032532375s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:26:53.092213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:26:52.059157Z","time spent":"1.032838186s","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T10:27:14.285782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.509205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-07-23T10:27:14.286426Z","caller":"traceutil/trace.go:171","msg":"trace[1859810944] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"114.482483ms","start":"2026-07-23T10:27:14.171923Z","end":"2026-07-23T10:27:14.286406Z","steps":["trace[1859810944] 'process raft request' (duration: 114.38582ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:14.286701Z","caller":"traceutil/trace.go:171","msg":"trace[1563673558] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"120.989419ms","start":"2026-07-23T10:27:14.165669Z","end":"2026-07-23T10:27:14.286658Z","steps":["trace[1563673558] 'process raft request' (duration: 120.451903ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:14.286826Z","caller":"traceutil/trace.go:171","msg":"trace[1287778164] transaction","detail":"{read_only:false; number_of_response:0; response_revision:551; }","duration":"121.311098ms","start":"2026-07-23T10:27:14.16546Z","end":"2026-07-23T10:27:14.286771Z","steps":["trace[1287778164] 'compare' (duration: 113.467984ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:14.293831Z","caller":"traceutil/trace.go:171","msg":"trace[246086292] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"121.651458ms","start":"2026-07-23T10:27:14.172162Z","end":"2026-07-23T10:27:14.293814Z","steps":["trace[246086292] 'process raft request' (duration: 121.05109ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:14.293955Z","caller":"traceutil/trace.go:171","msg":"trace[285001769] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"119.338611ms","start":"2026-07-23T10:27:14.174609Z","end":"2026-07-23T10:27:14.293948Z","steps":["trace[285001769] 'process raft request' (duration: 118.675832ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:14.294046Z","caller":"traceutil/trace.go:171","msg":"trace[1770247075] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"118.911129ms","start":"2026-07-23T10:27:14.175128Z","end":"2026-07-23T10:27:14.29404Z","steps":["trace[1770247075] 'process raft request' (duration: 118.178688ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:14.297934Z","caller":"traceutil/trace.go:171","msg":"trace[1933526521] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"114.410371ms","start":"2026-07-23T10:27:14.183494Z","end":"2026-07-23T10:27:14.297904Z","steps":["trace[1933526521] 'process raft request' (duration: 109.903792ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:15.146301Z","caller":"traceutil/trace.go:171","msg":"trace[71572419] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"752.614315ms","start":"2026-07-23T10:27:14.393667Z","end":"2026-07-23T10:27:15.146281Z","steps":["trace[71572419] 'process raft request' (duration: 736.155859ms)","trace[71572419] 'compare' (duration: 16.159298ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:27:15.146496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:14.39366Z","time spent":"752.719268ms","remote":"127.0.0.1:50746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:27:15.146494Z","caller":"traceutil/trace.go:171","msg":"trace[314604026] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"749.262709ms","start":"2026-07-23T10:27:14.3972Z","end":"2026-07-23T10:27:15.146462Z","steps":["trace[314604026] 'process raft request' (duration: 749.028813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:15.146612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:14.397191Z","time spent":"749.363932ms","remote":"127.0.0.1:50746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T10:27:15.157876Z","caller":"traceutil/trace.go:171","msg":"trace[426259349] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"750.142581ms","start":"2026-07-23T10:27:14.40771Z","end":"2026-07-23T10:27:15.157852Z","steps":["trace[426259349] 'process raft request' (duration: 749.836872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:15.158103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:14.407688Z","time spent":"750.253604ms","remote":"127.0.0.1:50746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:27:15.158354Z","caller":"traceutil/trace.go:171","msg":"trace[944197083] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"727.288468ms","start":"2026-07-23T10:27:14.431046Z","end":"2026-07-23T10:27:15.158334Z","steps":["trace[944197083] 'process raft request' (duration: 726.65813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:15.158628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:14.431022Z","time spent":"727.534115ms","remote":"127.0.0.1:50746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:27:21.507477Z","caller":"traceutil/trace.go:171","msg":"trace[1364998087] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"163.072052ms","start":"2026-07-23T10:27:21.344374Z","end":"2026-07-23T10:27:21.507446Z","steps":["trace[1364998087] 'read index received' (duration: 162.863296ms)","trace[1364998087] 'applied index is now lower than readState.Index' (duration: 207.536µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:27:21.507557Z","caller":"traceutil/trace.go:171","msg":"trace[1627716371] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"204.332338ms","start":"2026-07-23T10:27:21.303159Z","end":"2026-07-23T10:27:21.507492Z","steps":["trace[1627716371] 'process raft request' (duration: 204.137403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:21.507887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.469142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r9pv8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-23T10:27:21.507997Z","caller":"traceutil/trace.go:171","msg":"trace[1276806747] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r9pv8; range_end:; response_count:1; response_revision:641; }","duration":"163.621636ms","start":"2026-07-23T10:27:21.344353Z","end":"2026-07-23T10:27:21.507974Z","steps":["trace[1276806747] 'agreement among raft nodes before linearized reading' (duration: 163.231175ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:21.97955Z","caller":"traceutil/trace.go:171","msg":"trace[1919814558] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"457.456792ms","start":"2026-07-23T10:27:21.522062Z","end":"2026-07-23T10:27:21.979519Z","steps":["trace[1919814558] 'process raft request' (duration: 457.283678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:21.980024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:21.522009Z","time spent":"457.630606ms","remote":"127.0.0.1:50898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:27:22.791722Z","caller":"traceutil/trace.go:171","msg":"trace[1796189070] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.244882521s","start":"2026-07-23T10:27:21.546807Z","end":"2026-07-23T10:27:22.79169Z","steps":["trace[1796189070] 'process raft request' (duration: 1.244218203s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:22.791935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:21.546773Z","time spent":"1.245056466s","remote":"127.0.0.1:50806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:27:22.929744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.072159ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T10:27:22.929952Z","caller":"traceutil/trace.go:171","msg":"trace[587563737] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"1.074753399s","start":"2026-07-23T10:27:21.855131Z","end":"2026-07-23T10:27:22.929884Z","steps":["trace[587563737] 'read index received' (duration: 124.394124ms)","trace[587563737] 'applied index is now lower than readState.Index' (duration: 950.357135ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:27:22.93023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075078818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-23T10:27:22.930368Z","caller":"traceutil/trace.go:171","msg":"trace[151181617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"1.075274343s","start":"2026-07-23T10:27:21.85507Z","end":"2026-07-23T10:27:22.930345Z","steps":["trace[151181617] 'agreement among raft nodes before linearized reading' (duration: 1.074957144s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:22.930468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:21.855048Z","time spent":"1.075405536s","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T10:27:23.046283Z","caller":"traceutil/trace.go:171","msg":"trace[1623897711] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"116.259688ms","start":"2026-07-23T10:27:22.929993Z","end":"2026-07-23T10:27:23.046253Z","steps":["trace[1623897711] 'read index received' (duration: 115.755835ms)","trace[1623897711] 'applied index is now lower than readState.Index' (duration: 501.963µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:27:23.046418Z","caller":"traceutil/trace.go:171","msg":"trace[1658852092] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"192.324209ms","start":"2026-07-23T10:27:22.854069Z","end":"2026-07-23T10:27:23.046393Z","steps":["trace[1658852092] 'process raft request' (duration: 191.799525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:23.046604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.349095ms","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-07-23T10:27:23.046613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.05434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T10:27:23.046749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.996436ms","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-07-23T10:27:23.046816Z","caller":"traceutil/trace.go:171","msg":"trace[912119249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"711.367869ms","start":"2026-07-23T10:27:22.335431Z","end":"2026-07-23T10:27:23.046798Z","steps":["trace[912119249] 'agreement among raft nodes before linearized reading' (duration: 710.968248ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:23.046702Z","caller":"traceutil/trace.go:171","msg":"trace[90400337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"709.499779ms","start":"2026-07-23T10:27:22.337185Z","end":"2026-07-23T10:27:23.046685Z","steps":["trace[90400337] 'agreement among raft nodes before linearized reading' (duration: 709.1674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:23.046865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:22.335412Z","time spent":"711.44193ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T10:27:23.046859Z","caller":"traceutil/trace.go:171","msg":"trace[1609887579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"709.408077ms","start":"2026-07-23T10:27:22.337424Z","end":"2026-07-23T10:27:23.046833Z","steps":["trace[1609887579] 'agreement among raft nodes before linearized reading' (duration: 708.858692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:23.046915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:22.337162Z","time spent":"709.729805ms","remote":"127.0.0.1:50970","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-07-23T10:27:23.046583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.924623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T10:27:23.047012Z","caller":"traceutil/trace.go:171","msg":"trace[1604251527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"986.412196ms","start":"2026-07-23T10:27:22.060587Z","end":"2026-07-23T10:27:23.046999Z","steps":["trace[1604251527] 'agreement among raft nodes before linearized reading' (duration: 985.871132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:23.047087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:22.060569Z","time spent":"986.503778ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T10:27:23.046935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:22.337372Z","time spent":"709.55344ms","remote":"127.0.0.1:50970","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-07-23T10:27:23.062143Z","caller":"traceutil/trace.go:171","msg":"trace[1622657742] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"126.356643ms","start":"2026-07-23T10:27:22.935737Z","end":"2026-07-23T10:27:23.062093Z","steps":["trace[1622657742] 'process raft request' (duration: 126.145287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:24.465642Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.250545673s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-23T10:27:24.465744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000118218s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T10:27:24.465851Z","caller":"traceutil/trace.go:171","msg":"trace[1920390603] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:650; }","duration":"1.000239681s","start":"2026-07-23T10:27:23.46559Z","end":"2026-07-23T10:27:24.46583Z","steps":["trace[1920390603] 'range keys from in-memory index tree' (duration: 1.000100458s)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:24.466082Z","caller":"traceutil/trace.go:171","msg":"trace[1922409010] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:675; }","duration":"406.419378ms","start":"2026-07-23T10:27:24.059647Z","end":"2026-07-23T10:27:24.466067Z","steps":["trace[1922409010] 'read index received' (duration: 406.146561ms)","trace[1922409010] 'applied index is now lower than readState.Index' (duration: 272.037µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:27:24.466216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.565552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T10:27:24.466206Z","caller":"traceutil/trace.go:171","msg":"trace[717367260] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.263903824s","start":"2026-07-23T10:27:23.202267Z","end":"2026-07-23T10:27:24.466171Z","steps":["trace[717367260] 'process raft request' (duration: 1.263702339s)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:24.466267Z","caller":"traceutil/trace.go:171","msg":"trace[2070953370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"406.619293ms","start":"2026-07-23T10:27:24.059633Z","end":"2026-07-23T10:27:24.466252Z","steps":["trace[2070953370] 'agreement among raft nodes before linearized reading' (duration: 406.514171ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:24.466279Z","caller":"traceutil/trace.go:171","msg":"trace[635828644] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.263557615s","start":"2026-07-23T10:27:23.202676Z","end":"2026-07-23T10:27:24.466233Z","steps":["trace[635828644] 'process raft request' (duration: 1.263352749s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:24.466304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:24.05958Z","time spent":"406.715645ms","remote":"127.0.0.1:50684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T10:27:24.466304Z","caller":"traceutil/trace.go:171","msg":"trace[1005562465] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.266445221s","start":"2026-07-23T10:27:23.19984Z","end":"2026-07-23T10:27:24.466286Z","steps":["trace[1005562465] 'process raft request' (duration: 1.26600853s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:24.466376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:23.202247Z","time spent":"1.264035898s","remote":"127.0.0.1:50946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:27:24.466409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:23.19982Z","time spent":"1.266527133s","remote":"127.0.0.1:50786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T10:27:24.466427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:23.202661Z","time spent":"1.263681409s","remote":"127.0.0.1:50898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:27:25.58659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021948592953438,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T10:27:26.065863Z","caller":"traceutil/trace.go:171","msg":"trace[985026200] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"979.351944ms","start":"2026-07-23T10:27:25.086486Z","end":"2026-07-23T10:27:26.065838Z","steps":["trace[985026200] 'read index received' (duration: 979.135619ms)","trace[985026200] 'applied index is now lower than readState.Index' (duration: 215.085µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:27:26.065965Z","caller":"traceutil/trace.go:171","msg":"trace[1558844811] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"996.158489ms","start":"2026-07-23T10:27:25.069778Z","end":"2026-07-23T10:27:26.065937Z","steps":["trace[1558844811] 'process raft request' (duration: 995.910782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:26.066098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:25.069766Z","time spent":"996.258041ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:27:26.066128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.21289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T10:27:26.066162Z","caller":"traceutil/trace.go:171","msg":"trace[595929555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"979.264801ms","start":"2026-07-23T10:27:25.086888Z","end":"2026-07-23T10:27:26.066152Z","steps":["trace[595929555] 'agreement among raft nodes before linearized reading' (duration: 979.098367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:26.066161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.58546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T10:27:26.066204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:25.086843Z","time spent":"979.353503ms","remote":"127.0.0.1:50970","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":"info","ts":"2026-07-23T10:27:26.066213Z","caller":"traceutil/trace.go:171","msg":"trace[1688055423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"979.658221ms","start":"2026-07-23T10:27:25.086538Z","end":"2026-07-23T10:27:26.066196Z","steps":["trace[1688055423] 'agreement among raft nodes before linearized reading' (duration: 979.450476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:26.066248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:25.086529Z","time spent":"979.711333ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T10:27:26.066286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.762725ms","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-07-23T10:27:26.066389Z","caller":"traceutil/trace.go:171","msg":"trace[744239535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"979.98086ms","start":"2026-07-23T10:27:25.086385Z","end":"2026-07-23T10:27:26.066366Z","steps":["trace[744239535] 'agreement among raft nodes before linearized reading' (duration: 979.569589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:26.066434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:25.086363Z","time spent":"980.063532ms","remote":"127.0.0.1:50970","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-07-23T10:27:26.537685Z","caller":"traceutil/trace.go:171","msg":"trace[718656010] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"467.338217ms","start":"2026-07-23T10:27:26.070317Z","end":"2026-07-23T10:27:26.537655Z","steps":["trace[718656010] 'process raft request' (duration: 467.192133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:26.538027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:26.070289Z","time spent":"467.48278ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:27:26.543608Z","caller":"traceutil/trace.go:171","msg":"trace[2092468942] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"473.006193ms","start":"2026-07-23T10:27:26.070583Z","end":"2026-07-23T10:27:26.54359Z","steps":["trace[2092468942] 'process raft request' (duration: 472.868109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:26.543753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:26.070555Z","time spent":"473.118056ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:27:26.543885Z","caller":"traceutil/trace.go:171","msg":"trace[1233282273] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"472.543001ms","start":"2026-07-23T10:27:26.071311Z","end":"2026-07-23T10:27:26.543854Z","steps":["trace[1233282273] 'process raft request' (duration: 472.244203ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:26.544058Z","caller":"traceutil/trace.go:171","msg":"trace[963162802] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"416.74586ms","start":"2026-07-23T10:27:26.127303Z","end":"2026-07-23T10:27:26.544049Z","steps":["trace[963162802] 'process raft request' (duration: 416.495503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:26.544095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:26.071257Z","time spent":"472.717506ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:27:26.544113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:26.12727Z","time spent":"416.816851ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:27:45.265957Z","caller":"traceutil/trace.go:171","msg":"trace[1689626024] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:850; }","duration":"205.299935ms","start":"2026-07-23T10:27:45.060634Z","end":"2026-07-23T10:27:45.265934Z","steps":["trace[1689626024] 'read index received' (duration: 205.090651ms)","trace[1689626024] 'applied index is now lower than readState.Index' (duration: 208.394µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:27:45.266082Z","caller":"traceutil/trace.go:171","msg":"trace[1104129636] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"266.149115ms","start":"2026-07-23T10:27:44.999919Z","end":"2026-07-23T10:27:45.266068Z","steps":["trace[1104129636] 'process raft request' (duration: 265.863649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:45.266148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.997777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T10:27:45.266218Z","caller":"traceutil/trace.go:171","msg":"trace[460701452] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:820; }","duration":"124.14452ms","start":"2026-07-23T10:27:45.142059Z","end":"2026-07-23T10:27:45.266203Z","steps":["trace[460701452] 'agreement among raft nodes before linearized reading' (duration: 124.040728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:45.266341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.699054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T10:27:45.266383Z","caller":"traceutil/trace.go:171","msg":"trace[1151178850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"205.778246ms","start":"2026-07-23T10:27:45.060592Z","end":"2026-07-23T10:27:45.266371Z","steps":["trace[1151178850] 'agreement among raft nodes before linearized reading' (duration: 205.711924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:46.22301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.241623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T10:27:46.223042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.246749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qchcz\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-23T10:27:46.223099Z","caller":"traceutil/trace.go:171","msg":"trace[1305043870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"162.379785ms","start":"2026-07-23T10:27:46.060697Z","end":"2026-07-23T10:27:46.223077Z","steps":["trace[1305043870] 'range keys from in-memory index tree' (duration: 162.14781ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:27:46.22311Z","caller":"traceutil/trace.go:171","msg":"trace[613312069] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qchcz; range_end:; response_count:1; response_revision:820; }","duration":"765.347062ms","start":"2026-07-23T10:27:45.457743Z","end":"2026-07-23T10:27:46.22309Z","steps":["trace[613312069] 'range keys from in-memory index tree' (duration: 765.053715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:27:46.223215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:27:45.457728Z","time spent":"765.472494ms","remote":"127.0.0.1:50898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qchcz\" "} {"level":"warn","ts":"2026-07-23T10:29:03.392352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.589168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5547"} {"level":"info","ts":"2026-07-23T10:29:03.392444Z","caller":"traceutil/trace.go:171","msg":"trace[2115523906] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1211; }","duration":"163.75097ms","start":"2026-07-23T10:29:03.228671Z","end":"2026-07-23T10:29:03.392422Z","steps":["trace[2115523906] 'range keys from in-memory index tree' (duration: 163.317124ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:29:04.798058Z","caller":"traceutil/trace.go:171","msg":"trace[382560284] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"106.224896ms","start":"2026-07-23T10:29:04.691803Z","end":"2026-07-23T10:29:04.798028Z","steps":["trace[382560284] 'read index received' (duration: 106.021333ms)","trace[382560284] 'applied index is now lower than readState.Index' (duration: 202.263µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:29:04.798147Z","caller":"traceutil/trace.go:171","msg":"trace[58222374] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"133.271629ms","start":"2026-07-23T10:29:04.664841Z","end":"2026-07-23T10:29:04.798112Z","steps":["trace[58222374] 'process raft request' (duration: 132.994825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:29:04.798309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.480939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-23T10:29:04.798395Z","caller":"traceutil/trace.go:171","msg":"trace[145795994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"106.606751ms","start":"2026-07-23T10:29:04.691748Z","end":"2026-07-23T10:29:04.798355Z","steps":["trace[145795994] 'agreement among raft nodes before linearized reading' (duration: 106.390778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:29:45.451383Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021948592955201,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T10:29:45.47339Z","caller":"traceutil/trace.go:171","msg":"trace[609928156] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"539.191811ms","start":"2026-07-23T10:29:44.934174Z","end":"2026-07-23T10:29:45.473365Z","steps":["trace[609928156] 'process raft request' (duration: 539.043839ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:29:45.473438Z","caller":"traceutil/trace.go:171","msg":"trace[1988379915] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1442; }","duration":"523.056559ms","start":"2026-07-23T10:29:44.950321Z","end":"2026-07-23T10:29:45.473377Z","steps":["trace[1988379915] 'read index received' (duration: 523.045929ms)","trace[1988379915] 'applied index is now lower than readState.Index' (duration: 8.51µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:29:45.473547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:29:44.934141Z","time spent":"539.325393ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:29:45.473753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.564789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T10:29:45.473794Z","caller":"traceutil/trace.go:171","msg":"trace[854954834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1385; }","duration":"412.637901ms","start":"2026-07-23T10:29:45.061144Z","end":"2026-07-23T10:29:45.473782Z","steps":["trace[854954834] 'agreement among raft nodes before linearized reading' (duration: 412.56689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:29:45.473781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.948118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T10:29:45.473832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:29:45.061125Z","time spent":"412.699391ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T10:29:45.473857Z","caller":"traceutil/trace.go:171","msg":"trace[874708941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1385; }","duration":"456.054609ms","start":"2026-07-23T10:29:45.017779Z","end":"2026-07-23T10:29:45.473833Z","steps":["trace[874708941] 'agreement among raft nodes before linearized reading' (duration: 455.854507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:29:45.473853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.91915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T10:29:45.473891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:29:45.017764Z","time spent":"456.121201ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T10:29:45.473905Z","caller":"traceutil/trace.go:171","msg":"trace[1907076422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1385; }","duration":"177.015641ms","start":"2026-07-23T10:29:45.296874Z","end":"2026-07-23T10:29:45.473889Z","steps":["trace[1907076422] 'agreement among raft nodes before linearized reading' (duration: 176.764268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:29:45.473891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.56467ms","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-07-23T10:29:45.473963Z","caller":"traceutil/trace.go:171","msg":"trace[1050880205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1385; }","duration":"261.676931ms","start":"2026-07-23T10:29:45.212267Z","end":"2026-07-23T10:29:45.473944Z","steps":["trace[1050880205] 'agreement among raft nodes before linearized reading' (duration: 261.426798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:29:45.474104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.759877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-23T10:29:45.473596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.250442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T10:29:45.474162Z","caller":"traceutil/trace.go:171","msg":"trace[992130197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1385; }","duration":"179.846108ms","start":"2026-07-23T10:29:45.294302Z","end":"2026-07-23T10:29:45.474148Z","steps":["trace[992130197] 'agreement among raft nodes before linearized reading' (duration: 179.692596ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:29:45.474262Z","caller":"traceutil/trace.go:171","msg":"trace[100505205] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1385; }","duration":"523.91001ms","start":"2026-07-23T10:29:44.950277Z","end":"2026-07-23T10:29:45.474187Z","steps":["trace[100505205] 'agreement among raft nodes before linearized reading' (duration: 523.225461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:29:45.474308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:29:44.950258Z","time spent":"524.035392ms","remote":"127.0.0.1:51082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "}