{"level":"warn","ts":"2026-05-25T22:08:44.427166Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T22:08:44.427287Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-25T22:08:44.427523Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T22:08:44.427545Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-05-25T22:08:44.427626Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-25T22:08:44.428477Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-05-25T22:08:44.428641Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-25T22:08:44.431826Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.846437ms"} {"level":"info","ts":"2026-05-25T22:08:44.439283Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-05-25T22:08:44.439367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T22:08:44.439395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-05-25T22:08:44.439406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T22:08:44.439416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-05-25T22:08:44.439504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-05-25T22:08:44.449301Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T22:08:44.450429Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T22:08:44.45125Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-25T22:08:44.453473Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T22:08:44.454046Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T22:08:44.454052Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T22:08:44.45414Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T22:08:44.454169Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T22:08:44.455682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-05-25T22:08:44.456042Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-05-25T22:08:44.458791Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-25T22:08:44.458852Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-05-25T22:08:44.458881Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-05-25T22:08:44.459108Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T22:08:44.459175Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T22:08:45.44016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T22:08:45.440297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T22:08:45.440369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-05-25T22:08:45.440452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-05-25T22:08:45.440546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-05-25T22:08:45.44066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-05-25T22:08:45.440725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-05-25T22:08:45.441894Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T22:08:45.441968Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T22:08:45.44188Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T22:08:45.441891Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:08:45.442421Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T22:08:45.44251Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T22:08:45.442938Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:08:45.443202Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:08:45.443284Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:08:45.445154Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-05-25T22:08:45.446327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T22:09:27.47408Z","caller":"traceutil/trace.go:171","msg":"trace[1356337828] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"159.006564ms","start":"2026-05-25T22:09:27.315046Z","end":"2026-05-25T22:09:27.474053Z","steps":["trace[1356337828] 'process raft request' (duration: 158.815679ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:09:27.721066Z","caller":"traceutil/trace.go:171","msg":"trace[1796317288] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"325.990382ms","start":"2026-05-25T22:09:27.395049Z","end":"2026-05-25T22:09:27.721039Z","steps":["trace[1796317288] 'process raft request' (duration: 325.403467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:27.721489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.817102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-25T22:09:27.721569Z","caller":"traceutil/trace.go:171","msg":"trace[1036986384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"175.992867ms","start":"2026-05-25T22:09:27.54556Z","end":"2026-05-25T22:09:27.721553Z","steps":["trace[1036986384] 'agreement among raft nodes before linearized reading' (duration: 175.654879ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:09:27.721716Z","caller":"traceutil/trace.go:171","msg":"trace[1209117941] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"175.397361ms","start":"2026-05-25T22:09:27.545589Z","end":"2026-05-25T22:09:27.720987Z","steps":["trace[1209117941] 'read index received' (duration: 174.626932ms)","trace[1209117941] 'applied index is now lower than readState.Index' (duration: 769.449µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:09:27.72192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:27.395018Z","time spent":"326.127936ms","remote":"127.0.0.1:42322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:09:27.746353Z","caller":"traceutil/trace.go:171","msg":"trace[1793337716] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"168.681252ms","start":"2026-05-25T22:09:27.57765Z","end":"2026-05-25T22:09:27.746331Z","steps":["trace[1793337716] 'process raft request' (duration: 168.541757ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:09:27.985008Z","caller":"traceutil/trace.go:171","msg":"trace[1708611577] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"202.333247ms","start":"2026-05-25T22:09:27.782654Z","end":"2026-05-25T22:09:27.984987Z","steps":["trace[1708611577] 'process raft request' (duration: 202.199584ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:09:28.821937Z","caller":"traceutil/trace.go:171","msg":"trace[407262249] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"425.519263ms","start":"2026-05-25T22:09:28.396397Z","end":"2026-05-25T22:09:28.821916Z","steps":["trace[407262249] 'read index received' (duration: 425.484842ms)","trace[407262249] 'applied index is now lower than readState.Index' (duration: 33.391µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:09:28.821965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:28.330733Z","time spent":"491.225235ms","remote":"127.0.0.1:42272","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-25T22:09:28.822168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.76059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T22:09:28.8222Z","caller":"traceutil/trace.go:171","msg":"trace[1865076593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"425.840692ms","start":"2026-05-25T22:09:28.396352Z","end":"2026-05-25T22:09:28.822193Z","steps":["trace[1865076593] 'agreement among raft nodes before linearized reading' (duration: 425.707619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:28.822233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:28.396333Z","time spent":"425.893203ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-25T22:09:29.272592Z","caller":"traceutil/trace.go:171","msg":"trace[274768734] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"450.57403ms","start":"2026-05-25T22:09:28.821995Z","end":"2026-05-25T22:09:29.272569Z","steps":["trace[274768734] 'read index received' (duration: 449.78801ms)","trace[274768734] 'applied index is now lower than readState.Index' (duration: 784.82µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:09:29.272671Z","caller":"traceutil/trace.go:171","msg":"trace[885159597] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"849.289862ms","start":"2026-05-25T22:09:28.423331Z","end":"2026-05-25T22:09:29.27262Z","steps":["trace[885159597] 'process raft request' (duration: 848.612955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:29.272793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.06671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-25T22:09:29.27282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:28.423306Z","time spent":"849.432586ms","remote":"127.0.0.1:42322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:09:29.272832Z","caller":"traceutil/trace.go:171","msg":"trace[1740423685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"513.157052ms","start":"2026-05-25T22:09:28.759665Z","end":"2026-05-25T22:09:29.272822Z","steps":["trace[1740423685] 'agreement among raft nodes before linearized reading' (duration: 513.016159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:29.272871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:28.759648Z","time spent":"513.214614ms","remote":"127.0.0.1:42494","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-05-25T22:09:29.278437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.115549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:09:29.278523Z","caller":"traceutil/trace.go:171","msg":"trace[1301320955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"419.225702ms","start":"2026-05-25T22:09:28.859267Z","end":"2026-05-25T22:09:29.278492Z","steps":["trace[1301320955] 'agreement among raft nodes before linearized reading' (duration: 419.046278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:29.278716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:28.859235Z","time spent":"419.450569ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T22:09:29.278979Z","caller":"traceutil/trace.go:171","msg":"trace[1827296865] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"455.825954ms","start":"2026-05-25T22:09:28.823141Z","end":"2026-05-25T22:09:29.278967Z","steps":["trace[1827296865] 'process raft request' (duration: 454.90071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:29.279184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:28.823126Z","time spent":"455.884716ms","remote":"127.0.0.1:42272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:09:29.279607Z","caller":"traceutil/trace.go:171","msg":"trace[708547530] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"453.924567ms","start":"2026-05-25T22:09:28.825643Z","end":"2026-05-25T22:09:29.279568Z","steps":["trace[708547530] 'process raft request' (duration: 452.583942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:29.279649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.905709ms","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-05-25T22:09:29.279703Z","caller":"traceutil/trace.go:171","msg":"trace[333602909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"417.975821ms","start":"2026-05-25T22:09:28.861708Z","end":"2026-05-25T22:09:29.279684Z","steps":["trace[333602909] 'agreement among raft nodes before linearized reading' (duration: 417.774336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:29.279734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:28.861701Z","time spent":"418.025683ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T22:09:29.279809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:28.825634Z","time spent":"454.052819ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:09:30.684934Z","caller":"traceutil/trace.go:171","msg":"trace[698792540] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"236.898156ms","start":"2026-05-25T22:09:30.447958Z","end":"2026-05-25T22:09:30.684856Z","steps":["trace[698792540] 'process raft request' (duration: 231.747195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:44.293716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.447385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:09:44.293811Z","caller":"traceutil/trace.go:171","msg":"trace[990613886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"433.577448ms","start":"2026-05-25T22:09:43.860207Z","end":"2026-05-25T22:09:44.293784Z","steps":["trace[990613886] 'range keys from in-memory index tree' (duration: 433.350852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:44.293789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.514414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-25T22:09:44.293853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:43.860186Z","time spent":"433.65906ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T22:09:44.293874Z","caller":"traceutil/trace.go:171","msg":"trace[993162401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"475.650628ms","start":"2026-05-25T22:09:43.818202Z","end":"2026-05-25T22:09:44.293853Z","steps":["trace[993162401] 'range keys from in-memory index tree' (duration: 475.301489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:44.293919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:43.818181Z","time spent":"475.72847ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-25T22:09:44.415564Z","caller":"traceutil/trace.go:171","msg":"trace[1717528663] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"120.877735ms","start":"2026-05-25T22:09:44.294659Z","end":"2026-05-25T22:09:44.415537Z","steps":["trace[1717528663] 'read index received' (duration: 63.064664ms)","trace[1717528663] 'applied index is now lower than readState.Index' (duration: 57.811841ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:09:44.41577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.08623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:09:44.415854Z","caller":"traceutil/trace.go:171","msg":"trace[1790897824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"121.209373ms","start":"2026-05-25T22:09:44.294624Z","end":"2026-05-25T22:09:44.415833Z","steps":["trace[1790897824] 'agreement among raft nodes before linearized reading' (duration: 121.06627ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:09:44.417345Z","caller":"traceutil/trace.go:171","msg":"trace[105191231] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"119.685484ms","start":"2026-05-25T22:09:44.297332Z","end":"2026-05-25T22:09:44.417018Z","steps":["trace[105191231] 'process raft request' (duration: 119.52594ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:19.797656Z","caller":"traceutil/trace.go:171","msg":"trace[908615277] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"280.473974ms","start":"2026-05-25T22:10:19.517146Z","end":"2026-05-25T22:10:19.79762Z","steps":["trace[908615277] 'process raft request' (duration: 280.352931ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:19.797668Z","caller":"traceutil/trace.go:171","msg":"trace[1967004561] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1047; }","duration":"280.366121ms","start":"2026-05-25T22:10:19.517251Z","end":"2026-05-25T22:10:19.797617Z","steps":["trace[1967004561] 'read index received' (duration: 280.354141ms)","trace[1967004561] 'applied index is now lower than readState.Index' (duration: 9.72µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:10:19.797959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.664898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T22:10:19.798014Z","caller":"traceutil/trace.go:171","msg":"trace[558177213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"280.789552ms","start":"2026-05-25T22:10:19.517209Z","end":"2026-05-25T22:10:19.797998Z","steps":["trace[558177213] 'agreement among raft nodes before linearized reading' (duration: 280.533105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:20.327152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.603589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:10:20.327353Z","caller":"traceutil/trace.go:171","msg":"trace[1403907199] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"724.488318ms","start":"2026-05-25T22:10:19.602848Z","end":"2026-05-25T22:10:20.327336Z","steps":["trace[1403907199] 'process raft request' (duration: 724.390886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:20.327422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:10:19.602797Z","time spent":"724.589961ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:10:20.327538Z","caller":"traceutil/trace.go:171","msg":"trace[2108554441] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"809.712546ms","start":"2026-05-25T22:10:19.517814Z","end":"2026-05-25T22:10:20.327526Z","steps":["trace[2108554441] 'process raft request' (duration: 653.437371ms)","trace[2108554441] 'compare' (duration: 155.492495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:10:20.32759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:10:19.517802Z","time spent":"809.756747ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:10:20.327643Z","caller":"traceutil/trace.go:171","msg":"trace[170137204] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"526.00988ms","start":"2026-05-25T22:10:19.801618Z","end":"2026-05-25T22:10:20.327628Z","steps":["trace[170137204] 'process raft request' (duration: 525.682561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:20.32771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:10:19.801601Z","time spent":"526.074771ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:10:42.309315Z","caller":"traceutil/trace.go:171","msg":"trace[671328803] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"420.938893ms","start":"2026-05-25T22:10:41.888346Z","end":"2026-05-25T22:10:42.309285Z","steps":["trace[671328803] 'process raft request' (duration: 420.778729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:42.309542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:10:41.888321Z","time spent":"421.124267ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:10:42.755673Z","caller":"traceutil/trace.go:171","msg":"trace[2008486794] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"293.928728ms","start":"2026-05-25T22:10:42.46171Z","end":"2026-05-25T22:10:42.755639Z","steps":["trace[2008486794] 'process raft request' (duration: 269.172188ms)","trace[2008486794] 'compare' (duration: 24.340361ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:10:42.761715Z","caller":"traceutil/trace.go:171","msg":"trace[1817545922] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"299.762698ms","start":"2026-05-25T22:10:42.461921Z","end":"2026-05-25T22:10:42.761684Z","steps":["trace[1817545922] 'process raft request' (duration: 299.535302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:44.186013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.423675ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-25T22:10:44.186148Z","caller":"traceutil/trace.go:171","msg":"trace[275303423] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"328.67634ms","start":"2026-05-25T22:10:43.857416Z","end":"2026-05-25T22:10:44.186092Z","steps":["trace[275303423] 'read index received' (duration: 18.105552ms)","trace[275303423] 'applied index is now lower than readState.Index' (duration: 310.569388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:10:44.186234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.806773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:10:44.186286Z","caller":"traceutil/trace.go:171","msg":"trace[1213666398] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1090; }","duration":"328.926705ms","start":"2026-05-25T22:10:43.857346Z","end":"2026-05-25T22:10:44.186273Z","steps":["trace[1213666398] 'agreement among raft nodes before linearized reading' (duration: 328.839804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:44.186322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:10:43.85732Z","time spent":"328.992687ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:10:45.048726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.291319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:10:45.048834Z","caller":"traceutil/trace.go:171","msg":"trace[1051149873] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1095; }","duration":"211.460664ms","start":"2026-05-25T22:10:44.837338Z","end":"2026-05-25T22:10:45.048799Z","steps":["trace[1051149873] 'count revisions from in-memory index tree' (duration: 211.161787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:45.049334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.70856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:10:45.049532Z","caller":"traceutil/trace.go:171","msg":"trace[1175478460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"189.955415ms","start":"2026-05-25T22:10:44.859562Z","end":"2026-05-25T22:10:45.049518Z","steps":["trace[1175478460] 'range keys from in-memory index tree' (duration: 189.080426ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:59.516451Z","caller":"traceutil/trace.go:171","msg":"trace[1557748483] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"302.768324ms","start":"2026-05-25T22:10:59.213659Z","end":"2026-05-25T22:10:59.516428Z","steps":["trace[1557748483] 'process raft request' (duration: 302.636392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:59.516595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:10:59.213644Z","time spent":"302.882207ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:10:59.51895Z","caller":"traceutil/trace.go:171","msg":"trace[450879549] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"179.964033ms","start":"2026-05-25T22:10:59.338959Z","end":"2026-05-25T22:10:59.518923Z","steps":["trace[450879549] 'process raft request' (duration: 179.782659ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:01.064001Z","caller":"traceutil/trace.go:171","msg":"trace[492165383] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"217.472828ms","start":"2026-05-25T22:11:00.846512Z","end":"2026-05-25T22:11:01.063985Z","steps":["trace[492165383] 'read index received' (duration: 217.386506ms)","trace[492165383] 'applied index is now lower than readState.Index' (duration: 85.612µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:11:01.064056Z","caller":"traceutil/trace.go:171","msg":"trace[1693675933] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"217.945918ms","start":"2026-05-25T22:11:00.846086Z","end":"2026-05-25T22:11:01.064032Z","steps":["trace[1693675933] 'process raft request' (duration: 217.804745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:01.064184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.656402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-25T22:11:01.064228Z","caller":"traceutil/trace.go:171","msg":"trace[2058126508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1197; }","duration":"217.727464ms","start":"2026-05-25T22:11:00.846477Z","end":"2026-05-25T22:11:01.064205Z","steps":["trace[2058126508] 'agreement among raft nodes before linearized reading' (duration: 217.597391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:01.064277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.011655ms","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-05-25T22:11:01.064331Z","caller":"traceutil/trace.go:171","msg":"trace[1246339986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1197; }","duration":"216.087577ms","start":"2026-05-25T22:11:00.848227Z","end":"2026-05-25T22:11:01.064315Z","steps":["trace[1246339986] 'agreement among raft nodes before linearized reading' (duration: 215.948933ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:04.660692Z","caller":"traceutil/trace.go:171","msg":"trace[462756851] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"204.437708ms","start":"2026-05-25T22:11:04.456228Z","end":"2026-05-25T22:11:04.660665Z","steps":["trace[462756851] 'process raft request' (duration: 204.278874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:05.120973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.299478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:11:05.121046Z","caller":"traceutil/trace.go:171","msg":"trace[1601190604] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"261.000785ms","start":"2026-05-25T22:11:04.860033Z","end":"2026-05-25T22:11:05.121034Z","steps":["trace[1601190604] 'read index received' (duration: 72.511633ms)","trace[1601190604] 'applied index is now lower than readState.Index' (duration: 188.488132ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:11:05.12111Z","caller":"traceutil/trace.go:171","msg":"trace[1433891133] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"589.878681ms","start":"2026-05-25T22:11:04.531221Z","end":"2026-05-25T22:11:05.1211Z","steps":["trace[1433891133] 'process raft request' (duration: 401.394349ms)","trace[1433891133] 'compare' (duration: 188.215346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:11:05.121144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:11:04.531197Z","time spent":"589.929001ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:11:05.12123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.21279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:11:05.121248Z","caller":"traceutil/trace.go:171","msg":"trace[319496083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"261.269031ms","start":"2026-05-25T22:11:04.859971Z","end":"2026-05-25T22:11:05.12124Z","steps":["trace[319496083] 'agreement among raft nodes before linearized reading' (duration: 261.24041ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:19.878761Z","caller":"traceutil/trace.go:171","msg":"trace[777598248] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"101.406245ms","start":"2026-05-25T22:11:19.777337Z","end":"2026-05-25T22:11:19.878743Z","steps":["trace[777598248] 'process raft request' (duration: 101.289903ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:23.706147Z","caller":"traceutil/trace.go:171","msg":"trace[1045461415] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"149.790721ms","start":"2026-05-25T22:11:23.556322Z","end":"2026-05-25T22:11:23.706112Z","steps":["trace[1045461415] 'process raft request' (duration: 149.536855ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:24.633794Z","caller":"traceutil/trace.go:171","msg":"trace[1872587467] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"179.474023ms","start":"2026-05-25T22:11:24.454297Z","end":"2026-05-25T22:11:24.633771Z","steps":["trace[1872587467] 'read index received' (duration: 179.295298ms)","trace[1872587467] 'applied index is now lower than readState.Index' (duration: 177.835µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:11:24.633969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.649947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:11:24.634014Z","caller":"traceutil/trace.go:171","msg":"trace[403150167] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1310; }","duration":"179.741989ms","start":"2026-05-25T22:11:24.454257Z","end":"2026-05-25T22:11:24.633999Z","steps":["trace[403150167] 'agreement among raft nodes before linearized reading' (duration: 179.612067ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:24.633855Z","caller":"traceutil/trace.go:171","msg":"trace[52125149] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"267.782187ms","start":"2026-05-25T22:11:24.366022Z","end":"2026-05-25T22:11:24.633804Z","steps":["trace[52125149] 'process raft request' (duration: 267.621643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:25.184865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.797345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:11:25.184966Z","caller":"traceutil/trace.go:171","msg":"trace[1120625471] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"545.970085ms","start":"2026-05-25T22:11:24.638975Z","end":"2026-05-25T22:11:25.184945Z","steps":["trace[1120625471] 'process raft request' (duration: 297.042348ms)","trace[1120625471] 'compare' (duration: 248.409585ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:11:25.18503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:11:24.638944Z","time spent":"546.058106ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:11:25.446777Z","caller":"traceutil/trace.go:171","msg":"trace[765417825] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1360; }","duration":"616.734019ms","start":"2026-05-25T22:11:24.830023Z","end":"2026-05-25T22:11:25.446757Z","steps":["trace[765417825] 'read index received' (duration: 105.966557ms)","trace[765417825] 'applied index is now lower than readState.Index' (duration: 510.766642ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:11:25.446862Z","caller":"traceutil/trace.go:171","msg":"trace[612203813] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"669.056412ms","start":"2026-05-25T22:11:24.777796Z","end":"2026-05-25T22:11:25.446852Z","steps":["trace[612203813] 'process raft request' (duration: 636.15566ms)","trace[612203813] 'compare' (duration: 32.715328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:11:25.446929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:11:24.777774Z","time spent":"669.107714ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:11:25.447164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.127967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-25T22:11:25.447285Z","caller":"traceutil/trace.go:171","msg":"trace[948643724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1312; }","duration":"617.288781ms","start":"2026-05-25T22:11:24.829975Z","end":"2026-05-25T22:11:25.447264Z","steps":["trace[948643724] 'agreement among raft nodes before linearized reading' (duration: 616.966454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:25.447363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:11:24.829952Z","time spent":"617.397583ms","remote":"127.0.0.1:42494","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-05-25T22:11:25.468411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.196297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:11:25.468431Z","caller":"traceutil/trace.go:171","msg":"trace[76972417] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"284.469137ms","start":"2026-05-25T22:11:25.183934Z","end":"2026-05-25T22:11:25.468403Z","steps":["trace[76972417] 'process raft request' (duration: 284.208561ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:25.468484Z","caller":"traceutil/trace.go:171","msg":"trace[800972164] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1314; }","duration":"233.33118ms","start":"2026-05-25T22:11:25.235133Z","end":"2026-05-25T22:11:25.468465Z","steps":["trace[800972164] 'agreement among raft nodes before linearized reading' (duration: 233.208997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:25.468528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.216193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-25T22:11:25.468551Z","caller":"traceutil/trace.go:171","msg":"trace[56189875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1314; }","duration":"240.251675ms","start":"2026-05-25T22:11:25.228292Z","end":"2026-05-25T22:11:25.468544Z","steps":["trace[56189875] 'agreement among raft nodes before linearized reading' (duration: 240.205054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:25.468472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.855777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-25T22:11:25.468637Z","caller":"traceutil/trace.go:171","msg":"trace[1357671774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"241.062492ms","start":"2026-05-25T22:11:25.22757Z","end":"2026-05-25T22:11:25.468632Z","steps":["trace[1357671774] 'agreement among raft nodes before linearized reading' (duration: 240.781515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:25.468402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.749805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:11:25.468704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.286272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-25T22:11:25.468718Z","caller":"traceutil/trace.go:171","msg":"trace[1414951177] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1314; }","duration":"279.383514ms","start":"2026-05-25T22:11:25.189329Z","end":"2026-05-25T22:11:25.468712Z","steps":["trace[1414951177] 'agreement among raft nodes before linearized reading' (duration: 279.338173ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:25.468729Z","caller":"traceutil/trace.go:171","msg":"trace[448489455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"611.128264ms","start":"2026-05-25T22:11:24.857587Z","end":"2026-05-25T22:11:25.468715Z","steps":["trace[448489455] 'agreement among raft nodes before linearized reading' (duration: 610.726045ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:25.468781Z","caller":"traceutil/trace.go:171","msg":"trace[1761978910] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"277.583205ms","start":"2026-05-25T22:11:25.191185Z","end":"2026-05-25T22:11:25.468769Z","steps":["trace[1761978910] 'process raft request' (duration: 277.079213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:25.468828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:11:24.857569Z","time spent":"611.217166ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T22:11:25.468935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.103688ms","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-05-25T22:11:25.469002Z","caller":"traceutil/trace.go:171","msg":"trace[2111546626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1314; }","duration":"243.258661ms","start":"2026-05-25T22:11:25.225718Z","end":"2026-05-25T22:11:25.468976Z","steps":["trace[2111546626] 'agreement among raft nodes before linearized reading' (duration: 242.739609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:26.490541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.97181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:11:26.490649Z","caller":"traceutil/trace.go:171","msg":"trace[98778232] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1322; }","duration":"433.132214ms","start":"2026-05-25T22:11:26.057492Z","end":"2026-05-25T22:11:26.490624Z","steps":["trace[98778232] 'count revisions from in-memory index tree' (duration: 432.869218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:26.490705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:11:26.05747Z","time spent":"433.219845ms","remote":"127.0.0.1:47900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:11:33.171187Z","caller":"traceutil/trace.go:171","msg":"trace[539793957] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"225.708759ms","start":"2026-05-25T22:11:32.945456Z","end":"2026-05-25T22:11:33.171165Z","steps":["trace[539793957] 'process raft request' (duration: 225.561066ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:33.640922Z","caller":"traceutil/trace.go:171","msg":"trace[1355606105] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"147.799031ms","start":"2026-05-25T22:11:33.493097Z","end":"2026-05-25T22:11:33.640896Z","steps":["trace[1355606105] 'process raft request' (duration: 147.630567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:38.369166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.767745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-25T22:11:38.369253Z","caller":"traceutil/trace.go:171","msg":"trace[920089809] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1393; }","duration":"192.895879ms","start":"2026-05-25T22:11:38.176336Z","end":"2026-05-25T22:11:38.369232Z","steps":["trace[920089809] 'range keys from in-memory index tree' (duration: 192.56354ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:53.67946Z","caller":"traceutil/trace.go:171","msg":"trace[1064421180] linearizableReadLoop","detail":"{readStateIndex:1804; appliedIndex:1803; }","duration":"270.091304ms","start":"2026-05-25T22:12:53.409338Z","end":"2026-05-25T22:12:53.679429Z","steps":["trace[1064421180] 'read index received' (duration: 267.305613ms)","trace[1064421180] 'applied index is now lower than readState.Index' (duration: 2.784161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:53.679645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.276739ms","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-05-25T22:12:53.6798Z","caller":"traceutil/trace.go:171","msg":"trace[740919684] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1736; }","duration":"270.486885ms","start":"2026-05-25T22:12:53.409288Z","end":"2026-05-25T22:12:53.679775Z","steps":["trace[740919684] 'agreement among raft nodes before linearized reading' (duration: 270.242609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:53.67989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.764265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-25T22:12:53.67997Z","caller":"traceutil/trace.go:171","msg":"trace[1816381919] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1736; }","duration":"147.891237ms","start":"2026-05-25T22:12:53.532062Z","end":"2026-05-25T22:12:53.679953Z","steps":["trace[1816381919] 'agreement among raft nodes before linearized reading' (duration: 147.60053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:55.205081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.903659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:55.205426Z","caller":"traceutil/trace.go:171","msg":"trace[498812760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1745; }","duration":"345.011613ms","start":"2026-05-25T22:12:54.86012Z","end":"2026-05-25T22:12:55.205131Z","steps":["trace[498812760] 'range keys from in-memory index tree' (duration: 344.838718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:55.205425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.871399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:55.205502Z","caller":"traceutil/trace.go:171","msg":"trace[224156431] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1745; }","duration":"269.984842ms","start":"2026-05-25T22:12:54.935505Z","end":"2026-05-25T22:12:55.20549Z","steps":["trace[224156431] 'count revisions from in-memory index tree' (duration: 269.795357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:55.205617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:54.860105Z","time spent":"345.352642ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T22:12:56.930524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843648028419682,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:57.367179Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.09952038s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T22:12:57.367548Z","caller":"traceutil/trace.go:171","msg":"trace[565645397] linearizableReadLoop","detail":"{readStateIndex:1821; appliedIndex:1820; }","duration":"938.003497ms","start":"2026-05-25T22:12:56.429524Z","end":"2026-05-25T22:12:57.367527Z","steps":["trace[565645397] 'read index received' (duration: 937.903235ms)","trace[565645397] 'applied index is now lower than readState.Index' (duration: 99.402µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:57.367825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.429683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:57.367855Z","caller":"traceutil/trace.go:171","msg":"trace[1022042879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1753; }","duration":"508.499475ms","start":"2026-05-25T22:12:56.859348Z","end":"2026-05-25T22:12:57.367847Z","steps":["trace[1022042879] 'agreement among raft nodes before linearized reading' (duration: 508.440604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:57.367879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:56.85933Z","time spent":"508.541257ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T22:12:57.367887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.310535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-25T22:12:57.367998Z","caller":"traceutil/trace.go:171","msg":"trace[1252514734] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1753; }","duration":"938.51207ms","start":"2026-05-25T22:12:56.429466Z","end":"2026-05-25T22:12:57.367978Z","steps":["trace[1252514734] 'agreement among raft nodes before linearized reading' (duration: 938.231752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:57.368063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:56.429445Z","time spent":"938.606603ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-25T22:12:57.368174Z","caller":"traceutil/trace.go:171","msg":"trace[1404917885] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"1.100590588s","start":"2026-05-25T22:12:56.267557Z","end":"2026-05-25T22:12:57.368147Z","steps":["trace[1404917885] 'process raft request' (duration: 1.099868459s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:57.369181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:56.267534Z","time spent":"1.10147827s","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:12:58.530701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843648028419686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:59.031678Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843648028419686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:59.35202Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.426899607s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-25T22:12:59.723266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.936088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:12:59.723362Z","caller":"traceutil/trace.go:171","msg":"trace[453938663] linearizableReadLoop","detail":"{readStateIndex:1824; appliedIndex:1823; }","duration":"1.693592663s","start":"2026-05-25T22:12:58.029755Z","end":"2026-05-25T22:12:59.723348Z","steps":["trace[453938663] 'read index received' (duration: 1.322395308s)","trace[453938663] 'applied index is now lower than readState.Index' (duration: 371.195945ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:59.72345Z","caller":"traceutil/trace.go:171","msg":"trace[593954844] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"1.79841061s","start":"2026-05-25T22:12:57.92503Z","end":"2026-05-25T22:12:59.723441Z","steps":["trace[593954844] 'process raft request' (duration: 1.427204495s)","trace[593954844] 'compare' (duration: 370.095457ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:59.723508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:57.925012Z","time spent":"1.798473371s","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:12:59.723674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595714533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-25T22:12:59.723747Z","caller":"traceutil/trace.go:171","msg":"trace[2128842598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1756; }","duration":"1.595820436s","start":"2026-05-25T22:12:58.127911Z","end":"2026-05-25T22:12:59.723732Z","steps":["trace[2128842598] 'agreement among raft nodes before linearized reading' (duration: 1.595647581s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:59.723785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:58.127896Z","time spent":"1.595882529s","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T22:12:59.723892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.694148188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-25T22:12:59.723935Z","caller":"traceutil/trace.go:171","msg":"trace[1087683635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1756; }","duration":"1.69422734s","start":"2026-05-25T22:12:58.0297Z","end":"2026-05-25T22:12:59.723927Z","steps":["trace[1087683635] 'agreement among raft nodes before linearized reading' (duration: 1.694108357s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:59.723978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:58.029681Z","time spent":"1.694286351s","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T22:12:59.723986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02724637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-25T22:12:59.724036Z","caller":"traceutil/trace.go:171","msg":"trace[1238996440] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1756; }","duration":"1.027332462s","start":"2026-05-25T22:12:58.696696Z","end":"2026-05-25T22:12:59.724029Z","steps":["trace[1238996440] 'agreement among raft nodes before linearized reading' (duration: 1.027203729s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:59.724069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:58.696675Z","time spent":"1.027388304s","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-25T22:12:59.724141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.90067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-25T22:12:59.724168Z","caller":"traceutil/trace.go:171","msg":"trace[805781449] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1756; }","duration":"342.92837ms","start":"2026-05-25T22:12:59.381233Z","end":"2026-05-25T22:12:59.724162Z","steps":["trace[805781449] 'agreement among raft nodes before linearized reading' (duration: 342.850618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:59.72421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:59.381196Z","time spent":"343.008142ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-25T22:12:59.724242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383140378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-25T22:12:59.724268Z","caller":"traceutil/trace.go:171","msg":"trace[1132900308] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:1756; }","duration":"1.383192949s","start":"2026-05-25T22:12:58.34107Z","end":"2026-05-25T22:12:59.724263Z","steps":["trace[1132900308] 'agreement among raft nodes before linearized reading' (duration: 1.383084716s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:59.724294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:58.341056Z","time spent":"1.38323356s","remote":"127.0.0.1:42272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-05-25T22:12:59.724343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.42856ms","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-05-25T22:12:59.724403Z","caller":"traceutil/trace.go:171","msg":"trace[2108694786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1756; }","duration":"350.534973ms","start":"2026-05-25T22:12:59.373855Z","end":"2026-05-25T22:12:59.72439Z","steps":["trace[2108694786] 'agreement among raft nodes before linearized reading' (duration: 350.354418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:59.724435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:59.37384Z","time spent":"350.589224ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-25T22:12:59.724476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.594905714s","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-05-25T22:12:59.724507Z","caller":"traceutil/trace.go:171","msg":"trace[317614666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1756; }","duration":"1.594957925s","start":"2026-05-25T22:12:58.129544Z","end":"2026-05-25T22:12:59.724501Z","steps":["trace[317614666] 'agreement among raft nodes before linearized reading' (duration: 1.594831822s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:59.724535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:58.129532Z","time spent":"1.594996256s","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T22:12:59.724569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59554373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-25T22:12:59.723793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.056581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:59.725409Z","caller":"traceutil/trace.go:171","msg":"trace[1850511860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1756; }","duration":"868.708922ms","start":"2026-05-25T22:12:58.856689Z","end":"2026-05-25T22:12:59.725398Z","steps":["trace[1850511860] 'agreement among raft nodes before linearized reading' (duration: 867.075051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:59.725445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:58.856675Z","time spent":"868.761074ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T22:12:59.725596Z","caller":"traceutil/trace.go:171","msg":"trace[661602544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1756; }","duration":"1.596315719s","start":"2026-05-25T22:12:58.128991Z","end":"2026-05-25T22:12:59.725306Z","steps":["trace[661602544] 'agreement among raft nodes before linearized reading' (duration: 1.595525699s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:59.725623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:58.128974Z","time spent":"1.596642398s","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}