{"level":"warn","ts":"2026-03-18T09:51:50.518092Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T09:51:50.518222Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.162: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.19.213.162:2380","--initial-cluster=instance=https://199.19.213.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.162: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-03-18T09:51:50.518301Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T09:51:50.518321Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-03-18T09:51:50.518351Z","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-03-18T09:51:50.519185Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"]} {"level":"info","ts":"2026-03-18T09:51:50.519273Z","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.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.162: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-03-18T09:51:50.524035Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.507125ms"} {"level":"info","ts":"2026-03-18T09:51:50.532341Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8c0a14645b8502c5","cluster-id":"49fe27222e1c6db4"} {"level":"info","ts":"2026-03-18T09:51:50.532424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T09:51:50.532442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 0"} {"level":"info","ts":"2026-03-18T09:51:50.532451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8c0a14645b8502c5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T09:51:50.532457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 1"} {"level":"info","ts":"2026-03-18T09:51:50.532489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"warn","ts":"2026-03-18T09:51:50.542547Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T09:51:50.544596Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T09:51:50.546707Z","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-03-18T09:51:50.548783Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8c0a14645b8502c5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T09:51:50.549135Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8c0a14645b8502c5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T09:51:50.549219Z","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-03-18T09:51:50.549301Z","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-03-18T09:51:50.549313Z","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-03-18T09:51:50.550865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"info","ts":"2026-03-18T09:51:50.550995Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","added-peer-id":"8c0a14645b8502c5","added-peer-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-03-18T09:51:50.560896Z","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-03-18T09:51:50.56102Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-03-18T09:51:50.561053Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-03-18T09:51:50.561392Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8c0a14645b8502c5","initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T09:51:50.561446Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T09:51:50.633712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T09:51:50.633779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T09:51:50.633801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgPreVoteResp from 8c0a14645b8502c5 at term 1"} {"level":"info","ts":"2026-03-18T09:51:50.633815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became candidate at term 2"} {"level":"info","ts":"2026-03-18T09:51:50.63384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgVoteResp from 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-03-18T09:51:50.633853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became leader at term 2"} {"level":"info","ts":"2026-03-18T09:51:50.633863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8c0a14645b8502c5 elected leader 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-03-18T09:51:50.634712Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T09:51:50.635578Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8c0a14645b8502c5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.162:2379]}","request-path":"/0/members/8c0a14645b8502c5/attributes","cluster-id":"49fe27222e1c6db4","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T09:51:50.635665Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T09:51:50.635684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T09:51:50.635856Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T09:51:50.635908Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T09:51:50.636015Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T09:51:50.636101Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T09:51:50.636133Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T09:51:50.637897Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.162:2379"} {"level":"info","ts":"2026-03-18T09:51:50.63853Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-18T09:52:20.39989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.017492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T09:52:20.400015Z","caller":"traceutil/trace.go:171","msg":"trace[1805462883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"205.222807ms","start":"2026-03-18T09:52:20.194766Z","end":"2026-03-18T09:52:20.399989Z","steps":["trace[1805462883] 'range keys from in-memory index tree' (duration: 204.763175ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:20.640611Z","caller":"traceutil/trace.go:171","msg":"trace[1910004311] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"236.657775ms","start":"2026-03-18T09:52:20.403918Z","end":"2026-03-18T09:52:20.640576Z","steps":["trace[1910004311] 'process raft request' (duration: 236.510071ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:20.642659Z","caller":"traceutil/trace.go:171","msg":"trace[673270254] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:487; }","duration":"114.040795ms","start":"2026-03-18T09:52:20.528597Z","end":"2026-03-18T09:52:20.642638Z","steps":["trace[673270254] 'read index received' (duration: 111.837389ms)","trace[673270254] 'applied index is now lower than readState.Index' (duration: 2.202416ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:52:20.642905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.27109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:52:20.642844Z","caller":"traceutil/trace.go:171","msg":"trace[2136884183] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"238.693216ms","start":"2026-03-18T09:52:20.404118Z","end":"2026-03-18T09:52:20.642811Z","steps":["trace[2136884183] 'process raft request' (duration: 238.367238ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:20.642938Z","caller":"traceutil/trace.go:171","msg":"trace[2131466997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"114.372913ms","start":"2026-03-18T09:52:20.528556Z","end":"2026-03-18T09:52:20.642929Z","steps":["trace[2131466997] 'agreement among raft nodes before linearized reading' (duration: 114.280431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:24.028469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199738383353841716,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T09:52:24.503419Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.414138032s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T09:52:24.503692Z","caller":"traceutil/trace.go:171","msg":"trace[94891338] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.414542282s","start":"2026-03-18T09:52:23.089135Z","end":"2026-03-18T09:52:24.503677Z","steps":["trace[94891338] 'process raft request' (duration: 1.414440579s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:24.504068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:52:23.089118Z","time spent":"1.414608804s","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T09:52:24.504308Z","caller":"traceutil/trace.go:171","msg":"trace[823166042] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:495; }","duration":"976.693312ms","start":"2026-03-18T09:52:23.527584Z","end":"2026-03-18T09:52:24.504277Z","steps":["trace[823166042] 'read index received' (duration: 976.684161ms)","trace[823166042] 'applied index is now lower than readState.Index' (duration: 6.711µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:52:24.504487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.662655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:52:24.504526Z","caller":"traceutil/trace.go:171","msg":"trace[591754096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"975.729257ms","start":"2026-03-18T09:52:23.528786Z","end":"2026-03-18T09:52:24.504515Z","steps":["trace[591754096] 'agreement among raft nodes before linearized reading' (duration: 975.627624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:24.504561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:52:23.528762Z","time spent":"975.791129ms","remote":"127.0.0.1:35180","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-03-18T09:52:24.504593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.778852ms","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-03-18T09:52:24.504637Z","caller":"traceutil/trace.go:171","msg":"trace[170466664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"376.866354ms","start":"2026-03-18T09:52:24.127759Z","end":"2026-03-18T09:52:24.504625Z","steps":["trace[170466664] 'agreement among raft nodes before linearized reading' (duration: 376.741871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:24.504622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.775088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-18T09:52:24.504674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:52:24.127741Z","time spent":"376.922306ms","remote":"127.0.0.1:35422","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-03-18T09:52:24.504699Z","caller":"traceutil/trace.go:171","msg":"trace[817691865] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:477; }","duration":"709.890132ms","start":"2026-03-18T09:52:23.794788Z","end":"2026-03-18T09:52:24.504678Z","steps":["trace[817691865] 'agreement among raft nodes before linearized reading' (duration: 709.751178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:24.504742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:52:23.794772Z","time spent":"709.961403ms","remote":"127.0.0.1:35208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.162\" "} {"level":"warn","ts":"2026-03-18T09:52:24.504798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.933668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T09:52:24.504892Z","caller":"traceutil/trace.go:171","msg":"trace[1767439614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"977.315437ms","start":"2026-03-18T09:52:23.527542Z","end":"2026-03-18T09:52:24.504857Z","steps":["trace[1767439614] 'agreement among raft nodes before linearized reading' (duration: 976.888496ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:24.504933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:52:23.527522Z","time spent":"977.402499ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-18T09:52:28.931364Z","caller":"traceutil/trace.go:171","msg":"trace[1222285603] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"399.423768ms","start":"2026-03-18T09:52:28.531918Z","end":"2026-03-18T09:52:28.931341Z","steps":["trace[1222285603] 'read index received' (duration: 399.194923ms)","trace[1222285603] 'applied index is now lower than readState.Index' (duration: 227.935µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:52:28.931449Z","caller":"traceutil/trace.go:171","msg":"trace[275411962] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"403.396098ms","start":"2026-03-18T09:52:28.528022Z","end":"2026-03-18T09:52:28.931419Z","steps":["trace[275411962] 'process raft request' (duration: 403.188833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:28.93152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.585672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T09:52:28.931562Z","caller":"traceutil/trace.go:171","msg":"trace[1528175784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"399.646685ms","start":"2026-03-18T09:52:28.531906Z","end":"2026-03-18T09:52:28.931552Z","steps":["trace[1528175784] 'agreement among raft nodes before linearized reading' (duration: 399.557552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:28.931598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:52:28.528013Z","time spent":"403.503922ms","remote":"127.0.0.1:35422","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-03-18T09:52:28.93163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:52:28.531851Z","time spent":"399.769327ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T09:52:28.931781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.522792ms","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-03-18T09:52:28.931853Z","caller":"traceutil/trace.go:171","msg":"trace[207184858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"256.617985ms","start":"2026-03-18T09:52:28.675226Z","end":"2026-03-18T09:52:28.931844Z","steps":["trace[207184858] 'agreement among raft nodes before linearized reading' (duration: 256.513982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:28.931834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.674961ms","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-03-18T09:52:28.931968Z","caller":"traceutil/trace.go:171","msg":"trace[1738023434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"255.825265ms","start":"2026-03-18T09:52:28.676126Z","end":"2026-03-18T09:52:28.931952Z","steps":["trace[1738023434] 'agreement among raft nodes before linearized reading' (duration: 255.637311ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:29.046646Z","caller":"traceutil/trace.go:171","msg":"trace[826902675] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"109.495653ms","start":"2026-03-18T09:52:28.937129Z","end":"2026-03-18T09:52:29.046625Z","steps":["trace[826902675] 'process raft request' (duration: 109.447071ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:29.046709Z","caller":"traceutil/trace.go:171","msg":"trace[22746489] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"109.604435ms","start":"2026-03-18T09:52:28.937057Z","end":"2026-03-18T09:52:29.046661Z","steps":["trace[22746489] 'process raft request' (duration: 61.272754ms)","trace[22746489] 'compare' (duration: 48.127285ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:52:30.222693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047208826s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T09:52:30.22276Z","caller":"traceutil/trace.go:171","msg":"trace[140910233] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"694.409674ms","start":"2026-03-18T09:52:29.528338Z","end":"2026-03-18T09:52:30.222748Z","steps":["trace[140910233] 'read index received' (duration: 39.582µs)","trace[140910233] 'applied index is now lower than readState.Index' (duration: 694.368972ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:52:30.222809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.465215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:52:30.222838Z","caller":"traceutil/trace.go:171","msg":"trace[179598379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"694.537057ms","start":"2026-03-18T09:52:29.528295Z","end":"2026-03-18T09:52:30.222832Z","steps":["trace[179598379] 'agreement among raft nodes before linearized reading' (duration: 694.483675ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:30.222859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:52:29.528271Z","time spent":"694.582678ms","remote":"127.0.0.1:35180","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-03-18T09:52:31.151347Z","caller":"traceutil/trace.go:171","msg":"trace[1269134576] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"179.36345ms","start":"2026-03-18T09:52:30.971959Z","end":"2026-03-18T09:52:31.151322Z","steps":["trace[1269134576] 'read index received' (duration: 179.026002ms)","trace[1269134576] 'applied index is now lower than readState.Index' (duration: 336.368µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:52:31.151456Z","caller":"traceutil/trace.go:171","msg":"trace[661749359] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"211.600498ms","start":"2026-03-18T09:52:30.939822Z","end":"2026-03-18T09:52:31.151423Z","steps":["trace[661749359] 'process raft request' (duration: 211.213178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:31.151616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.05231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-18T09:52:31.151634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.619107ms","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-03-18T09:52:31.151672Z","caller":"traceutil/trace.go:171","msg":"trace[658839322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"101.151712ms","start":"2026-03-18T09:52:31.050506Z","end":"2026-03-18T09:52:31.151658Z","steps":["trace[658839322] 'agreement among raft nodes before linearized reading' (duration: 100.979967ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:31.151676Z","caller":"traceutil/trace.go:171","msg":"trace[366781872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"179.741039ms","start":"2026-03-18T09:52:30.971923Z","end":"2026-03-18T09:52:31.151664Z","steps":["trace[366781872] 'agreement among raft nodes before linearized reading' (duration: 179.534614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:31.151754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.149802ms","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-03-18T09:52:31.151829Z","caller":"traceutil/trace.go:171","msg":"trace[903343885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"101.279245ms","start":"2026-03-18T09:52:31.05053Z","end":"2026-03-18T09:52:31.151809Z","steps":["trace[903343885] 'agreement among raft nodes before linearized reading' (duration: 101.001538ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:33.411195Z","caller":"traceutil/trace.go:171","msg":"trace[683602644] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"255.328872ms","start":"2026-03-18T09:52:33.155839Z","end":"2026-03-18T09:52:33.411168Z","steps":["trace[683602644] 'read index received' (duration: 255.150967ms)","trace[683602644] 'applied index is now lower than readState.Index' (duration: 176.805µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:52:33.411306Z","caller":"traceutil/trace.go:171","msg":"trace[422811192] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"378.442205ms","start":"2026-03-18T09:52:33.032833Z","end":"2026-03-18T09:52:33.411275Z","steps":["trace[422811192] 'process raft request' (duration: 378.157687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:33.411473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:52:33.032804Z","time spent":"378.576738ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T09:52:33.411575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.670262ms","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-03-18T09:52:33.411763Z","caller":"traceutil/trace.go:171","msg":"trace[200856725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"255.955849ms","start":"2026-03-18T09:52:33.155795Z","end":"2026-03-18T09:52:33.41175Z","steps":["trace[200856725] 'agreement among raft nodes before linearized reading' (duration: 255.504938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:33.413401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.2949ms","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-03-18T09:52:33.413491Z","caller":"traceutil/trace.go:171","msg":"trace[1828623067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"237.423783ms","start":"2026-03-18T09:52:33.17605Z","end":"2026-03-18T09:52:33.413473Z","steps":["trace[1828623067] 'agreement among raft nodes before linearized reading' (duration: 237.27593ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:33.413748Z","caller":"traceutil/trace.go:171","msg":"trace[1019366163] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"182.494459ms","start":"2026-03-18T09:52:33.231241Z","end":"2026-03-18T09:52:33.413735Z","steps":["trace[1019366163] 'process raft request' (duration: 182.009307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:33.414008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.567937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T09:52:33.414064Z","caller":"traceutil/trace.go:171","msg":"trace[523036971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"225.624039ms","start":"2026-03-18T09:52:33.188426Z","end":"2026-03-18T09:52:33.41405Z","steps":["trace[523036971] 'agreement among raft nodes before linearized reading' (duration: 225.516646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:52:33.414322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.274485ms","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-03-18T09:52:33.414376Z","caller":"traceutil/trace.go:171","msg":"trace[1833546043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"226.350637ms","start":"2026-03-18T09:52:33.188011Z","end":"2026-03-18T09:52:33.414362Z","steps":["trace[1833546043] 'agreement among raft nodes before linearized reading' (duration: 226.263825ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:36.173197Z","caller":"traceutil/trace.go:171","msg":"trace[1054930266] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"138.560178ms","start":"2026-03-18T09:52:36.034612Z","end":"2026-03-18T09:52:36.173172Z","steps":["trace[1054930266] 'process raft request' (duration: 138.425435ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:48.084254Z","caller":"traceutil/trace.go:171","msg":"trace[1611334047] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"136.131277ms","start":"2026-03-18T09:52:47.948094Z","end":"2026-03-18T09:52:48.084225Z","steps":["trace[1611334047] 'process raft request' (duration: 135.966763ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:52:49.120764Z","caller":"traceutil/trace.go:171","msg":"trace[1107081678] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"208.675943ms","start":"2026-03-18T09:52:48.912065Z","end":"2026-03-18T09:52:49.120741Z","steps":["trace[1107081678] 'process raft request' (duration: 208.465388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:19.476057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.535331ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T09:53:19.636909Z","caller":"traceutil/trace.go:171","msg":"trace[2053656594] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"353.452197ms","start":"2026-03-18T09:53:19.283327Z","end":"2026-03-18T09:53:19.636779Z","steps":["trace[2053656594] 'process raft request' (duration: 353.253781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:19.637086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:19.283302Z","time spent":"353.696474ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:53:20.126188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.965764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T09:53:20.126291Z","caller":"traceutil/trace.go:171","msg":"trace[1854522900] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:800; }","duration":"481.57384ms","start":"2026-03-18T09:53:19.6447Z","end":"2026-03-18T09:53:20.126274Z","steps":["trace[1854522900] 'read index received' (duration: 11.053047ms)","trace[1854522900] 'applied index is now lower than readState.Index' (duration: 470.519613ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:53:20.12635Z","caller":"traceutil/trace.go:171","msg":"trace[667066932] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"482.179045ms","start":"2026-03-18T09:53:19.644141Z","end":"2026-03-18T09:53:20.12632Z","steps":["trace[667066932] 'process raft request' (duration: 123.003266ms)","trace[667066932] 'compare' (duration: 358.850021ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:53:20.126459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.760085ms","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-03-18T09:53:20.126491Z","caller":"traceutil/trace.go:171","msg":"trace[1039251171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"481.831426ms","start":"2026-03-18T09:53:19.644648Z","end":"2026-03-18T09:53:20.12648Z","steps":["trace[1039251171] 'agreement among raft nodes before linearized reading' (duration: 481.713573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:20.126494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:19.644132Z","time spent":"482.291678ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:53:20.126526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:19.644641Z","time spent":"481.878257ms","remote":"127.0.0.1:35422","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-03-18T09:53:20.126624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.389007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T09:53:20.126702Z","caller":"traceutil/trace.go:171","msg":"trace[1411539902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"454.49232ms","start":"2026-03-18T09:53:19.672186Z","end":"2026-03-18T09:53:20.126679Z","steps":["trace[1411539902] 'agreement among raft nodes before linearized reading' (duration: 454.307766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:20.12674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:19.672172Z","time spent":"454.559852ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T09:53:20.126851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.649674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:53:20.126879Z","caller":"traceutil/trace.go:171","msg":"trace[906844123] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:769; }","duration":"393.703285ms","start":"2026-03-18T09:53:19.733164Z","end":"2026-03-18T09:53:20.126868Z","steps":["trace[906844123] 'agreement among raft nodes before linearized reading' (duration: 393.660154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:20.126909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:19.733151Z","time spent":"393.750998ms","remote":"127.0.0.1:35564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-03-18T09:53:20.429334Z","caller":"traceutil/trace.go:171","msg":"trace[1927713884] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"298.763754ms","start":"2026-03-18T09:53:20.130545Z","end":"2026-03-18T09:53:20.429308Z","steps":["trace[1927713884] 'process raft request' (duration: 206.49078ms)","trace[1927713884] 'compare' (duration: 92.09803ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:53:20.429478Z","caller":"traceutil/trace.go:171","msg":"trace[1460708227] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"297.861783ms","start":"2026-03-18T09:53:20.131585Z","end":"2026-03-18T09:53:20.429446Z","steps":["trace[1460708227] 'process raft request' (duration: 297.683588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:21.908788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.53053ms","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-03-18T09:53:21.908858Z","caller":"traceutil/trace.go:171","msg":"trace[1395783919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:771; }","duration":"249.650703ms","start":"2026-03-18T09:53:21.659193Z","end":"2026-03-18T09:53:21.908844Z","steps":["trace[1395783919] 'range keys from in-memory index tree' (duration: 249.345525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:21.908837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.73676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:53:21.908917Z","caller":"traceutil/trace.go:171","msg":"trace[1954544187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"380.853834ms","start":"2026-03-18T09:53:21.528042Z","end":"2026-03-18T09:53:21.908896Z","steps":["trace[1954544187] 'range keys from in-memory index tree' (duration: 380.628628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:21.908973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:21.528023Z","time spent":"380.937796ms","remote":"127.0.0.1:35180","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-03-18T09:53:21.908949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.220774ms","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-03-18T09:53:21.909507Z","caller":"traceutil/trace.go:171","msg":"trace[1696295366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:771; }","duration":"267.825389ms","start":"2026-03-18T09:53:21.641661Z","end":"2026-03-18T09:53:21.909486Z","steps":["trace[1696295366] 'range keys from in-memory index tree' (duration: 267.004829ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:22.271869Z","caller":"traceutil/trace.go:171","msg":"trace[1023057039] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"359.610761ms","start":"2026-03-18T09:53:21.912231Z","end":"2026-03-18T09:53:22.271842Z","steps":["trace[1023057039] 'process raft request' (duration: 359.454967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:22.272027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:21.912215Z","time spent":"359.733523ms","remote":"127.0.0.1:35422","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-03-18T09:53:22.716201Z","caller":"traceutil/trace.go:171","msg":"trace[442757823] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:804; }","duration":"586.163514ms","start":"2026-03-18T09:53:22.130013Z","end":"2026-03-18T09:53:22.716177Z","steps":["trace[442757823] 'read index received' (duration: 141.727445ms)","trace[442757823] 'applied index is now lower than readState.Index' (duration: 444.435129ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:53:22.71626Z","caller":"traceutil/trace.go:171","msg":"trace[8307371] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"802.739437ms","start":"2026-03-18T09:53:21.913488Z","end":"2026-03-18T09:53:22.716227Z","steps":["trace[8307371] 'process raft request' (duration: 802.542562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:22.716488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.74509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T09:53:22.716542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.496337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T09:53:22.716555Z","caller":"traceutil/trace.go:171","msg":"trace[774363420] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:773; }","duration":"132.867124ms","start":"2026-03-18T09:53:22.583672Z","end":"2026-03-18T09:53:22.716539Z","steps":["trace[774363420] 'agreement among raft nodes before linearized reading' (duration: 132.6945ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:22.716584Z","caller":"traceutil/trace.go:171","msg":"trace[1261815989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"282.62185ms","start":"2026-03-18T09:53:22.433951Z","end":"2026-03-18T09:53:22.716573Z","steps":["trace[1261815989] 'agreement among raft nodes before linearized reading' (duration: 282.383464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:22.7166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.568404ms","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-03-18T09:53:22.716683Z","caller":"traceutil/trace.go:171","msg":"trace[63539831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"586.723958ms","start":"2026-03-18T09:53:22.129938Z","end":"2026-03-18T09:53:22.716662Z","steps":["trace[63539831] 'agreement among raft nodes before linearized reading' (duration: 586.360709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:22.716733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:22.129906Z","time spent":"586.818221ms","remote":"127.0.0.1:35422","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-03-18T09:53:22.716722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.095128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T09:53:22.716817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.763303ms","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-03-18T09:53:22.716884Z","caller":"traceutil/trace.go:171","msg":"trace[480350753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"188.412155ms","start":"2026-03-18T09:53:22.528427Z","end":"2026-03-18T09:53:22.716839Z","steps":["trace[480350753] 'agreement among raft nodes before linearized reading' (duration: 187.987835ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:22.716896Z","caller":"traceutil/trace.go:171","msg":"trace[290863147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"282.894206ms","start":"2026-03-18T09:53:22.433982Z","end":"2026-03-18T09:53:22.716876Z","steps":["trace[290863147] 'agreement among raft nodes before linearized reading' (duration: 282.569808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:22.717437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:21.913474Z","time spent":"802.86186ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:53:23.413314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.681095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T09:53:23.41353Z","caller":"traceutil/trace.go:171","msg":"trace[448773825] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"691.698191ms","start":"2026-03-18T09:53:22.721814Z","end":"2026-03-18T09:53:23.413512Z","steps":["trace[448773825] 'process raft request' (duration: 691.601319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:23.413602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:22.721798Z","time spent":"691.774963ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T09:53:23.41354Z","caller":"traceutil/trace.go:171","msg":"trace[918386437] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"691.853955ms","start":"2026-03-18T09:53:22.721653Z","end":"2026-03-18T09:53:23.413507Z","steps":["trace[918386437] 'process raft request' (duration: 366.896403ms)","trace[918386437] 'compare' (duration: 324.562132ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:53:23.414125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:22.721634Z","time spent":"692.357478ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:53:24.91561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386663514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:53:24.916314Z","caller":"traceutil/trace.go:171","msg":"trace[1206788703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"1.387398913s","start":"2026-03-18T09:53:23.528888Z","end":"2026-03-18T09:53:24.916287Z","steps":["trace[1206788703] 'range keys from in-memory index tree' (duration: 1.386576262s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:24.916417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:23.528867Z","time spent":"1.387524955s","remote":"127.0.0.1:35180","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-03-18T09:53:24.91567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119025651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T09:53:24.916654Z","caller":"traceutil/trace.go:171","msg":"trace[1776983718] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:776; }","duration":"1.120072807s","start":"2026-03-18T09:53:23.796566Z","end":"2026-03-18T09:53:24.916639Z","steps":["trace[1776983718] 'range keys from in-memory index tree' (duration: 1.118808964s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:24.916756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:23.796545Z","time spent":"1.12019707s","remote":"127.0.0.1:35208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.162\" "} {"level":"warn","ts":"2026-03-18T09:53:24.915723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.863937ms","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-03-18T09:53:24.917011Z","caller":"traceutil/trace.go:171","msg":"trace[1443157083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"121.178939ms","start":"2026-03-18T09:53:24.795795Z","end":"2026-03-18T09:53:24.916974Z","steps":["trace[1443157083] 'range keys from in-memory index tree' (duration: 119.667312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:24.915752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.780091ms","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-03-18T09:53:24.917322Z","caller":"traceutil/trace.go:171","msg":"trace[2114230429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:776; }","duration":"195.401552ms","start":"2026-03-18T09:53:24.721903Z","end":"2026-03-18T09:53:24.917305Z","steps":["trace[2114230429] 'range keys from in-memory index tree' (duration: 193.567255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:24.91583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.482221ms","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-03-18T09:53:24.917514Z","caller":"traceutil/trace.go:171","msg":"trace[2084084942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"641.198514ms","start":"2026-03-18T09:53:24.276302Z","end":"2026-03-18T09:53:24.9175Z","steps":["trace[2084084942] 'range keys from in-memory index tree' (duration: 639.389128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:24.917596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:24.276286Z","time spent":"641.270146ms","remote":"127.0.0.1:35422","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-03-18T09:53:25.20322Z","caller":"traceutil/trace.go:171","msg":"trace[928138503] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"282.798905ms","start":"2026-03-18T09:53:24.920396Z","end":"2026-03-18T09:53:25.203195Z","steps":["trace[928138503] 'process raft request' (duration: 282.007634ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:25.203579Z","caller":"traceutil/trace.go:171","msg":"trace[598496240] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"281.545203ms","start":"2026-03-18T09:53:24.922013Z","end":"2026-03-18T09:53:25.203558Z","steps":["trace[598496240] 'process raft request' (duration: 281.126653ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:25.203611Z","caller":"traceutil/trace.go:171","msg":"trace[32810219] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:809; }","duration":"278.902647ms","start":"2026-03-18T09:53:24.924678Z","end":"2026-03-18T09:53:25.20358Z","steps":["trace[32810219] 'read index received' (duration: 73.182016ms)","trace[32810219] 'applied index is now lower than readState.Index' (duration: 205.719511ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:53:25.203669Z","caller":"traceutil/trace.go:171","msg":"trace[1631968892] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"281.40604ms","start":"2026-03-18T09:53:24.922199Z","end":"2026-03-18T09:53:25.203605Z","steps":["trace[1631968892] 'process raft request' (duration: 281.251056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:25.203753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.058911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:53:25.203887Z","caller":"traceutil/trace.go:171","msg":"trace[626791148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"279.196135ms","start":"2026-03-18T09:53:24.924648Z","end":"2026-03-18T09:53:25.203844Z","steps":["trace[626791148] 'agreement among raft nodes before linearized reading' (duration: 279.055881ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:25.211099Z","caller":"traceutil/trace.go:171","msg":"trace[2028461661] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"211.860215ms","start":"2026-03-18T09:53:24.999223Z","end":"2026-03-18T09:53:25.211084Z","steps":["trace[2028461661] 'process raft request' (duration: 211.732742ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:39.628988Z","caller":"traceutil/trace.go:171","msg":"trace[1601873551] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"313.960966ms","start":"2026-03-18T09:53:39.315005Z","end":"2026-03-18T09:53:39.628966Z","steps":["trace[1601873551] 'read index received' (duration: 313.874564ms)","trace[1601873551] 'applied index is now lower than readState.Index' (duration: 85.732µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:53:39.629032Z","caller":"traceutil/trace.go:171","msg":"trace[1215645876] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"318.972022ms","start":"2026-03-18T09:53:39.310033Z","end":"2026-03-18T09:53:39.629005Z","steps":["trace[1215645876] 'process raft request' (duration: 318.822838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:39.62918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.927574ms","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-03-18T09:53:39.629214Z","caller":"traceutil/trace.go:171","msg":"trace[784947746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:870; }","duration":"306.011226ms","start":"2026-03-18T09:53:39.323193Z","end":"2026-03-18T09:53:39.629204Z","steps":["trace[784947746] 'agreement among raft nodes before linearized reading' (duration: 305.893103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:39.62924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:39.323174Z","time spent":"306.060008ms","remote":"127.0.0.1:35422","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-03-18T09:53:39.62925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.163253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-18T09:53:39.629253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.228782ms","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-03-18T09:53:39.62934Z","caller":"traceutil/trace.go:171","msg":"trace[529952799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:870; }","duration":"314.367536ms","start":"2026-03-18T09:53:39.314955Z","end":"2026-03-18T09:53:39.629322Z","steps":["trace[529952799] 'agreement among raft nodes before linearized reading' (duration: 314.12013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:39.629388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:39.314935Z","time spent":"314.443628ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-18T09:53:39.629289Z","caller":"traceutil/trace.go:171","msg":"trace[564174716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:870; }","duration":"100.241485ms","start":"2026-03-18T09:53:39.529036Z","end":"2026-03-18T09:53:39.629277Z","steps":["trace[564174716] 'agreement among raft nodes before linearized reading' (duration: 100.118422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:39.629496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:39.310015Z","time spent":"319.081084ms","remote":"127.0.0.1:35422","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-03-18T09:53:54.349405Z","caller":"traceutil/trace.go:171","msg":"trace[1674835988] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"168.414875ms","start":"2026-03-18T09:53:54.180969Z","end":"2026-03-18T09:53:54.349384Z","steps":["trace[1674835988] 'process raft request' (duration: 168.283082ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:56.04513Z","caller":"traceutil/trace.go:171","msg":"trace[8441460] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"349.849537ms","start":"2026-03-18T09:53:55.695259Z","end":"2026-03-18T09:53:56.045108Z","steps":["trace[8441460] 'read index received' (duration: 349.800155ms)","trace[8441460] 'applied index is now lower than readState.Index' (duration: 48.502µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:53:56.045235Z","caller":"traceutil/trace.go:171","msg":"trace[726750821] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"351.377404ms","start":"2026-03-18T09:53:55.693823Z","end":"2026-03-18T09:53:56.0452Z","steps":["trace[726750821] 'process raft request' (duration: 351.149778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.045402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.345417ms","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-03-18T09:53:56.045407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.988103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T09:53:56.045424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:55.693799Z","time spent":"351.521678ms","remote":"127.0.0.1:35422","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-03-18T09:53:56.045441Z","caller":"traceutil/trace.go:171","msg":"trace[1352598877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"346.421789ms","start":"2026-03-18T09:53:55.699007Z","end":"2026-03-18T09:53:56.045428Z","steps":["trace[1352598877] 'agreement among raft nodes before linearized reading' (duration: 346.237215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.045437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.935044ms","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-03-18T09:53:56.045478Z","caller":"traceutil/trace.go:171","msg":"trace[357707596] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:935; }","duration":"331.096895ms","start":"2026-03-18T09:53:55.714364Z","end":"2026-03-18T09:53:56.045461Z","steps":["trace[357707596] 'agreement among raft nodes before linearized reading' (duration: 330.926611ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.045488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:55.698992Z","time spent":"346.487351ms","remote":"127.0.0.1:35422","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":"info","ts":"2026-03-18T09:53:56.045499Z","caller":"traceutil/trace.go:171","msg":"trace[1311859576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"302.025337ms","start":"2026-03-18T09:53:55.743458Z","end":"2026-03-18T09:53:56.045484Z","steps":["trace[1311859576] 'agreement among raft nodes before linearized reading' (duration: 301.808862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.04553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:55.714349Z","time spent":"331.166298ms","remote":"127.0.0.1:35316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-18T09:53:56.045551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:55.743445Z","time spent":"302.089798ms","remote":"127.0.0.1:35422","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-03-18T09:53:56.045595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.651271ms","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-03-18T09:53:56.045676Z","caller":"traceutil/trace.go:171","msg":"trace[664823615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"294.781054ms","start":"2026-03-18T09:53:55.750875Z","end":"2026-03-18T09:53:56.045656Z","steps":["trace[664823615] 'agreement among raft nodes before linearized reading' (duration: 294.474297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.045771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.497972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T09:53:56.045907Z","caller":"traceutil/trace.go:171","msg":"trace[1521851316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:935; }","duration":"350.580424ms","start":"2026-03-18T09:53:55.695228Z","end":"2026-03-18T09:53:56.045808Z","steps":["trace[1521851316] 'agreement among raft nodes before linearized reading' (duration: 350.43131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.046211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:55.695214Z","time spent":"350.984635ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T09:53:56.651098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.838845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T09:53:56.651275Z","caller":"traceutil/trace.go:171","msg":"trace[878807025] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"601.594532ms","start":"2026-03-18T09:53:56.04967Z","end":"2026-03-18T09:53:56.651264Z","steps":["trace[878807025] 'process raft request' (duration: 497.534172ms)","trace[878807025] 'compare' (duration: 103.141308ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:53:56.651331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:56.049644Z","time spent":"601.669404ms","remote":"127.0.0.1:35422","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-03-18T09:53:56.651518Z","caller":"traceutil/trace.go:171","msg":"trace[393287982] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"601.427269ms","start":"2026-03-18T09:53:56.050066Z","end":"2026-03-18T09:53:56.651494Z","steps":["trace[393287982] 'process raft request' (duration: 601.151441ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:56.651602Z","caller":"traceutil/trace.go:171","msg":"trace[1563443724] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"601.224244ms","start":"2026-03-18T09:53:56.050345Z","end":"2026-03-18T09:53:56.65157Z","steps":["trace[1563443724] 'process raft request' (duration: 601.08902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.651652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:56.05033Z","time spent":"601.303806ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:53:56.651661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:56.050038Z","time spent":"601.547932ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T09:53:56.651801Z","caller":"traceutil/trace.go:171","msg":"trace[563800672] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:977; }","duration":"579.927099ms","start":"2026-03-18T09:53:56.071863Z","end":"2026-03-18T09:53:56.65179Z","steps":["trace[563800672] 'read index received' (duration: 475.318004ms)","trace[563800672] 'applied index is now lower than readState.Index' (duration: 104.608275ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:53:56.651844Z","caller":"traceutil/trace.go:171","msg":"trace[709759565] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"250.237539ms","start":"2026-03-18T09:53:56.401601Z","end":"2026-03-18T09:53:56.651839Z","steps":["trace[709759565] 'process raft request' (duration: 250.146967ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:56.651818Z","caller":"traceutil/trace.go:171","msg":"trace[1022991082] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"601.415638ms","start":"2026-03-18T09:53:56.050381Z","end":"2026-03-18T09:53:56.651797Z","steps":["trace[1022991082] 'process raft request' (duration: 601.160832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.652062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.585009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:53:56.652096Z","caller":"traceutil/trace.go:171","msg":"trace[354528337] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:940; }","duration":"266.64628ms","start":"2026-03-18T09:53:56.385443Z","end":"2026-03-18T09:53:56.652089Z","steps":["trace[354528337] 'agreement among raft nodes before linearized reading' (duration: 266.602408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.652117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.489638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:53:56.652181Z","caller":"traceutil/trace.go:171","msg":"trace[400798172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"123.627971ms","start":"2026-03-18T09:53:56.528536Z","end":"2026-03-18T09:53:56.652163Z","steps":["trace[400798172] 'agreement among raft nodes before linearized reading' (duration: 123.532319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.652084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:56.050366Z","time spent":"601.645895ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:53:56.651912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.051112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T09:53:56.652345Z","caller":"traceutil/trace.go:171","msg":"trace[1801825732] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:940; }","duration":"580.488843ms","start":"2026-03-18T09:53:56.071832Z","end":"2026-03-18T09:53:56.652321Z","steps":["trace[1801825732] 'agreement among raft nodes before linearized reading' (duration: 580.028502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:56.652388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:56.071816Z","time spent":"580.558605ms","remote":"127.0.0.1:35352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-03-18T09:53:58.113696Z","caller":"traceutil/trace.go:171","msg":"trace[952582322] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"273.894461ms","start":"2026-03-18T09:53:57.839771Z","end":"2026-03-18T09:53:58.113665Z","steps":["trace[952582322] 'process raft request' (duration: 273.713396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:59.082005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.686655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T09:53:59.082397Z","caller":"traceutil/trace.go:171","msg":"trace[1732130487] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"553.32907ms","start":"2026-03-18T09:53:58.529054Z","end":"2026-03-18T09:53:59.082383Z","steps":["trace[1732130487] 'read index received' (duration: 45.151µs)","trace[1732130487] 'applied index is now lower than readState.Index' (duration: 553.282619ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:53:59.082572Z","caller":"traceutil/trace.go:171","msg":"trace[594512980] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"964.422732ms","start":"2026-03-18T09:53:58.118139Z","end":"2026-03-18T09:53:59.082562Z","steps":["trace[594512980] 'process raft request' (duration: 283.121571ms)","trace[594512980] 'compare' (duration: 680.45491ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:53:59.082631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:58.118121Z","time spent":"964.481184ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:53:59.082672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.604547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:53:59.082762Z","caller":"traceutil/trace.go:171","msg":"trace[634799608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"553.73473ms","start":"2026-03-18T09:53:58.529006Z","end":"2026-03-18T09:53:59.082741Z","steps":["trace[634799608] 'agreement among raft nodes before linearized reading' (duration: 553.599967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:59.082809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:58.528986Z","time spent":"553.811751ms","remote":"127.0.0.1:35180","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-03-18T09:53:59.293547Z","caller":"traceutil/trace.go:171","msg":"trace[1701500221] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:990; }","duration":"211.100866ms","start":"2026-03-18T09:53:59.082421Z","end":"2026-03-18T09:53:59.293522Z","steps":["trace[1701500221] 'read index received' (duration: 3.460998ms)","trace[1701500221] 'applied index is now lower than readState.Index' (duration: 207.638978ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:53:59.293814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.635516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-18T09:53:59.293815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.207145ms","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-03-18T09:53:59.293932Z","caller":"traceutil/trace.go:171","msg":"trace[955855534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:949; }","duration":"637.363979ms","start":"2026-03-18T09:53:58.65655Z","end":"2026-03-18T09:53:59.293914Z","steps":["trace[955855534] 'agreement among raft nodes before linearized reading' (duration: 637.046051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:59.293982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.728308ms","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-03-18T09:53:59.294043Z","caller":"traceutil/trace.go:171","msg":"trace[71773794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:949; }","duration":"637.819741ms","start":"2026-03-18T09:53:58.656208Z","end":"2026-03-18T09:53:59.294028Z","steps":["trace[71773794] 'agreement among raft nodes before linearized reading' (duration: 637.697368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:59.294053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.118341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:53:59.294032Z","caller":"traceutil/trace.go:171","msg":"trace[713645511] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"420.018326ms","start":"2026-03-18T09:53:58.873986Z","end":"2026-03-18T09:53:59.294004Z","steps":["trace[713645511] 'process raft request' (duration: 419.38183ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:53:59.294083Z","caller":"traceutil/trace.go:171","msg":"trace[1799586492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"210.189113ms","start":"2026-03-18T09:53:59.083886Z","end":"2026-03-18T09:53:59.294075Z","steps":["trace[1799586492] 'agreement among raft nodes before linearized reading' (duration: 210.145472ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:59.294084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:58.656186Z","time spent":"637.889942ms","remote":"127.0.0.1:35422","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-03-18T09:53:59.294169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:58.873968Z","time spent":"420.132959ms","remote":"127.0.0.1:35334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5958,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:53:59.293986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:58.656538Z","time spent":"637.43899ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T09:53:59.293864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.605115ms","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-03-18T09:53:59.294447Z","caller":"traceutil/trace.go:171","msg":"trace[2031715030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:949; }","duration":"638.263821ms","start":"2026-03-18T09:53:58.656175Z","end":"2026-03-18T09:53:59.294438Z","steps":["trace[2031715030] 'agreement among raft nodes before linearized reading' (duration: 637.473011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:59.294474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:58.656156Z","time spent":"638.312233ms","remote":"127.0.0.1:35422","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-03-18T09:53:59.293883Z","caller":"traceutil/trace.go:171","msg":"trace[1880469824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:949; }","duration":"637.757319ms","start":"2026-03-18T09:53:58.656114Z","end":"2026-03-18T09:53:59.293871Z","steps":["trace[1880469824] 'agreement among raft nodes before linearized reading' (duration: 637.551274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:59.298677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:58.656095Z","time spent":"642.554059ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T09:53:59.75304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.049705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T09:53:59.753248Z","caller":"traceutil/trace.go:171","msg":"trace[1178642273] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"450.47521ms","start":"2026-03-18T09:53:59.30276Z","end":"2026-03-18T09:53:59.753235Z","steps":["trace[1178642273] 'process raft request' (duration: 450.349717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:59.753305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:59.302744Z","time spent":"450.534052ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T09:53:59.753278Z","caller":"traceutil/trace.go:171","msg":"trace[1393404647] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"453.933687ms","start":"2026-03-18T09:53:59.299289Z","end":"2026-03-18T09:53:59.753222Z","steps":["trace[1393404647] 'process raft request' (duration: 187.591826ms)","trace[1393404647] 'compare' (duration: 265.789687ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:53:59.753333Z","caller":"traceutil/trace.go:171","msg":"trace[965233063] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"450.03395ms","start":"2026-03-18T09:53:59.303274Z","end":"2026-03-18T09:53:59.753308Z","steps":["trace[965233063] 'process raft request' (duration: 449.926627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:53:59.753471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:59.303266Z","time spent":"450.132262ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:53:59.753496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:53:59.299264Z","time spent":"454.104582ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T09:54:01.421284Z","caller":"traceutil/trace.go:171","msg":"trace[819278023] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"212.737617ms","start":"2026-03-18T09:54:01.208525Z","end":"2026-03-18T09:54:01.421262Z","steps":["trace[819278023] 'read index received' (duration: 212.559712ms)","trace[819278023] 'applied index is now lower than readState.Index' (duration: 177.025µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:54:01.421401Z","caller":"traceutil/trace.go:171","msg":"trace[1392919052] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"331.986538ms","start":"2026-03-18T09:54:01.089368Z","end":"2026-03-18T09:54:01.421355Z","steps":["trace[1392919052] 'process raft request' (duration: 331.767522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:01.421433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.89607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T09:54:01.421619Z","caller":"traceutil/trace.go:171","msg":"trace[1531595974] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:954; }","duration":"213.139447ms","start":"2026-03-18T09:54:01.208461Z","end":"2026-03-18T09:54:01.421601Z","steps":["trace[1531595974] 'agreement among raft nodes before linearized reading' (duration: 212.916081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:01.421635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:01.089349Z","time spent":"332.146741ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:54:01.653663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.724702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T09:54:01.654087Z","caller":"traceutil/trace.go:171","msg":"trace[1843678454] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"229.774804ms","start":"2026-03-18T09:54:01.42429Z","end":"2026-03-18T09:54:01.654065Z","steps":["trace[1843678454] 'process raft request' (duration: 115.479437ms)","trace[1843678454] 'compare' (duration: 113.481476ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:54:02.016042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.173082ms","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-03-18T09:54:02.016097Z","caller":"traceutil/trace.go:171","msg":"trace[2025889972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"259.272575ms","start":"2026-03-18T09:54:01.756813Z","end":"2026-03-18T09:54:02.016085Z","steps":["trace[2025889972] 'range keys from in-memory index tree' (duration: 259.049459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:02.016285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.046013ms","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-03-18T09:54:02.01631Z","caller":"traceutil/trace.go:171","msg":"trace[1685282284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"258.091564ms","start":"2026-03-18T09:54:01.758213Z","end":"2026-03-18T09:54:02.016304Z","steps":["trace[1685282284] 'range keys from in-memory index tree' (duration: 257.970432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:02.016609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.069554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-18T09:54:02.01668Z","caller":"traceutil/trace.go:171","msg":"trace[944176278] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:955; }","duration":"130.177736ms","start":"2026-03-18T09:54:01.886484Z","end":"2026-03-18T09:54:02.016662Z","steps":["trace[944176278] 'range keys from in-memory index tree' (duration: 129.93735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:02.017064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.229722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T09:54:02.01712Z","caller":"traceutil/trace.go:171","msg":"trace[1505265724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"255.297994ms","start":"2026-03-18T09:54:01.761807Z","end":"2026-03-18T09:54:02.017105Z","steps":["trace[1505265724] 'range keys from in-memory index tree' (duration: 254.72666ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:54:02.549646Z","caller":"traceutil/trace.go:171","msg":"trace[890267290] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"529.898411ms","start":"2026-03-18T09:54:02.019725Z","end":"2026-03-18T09:54:02.549623Z","steps":["trace[890267290] 'process raft request' (duration: 529.690796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:02.549805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:02.019699Z","time spent":"530.018165ms","remote":"127.0.0.1:35422","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-03-18T09:54:02.550042Z","caller":"traceutil/trace.go:171","msg":"trace[392846603] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"529.662186ms","start":"2026-03-18T09:54:02.020356Z","end":"2026-03-18T09:54:02.550019Z","steps":["trace[392846603] 'process raft request' (duration: 529.224885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:02.550177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:02.020343Z","time spent":"529.762968ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T09:54:02.550207Z","caller":"traceutil/trace.go:171","msg":"trace[1906106989] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"529.837011ms","start":"2026-03-18T09:54:02.020358Z","end":"2026-03-18T09:54:02.550195Z","steps":["trace[1906106989] 'process raft request' (duration: 529.547003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:02.550294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:02.020341Z","time spent":"529.920582ms","remote":"127.0.0.1:35422","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-03-18T09:54:09.131137Z","caller":"traceutil/trace.go:171","msg":"trace[1272153043] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"538.685113ms","start":"2026-03-18T09:54:08.592423Z","end":"2026-03-18T09:54:09.131108Z","steps":["trace[1272153043] 'process raft request' (duration: 538.466378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:09.131351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:08.592405Z","time spent":"538.846347ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:54:10.649946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188353204s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T09:54:10.650085Z","caller":"traceutil/trace.go:171","msg":"trace[2022512946] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"2.055988629s","start":"2026-03-18T09:54:08.594076Z","end":"2026-03-18T09:54:10.650064Z","steps":["trace[2022512946] 'process raft request' (duration: 867.4522ms)","trace[2022512946] 'compare' (duration: 1.188147518s)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:54:10.650158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:08.594066Z","time spent":"2.05606206s","remote":"127.0.0.1:35422","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-03-18T09:54:10.673679Z","caller":"traceutil/trace.go:171","msg":"trace[748594090] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1054; }","duration":"2.026507949s","start":"2026-03-18T09:54:08.647146Z","end":"2026-03-18T09:54:10.673654Z","steps":["trace[748594090] 'read index received' (duration: 483.730625ms)","trace[748594090] 'applied index is now lower than readState.Index' (duration: 1.542776014s)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:54:10.67393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205115463s","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-03-18T09:54:10.67393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.036188ms","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:530"} {"level":"info","ts":"2026-03-18T09:54:10.673969Z","caller":"traceutil/trace.go:171","msg":"trace[338377991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1012; }","duration":"1.205204866s","start":"2026-03-18T09:54:09.468754Z","end":"2026-03-18T09:54:10.673959Z","steps":["trace[338377991] 'agreement among raft nodes before linearized reading' (duration: 1.205039952s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:10.673998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:09.468735Z","time spent":"1.205255957s","remote":"127.0.0.1:35422","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-03-18T09:54:10.673978Z","caller":"traceutil/trace.go:171","msg":"trace[1606442894] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1012; }","duration":"432.158171ms","start":"2026-03-18T09:54:10.241809Z","end":"2026-03-18T09:54:10.673967Z","steps":["trace[1606442894] 'agreement among raft nodes before linearized reading' (duration: 432.014317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:10.674029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:10.24179Z","time spent":"432.231603ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-18T09:54:10.674039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.324122ms","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":"warn","ts":"2026-03-18T09:54:10.674053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.026898988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-18T09:54:10.674112Z","caller":"traceutil/trace.go:171","msg":"trace[1599435511] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1012; }","duration":"787.447545ms","start":"2026-03-18T09:54:09.886644Z","end":"2026-03-18T09:54:10.674092Z","steps":["trace[1599435511] 'agreement among raft nodes before linearized reading' (duration: 787.24038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:10.674171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:09.886625Z","time spent":"787.536107ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-18T09:54:10.674186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146887842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T09:54:10.674239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.91359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T09:54:10.674277Z","caller":"traceutil/trace.go:171","msg":"trace[768405519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1012; }","duration":"546.990502ms","start":"2026-03-18T09:54:10.127276Z","end":"2026-03-18T09:54:10.674267Z","steps":["trace[768405519] 'agreement among raft nodes before linearized reading' (duration: 546.863259ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:54:10.674266Z","caller":"traceutil/trace.go:171","msg":"trace[1886881265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"1.147011286s","start":"2026-03-18T09:54:09.527237Z","end":"2026-03-18T09:54:10.674248Z","steps":["trace[1886881265] 'agreement among raft nodes before linearized reading' (duration: 1.14678957s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:10.674308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:10.127254Z","time spent":"547.047463ms","remote":"127.0.0.1:35422","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-03-18T09:54:10.674323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:09.527223Z","time spent":"1.147091837s","remote":"127.0.0.1:35180","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-03-18T09:54:10.674257Z","caller":"traceutil/trace.go:171","msg":"trace[667991046] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1012; }","duration":"2.027005291s","start":"2026-03-18T09:54:08.6471Z","end":"2026-03-18T09:54:10.674105Z","steps":["trace[667991046] 'agreement among raft nodes before linearized reading' (duration: 2.026630302s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:10.674358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.794762ms","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-03-18T09:54:10.674415Z","caller":"traceutil/trace.go:171","msg":"trace[464341893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1012; }","duration":"991.893835ms","start":"2026-03-18T09:54:09.682508Z","end":"2026-03-18T09:54:10.674402Z","steps":["trace[464341893] 'agreement among raft nodes before linearized reading' (duration: 991.776962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:10.67446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:09.682487Z","time spent":"991.963467ms","remote":"127.0.0.1:35422","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-03-18T09:54:10.674572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:54:08.647082Z","time spent":"2.02737648s","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-18T09:54:16.256303Z","caller":"traceutil/trace.go:171","msg":"trace[404398272] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"141.529301ms","start":"2026-03-18T09:54:16.114734Z","end":"2026-03-18T09:54:16.256263Z","steps":["trace[404398272] 'process raft request' (duration: 141.296286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:27.069303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.243074ms","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-03-18T09:54:27.069394Z","caller":"traceutil/trace.go:171","msg":"trace[1986335808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"192.336946ms","start":"2026-03-18T09:54:26.877037Z","end":"2026-03-18T09:54:27.069374Z","steps":["trace[1986335808] 'range keys from in-memory index tree' (duration: 192.155901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:27.069303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.075063ms","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-03-18T09:54:27.069542Z","caller":"traceutil/trace.go:171","msg":"trace[217757105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1079; }","duration":"191.37101ms","start":"2026-03-18T09:54:26.878158Z","end":"2026-03-18T09:54:27.069529Z","steps":["trace[217757105] 'range keys from in-memory index tree' (duration: 190.926299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:27.069506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.32271ms","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-03-18T09:54:27.069633Z","caller":"traceutil/trace.go:171","msg":"trace[1700520243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"191.488714ms","start":"2026-03-18T09:54:26.878121Z","end":"2026-03-18T09:54:27.06961Z","steps":["trace[1700520243] 'range keys from in-memory index tree' (duration: 191.065505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:54:27.069876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.890424ms","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:531"} {"level":"info","ts":"2026-03-18T09:54:27.069933Z","caller":"traceutil/trace.go:171","msg":"trace[1894338848] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1079; }","duration":"196.651693ms","start":"2026-03-18T09:54:26.873266Z","end":"2026-03-18T09:54:27.069918Z","steps":["trace[1894338848] 'range keys from in-memory index tree' (duration: 195.73219ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:57:13.041918Z","caller":"traceutil/trace.go:171","msg":"trace[449161526] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"124.066269ms","start":"2026-03-18T09:57:12.917731Z","end":"2026-03-18T09:57:13.041798Z","steps":["trace[449161526] 'process raft request' (duration: 123.889144ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:57:13.650857Z","caller":"traceutil/trace.go:171","msg":"trace[921646445] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1862; }","duration":"610.285371ms","start":"2026-03-18T09:57:13.040546Z","end":"2026-03-18T09:57:13.650832Z","steps":["trace[921646445] 'read index received' (duration: 1.066907ms)","trace[921646445] 'applied index is now lower than readState.Index' (duration: 609.217504ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:57:13.650905Z","caller":"traceutil/trace.go:171","msg":"trace[1086425094] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"658.496338ms","start":"2026-03-18T09:57:12.992379Z","end":"2026-03-18T09:57:13.650876Z","steps":["trace[1086425094] 'process raft request' (duration: 604.558137ms)","trace[1086425094] 'compare' (duration: 53.551452ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:57:13.651083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:57:12.992356Z","time spent":"658.641302ms","remote":"127.0.0.1:35334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7021,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T09:57:13.651147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.385297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T09:57:13.65122Z","caller":"traceutil/trace.go:171","msg":"trace[1549132461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1782; }","duration":"122.483809ms","start":"2026-03-18T09:57:13.528716Z","end":"2026-03-18T09:57:13.6512Z","steps":["trace[1549132461] 'agreement among raft nodes before linearized reading' (duration: 122.386547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:57:13.651234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.569728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-03-18T09:57:13.651301Z","caller":"traceutil/trace.go:171","msg":"trace[55110755] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1782; }","duration":"610.784154ms","start":"2026-03-18T09:57:13.040504Z","end":"2026-03-18T09:57:13.651288Z","steps":["trace[55110755] 'agreement among raft nodes before linearized reading' (duration: 610.516317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:57:13.65135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:57:13.040484Z","time spent":"610.857895ms","remote":"127.0.0.1:35350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2746,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-03-18T09:57:14.2624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.457804ms","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-03-18T09:57:14.262433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.192847ms","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-03-18T09:57:14.262473Z","caller":"traceutil/trace.go:171","msg":"trace[1005127779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1784; }","duration":"143.561416ms","start":"2026-03-18T09:57:14.118896Z","end":"2026-03-18T09:57:14.262457Z","steps":["trace[1005127779] 'range keys from in-memory index tree' (duration: 143.30358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:57:14.262481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.358477ms","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-03-18T09:57:14.262517Z","caller":"traceutil/trace.go:171","msg":"trace[1394976791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1784; }","duration":"299.300069ms","start":"2026-03-18T09:57:13.963197Z","end":"2026-03-18T09:57:14.262497Z","steps":["trace[1394976791] 'range keys from in-memory index tree' (duration: 299.074024ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:57:14.262532Z","caller":"traceutil/trace.go:171","msg":"trace[1377213825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1784; }","duration":"142.448769ms","start":"2026-03-18T09:57:14.120069Z","end":"2026-03-18T09:57:14.262517Z","steps":["trace[1377213825] 'range keys from in-memory index tree' (duration: 142.242744ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:57:14.548625Z","caller":"traceutil/trace.go:171","msg":"trace[1674210435] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"281.585235ms","start":"2026-03-18T09:57:14.267011Z","end":"2026-03-18T09:57:14.548596Z","steps":["trace[1674210435] 'process raft request' (duration: 269.850141ms)","trace[1674210435] 'compare' (duration: 11.502818ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:57:14.548904Z","caller":"traceutil/trace.go:171","msg":"trace[1807465888] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"280.95661ms","start":"2026-03-18T09:57:14.267917Z","end":"2026-03-18T09:57:14.548873Z","steps":["trace[1807465888] 'process raft request' (duration: 280.907448ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:57:14.548933Z","caller":"traceutil/trace.go:171","msg":"trace[1652776672] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"281.538504ms","start":"2026-03-18T09:57:14.267319Z","end":"2026-03-18T09:57:14.548858Z","steps":["trace[1652776672] 'process raft request' (duration: 281.207026ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:57:14.548939Z","caller":"traceutil/trace.go:171","msg":"trace[1339021681] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"281.3669ms","start":"2026-03-18T09:57:14.267561Z","end":"2026-03-18T09:57:14.548928Z","steps":["trace[1339021681] 'process raft request' (duration: 281.165225ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:57:26.577566Z","caller":"traceutil/trace.go:171","msg":"trace[1222246350] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"221.43491ms","start":"2026-03-18T09:57:26.356061Z","end":"2026-03-18T09:57:26.577496Z","steps":["trace[1222246350] 'process raft request' (duration: 185.847365ms)","trace[1222246350] 'compare' (duration: 35.261677ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:57:26.869373Z","caller":"traceutil/trace.go:171","msg":"trace[230843770] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"228.789483ms","start":"2026-03-18T09:57:26.640542Z","end":"2026-03-18T09:57:26.869331Z","steps":["trace[230843770] 'process raft request' (duration: 173.713013ms)","trace[230843770] 'compare' (duration: 54.751382ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T09:57:26.871386Z","caller":"traceutil/trace.go:171","msg":"trace[1795935629] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"230.26269ms","start":"2026-03-18T09:57:26.641102Z","end":"2026-03-18T09:57:26.871364Z","steps":["trace[1795935629] 'process raft request' (duration: 230.143717ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:57:26.871533Z","caller":"traceutil/trace.go:171","msg":"trace[1506060772] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"230.28773ms","start":"2026-03-18T09:57:26.641237Z","end":"2026-03-18T09:57:26.871525Z","steps":["trace[1506060772] 'process raft request' (duration: 230.074525ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:57:26.871544Z","caller":"traceutil/trace.go:171","msg":"trace[129075901] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1848; }","duration":"229.767678ms","start":"2026-03-18T09:57:26.641769Z","end":"2026-03-18T09:57:26.871536Z","steps":["trace[129075901] 'process raft request' (duration: 229.573813ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T09:57:26.871409Z","caller":"traceutil/trace.go:171","msg":"trace[1076348110] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1929; }","duration":"192.47031ms","start":"2026-03-18T09:57:26.678927Z","end":"2026-03-18T09:57:26.871398Z","steps":["trace[1076348110] 'read index received' (duration: 135.287608ms)","trace[1076348110] 'applied index is now lower than readState.Index' (duration: 57.182022ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:57:26.871856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.921061ms","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:525"} {"level":"info","ts":"2026-03-18T09:57:26.871883Z","caller":"traceutil/trace.go:171","msg":"trace[1445923130] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1848; }","duration":"193.012303ms","start":"2026-03-18T09:57:26.678862Z","end":"2026-03-18T09:57:26.871874Z","steps":["trace[1445923130] 'agreement among raft nodes before linearized reading' (duration: 192.90005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:57:26.872335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.693021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2828"} {"level":"info","ts":"2026-03-18T09:57:26.872357Z","caller":"traceutil/trace.go:171","msg":"trace[1150617923] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1848; }","duration":"130.742963ms","start":"2026-03-18T09:57:26.741607Z","end":"2026-03-18T09:57:26.87235Z","steps":["trace[1150617923] 'agreement among raft nodes before linearized reading' (duration: 130.684901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T09:57:27.214182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.991857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T09:57:27.214287Z","caller":"traceutil/trace.go:171","msg":"trace[282222688] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"334.855634ms","start":"2026-03-18T09:57:26.879414Z","end":"2026-03-18T09:57:27.21427Z","steps":["trace[282222688] 'process raft request' (duration: 224.371074ms)","trace[282222688] 'compare' (duration: 109.864164ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T09:57:27.214383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T09:57:26.879397Z","time spent":"334.959426ms","remote":"127.0.0.1:35350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}