{"level":"warn","ts":"2026-02-09T05:49:10.173239Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-09T05:49:10.173377Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-02-09T05:49:10.173471Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-09T05:49:10.173503Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-09T05:49:10.173538Z","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-02-09T05:49:10.17429Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-02-09T05:49:10.174843Z","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":2,"max-cpu-available":2,"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://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-02-09T05:49:10.182252Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.208999ms"} {"level":"info","ts":"2026-02-09T05:49:10.217117Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-09T05:49:10.217315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T05:49:10.217371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-09T05:49:10.217434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T05:49:10.21748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-09T05:49:10.217589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-09T05:49:10.231347Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T05:49:10.234763Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T05:49:10.238507Z","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-02-09T05:49:10.244873Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T05:49:10.26532Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T05:49:10.265435Z","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-02-09T05:49:10.265493Z","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-02-09T05:49:10.26551Z","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-02-09T05:49:10.265737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-09T05:49:10.265816Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-09T05:49:10.266165Z","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-02-09T05:49:10.26624Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-09T05:49:10.266868Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-09T05:49:10.266413Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T05:49:10.267332Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T05:49:10.918336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T05:49:10.91838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T05:49:10.918394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-09T05:49:10.918404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-09T05:49:10.91841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-09T05:49:10.918433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-09T05:49:10.918441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-09T05:49:10.919408Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T05:49:10.919442Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T05:49:10.919417Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T05:49:10.919831Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T05:49:10.921332Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T05:49:10.921429Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T05:49:10.92145Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T05:49:10.923361Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-02-09T05:49:10.923844Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T05:49:10.923898Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T05:49:10.925969Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-09T05:49:20.455411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.818042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-09T05:49:20.455479Z","caller":"traceutil/trace.go:171","msg":"trace[432006312] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:384; }","duration":"294.896194ms","start":"2026-02-09T05:49:20.160569Z","end":"2026-02-09T05:49:20.455465Z","steps":["trace[432006312] 'range keys from in-memory index tree' (duration: 294.74845ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:20.456675Z","caller":"traceutil/trace.go:171","msg":"trace[163407327] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"290.00531ms","start":"2026-02-09T05:49:20.16666Z","end":"2026-02-09T05:49:20.456665Z","steps":["trace[163407327] 'process raft request' (duration: 289.751814ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:25.871175Z","caller":"traceutil/trace.go:171","msg":"trace[2096407686] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"266.73689ms","start":"2026-02-09T05:49:25.604402Z","end":"2026-02-09T05:49:25.871139Z","steps":["trace[2096407686] 'process raft request' (duration: 264.925788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:26.173214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.023883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T05:49:26.173347Z","caller":"traceutil/trace.go:171","msg":"trace[2075506016] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:422; }","duration":"335.231039ms","start":"2026-02-09T05:49:25.838073Z","end":"2026-02-09T05:49:26.173304Z","steps":["trace[2075506016] 'read index received' (duration: 31.183084ms)","trace[2075506016] 'applied index is now lower than readState.Index' (duration: 304.046665ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:49:26.173356Z","caller":"traceutil/trace.go:171","msg":"trace[465012261] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"376.450106ms","start":"2026-02-09T05:49:25.796885Z","end":"2026-02-09T05:49:26.173335Z","steps":["trace[465012261] 'process raft request' (duration: 240.242196ms)","trace[465012261] 'compare' (duration: 134.786037ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:49:26.173448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.369052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:49:26.173477Z","caller":"traceutil/trace.go:171","msg":"trace[316638346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"335.397362ms","start":"2026-02-09T05:49:25.83807Z","end":"2026-02-09T05:49:26.173467Z","steps":["trace[316638346] 'agreement among raft nodes before linearized reading' (duration: 335.344881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:26.173481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:49:25.796868Z","time spent":"376.541088ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T05:49:26.173521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:49:25.838036Z","time spent":"335.477784ms","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T05:49:26.173731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.182299ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:49:26.173794Z","caller":"traceutil/trace.go:171","msg":"trace[436190199] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:414; }","duration":"270.246201ms","start":"2026-02-09T05:49:25.903539Z","end":"2026-02-09T05:49:26.173785Z","steps":["trace[436190199] 'agreement among raft nodes before linearized reading' (duration: 270.173099ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:36.889216Z","caller":"traceutil/trace.go:171","msg":"trace[394991498] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"227.212603ms","start":"2026-02-09T05:49:36.661985Z","end":"2026-02-09T05:49:36.889198Z","steps":["trace[394991498] 'read index received' (duration: 226.889396ms)","trace[394991498] 'applied index is now lower than readState.Index' (duration: 322.517µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:49:36.889361Z","caller":"traceutil/trace.go:171","msg":"trace[897033013] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"239.428557ms","start":"2026-02-09T05:49:36.649927Z","end":"2026-02-09T05:49:36.889356Z","steps":["trace[897033013] 'process raft request' (duration: 238.924625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:36.889496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.49578ms","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-02-09T05:49:36.889517Z","caller":"traceutil/trace.go:171","msg":"trace[2052001104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"227.538741ms","start":"2026-02-09T05:49:36.661973Z","end":"2026-02-09T05:49:36.889512Z","steps":["trace[2052001104] 'agreement among raft nodes before linearized reading' (duration: 227.49026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:36.889586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.268505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T05:49:36.889602Z","caller":"traceutil/trace.go:171","msg":"trace[1132026732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"227.293205ms","start":"2026-02-09T05:49:36.662305Z","end":"2026-02-09T05:49:36.889599Z","steps":["trace[1132026732] 'agreement among raft nodes before linearized reading' (duration: 227.266635ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:40.762613Z","caller":"traceutil/trace.go:171","msg":"trace[466724166] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"263.514196ms","start":"2026-02-09T05:49:40.499078Z","end":"2026-02-09T05:49:40.762592Z","steps":["trace[466724166] 'process raft request' (duration: 263.388823ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:44.019965Z","caller":"traceutil/trace.go:171","msg":"trace[427191683] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"191.287749ms","start":"2026-02-09T05:49:43.828661Z","end":"2026-02-09T05:49:44.019949Z","steps":["trace[427191683] 'read index received' (duration: 191.181396ms)","trace[427191683] 'applied index is now lower than readState.Index' (duration: 105.523µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:49:44.020048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.370191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:49:44.020066Z","caller":"traceutil/trace.go:171","msg":"trace[1805108103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"191.425132ms","start":"2026-02-09T05:49:43.828635Z","end":"2026-02-09T05:49:44.02006Z","steps":["trace[1805108103] 'agreement among raft nodes before linearized reading' (duration: 191.373331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:45.161787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.232017ms","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-02-09T05:49:45.161892Z","caller":"traceutil/trace.go:171","msg":"trace[1586567751] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:520; }","duration":"933.34407ms","start":"2026-02-09T05:49:44.228531Z","end":"2026-02-09T05:49:45.161875Z","steps":["trace[1586567751] 'range keys from in-memory index tree' (duration: 933.216747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:45.161922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.342463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T05:49:45.162017Z","caller":"traceutil/trace.go:171","msg":"trace[104821856] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"332.085417ms","start":"2026-02-09T05:49:44.829919Z","end":"2026-02-09T05:49:45.162005Z","steps":["trace[104821856] 'read index received' (duration: 20.731µs)","trace[104821856] 'applied index is now lower than readState.Index' (duration: 332.063506ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:49:45.162068Z","caller":"traceutil/trace.go:171","msg":"trace[1334463294] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.141157193s","start":"2026-02-09T05:49:44.020897Z","end":"2026-02-09T05:49:45.162055Z","steps":["trace[1334463294] 'process raft request' (duration: 359.617736ms)","trace[1334463294] 'compare' (duration: 781.060286ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:49:45.162152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:49:44.020884Z","time spent":"1.141212735s","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T05:49:45.162202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.277921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:49:45.16223Z","caller":"traceutil/trace.go:171","msg":"trace[34508790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"332.328832ms","start":"2026-02-09T05:49:44.829893Z","end":"2026-02-09T05:49:45.162221Z","steps":["trace[34508790] 'agreement among raft nodes before linearized reading' (duration: 332.178459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:45.162258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:49:44.82988Z","time spent":"332.371874ms","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T05:49:45.16249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.251342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T05:49:45.162527Z","caller":"traceutil/trace.go:171","msg":"trace[396900635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"214.299764ms","start":"2026-02-09T05:49:44.948216Z","end":"2026-02-09T05:49:45.162516Z","steps":["trace[396900635] 'agreement among raft nodes before linearized reading' (duration: 214.240283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:45.162545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.767327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T05:49:45.162582Z","caller":"traceutil/trace.go:171","msg":"trace[375562446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"218.831228ms","start":"2026-02-09T05:49:44.943739Z","end":"2026-02-09T05:49:45.162571Z","steps":["trace[375562446] 'agreement among raft nodes before linearized reading' (duration: 218.653104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:45.162759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.536332ms","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-02-09T05:49:45.162785Z","caller":"traceutil/trace.go:171","msg":"trace[1912324386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"215.575683ms","start":"2026-02-09T05:49:44.947202Z","end":"2026-02-09T05:49:45.162778Z","steps":["trace[1912324386] 'agreement among raft nodes before linearized reading' (duration: 215.529202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:45.1628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.999642ms","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-02-09T05:49:45.162832Z","caller":"traceutil/trace.go:171","msg":"trace[324681266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"219.040203ms","start":"2026-02-09T05:49:44.943781Z","end":"2026-02-09T05:49:45.162821Z","steps":["trace[324681266] 'agreement among raft nodes before linearized reading' (duration: 218.984452ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:45.310372Z","caller":"traceutil/trace.go:171","msg":"trace[2048367539] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"142.861415ms","start":"2026-02-09T05:49:45.167465Z","end":"2026-02-09T05:49:45.310327Z","steps":["trace[2048367539] 'read index received' (duration: 114.811394ms)","trace[2048367539] 'applied index is now lower than readState.Index' (duration: 28.049281ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:49:45.310521Z","caller":"traceutil/trace.go:171","msg":"trace[452216581] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"143.1076ms","start":"2026-02-09T05:49:45.167401Z","end":"2026-02-09T05:49:45.310509Z","steps":["trace[452216581] 'process raft request' (duration: 114.867375ms)","trace[452216581] 'compare' (duration: 27.946998ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:49:45.310594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.110791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-09T05:49:45.310637Z","caller":"traceutil/trace.go:171","msg":"trace[494138728] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:522; }","duration":"143.179932ms","start":"2026-02-09T05:49:45.167445Z","end":"2026-02-09T05:49:45.310625Z","steps":["trace[494138728] 'agreement among raft nodes before linearized reading' (duration: 143.09653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:45.391493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.966077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:49:45.391673Z","caller":"traceutil/trace.go:171","msg":"trace[1828262977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"224.137501ms","start":"2026-02-09T05:49:45.167494Z","end":"2026-02-09T05:49:45.391631Z","steps":["trace[1828262977] 'agreement among raft nodes before linearized reading' (duration: 223.890405ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:45.392092Z","caller":"traceutil/trace.go:171","msg":"trace[30618027] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"220.761943ms","start":"2026-02-09T05:49:45.171312Z","end":"2026-02-09T05:49:45.392074Z","steps":["trace[30618027] 'process raft request' (duration: 219.388192ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:45.392401Z","caller":"traceutil/trace.go:171","msg":"trace[885619824] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"217.368755ms","start":"2026-02-09T05:49:45.175015Z","end":"2026-02-09T05:49:45.392384Z","steps":["trace[885619824] 'process raft request' (duration: 215.726096ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:45.39107Z","caller":"traceutil/trace.go:171","msg":"trace[1510414183] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"219.78136ms","start":"2026-02-09T05:49:45.171255Z","end":"2026-02-09T05:49:45.391037Z","steps":["trace[1510414183] 'process raft request' (duration: 219.35204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:47.523388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.821613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-09T05:49:47.523942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.186968ms","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-02-09T05:49:47.524019Z","caller":"traceutil/trace.go:171","msg":"trace[855012575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"101.294401ms","start":"2026-02-09T05:49:47.422695Z","end":"2026-02-09T05:49:47.523989Z","steps":["trace[855012575] 'range keys from in-memory index tree' (duration: 100.668796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:47.524016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.864767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T05:49:47.52409Z","caller":"traceutil/trace.go:171","msg":"trace[154235134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"102.936468ms","start":"2026-02-09T05:49:47.421116Z","end":"2026-02-09T05:49:47.524053Z","steps":["trace[154235134] 'range keys from in-memory index tree' (duration: 102.807186ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:47.525005Z","caller":"traceutil/trace.go:171","msg":"trace[1237475219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"101.923865ms","start":"2026-02-09T05:49:47.421522Z","end":"2026-02-09T05:49:47.523446Z","steps":["trace[1237475219] 'range keys from in-memory index tree' (duration: 101.757791ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:51.406719Z","caller":"traceutil/trace.go:171","msg":"trace[387578624] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"735.357686ms","start":"2026-02-09T05:49:50.671347Z","end":"2026-02-09T05:49:51.406704Z","steps":["trace[387578624] 'process raft request' (duration: 735.091609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:49:51.406815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:49:50.671313Z","time spent":"735.448797ms","remote":"127.0.0.1:49472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:49:52.817514Z","caller":"traceutil/trace.go:171","msg":"trace[927951425] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"147.415481ms","start":"2026-02-09T05:49:52.670082Z","end":"2026-02-09T05:49:52.817498Z","steps":["trace[927951425] 'process raft request' (duration: 147.337729ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:53.993834Z","caller":"traceutil/trace.go:171","msg":"trace[1090467331] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"186.778765ms","start":"2026-02-09T05:49:53.807027Z","end":"2026-02-09T05:49:53.993806Z","steps":["trace[1090467331] 'process raft request' (duration: 159.87274ms)","trace[1090467331] 'compare' (duration: 26.791012ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:49:53.994061Z","caller":"traceutil/trace.go:171","msg":"trace[1936445922] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"150.3737ms","start":"2026-02-09T05:49:53.843671Z","end":"2026-02-09T05:49:53.994044Z","steps":["trace[1936445922] 'read index received' (duration: 57.390452ms)","trace[1936445922] 'applied index is now lower than readState.Index' (duration: 92.982458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:49:53.994158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.473232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-09T05:49:53.99418Z","caller":"traceutil/trace.go:171","msg":"trace[1182992607] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:565; }","duration":"150.528503ms","start":"2026-02-09T05:49:53.843644Z","end":"2026-02-09T05:49:53.994172Z","steps":["trace[1182992607] 'agreement among raft nodes before linearized reading' (duration: 150.457242ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:49:54.184879Z","caller":"traceutil/trace.go:171","msg":"trace[2134447600] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"111.154009ms","start":"2026-02-09T05:49:54.0737Z","end":"2026-02-09T05:49:54.184853Z","steps":["trace[2134447600] 'process raft request' (duration: 96.364096ms)","trace[2134447600] 'compare' (duration: 14.692771ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:49:56.113246Z","caller":"traceutil/trace.go:171","msg":"trace[35276950] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"247.568205ms","start":"2026-02-09T05:49:55.865652Z","end":"2026-02-09T05:49:56.11322Z","steps":["trace[35276950] 'process raft request' (duration: 213.457463ms)","trace[35276950] 'compare' (duration: 33.973689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:50:02.098601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810234936381048,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T05:50:02.107431Z","caller":"traceutil/trace.go:171","msg":"trace[1351004915] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"644.675211ms","start":"2026-02-09T05:50:01.462576Z","end":"2026-02-09T05:50:02.107251Z","steps":["trace[1351004915] 'process raft request' (duration: 644.547488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:02.107511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:01.462567Z","time spent":"644.888636ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:02.431787Z","caller":"traceutil/trace.go:171","msg":"trace[1403366572] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:672; }","duration":"833.471393ms","start":"2026-02-09T05:50:01.598293Z","end":"2026-02-09T05:50:02.431764Z","steps":["trace[1403366572] 'read index received' (duration: 508.895611ms)","trace[1403366572] 'applied index is now lower than readState.Index' (duration: 324.574812ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:50:02.432034Z","caller":"traceutil/trace.go:171","msg":"trace[1313293410] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"748.921ms","start":"2026-02-09T05:50:01.683103Z","end":"2026-02-09T05:50:02.432024Z","steps":["trace[1313293410] 'process raft request' (duration: 748.551581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:02.432121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:01.683086Z","time spent":"748.975361ms","remote":"127.0.0.1:50384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-09T05:50:02.432382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.082327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T05:50:02.432415Z","caller":"traceutil/trace.go:171","msg":"trace[744141199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"834.136527ms","start":"2026-02-09T05:50:01.598268Z","end":"2026-02-09T05:50:02.432405Z","steps":["trace[744141199] 'agreement among raft nodes before linearized reading' (duration: 834.001844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:02.432442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:01.598258Z","time spent":"834.177878ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T05:50:02.432643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.71632ms","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-02-09T05:50:02.432672Z","caller":"traceutil/trace.go:171","msg":"trace[7645210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"815.758721ms","start":"2026-02-09T05:50:01.616904Z","end":"2026-02-09T05:50:02.432662Z","steps":["trace[7645210] 'agreement among raft nodes before linearized reading' (duration: 815.68475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:02.432698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:01.616892Z","time spent":"815.800052ms","remote":"127.0.0.1:49560","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\" "} {"level":"warn","ts":"2026-02-09T05:50:02.432893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.525633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T05:50:02.432918Z","caller":"traceutil/trace.go:171","msg":"trace[1441191554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"814.559033ms","start":"2026-02-09T05:50:01.618351Z","end":"2026-02-09T05:50:02.43291Z","steps":["trace[1441191554] 'agreement among raft nodes before linearized reading' (duration: 814.489181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:02.432913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.610082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:50:02.432986Z","caller":"traceutil/trace.go:171","msg":"trace[1179306204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"586.720605ms","start":"2026-02-09T05:50:01.846245Z","end":"2026-02-09T05:50:02.432966Z","steps":["trace[1179306204] 'agreement among raft nodes before linearized reading' (duration: 586.577732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:02.433027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:01.846233Z","time spent":"586.786267ms","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T05:50:02.433214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.148212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T05:50:02.433249Z","caller":"traceutil/trace.go:171","msg":"trace[203283691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:656; }","duration":"243.204384ms","start":"2026-02-09T05:50:02.190033Z","end":"2026-02-09T05:50:02.433238Z","steps":["trace[203283691] 'agreement among raft nodes before linearized reading' (duration: 243.126232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:02.432945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:01.618347Z","time spent":"814.592984ms","remote":"127.0.0.1:49560","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-02-09T05:50:04.610532Z","caller":"traceutil/trace.go:171","msg":"trace[869098105] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"102.022148ms","start":"2026-02-09T05:50:04.508483Z","end":"2026-02-09T05:50:04.610505Z","steps":["trace[869098105] 'process raft request' (duration: 89.792674ms)","trace[869098105] 'compare' (duration: 12.08172ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:50:04.614715Z","caller":"traceutil/trace.go:171","msg":"trace[723923107] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"104.494545ms","start":"2026-02-09T05:50:04.510192Z","end":"2026-02-09T05:50:04.614687Z","steps":["trace[723923107] 'process raft request' (duration: 104.467044ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:04.61547Z","caller":"traceutil/trace.go:171","msg":"trace[2054020337] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"106.184615ms","start":"2026-02-09T05:50:04.509263Z","end":"2026-02-09T05:50:04.615448Z","steps":["trace[2054020337] 'process raft request' (duration: 105.315145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:05.200862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.499598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T05:50:05.200931Z","caller":"traceutil/trace.go:171","msg":"trace[18508576] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"359.258507ms","start":"2026-02-09T05:50:04.84166Z","end":"2026-02-09T05:50:05.200919Z","steps":["trace[18508576] 'read index received' (duration: 172.653736ms)","trace[18508576] 'applied index is now lower than readState.Index' (duration: 186.603801ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:50:05.200958Z","caller":"traceutil/trace.go:171","msg":"trace[1508339346] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"359.499283ms","start":"2026-02-09T05:50:04.84143Z","end":"2026-02-09T05:50:05.200929Z","steps":["trace[1508339346] 'process raft request' (duration: 172.889572ms)","trace[1508339346] 'compare' (duration: 186.227432ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:50:05.201012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.347269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:50:05.201044Z","caller":"traceutil/trace.go:171","msg":"trace[1303762083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"359.494122ms","start":"2026-02-09T05:50:04.841545Z","end":"2026-02-09T05:50:05.201039Z","steps":["trace[1303762083] 'agreement among raft nodes before linearized reading' (duration: 359.40122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:05.201051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:04.841421Z","time spent":"359.578545ms","remote":"127.0.0.1:49362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-09T05:50:05.20106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:04.84154Z","time spent":"359.516603ms","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T05:50:05.219148Z","caller":"traceutil/trace.go:171","msg":"trace[2115270667] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"377.260974ms","start":"2026-02-09T05:50:04.841879Z","end":"2026-02-09T05:50:05.21914Z","steps":["trace[2115270667] 'process raft request' (duration: 377.0617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:05.2192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:04.841874Z","time spent":"377.298435ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:05.219383Z","caller":"traceutil/trace.go:171","msg":"trace[455751848] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"373.427616ms","start":"2026-02-09T05:50:04.845949Z","end":"2026-02-09T05:50:05.219377Z","steps":["trace[455751848] 'process raft request' (duration: 373.077558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:05.219414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:04.845942Z","time spent":"373.458016ms","remote":"127.0.0.1:49456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:05.219602Z","caller":"traceutil/trace.go:171","msg":"trace[1538086679] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"373.59774ms","start":"2026-02-09T05:50:04.845999Z","end":"2026-02-09T05:50:05.219597Z","steps":["trace[1538086679] 'process raft request' (duration: 373.284533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:05.219633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:04.845981Z","time spent":"373.63803ms","remote":"127.0.0.1:49576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:05.219858Z","caller":"traceutil/trace.go:171","msg":"trace[1666247623] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"364.951729ms","start":"2026-02-09T05:50:04.854901Z","end":"2026-02-09T05:50:05.219853Z","steps":["trace[1666247623] 'process raft request' (duration: 364.58055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:05.219889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:04.854892Z","time spent":"364.983319ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:10.781245Z","caller":"traceutil/trace.go:171","msg":"trace[1274244167] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"129.528296ms","start":"2026-02-09T05:50:10.651695Z","end":"2026-02-09T05:50:10.781224Z","steps":["trace[1274244167] 'read index received' (duration: 129.377723ms)","trace[1274244167] 'applied index is now lower than readState.Index' (duration: 149.903µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:50:10.781479Z","caller":"traceutil/trace.go:171","msg":"trace[2030323109] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"253.882982ms","start":"2026-02-09T05:50:10.527589Z","end":"2026-02-09T05:50:10.781472Z","steps":["trace[2030323109] 'process raft request' (duration: 253.526434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:10.78168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.966956ms","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-02-09T05:50:10.78171Z","caller":"traceutil/trace.go:171","msg":"trace[1231564101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:728; }","duration":"130.044148ms","start":"2026-02-09T05:50:10.651657Z","end":"2026-02-09T05:50:10.781701Z","steps":["trace[1231564101] 'agreement among raft nodes before linearized reading' (duration: 129.945276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:10.78183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.08545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T05:50:10.781852Z","caller":"traceutil/trace.go:171","msg":"trace[1482333697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"121.128211ms","start":"2026-02-09T05:50:10.660719Z","end":"2026-02-09T05:50:10.781847Z","steps":["trace[1482333697] 'agreement among raft nodes before linearized reading' (duration: 121.07402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:10.782002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.032359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-09T05:50:10.784593Z","caller":"traceutil/trace.go:171","msg":"trace[1367845731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:728; }","duration":"120.63914ms","start":"2026-02-09T05:50:10.663944Z","end":"2026-02-09T05:50:10.784583Z","steps":["trace[1367845731] 'agreement among raft nodes before linearized reading' (duration: 118.02349ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:10.941374Z","caller":"traceutil/trace.go:171","msg":"trace[1748305841] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:748; }","duration":"111.758313ms","start":"2026-02-09T05:50:10.829601Z","end":"2026-02-09T05:50:10.941359Z","steps":["trace[1748305841] 'read index received' (duration: 107.7584ms)","trace[1748305841] 'applied index is now lower than readState.Index' (duration: 3.999303ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:50:10.941485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.873505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:50:10.94151Z","caller":"traceutil/trace.go:171","msg":"trace[2098466791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"111.947487ms","start":"2026-02-09T05:50:10.829556Z","end":"2026-02-09T05:50:10.941504Z","steps":["trace[2098466791] 'agreement among raft nodes before linearized reading' (duration: 111.861775ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:10.941713Z","caller":"traceutil/trace.go:171","msg":"trace[1104530968] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"152.704423ms","start":"2026-02-09T05:50:10.789002Z","end":"2026-02-09T05:50:10.941706Z","steps":["trace[1104530968] 'process raft request' (duration: 148.349562ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:10.941802Z","caller":"traceutil/trace.go:171","msg":"trace[1795818241] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"150.466091ms","start":"2026-02-09T05:50:10.791331Z","end":"2026-02-09T05:50:10.941797Z","steps":["trace[1795818241] 'process raft request' (duration: 149.981359ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:10.941867Z","caller":"traceutil/trace.go:171","msg":"trace[377664762] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"148.30654ms","start":"2026-02-09T05:50:10.793556Z","end":"2026-02-09T05:50:10.941863Z","steps":["trace[377664762] 'process raft request' (duration: 147.784068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.357156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.633851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:50:13.357247Z","caller":"traceutil/trace.go:171","msg":"trace[435294162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"526.756144ms","start":"2026-02-09T05:50:12.830461Z","end":"2026-02-09T05:50:13.357217Z","steps":["trace[435294162] 'range keys from in-memory index tree' (duration: 526.562459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.357292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:12.830406Z","time spent":"526.875847ms","remote":"127.0.0.1:49276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T05:50:13.357383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.252364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-09T05:50:13.357451Z","caller":"traceutil/trace.go:171","msg":"trace[208007230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:732; }","duration":"411.333756ms","start":"2026-02-09T05:50:12.946098Z","end":"2026-02-09T05:50:13.357432Z","steps":["trace[208007230] 'range keys from in-memory index tree' (duration: 411.135391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.35749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:12.946096Z","time spent":"411.385407ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T05:50:13.357685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005812282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-09T05:50:13.357701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.635853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T05:50:13.357724Z","caller":"traceutil/trace.go:171","msg":"trace[92203160] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:732; }","duration":"1.005877633s","start":"2026-02-09T05:50:12.351835Z","end":"2026-02-09T05:50:13.357713Z","steps":["trace[92203160] 'count revisions from in-memory index tree' (duration: 1.005755591s)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:13.357734Z","caller":"traceutil/trace.go:171","msg":"trace[796629138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"411.694814ms","start":"2026-02-09T05:50:12.94603Z","end":"2026-02-09T05:50:13.357725Z","steps":["trace[796629138] 'range keys from in-memory index tree' (duration: 411.486579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.357763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:12.946017Z","time spent":"411.739876ms","remote":"127.0.0.1:49560","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":"warn","ts":"2026-02-09T05:50:13.357918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.758255ms","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-02-09T05:50:13.357944Z","caller":"traceutil/trace.go:171","msg":"trace[128680279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:732; }","duration":"411.789056ms","start":"2026-02-09T05:50:12.946147Z","end":"2026-02-09T05:50:13.357936Z","steps":["trace[128680279] 'range keys from in-memory index tree' (duration: 411.702975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.357968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:12.946144Z","time spent":"411.817678ms","remote":"127.0.0.1:49560","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\" "} {"level":"warn","ts":"2026-02-09T05:50:13.358006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.116751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T05:50:13.35804Z","caller":"traceutil/trace.go:171","msg":"trace[176551374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:732; }","duration":"567.176682ms","start":"2026-02-09T05:50:12.790853Z","end":"2026-02-09T05:50:13.35803Z","steps":["trace[176551374] 'range keys from in-memory index tree' (duration: 567.003829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.358065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:12.790839Z","time spent":"567.220944ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T05:50:13.358146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.452317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T05:50:13.358176Z","caller":"traceutil/trace.go:171","msg":"trace[468204864] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:732; }","duration":"376.502527ms","start":"2026-02-09T05:50:12.981665Z","end":"2026-02-09T05:50:13.358167Z","steps":["trace[468204864] 'count revisions from in-memory index tree' (duration: 376.418475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.358203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:12.981656Z","time spent":"376.539298ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-09T05:50:13.357763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:12.351817Z","time spent":"1.005933235s","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-09T05:50:13.888528Z","caller":"traceutil/trace.go:171","msg":"trace[488781437] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"523.355405ms","start":"2026-02-09T05:50:13.365158Z","end":"2026-02-09T05:50:13.888514Z","steps":["trace[488781437] 'process raft request' (duration: 523.263603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.888647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:13.365143Z","time spent":"523.440288ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:13.944568Z","caller":"traceutil/trace.go:171","msg":"trace[288006984] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:753; }","duration":"139.411785ms","start":"2026-02-09T05:50:13.805133Z","end":"2026-02-09T05:50:13.944544Z","steps":["trace[288006984] 'read index received' (duration: 83.766084ms)","trace[288006984] 'applied index is now lower than readState.Index' (duration: 55.644451ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:50:13.944919Z","caller":"traceutil/trace.go:171","msg":"trace[303263467] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"577.903411ms","start":"2026-02-09T05:50:13.367003Z","end":"2026-02-09T05:50:13.944906Z","steps":["trace[303263467] 'process raft request' (duration: 577.308907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.94607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.92301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-09T05:50:13.946113Z","caller":"traceutil/trace.go:171","msg":"trace[18635485] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:737; }","duration":"141.002592ms","start":"2026-02-09T05:50:13.805099Z","end":"2026-02-09T05:50:13.946101Z","steps":["trace[18635485] 'agreement among raft nodes before linearized reading' (duration: 140.838029ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:13.946327Z","caller":"traceutil/trace.go:171","msg":"trace[1999445331] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"578.176627ms","start":"2026-02-09T05:50:13.36814Z","end":"2026-02-09T05:50:13.946317Z","steps":["trace[1999445331] 'process raft request' (duration: 576.297674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.946604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:13.366995Z","time spent":"577.958183ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T05:50:13.947967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:13.368132Z","time spent":"579.778044ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:13.948116Z","caller":"traceutil/trace.go:171","msg":"trace[1360533064] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"579.341955ms","start":"2026-02-09T05:50:13.368758Z","end":"2026-02-09T05:50:13.9481Z","steps":["trace[1360533064] 'process raft request' (duration: 575.720031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.948191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:13.368751Z","time spent":"579.408416ms","remote":"127.0.0.1:49560","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-02-09T05:50:13.948264Z","caller":"traceutil/trace.go:171","msg":"trace[252143045] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"476.863176ms","start":"2026-02-09T05:50:13.471391Z","end":"2026-02-09T05:50:13.948254Z","steps":["trace[252143045] 'process raft request' (duration: 473.11652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.948335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:13.471368Z","time spent":"476.931919ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T05:50:13.948845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.074901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T05:50:13.948891Z","caller":"traceutil/trace.go:171","msg":"trace[34782611] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:737; }","duration":"112.138302ms","start":"2026-02-09T05:50:13.836743Z","end":"2026-02-09T05:50:13.948881Z","steps":["trace[34782611] 'agreement among raft nodes before linearized reading' (duration: 112.06436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:13.949459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.140485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:50:13.949508Z","caller":"traceutil/trace.go:171","msg":"trace[170240369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"113.220067ms","start":"2026-02-09T05:50:13.836274Z","end":"2026-02-09T05:50:13.949494Z","steps":["trace[170240369] 'agreement among raft nodes before linearized reading' (duration: 112.310506ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:14.503Z","caller":"traceutil/trace.go:171","msg":"trace[1964062887] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"279.322941ms","start":"2026-02-09T05:50:14.22366Z","end":"2026-02-09T05:50:14.502983Z","steps":["trace[1964062887] 'process raft request' (duration: 279.196588ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:28.051538Z","caller":"traceutil/trace.go:171","msg":"trace[490600332] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"119.162737ms","start":"2026-02-09T05:50:27.932364Z","end":"2026-02-09T05:50:28.051526Z","steps":["trace[490600332] 'process raft request' (duration: 118.971573ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:28.181542Z","caller":"traceutil/trace.go:171","msg":"trace[1748513601] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"124.117475ms","start":"2026-02-09T05:50:28.057409Z","end":"2026-02-09T05:50:28.181526Z","steps":["trace[1748513601] 'process raft request' (duration: 124.089345ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:28.184705Z","caller":"traceutil/trace.go:171","msg":"trace[1207841123] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"128.202395ms","start":"2026-02-09T05:50:28.056492Z","end":"2026-02-09T05:50:28.184694Z","steps":["trace[1207841123] 'process raft request' (duration: 123.801508ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:29.525394Z","caller":"traceutil/trace.go:171","msg":"trace[866442158] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"146.117869ms","start":"2026-02-09T05:50:29.379255Z","end":"2026-02-09T05:50:29.525373Z","steps":["trace[866442158] 'read index received' (duration: 56.513961ms)","trace[866442158] 'applied index is now lower than readState.Index' (duration: 89.602818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:50:29.525551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.277242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:50:29.525581Z","caller":"traceutil/trace.go:171","msg":"trace[2136837156] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:785; }","duration":"146.377684ms","start":"2026-02-09T05:50:29.379195Z","end":"2026-02-09T05:50:29.525573Z","steps":["trace[2136837156] 'agreement among raft nodes before linearized reading' (duration: 146.290532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:30.852305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.096591ms","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-02-09T05:50:30.852376Z","caller":"traceutil/trace.go:171","msg":"trace[30637780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"275.210343ms","start":"2026-02-09T05:50:30.577148Z","end":"2026-02-09T05:50:30.852358Z","steps":["trace[30637780] 'range keys from in-memory index tree' (duration: 274.921496ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:31.284239Z","caller":"traceutil/trace.go:171","msg":"trace[79775936] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"427.723193ms","start":"2026-02-09T05:50:30.856499Z","end":"2026-02-09T05:50:31.284222Z","steps":["trace[79775936] 'process raft request' (duration: 427.61788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:31.284352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:30.856433Z","time spent":"427.856905ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:42.34142Z","caller":"traceutil/trace.go:171","msg":"trace[1028334233] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"117.545801ms","start":"2026-02-09T05:50:42.223842Z","end":"2026-02-09T05:50:42.341388Z","steps":["trace[1028334233] 'process raft request' (duration: 117.430979ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:44.104395Z","caller":"traceutil/trace.go:171","msg":"trace[587394302] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"274.752853ms","start":"2026-02-09T05:50:43.829619Z","end":"2026-02-09T05:50:44.104372Z","steps":["trace[587394302] 'read index received' (duration: 274.544318ms)","trace[587394302] 'applied index is now lower than readState.Index' (duration: 207.365µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:50:44.104512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.868405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:50:44.104543Z","caller":"traceutil/trace.go:171","msg":"trace[506097904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"274.929107ms","start":"2026-02-09T05:50:43.829603Z","end":"2026-02-09T05:50:44.104533Z","steps":["trace[506097904] 'agreement among raft nodes before linearized reading' (duration: 274.852116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:44.546326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.87618ms","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-02-09T05:50:44.546413Z","caller":"traceutil/trace.go:171","msg":"trace[1631514061] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:875; }","duration":"317.970482ms","start":"2026-02-09T05:50:44.22843Z","end":"2026-02-09T05:50:44.5464Z","steps":["trace[1631514061] 'range keys from in-memory index tree' (duration: 317.86512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:44.546616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.307912ms","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-02-09T05:50:44.546655Z","caller":"traceutil/trace.go:171","msg":"trace[1683632977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:875; }","duration":"197.359043ms","start":"2026-02-09T05:50:44.349284Z","end":"2026-02-09T05:50:44.546644Z","steps":["trace[1683632977] 'range keys from in-memory index tree' (duration: 197.21624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:44.546783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.379161ms","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-02-09T05:50:44.5468Z","caller":"traceutil/trace.go:171","msg":"trace[893506108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:875; }","duration":"175.414472ms","start":"2026-02-09T05:50:44.371382Z","end":"2026-02-09T05:50:44.546796Z","steps":["trace[893506108] 'range keys from in-memory index tree' (duration: 175.31839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:44.547355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.168267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T05:50:44.547377Z","caller":"traceutil/trace.go:171","msg":"trace[1229421334] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:875; }","duration":"175.204197ms","start":"2026-02-09T05:50:44.372168Z","end":"2026-02-09T05:50:44.547372Z","steps":["trace[1229421334] 'range keys from in-memory index tree' (duration: 175.119505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:44.547522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.742851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T05:50:44.547553Z","caller":"traceutil/trace.go:171","msg":"trace[222936726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:875; }","duration":"125.799522ms","start":"2026-02-09T05:50:44.421749Z","end":"2026-02-09T05:50:44.547549Z","steps":["trace[222936726] 'range keys from in-memory index tree' (duration: 125.67095ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:44.884403Z","caller":"traceutil/trace.go:171","msg":"trace[284054996] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"330.855714ms","start":"2026-02-09T05:50:44.553533Z","end":"2026-02-09T05:50:44.884388Z","steps":["trace[284054996] 'process raft request' (duration: 330.768392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:44.884497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:44.553518Z","time spent":"330.929067ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:45.119783Z","caller":"traceutil/trace.go:171","msg":"trace[780898751] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:906; }","duration":"201.30693ms","start":"2026-02-09T05:50:44.918455Z","end":"2026-02-09T05:50:45.119762Z","steps":["trace[780898751] 'read index received' (duration: 201.034594ms)","trace[780898751] 'applied index is now lower than readState.Index' (duration: 271.506µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:50:45.119911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.429552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:50:45.119942Z","caller":"traceutil/trace.go:171","msg":"trace[1217108081] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:880; }","duration":"201.497734ms","start":"2026-02-09T05:50:44.918436Z","end":"2026-02-09T05:50:45.119933Z","steps":["trace[1217108081] 'agreement among raft nodes before linearized reading' (duration: 201.406722ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:45.120278Z","caller":"traceutil/trace.go:171","msg":"trace[1880901308] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"566.627222ms","start":"2026-02-09T05:50:44.553638Z","end":"2026-02-09T05:50:45.120265Z","steps":["trace[1880901308] 'process raft request' (duration: 565.915146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:45.120358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:44.553634Z","time spent":"566.676363ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:45.1205Z","caller":"traceutil/trace.go:171","msg":"trace[1022310172] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"566.826706ms","start":"2026-02-09T05:50:44.553664Z","end":"2026-02-09T05:50:45.120491Z","steps":["trace[1022310172] 'process raft request' (duration: 566.005468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:45.120585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:44.553661Z","time spent":"566.866376ms","remote":"127.0.0.1:49560","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-02-09T05:50:45.120716Z","caller":"traceutil/trace.go:171","msg":"trace[1793383218] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"566.972039ms","start":"2026-02-09T05:50:44.553735Z","end":"2026-02-09T05:50:45.120707Z","steps":["trace[1793383218] 'process raft request' (duration: 565.967377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:45.120781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:44.553684Z","time spent":"567.064851ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:45.120914Z","caller":"traceutil/trace.go:171","msg":"trace[724569617] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"296.735406ms","start":"2026-02-09T05:50:44.824168Z","end":"2026-02-09T05:50:45.120903Z","steps":["trace[724569617] 'process raft request' (duration: 295.56262ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:54.095359Z","caller":"traceutil/trace.go:171","msg":"trace[594105104] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:934; }","duration":"107.806238ms","start":"2026-02-09T05:50:53.987542Z","end":"2026-02-09T05:50:54.095348Z","steps":["trace[594105104] 'read index received' (duration: 107.802427ms)","trace[594105104] 'applied index is now lower than readState.Index' (duration: 2.761µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:50:54.095824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.270818ms","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-02-09T05:50:54.095844Z","caller":"traceutil/trace.go:171","msg":"trace[857422222] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:902; }","duration":"108.324609ms","start":"2026-02-09T05:50:53.987514Z","end":"2026-02-09T05:50:54.095839Z","steps":["trace[857422222] 'agreement among raft nodes before linearized reading' (duration: 107.90931ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:50:55.850976Z","caller":"traceutil/trace.go:171","msg":"trace[1337324228] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"342.221895ms","start":"2026-02-09T05:50:55.508726Z","end":"2026-02-09T05:50:55.850948Z","steps":["trace[1337324228] 'process raft request' (duration: 341.939958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:55.851097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:55.508715Z","time spent":"342.314626ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:50:57.672015Z","caller":"traceutil/trace.go:171","msg":"trace[487200556] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"283.841413ms","start":"2026-02-09T05:50:57.388156Z","end":"2026-02-09T05:50:57.671998Z","steps":["trace[487200556] 'process raft request' (duration: 283.68859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:57.84929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.878359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T05:50:57.849472Z","caller":"traceutil/trace.go:171","msg":"trace[1868709500] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"461.292119ms","start":"2026-02-09T05:50:57.388163Z","end":"2026-02-09T05:50:57.849455Z","steps":["trace[1868709500] 'process raft request' (duration: 348.202685ms)","trace[1868709500] 'compare' (duration: 112.799137ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:50:57.849575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:57.388149Z","time spent":"461.372151ms","remote":"127.0.0.1:49560","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-02-09T05:50:59.043733Z","caller":"traceutil/trace.go:171","msg":"trace[1142192464] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"299.343023ms","start":"2026-02-09T05:50:58.744369Z","end":"2026-02-09T05:50:59.043712Z","steps":["trace[1142192464] 'process raft request' (duration: 299.138319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:50:59.356742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.458669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T05:50:59.356801Z","caller":"traceutil/trace.go:171","msg":"trace[484889079] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"310.356775ms","start":"2026-02-09T05:50:59.046433Z","end":"2026-02-09T05:50:59.35679Z","steps":["trace[484889079] 'process raft request' (duration: 75.798944ms)","trace[484889079] 'compare' (duration: 234.342166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:50:59.356833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:59.046411Z","time spent":"310.408047ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T05:51:00.450805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.80853ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T05:51:00.450889Z","caller":"traceutil/trace.go:171","msg":"trace[1911572708] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"1.062808668s","start":"2026-02-09T05:50:59.388064Z","end":"2026-02-09T05:51:00.450873Z","steps":["trace[1911572708] 'read index received' (duration: 345.951777ms)","trace[1911572708] 'applied index is now lower than readState.Index' (duration: 716.855611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:51:00.451249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063173696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-09T05:51:00.451304Z","caller":"traceutil/trace.go:171","msg":"trace[578484120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:916; }","duration":"1.063250557s","start":"2026-02-09T05:50:59.38804Z","end":"2026-02-09T05:51:00.451291Z","steps":["trace[578484120] 'agreement among raft nodes before linearized reading' (duration: 1.063068213s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:00.451303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.145101ms","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-02-09T05:51:00.451349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:59.388027Z","time spent":"1.063313668s","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-09T05:51:00.451367Z","caller":"traceutil/trace.go:171","msg":"trace[602387852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:916; }","duration":"775.237232ms","start":"2026-02-09T05:50:59.676112Z","end":"2026-02-09T05:51:00.451349Z","steps":["trace[602387852] 'agreement among raft nodes before linearized reading' (duration: 775.048658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:00.451407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:59.676078Z","time spent":"775.321614ms","remote":"127.0.0.1:49560","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\" "} {"level":"warn","ts":"2026-02-09T05:51:00.451567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.243113ms","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-02-09T05:51:00.451597Z","caller":"traceutil/trace.go:171","msg":"trace[1319378120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:916; }","duration":"591.293234ms","start":"2026-02-09T05:50:59.860295Z","end":"2026-02-09T05:51:00.451588Z","steps":["trace[1319378120] 'agreement among raft nodes before linearized reading' (duration: 591.204172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:00.451622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:59.860274Z","time spent":"591.343195ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T05:51:00.45166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.785165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:51:00.451688Z","caller":"traceutil/trace.go:171","msg":"trace[801342764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"622.827197ms","start":"2026-02-09T05:50:59.828851Z","end":"2026-02-09T05:51:00.451678Z","steps":["trace[801342764] 'agreement among raft nodes before linearized reading' (duration: 622.790916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:00.451712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:59.828836Z","time spent":"622.870677ms","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T05:51:00.451778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.981767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:51:00.451809Z","caller":"traceutil/trace.go:171","msg":"trace[1514553188] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:916; }","duration":"193.035049ms","start":"2026-02-09T05:51:00.258764Z","end":"2026-02-09T05:51:00.451799Z","steps":["trace[1514553188] 'agreement among raft nodes before linearized reading' (duration: 192.988408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:00.451623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.369429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T05:51:00.451963Z","caller":"traceutil/trace.go:171","msg":"trace[809146792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:916; }","duration":"598.748557ms","start":"2026-02-09T05:50:59.853203Z","end":"2026-02-09T05:51:00.451952Z","steps":["trace[809146792] 'agreement among raft nodes before linearized reading' (duration: 598.360629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:00.452001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:50:59.853185Z","time spent":"598.804178ms","remote":"127.0.0.1:49560","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-02-09T05:51:00.681574Z","caller":"traceutil/trace.go:171","msg":"trace[1794485776] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"222.626188ms","start":"2026-02-09T05:51:00.458928Z","end":"2026-02-09T05:51:00.681554Z","steps":["trace[1794485776] 'process raft request' (duration: 222.537626ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:51:00.68165Z","caller":"traceutil/trace.go:171","msg":"trace[1545887534] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"223.901006ms","start":"2026-02-09T05:51:00.457707Z","end":"2026-02-09T05:51:00.681608Z","steps":["trace[1545887534] 'process raft request' (duration: 223.642521ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:51:00.681877Z","caller":"traceutil/trace.go:171","msg":"trace[728821123] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"222.180749ms","start":"2026-02-09T05:51:00.459686Z","end":"2026-02-09T05:51:00.681866Z","steps":["trace[728821123] 'process raft request' (duration: 221.837982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:00.951096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.328874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:51:00.951156Z","caller":"traceutil/trace.go:171","msg":"trace[297634964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"121.405926ms","start":"2026-02-09T05:51:00.829736Z","end":"2026-02-09T05:51:00.951142Z","steps":["trace[297634964] 'range keys from in-memory index tree' (duration: 121.262243ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:51:01.479115Z","caller":"traceutil/trace.go:171","msg":"trace[1928371700] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"116.362005ms","start":"2026-02-09T05:51:01.362731Z","end":"2026-02-09T05:51:01.479093Z","steps":["trace[1928371700] 'process raft request' (duration: 116.238253ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:51:04.627561Z","caller":"traceutil/trace.go:171","msg":"trace[1024016183] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"102.791096ms","start":"2026-02-09T05:51:04.524752Z","end":"2026-02-09T05:51:04.627543Z","steps":["trace[1024016183] 'process raft request' (duration: 102.680584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:05.303947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.730891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T05:51:05.304096Z","caller":"traceutil/trace.go:171","msg":"trace[1904922809] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"601.035698ms","start":"2026-02-09T05:51:04.703045Z","end":"2026-02-09T05:51:05.304081Z","steps":["trace[1904922809] 'read index received' (duration: 20.100951ms)","trace[1904922809] 'applied index is now lower than readState.Index' (duration: 580.933996ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:51:05.3042Z","caller":"traceutil/trace.go:171","msg":"trace[962711669] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"606.862225ms","start":"2026-02-09T05:51:04.697332Z","end":"2026-02-09T05:51:05.304194Z","steps":["trace[962711669] 'process raft request' (duration: 25.840167ms)","trace[962711669] 'compare' (duration: 580.446435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:51:05.30423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:04.697323Z","time spent":"606.891166ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T05:51:05.304364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.316554ms","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-02-09T05:51:05.304377Z","caller":"traceutil/trace.go:171","msg":"trace[1617423562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:937; }","duration":"601.344284ms","start":"2026-02-09T05:51:04.703028Z","end":"2026-02-09T05:51:05.304372Z","steps":["trace[1617423562] 'agreement among raft nodes before linearized reading' (duration: 601.283963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:05.30439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:04.703022Z","time spent":"601.365655ms","remote":"127.0.0.1:49560","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\" "} {"level":"warn","ts":"2026-02-09T05:51:05.304471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.790082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T05:51:05.304482Z","caller":"traceutil/trace.go:171","msg":"trace[648703674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:937; }","duration":"600.801502ms","start":"2026-02-09T05:51:04.703677Z","end":"2026-02-09T05:51:05.304479Z","steps":["trace[648703674] 'agreement among raft nodes before linearized reading' (duration: 600.761701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:05.304496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:04.703664Z","time spent":"600.829804ms","remote":"127.0.0.1:49560","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":"warn","ts":"2026-02-09T05:51:05.304586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.674152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:51:05.304598Z","caller":"traceutil/trace.go:171","msg":"trace[832848005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"468.747053ms","start":"2026-02-09T05:51:04.835847Z","end":"2026-02-09T05:51:05.304594Z","steps":["trace[832848005] 'agreement among raft nodes before linearized reading' (duration: 468.725912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:05.304608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:04.835827Z","time spent":"468.778973ms","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T05:51:05.717783Z","caller":"traceutil/trace.go:171","msg":"trace[1072443373] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"407.527768ms","start":"2026-02-09T05:51:05.310238Z","end":"2026-02-09T05:51:05.717766Z","steps":["trace[1072443373] 'process raft request' (duration: 402.709153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:05.717879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:05.310229Z","time spent":"407.60051ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:51:05.723278Z","caller":"traceutil/trace.go:171","msg":"trace[243482655] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"411.441064ms","start":"2026-02-09T05:51:05.311829Z","end":"2026-02-09T05:51:05.72327Z","steps":["trace[243482655] 'process raft request' (duration: 411.313212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:05.723339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:05.311823Z","time spent":"411.486425ms","remote":"127.0.0.1:49560","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-02-09T05:51:05.724082Z","caller":"traceutil/trace.go:171","msg":"trace[1598952979] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"183.607942ms","start":"2026-02-09T05:51:05.540465Z","end":"2026-02-09T05:51:05.724073Z","steps":["trace[1598952979] 'process raft request' (duration: 182.757913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:05.724733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.291836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T05:51:05.724764Z","caller":"traceutil/trace.go:171","msg":"trace[1730958224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"235.384169ms","start":"2026-02-09T05:51:05.489372Z","end":"2026-02-09T05:51:05.724756Z","steps":["trace[1730958224] 'agreement among raft nodes before linearized reading' (duration: 235.289137ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:51:05.725164Z","caller":"traceutil/trace.go:171","msg":"trace[2053364384] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:971; }","duration":"233.794204ms","start":"2026-02-09T05:51:05.489454Z","end":"2026-02-09T05:51:05.723248Z","steps":["trace[2053364384] 'read index received' (duration: 223.503528ms)","trace[2053364384] 'applied index is now lower than readState.Index' (duration: 10.289786ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:51:05.725301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.336288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:51:05.725336Z","caller":"traceutil/trace.go:171","msg":"trace[1050183195] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:940; }","duration":"138.43871ms","start":"2026-02-09T05:51:05.586891Z","end":"2026-02-09T05:51:05.72533Z","steps":["trace[1050183195] 'agreement among raft nodes before linearized reading' (duration: 138.348868ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:51:09.63131Z","caller":"traceutil/trace.go:171","msg":"trace[2097672460] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"449.096902ms","start":"2026-02-09T05:51:09.182196Z","end":"2026-02-09T05:51:09.631293Z","steps":["trace[2097672460] 'process raft request' (duration: 448.647312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:09.631424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:09.18217Z","time spent":"449.193863ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:51:09.632632Z","caller":"traceutil/trace.go:171","msg":"trace[318700909] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"316.733585ms","start":"2026-02-09T05:51:09.315889Z","end":"2026-02-09T05:51:09.632623Z","steps":["trace[318700909] 'read index received' (duration: 314.882284ms)","trace[318700909] 'applied index is now lower than readState.Index' (duration: 1.850121ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:51:09.633491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.112016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:51:09.633526Z","caller":"traceutil/trace.go:171","msg":"trace[1778984073] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:960; }","duration":"165.175998ms","start":"2026-02-09T05:51:09.468342Z","end":"2026-02-09T05:51:09.633518Z","steps":["trace[1778984073] 'agreement among raft nodes before linearized reading' (duration: 165.119066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:09.634269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.383231ms","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-02-09T05:51:09.6343Z","caller":"traceutil/trace.go:171","msg":"trace[1033950207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"318.455883ms","start":"2026-02-09T05:51:09.315838Z","end":"2026-02-09T05:51:09.634293Z","steps":["trace[1033950207] 'agreement among raft nodes before linearized reading' (duration: 316.839447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:09.634348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:09.315815Z","time spent":"318.527254ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-09T05:51:11.803166Z","caller":"traceutil/trace.go:171","msg":"trace[1300587197] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"155.324409ms","start":"2026-02-09T05:51:11.64783Z","end":"2026-02-09T05:51:11.803154Z","steps":["trace[1300587197] 'process raft request' (duration: 155.212117ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:51:11.912516Z","caller":"traceutil/trace.go:171","msg":"trace[634254590] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"106.471918ms","start":"2026-02-09T05:51:11.80602Z","end":"2026-02-09T05:51:11.912492Z","steps":["trace[634254590] 'process raft request' (duration: 52.245048ms)","trace[634254590] 'compare' (duration: 53.612257ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:51:11.912876Z","caller":"traceutil/trace.go:171","msg":"trace[1626535581] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"105.68063ms","start":"2026-02-09T05:51:11.807183Z","end":"2026-02-09T05:51:11.912864Z","steps":["trace[1626535581] 'process raft request' (duration: 104.832272ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:51:11.913096Z","caller":"traceutil/trace.go:171","msg":"trace[1181491246] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"104.649568ms","start":"2026-02-09T05:51:11.808436Z","end":"2026-02-09T05:51:11.913085Z","steps":["trace[1181491246] 'process raft request' (duration: 103.622586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:18.364694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.303631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T05:51:18.365136Z","caller":"traceutil/trace.go:171","msg":"trace[559417163] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"417.354114ms","start":"2026-02-09T05:51:17.947753Z","end":"2026-02-09T05:51:18.365107Z","steps":["trace[559417163] 'process raft request' (duration: 97.557462ms)","trace[559417163] 'compare' (duration: 319.063176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:51:18.36526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:17.947737Z","time spent":"417.493597ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T05:51:18.365809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.198852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T05:51:18.365865Z","caller":"traceutil/trace.go:171","msg":"trace[2112652755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"397.290584ms","start":"2026-02-09T05:51:17.968567Z","end":"2026-02-09T05:51:18.365857Z","steps":["trace[2112652755] 'agreement among raft nodes before linearized reading' (duration: 397.162811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:18.366264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:17.968554Z","time spent":"397.358495ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-09T05:51:18.398308Z","caller":"traceutil/trace.go:171","msg":"trace[1707159435] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"429.680184ms","start":"2026-02-09T05:51:17.968603Z","end":"2026-02-09T05:51:18.398284Z","steps":["trace[1707159435] 'read index received' (duration: 76.668583ms)","trace[1707159435] 'applied index is now lower than readState.Index' (duration: 319.558577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:51:18.446784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.738541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T05:51:18.447984Z","caller":"traceutil/trace.go:171","msg":"trace[1727852541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"443.932858ms","start":"2026-02-09T05:51:18.004Z","end":"2026-02-09T05:51:18.447933Z","steps":["trace[1727852541] 'agreement among raft nodes before linearized reading' (duration: 442.65688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:18.448141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:18.003987Z","time spent":"444.143672ms","remote":"127.0.0.1:49560","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":"warn","ts":"2026-02-09T05:51:18.447901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.786648ms","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-02-09T05:51:18.448327Z","caller":"traceutil/trace.go:171","msg":"trace[1729400666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"435.245157ms","start":"2026-02-09T05:51:18.013073Z","end":"2026-02-09T05:51:18.448319Z","steps":["trace[1729400666] 'agreement among raft nodes before linearized reading' (duration: 434.756537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:18.449621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:18.013067Z","time spent":"436.523485ms","remote":"127.0.0.1:49560","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\" "} {"level":"warn","ts":"2026-02-09T05:51:18.596159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.944454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T05:51:18.596404Z","caller":"traceutil/trace.go:171","msg":"trace[810069858] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"135.782932ms","start":"2026-02-09T05:51:18.460604Z","end":"2026-02-09T05:51:18.596387Z","steps":["trace[810069858] 'process raft request' (duration: 135.72036ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:51:18.596803Z","caller":"traceutil/trace.go:171","msg":"trace[440470107] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"138.475462ms","start":"2026-02-09T05:51:18.458311Z","end":"2026-02-09T05:51:18.596787Z","steps":["trace[440470107] 'process raft request' (duration: 27.793051ms)","trace[440470107] 'compare' (duration: 109.795961ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:51:19.445431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.195002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-09T05:51:19.445964Z","caller":"traceutil/trace.go:171","msg":"trace[864435188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"709.781454ms","start":"2026-02-09T05:51:18.736158Z","end":"2026-02-09T05:51:19.445939Z","steps":["trace[864435188] 'range keys from in-memory index tree' (duration: 709.020887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:19.446051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:18.736136Z","time spent":"709.899327ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T05:51:19.446349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.838466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T05:51:19.446713Z","caller":"traceutil/trace.go:171","msg":"trace[1266414836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"613.223665ms","start":"2026-02-09T05:51:18.83346Z","end":"2026-02-09T05:51:19.446684Z","steps":["trace[1266414836] 'range keys from in-memory index tree' (duration: 612.750054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T05:51:19.44684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:51:18.833436Z","time spent":"613.389778ms","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T05:51:19.480715Z","caller":"traceutil/trace.go:171","msg":"trace[270748910] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"250.290427ms","start":"2026-02-09T05:51:19.230413Z","end":"2026-02-09T05:51:19.480703Z","steps":["trace[270748910] 'read index received' (duration: 245.031751ms)","trace[270748910] 'applied index is now lower than readState.Index' (duration: 5.258056ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:51:19.480958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.541102ms","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-02-09T05:51:19.481014Z","caller":"traceutil/trace.go:171","msg":"trace[1262785474] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1011; }","duration":"250.646544ms","start":"2026-02-09T05:51:19.23036Z","end":"2026-02-09T05:51:19.481007Z","steps":["trace[1262785474] 'agreement among raft nodes before linearized reading' (duration: 250.564773ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T05:54:25.730621Z","caller":"traceutil/trace.go:171","msg":"trace[2072440441] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"105.556894ms","start":"2026-02-09T05:54:25.625046Z","end":"2026-02-09T05:54:25.730603Z","steps":["trace[2072440441] 'process raft request' (duration: 75.138114ms)","trace[2072440441] 'compare' (duration: 30.332888ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T05:54:30.721802Z","caller":"traceutil/trace.go:171","msg":"trace[1449481009] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"376.037108ms","start":"2026-02-09T05:54:30.345755Z","end":"2026-02-09T05:54:30.721792Z","steps":["trace[1449481009] 'process raft request' (duration: 305.436651ms)","trace[1449481009] 'compare' (duration: 70.047604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:54:30.721952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T05:54:30.345735Z","time spent":"376.163961ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T05:54:30.721562Z","caller":"traceutil/trace.go:171","msg":"trace[1291206455] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"116.749074ms","start":"2026-02-09T05:54:30.604794Z","end":"2026-02-09T05:54:30.721543Z","steps":["trace[1291206455] 'read index received' (duration: 46.315791ms)","trace[1291206455] 'applied index is now lower than readState.Index' (duration: 70.432533ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T05:54:30.722706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.908412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-09T05:54:30.722766Z","caller":"traceutil/trace.go:171","msg":"trace[386297632] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1838; }","duration":"118.002534ms","start":"2026-02-09T05:54:30.604755Z","end":"2026-02-09T05:54:30.722757Z","steps":["trace[386297632] 'agreement among raft nodes before linearized reading' (duration: 117.620465ms)"],"step_count":1}