{"level":"warn","ts":"2026-02-10T15:00:14.688458Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T15:00:14.688571Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-10T15:00:14.695015Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T15:00:14.695034Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-10T15:00:14.695059Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-10T15:00:14.695662Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-10T15:00:14.69574Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-10T15:00:14.705207Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.259764ms"} {"level":"info","ts":"2026-02-10T15:00:14.74108Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-10T15:00:14.741203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T15:00:14.741233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-10T15:00:14.741316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T15:00:14.741339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-10T15:00:14.741393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-10T15:00:14.750219Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T15:00:14.759614Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T15:00:14.773158Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-10T15:00:14.792218Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T15:00:14.793339Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T15:00:14.793471Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T15:00:14.793513Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T15:00:14.793534Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T15:00:14.793905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-10T15:00:14.797014Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-10T15:00:14.799287Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-10T15:00:14.802601Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T15:00:14.802405Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-10T15:00:14.809893Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T15:00:14.810056Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-10T15:00:14.841565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T15:00:14.841679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T15:00:14.841712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-10T15:00:14.841795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-10T15:00:14.841825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-10T15:00:14.841849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-10T15:00:14.841871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-10T15:00:14.847785Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:00:14.849152Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T15:00:14.849176Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T15:00:14.852168Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:00:14.852322Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:00:14.852412Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:00:14.852552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T15:00:14.856566Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"info","ts":"2026-02-10T15:00:14.862296Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T15:00:14.863156Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T15:00:14.873967Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T15:00:39.233503Z","caller":"traceutil/trace.go:171","msg":"trace[1372498480] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"167.248976ms","start":"2026-02-10T15:00:39.066232Z","end":"2026-02-10T15:00:39.23348Z","steps":["trace[1372498480] 'process raft request' (duration: 167.138393ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:39.244711Z","caller":"traceutil/trace.go:171","msg":"trace[1193628916] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"177.494084ms","start":"2026-02-10T15:00:39.0672Z","end":"2026-02-10T15:00:39.244695Z","steps":["trace[1193628916] 'process raft request' (duration: 177.27212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:39.634106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.749306ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-02-10T15:00:39.634176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:39.246021Z","time spent":"388.152268ms","remote":"127.0.0.1:51638","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-10T15:00:43.472449Z","caller":"traceutil/trace.go:171","msg":"trace[446528315] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"224.522987ms","start":"2026-02-10T15:00:43.247904Z","end":"2026-02-10T15:00:43.472427Z","steps":["trace[446528315] 'process raft request' (duration: 224.363054ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:43.472728Z","caller":"traceutil/trace.go:171","msg":"trace[372300356] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:479; }","duration":"218.551835ms","start":"2026-02-10T15:00:43.254165Z","end":"2026-02-10T15:00:43.472716Z","steps":["trace[372300356] 'read index received' (duration: 218.547635ms)","trace[372300356] 'applied index is now lower than readState.Index' (duration: 3.58µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:00:43.472861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.676277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T15:00:43.472891Z","caller":"traceutil/trace.go:171","msg":"trace[284868402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"218.740859ms","start":"2026-02-10T15:00:43.25414Z","end":"2026-02-10T15:00:43.472881Z","steps":["trace[284868402] 'agreement among raft nodes before linearized reading' (duration: 218.612986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:47.882598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.387589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T15:00:47.882655Z","caller":"traceutil/trace.go:171","msg":"trace[2051375691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"148.47462ms","start":"2026-02-10T15:00:47.73417Z","end":"2026-02-10T15:00:47.882644Z","steps":["trace[2051375691] 'range keys from in-memory index tree' (duration: 148.293117ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:49.6743Z","caller":"traceutil/trace.go:171","msg":"trace[1758534088] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"112.611626ms","start":"2026-02-10T15:00:49.561669Z","end":"2026-02-10T15:00:49.674281Z","steps":["trace[1758534088] 'process raft request' (duration: 111.054194ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:50.049209Z","caller":"traceutil/trace.go:171","msg":"trace[1822568049] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"102.53725ms","start":"2026-02-10T15:00:49.946652Z","end":"2026-02-10T15:00:50.049189Z","steps":["trace[1822568049] 'process raft request' (duration: 91.438443ms)","trace[1822568049] 'compare' (duration: 11.016266ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:51.693355Z","caller":"traceutil/trace.go:171","msg":"trace[880550595] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"111.211207ms","start":"2026-02-10T15:00:51.582124Z","end":"2026-02-10T15:00:51.693336Z","steps":["trace[880550595] 'read index received' (duration: 111.044704ms)","trace[880550595] 'applied index is now lower than readState.Index' (duration: 165.633µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:51.69362Z","caller":"traceutil/trace.go:171","msg":"trace[1736885777] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"131.333419ms","start":"2026-02-10T15:00:51.562271Z","end":"2026-02-10T15:00:51.693604Z","steps":["trace[1736885777] 'process raft request' (duration: 130.961411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:51.693898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.768768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:51.693935Z","caller":"traceutil/trace.go:171","msg":"trace[1034726668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"111.84034ms","start":"2026-02-10T15:00:51.582083Z","end":"2026-02-10T15:00:51.693923Z","steps":["trace[1034726668] 'agreement among raft nodes before linearized reading' (duration: 111.778559ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:51.819169Z","caller":"traceutil/trace.go:171","msg":"trace[1532634217] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"120.11947ms","start":"2026-02-10T15:00:51.699026Z","end":"2026-02-10T15:00:51.819145Z","steps":["trace[1532634217] 'process raft request' (duration: 119.917166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:54.465949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.84722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mdx46\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-10T15:00:54.466024Z","caller":"traceutil/trace.go:171","msg":"trace[86023500] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mdx46; range_end:; response_count:1; response_revision:529; }","duration":"215.955332ms","start":"2026-02-10T15:00:54.250049Z","end":"2026-02-10T15:00:54.466004Z","steps":["trace[86023500] 'range keys from in-memory index tree' (duration: 215.765518ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:54.761879Z","caller":"traceutil/trace.go:171","msg":"trace[1817712690] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"280.744808ms","start":"2026-02-10T15:00:54.481111Z","end":"2026-02-10T15:00:54.761856Z","steps":["trace[1817712690] 'process raft request' (duration: 280.602265ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:09.493428Z","caller":"traceutil/trace.go:171","msg":"trace[541872916] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"981.191752ms","start":"2026-02-10T15:01:08.512217Z","end":"2026-02-10T15:01:09.493409Z","steps":["trace[541872916] 'process raft request' (duration: 981.069229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:09.49382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:08.512204Z","time spent":"981.289714ms","remote":"127.0.0.1:51790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4832,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:09.503188Z","caller":"traceutil/trace.go:171","msg":"trace[975873883] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"457.333944ms","start":"2026-02-10T15:01:09.045844Z","end":"2026-02-10T15:01:09.503177Z","steps":["trace[975873883] 'read index received' (duration: 447.853231ms)","trace[975873883] 'applied index is now lower than readState.Index' (duration: 9.480123ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:09.503249Z","caller":"traceutil/trace.go:171","msg":"trace[614914181] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"898.504617ms","start":"2026-02-10T15:01:08.604734Z","end":"2026-02-10T15:01:09.503238Z","steps":["trace[614914181] 'process raft request' (duration: 898.379604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:09.503304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:08.604718Z","time spent":"898.542998ms","remote":"127.0.0.1:52110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:01:09.503413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.568299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T15:01:09.503429Z","caller":"traceutil/trace.go:171","msg":"trace[860110158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"457.6273ms","start":"2026-02-10T15:01:09.045796Z","end":"2026-02-10T15:01:09.503423Z","steps":["trace[860110158] 'agreement among raft nodes before linearized reading' (duration: 457.561559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:09.503484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:09.045778Z","time spent":"457.702151ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T15:01:09.511965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.025724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-10T15:01:09.512014Z","caller":"traceutil/trace.go:171","msg":"trace[1656930487] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:692; }","duration":"365.115996ms","start":"2026-02-10T15:01:09.146889Z","end":"2026-02-10T15:01:09.512005Z","steps":["trace[1656930487] 'agreement among raft nodes before linearized reading' (duration: 365.011334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:09.512035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:09.146873Z","time spent":"365.156797ms","remote":"127.0.0.1:51638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.41\" "} {"level":"info","ts":"2026-02-10T15:01:14.1154Z","caller":"traceutil/trace.go:171","msg":"trace[753054304] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"312.165013ms","start":"2026-02-10T15:01:13.80322Z","end":"2026-02-10T15:01:14.115385Z","steps":["trace[753054304] 'read index received' (duration: 312.06159ms)","trace[753054304] 'applied index is now lower than readState.Index' (duration: 103.023µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:14.115458Z","caller":"traceutil/trace.go:171","msg":"trace[1004011556] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"336.419069ms","start":"2026-02-10T15:01:13.77903Z","end":"2026-02-10T15:01:14.115449Z","steps":["trace[1004011556] 'process raft request' (duration: 336.265106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:14.115498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.269785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:14.115518Z","caller":"traceutil/trace.go:171","msg":"trace[145194506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"312.318546ms","start":"2026-02-10T15:01:13.803193Z","end":"2026-02-10T15:01:14.115512Z","steps":["trace[145194506] 'agreement among raft nodes before linearized reading' (duration: 312.257185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:14.115522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:13.779017Z","time spent":"336.464919ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:01:14.115531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:13.803183Z","time spent":"312.345526ms","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T15:01:14.11569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.853577ms","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-02-10T15:01:14.115703Z","caller":"traceutil/trace.go:171","msg":"trace[862854317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"208.892248ms","start":"2026-02-10T15:01:13.906807Z","end":"2026-02-10T15:01:14.115699Z","steps":["trace[862854317] 'agreement among raft nodes before linearized reading' (duration: 208.839857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:14.115787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.350878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T15:01:14.115799Z","caller":"traceutil/trace.go:171","msg":"trace[842274196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"129.3962ms","start":"2026-02-10T15:01:13.9864Z","end":"2026-02-10T15:01:14.115796Z","steps":["trace[842274196] 'agreement among raft nodes before linearized reading' (duration: 129.353289ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:14.505382Z","caller":"traceutil/trace.go:171","msg":"trace[1090748708] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"386.468123ms","start":"2026-02-10T15:01:14.118891Z","end":"2026-02-10T15:01:14.505359Z","steps":["trace[1090748708] 'process raft request' (duration: 384.301389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:14.505503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:14.118882Z","time spent":"386.558945ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:14.506285Z","caller":"traceutil/trace.go:171","msg":"trace[600760962] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"387.361112ms","start":"2026-02-10T15:01:14.118911Z","end":"2026-02-10T15:01:14.506273Z","steps":["trace[600760962] 'process raft request' (duration: 386.244399ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:14.506425Z","caller":"traceutil/trace.go:171","msg":"trace[1328061892] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:741; }","duration":"350.891335ms","start":"2026-02-10T15:01:14.155525Z","end":"2026-02-10T15:01:14.506416Z","steps":["trace[1328061892] 'read index received' (duration: 347.675099ms)","trace[1328061892] 'applied index is now lower than readState.Index' (duration: 3.215536ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:14.506532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.002357ms","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-02-10T15:01:14.506557Z","caller":"traceutil/trace.go:171","msg":"trace[973192504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:720; }","duration":"351.051268ms","start":"2026-02-10T15:01:14.155498Z","end":"2026-02-10T15:01:14.50655Z","steps":["trace[973192504] 'agreement among raft nodes before linearized reading' (duration: 350.950666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:14.506583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:14.155488Z","time spent":"351.089269ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T15:01:14.506959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:14.118905Z","time spent":"387.411373ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:14.790366Z","caller":"traceutil/trace.go:171","msg":"trace[1645511695] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"270.846626ms","start":"2026-02-10T15:01:14.519493Z","end":"2026-02-10T15:01:14.79034Z","steps":["trace[1645511695] 'process raft request' (duration: 270.692223ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:16.272875Z","caller":"traceutil/trace.go:171","msg":"trace[789814779] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"149.454051ms","start":"2026-02-10T15:01:16.123398Z","end":"2026-02-10T15:01:16.272852Z","steps":["trace[789814779] 'process raft request' (duration: 149.325278ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:16.868916Z","caller":"traceutil/trace.go:171","msg":"trace[536280564] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"344.669897ms","start":"2026-02-10T15:01:16.524217Z","end":"2026-02-10T15:01:16.868887Z","steps":["trace[536280564] 'process raft request' (duration: 344.585335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:16.869061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:16.524206Z","time spent":"344.792439ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:01:17.043784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.585232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:17.043863Z","caller":"traceutil/trace.go:171","msg":"trace[1589191728] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"245.871325ms","start":"2026-02-10T15:01:16.797979Z","end":"2026-02-10T15:01:17.043851Z","steps":["trace[1589191728] 'read index received' (duration: 71.301101ms)","trace[1589191728] 'applied index is now lower than readState.Index' (duration: 174.569374ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:17.044004Z","caller":"traceutil/trace.go:171","msg":"trace[786411548] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"519.608959ms","start":"2026-02-10T15:01:16.524387Z","end":"2026-02-10T15:01:17.043996Z","steps":["trace[786411548] 'process raft request' (duration: 394.748252ms)","trace[786411548] 'compare' (duration: 124.51685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:17.044044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:16.524381Z","time spent":"519.64142ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:01:17.044215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.233893ms","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-02-10T15:01:17.044235Z","caller":"traceutil/trace.go:171","msg":"trace[2056343546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"246.278213ms","start":"2026-02-10T15:01:16.79795Z","end":"2026-02-10T15:01:17.044228Z","steps":["trace[2056343546] 'agreement among raft nodes before linearized reading' (duration: 246.199581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:17.044329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.267711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:17.044364Z","caller":"traceutil/trace.go:171","msg":"trace[824387597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"241.334272ms","start":"2026-02-10T15:01:16.803024Z","end":"2026-02-10T15:01:17.044358Z","steps":["trace[824387597] 'agreement among raft nodes before linearized reading' (duration: 241.292081ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:17.267345Z","caller":"traceutil/trace.go:171","msg":"trace[1746718339] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"217.888822ms","start":"2026-02-10T15:01:17.049437Z","end":"2026-02-10T15:01:17.267325Z","steps":["trace[1746718339] 'process raft request' (duration: 157.338952ms)","trace[1746718339] 'compare' (duration: 60.471549ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:18.249161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.706751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-10T15:01:18.249215Z","caller":"traceutil/trace.go:171","msg":"trace[264110250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"645.817253ms","start":"2026-02-10T15:01:17.603386Z","end":"2026-02-10T15:01:18.249203Z","steps":["trace[264110250] 'range keys from in-memory index tree' (duration: 645.491517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:18.249241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:17.603367Z","time spent":"645.868685ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-10T15:01:18.249724Z","caller":"traceutil/trace.go:171","msg":"trace[938461] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"444.722476ms","start":"2026-02-10T15:01:17.804988Z","end":"2026-02-10T15:01:18.249711Z","steps":["trace[938461] 'read index received' (duration: 442.356398ms)","trace[938461] 'applied index is now lower than readState.Index' (duration: 2.365368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:18.249779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.792217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:18.2498Z","caller":"traceutil/trace.go:171","msg":"trace[99362668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"444.870208ms","start":"2026-02-10T15:01:17.804923Z","end":"2026-02-10T15:01:18.249794Z","steps":["trace[99362668] 'agreement among raft nodes before linearized reading' (duration: 444.828327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:18.249825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:17.804906Z","time spent":"444.911541ms","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T15:01:18.249808Z","caller":"traceutil/trace.go:171","msg":"trace[93998365] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"607.298884ms","start":"2026-02-10T15:01:17.642501Z","end":"2026-02-10T15:01:18.249799Z","steps":["trace[93998365] 'process raft request' (duration: 604.843925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:18.249896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:17.64248Z","time spent":"607.370067ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:18.778783Z","caller":"traceutil/trace.go:171","msg":"trace[1987013228] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"120.682231ms","start":"2026-02-10T15:01:18.658084Z","end":"2026-02-10T15:01:18.778766Z","steps":["trace[1987013228] 'process raft request' (duration: 89.601685ms)","trace[1987013228] 'compare' (duration: 31.010254ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:34.450386Z","caller":"traceutil/trace.go:171","msg":"trace[63998082] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"124.088049ms","start":"2026-02-10T15:01:34.326277Z","end":"2026-02-10T15:01:34.450365Z","steps":["trace[63998082] 'process raft request' (duration: 123.977957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:34.606346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.877759ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T15:01:34.60643Z","caller":"traceutil/trace.go:171","msg":"trace[1502013951] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"132.324958ms","start":"2026-02-10T15:01:34.474085Z","end":"2026-02-10T15:01:34.60641Z","steps":["trace[1502013951] 'read index received' (duration: 47.671µs)","trace[1502013951] 'applied index is now lower than readState.Index' (duration: 132.276347ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:34.606562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.4769ms","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-02-10T15:01:34.606592Z","caller":"traceutil/trace.go:171","msg":"trace[2009254235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"132.590163ms","start":"2026-02-10T15:01:34.473992Z","end":"2026-02-10T15:01:34.606582Z","steps":["trace[2009254235] 'agreement among raft nodes before linearized reading' (duration: 132.464971ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:35.102767Z","caller":"traceutil/trace.go:171","msg":"trace[666153500] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"111.64802ms","start":"2026-02-10T15:01:34.991103Z","end":"2026-02-10T15:01:35.102751Z","steps":["trace[666153500] 'process raft request' (duration: 111.258962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:35.382123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.699523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T15:01:35.382178Z","caller":"traceutil/trace.go:171","msg":"trace[1063028598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"194.795604ms","start":"2026-02-10T15:01:35.187368Z","end":"2026-02-10T15:01:35.382164Z","steps":["trace[1063028598] 'range keys from in-memory index tree' (duration: 194.56615ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:37.721458Z","caller":"traceutil/trace.go:171","msg":"trace[775077180] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"325.02117ms","start":"2026-02-10T15:01:37.39642Z","end":"2026-02-10T15:01:37.721441Z","steps":["trace[775077180] 'process raft request' (duration: 324.933388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:37.721627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:37.396403Z","time spent":"325.107231ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:37.74231Z","caller":"traceutil/trace.go:171","msg":"trace[835300635] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"343.705895ms","start":"2026-02-10T15:01:37.398582Z","end":"2026-02-10T15:01:37.742288Z","steps":["trace[835300635] 'process raft request' (duration: 343.600622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:37.742461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:37.398572Z","time spent":"343.820128ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:49.917935Z","caller":"traceutil/trace.go:171","msg":"trace[1745941253] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"114.565621ms","start":"2026-02-10T15:01:49.803354Z","end":"2026-02-10T15:01:49.917919Z","steps":["trace[1745941253] 'read index received' (duration: 114.518951ms)","trace[1745941253] 'applied index is now lower than readState.Index' (duration: 45.95µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:49.918022Z","caller":"traceutil/trace.go:171","msg":"trace[409102762] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"118.484442ms","start":"2026-02-10T15:01:49.799532Z","end":"2026-02-10T15:01:49.918017Z","steps":["trace[409102762] 'process raft request' (duration: 118.293619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:49.918264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.636508ms","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-02-10T15:01:49.918349Z","caller":"traceutil/trace.go:171","msg":"trace[1655135580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:874; }","duration":"105.767741ms","start":"2026-02-10T15:01:49.812566Z","end":"2026-02-10T15:01:49.918334Z","steps":["trace[1655135580] 'agreement among raft nodes before linearized reading' (duration: 105.562107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:49.918285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.918799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:49.91886Z","caller":"traceutil/trace.go:171","msg":"trace[2143666249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"115.525331ms","start":"2026-02-10T15:01:49.803325Z","end":"2026-02-10T15:01:49.91885Z","steps":["trace[2143666249] 'agreement among raft nodes before linearized reading' (duration: 114.929859ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:50.932373Z","caller":"traceutil/trace.go:171","msg":"trace[686512456] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"127.491598ms","start":"2026-02-10T15:01:50.804867Z","end":"2026-02-10T15:01:50.932358Z","steps":["trace[686512456] 'read index received' (duration: 127.355255ms)","trace[686512456] 'applied index is now lower than readState.Index' (duration: 135.883µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:50.932439Z","caller":"traceutil/trace.go:171","msg":"trace[605870291] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"382.607617ms","start":"2026-02-10T15:01:50.549825Z","end":"2026-02-10T15:01:50.932433Z","steps":["trace[605870291] 'process raft request' (duration: 382.435203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:50.932506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:50.549811Z","time spent":"382.641697ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:01:50.932666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.793794ms","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-02-10T15:01:50.932683Z","caller":"traceutil/trace.go:171","msg":"trace[114637029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:876; }","duration":"127.836425ms","start":"2026-02-10T15:01:50.804841Z","end":"2026-02-10T15:01:50.932678Z","steps":["trace[114637029] 'agreement among raft nodes before linearized reading' (duration: 127.736403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:50.932769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.693772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:50.932814Z","caller":"traceutil/trace.go:171","msg":"trace[1791164624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"127.753133ms","start":"2026-02-10T15:01:50.805049Z","end":"2026-02-10T15:01:50.932802Z","steps":["trace[1791164624] 'agreement among raft nodes before linearized reading' (duration: 127.682692ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:51.243788Z","caller":"traceutil/trace.go:171","msg":"trace[1361181366] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"303.400004ms","start":"2026-02-10T15:01:50.940368Z","end":"2026-02-10T15:01:51.243768Z","steps":["trace[1361181366] 'process raft request' (duration: 301.24728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:51.243948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:50.940357Z","time spent":"303.530466ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:55.603357Z","caller":"traceutil/trace.go:171","msg":"trace[807936339] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"278.668305ms","start":"2026-02-10T15:01:55.324673Z","end":"2026-02-10T15:01:55.603341Z","steps":["trace[807936339] 'read index received' (duration: 278.543911ms)","trace[807936339] 'applied index is now lower than readState.Index' (duration: 123.874µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:55.603541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.846448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T15:01:55.603558Z","caller":"traceutil/trace.go:171","msg":"trace[1295574649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:888; }","duration":"278.91133ms","start":"2026-02-10T15:01:55.324641Z","end":"2026-02-10T15:01:55.603552Z","steps":["trace[1295574649] 'agreement among raft nodes before linearized reading' (duration: 278.803108ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:55.603757Z","caller":"traceutil/trace.go:171","msg":"trace[866761391] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"339.160931ms","start":"2026-02-10T15:01:55.264591Z","end":"2026-02-10T15:01:55.603751Z","steps":["trace[866761391] 'process raft request' (duration: 338.664351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:55.603812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:55.26458Z","time spent":"339.190382ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:59.217344Z","caller":"traceutil/trace.go:171","msg":"trace[275817876] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"250.397015ms","start":"2026-02-10T15:01:58.966933Z","end":"2026-02-10T15:01:59.21733Z","steps":["trace[275817876] 'process raft request' (duration: 249.978195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.248564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.747942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:02:00.248687Z","caller":"traceutil/trace.go:171","msg":"trace[1955694937] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:932; }","duration":"813.26481ms","start":"2026-02-10T15:01:59.435411Z","end":"2026-02-10T15:02:00.248676Z","steps":["trace[1955694937] 'read index received' (duration: 412.356895ms)","trace[1955694937] 'applied index is now lower than readState.Index' (duration: 400.907295ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:00.248741Z","caller":"traceutil/trace.go:171","msg":"trace[100531984] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"894.884113ms","start":"2026-02-10T15:01:59.35385Z","end":"2026-02-10T15:02:00.248734Z","steps":["trace[100531984] 'process raft request' (duration: 894.778851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.248793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.353834Z","time spent":"894.921793ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:00.248827Z","caller":"traceutil/trace.go:171","msg":"trace[1951195434] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"969.967431ms","start":"2026-02-10T15:01:59.27885Z","end":"2026-02-10T15:02:00.248817Z","steps":["trace[1951195434] 'process raft request' (duration: 568.910313ms)","trace[1951195434] 'compare' (duration: 400.243761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:00.248868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.278831Z","time spent":"970.014841ms","remote":"127.0.0.1:51638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:00.248969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.558345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:02:00.248993Z","caller":"traceutil/trace.go:171","msg":"trace[1461674731] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:899; }","duration":"813.614107ms","start":"2026-02-10T15:01:59.435372Z","end":"2026-02-10T15:02:00.248986Z","steps":["trace[1461674731] 'agreement among raft nodes before linearized reading' (duration: 813.548406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.249015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.435337Z","time spent":"813.669827ms","remote":"127.0.0.1:51954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:02:00.249135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.874351ms","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-02-10T15:02:00.249157Z","caller":"traceutil/trace.go:171","msg":"trace[305825543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"619.929661ms","start":"2026-02-10T15:01:59.629221Z","end":"2026-02-10T15:02:00.249151Z","steps":["trace[305825543] 'agreement among raft nodes before linearized reading' (duration: 619.85191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.249175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.629206Z","time spent":"619.963743ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T15:02:00.249245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.87574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:00.249265Z","caller":"traceutil/trace.go:171","msg":"trace[2038518211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"442.898731ms","start":"2026-02-10T15:01:59.806361Z","end":"2026-02-10T15:02:00.24926Z","steps":["trace[2038518211] 'agreement among raft nodes before linearized reading' (duration: 442.86514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.249284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.806319Z","time spent":"442.958832ms","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T15:02:00.249289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.286318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T15:02:00.249307Z","caller":"traceutil/trace.go:171","msg":"trace[200217974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"554.336889ms","start":"2026-02-10T15:01:59.694964Z","end":"2026-02-10T15:02:00.249301Z","steps":["trace[200217974] 'agreement among raft nodes before linearized reading' (duration: 554.284008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.249323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.694948Z","time spent":"554.37077ms","remote":"127.0.0.1:51884","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-02-10T15:02:00.24941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.709053ms","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-02-10T15:02:00.249432Z","caller":"traceutil/trace.go:171","msg":"trace[1698899485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:899; }","duration":"259.759125ms","start":"2026-02-10T15:01:59.989667Z","end":"2026-02-10T15:02:00.249426Z","steps":["trace[1698899485] 'agreement among raft nodes before linearized reading' (duration: 259.699164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.24951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.682695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T15:02:00.249528Z","caller":"traceutil/trace.go:171","msg":"trace[276039255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:899; }","duration":"294.727775ms","start":"2026-02-10T15:01:59.954795Z","end":"2026-02-10T15:02:00.249522Z","steps":["trace[276039255] 'agreement among raft nodes before linearized reading' (duration: 294.677334ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:06.928624Z","caller":"traceutil/trace.go:171","msg":"trace[1425405309] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"541.787128ms","start":"2026-02-10T15:02:06.386821Z","end":"2026-02-10T15:02:06.928608Z","steps":["trace[1425405309] 'process raft request' (duration: 541.665255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:06.928744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:06.386809Z","time spent":"541.867059ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:06.940406Z","caller":"traceutil/trace.go:171","msg":"trace[1711746098] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"501.339294ms","start":"2026-02-10T15:02:06.439047Z","end":"2026-02-10T15:02:06.940386Z","steps":["trace[1711746098] 'read index received' (duration: 490.04313ms)","trace[1711746098] 'applied index is now lower than readState.Index' (duration: 11.295334ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:06.940644Z","caller":"traceutil/trace.go:171","msg":"trace[1457381655] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"553.162723ms","start":"2026-02-10T15:02:06.387469Z","end":"2026-02-10T15:02:06.940632Z","steps":["trace[1457381655] 'process raft request' (duration: 552.831945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:06.940724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:06.387462Z","time spent":"553.209914ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:06.941012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.959866ms","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-02-10T15:02:06.941057Z","caller":"traceutil/trace.go:171","msg":"trace[1807024449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"502.031138ms","start":"2026-02-10T15:02:06.439016Z","end":"2026-02-10T15:02:06.941047Z","steps":["trace[1807024449] 'agreement among raft nodes before linearized reading' (duration: 501.866055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:06.941087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:06.438999Z","time spent":"502.081219ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T15:02:06.941242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.051186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:06.941288Z","caller":"traceutil/trace.go:171","msg":"trace[717489381] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:918; }","duration":"464.130868ms","start":"2026-02-10T15:02:06.477149Z","end":"2026-02-10T15:02:06.94128Z","steps":["trace[717489381] 'agreement among raft nodes before linearized reading' (duration: 464.067427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:06.941322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:06.477132Z","time spent":"464.179948ms","remote":"127.0.0.1:52014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:02:07.404982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.141664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T15:02:07.405467Z","caller":"traceutil/trace.go:171","msg":"trace[1048430868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"100.657975ms","start":"2026-02-10T15:02:07.304792Z","end":"2026-02-10T15:02:07.40545Z","steps":["trace[1048430868] 'range keys from in-memory index tree' (duration: 99.96028ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:07.778719Z","caller":"traceutil/trace.go:171","msg":"trace[1791276480] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"369.336143ms","start":"2026-02-10T15:02:07.409365Z","end":"2026-02-10T15:02:07.778701Z","steps":["trace[1791276480] 'process raft request' (duration: 369.232891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:07.778831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:07.409341Z","time spent":"369.432666ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:09.177901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.250954ms","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-02-10T15:02:09.177953Z","caller":"traceutil/trace.go:171","msg":"trace[179407201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"210.330756ms","start":"2026-02-10T15:02:08.967608Z","end":"2026-02-10T15:02:09.177939Z","steps":["trace[179407201] 'range keys from in-memory index tree' (duration: 210.160442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:09.178147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.567023ms","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-02-10T15:02:09.178162Z","caller":"traceutil/trace.go:171","msg":"trace[1350318612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"231.601523ms","start":"2026-02-10T15:02:08.946555Z","end":"2026-02-10T15:02:09.178157Z","steps":["trace[1350318612] 'range keys from in-memory index tree' (duration: 231.517351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:09.178192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.015191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T15:02:09.178243Z","caller":"traceutil/trace.go:171","msg":"trace[187467600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"246.091444ms","start":"2026-02-10T15:02:08.932135Z","end":"2026-02-10T15:02:09.178226Z","steps":["trace[187467600] 'range keys from in-memory index tree' (duration: 245.918238ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:09.466043Z","caller":"traceutil/trace.go:171","msg":"trace[1990755948] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"277.49573ms","start":"2026-02-10T15:02:09.18853Z","end":"2026-02-10T15:02:09.466026Z","steps":["trace[1990755948] 'process raft request' (duration: 277.47451ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:09.466555Z","caller":"traceutil/trace.go:171","msg":"trace[465229803] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"281.933481ms","start":"2026-02-10T15:02:09.184615Z","end":"2026-02-10T15:02:09.466549Z","steps":["trace[465229803] 'process raft request' (duration: 281.264307ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:09.466641Z","caller":"traceutil/trace.go:171","msg":"trace[286220960] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"278.152173ms","start":"2026-02-10T15:02:09.188485Z","end":"2026-02-10T15:02:09.466637Z","steps":["trace[286220960] 'process raft request' (duration: 277.49585ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:09.484022Z","caller":"traceutil/trace.go:171","msg":"trace[1847432366] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"155.29517ms","start":"2026-02-10T15:02:09.328705Z","end":"2026-02-10T15:02:09.484001Z","steps":["trace[1847432366] 'process raft request' (duration: 155.175788ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:19.586484Z","caller":"traceutil/trace.go:171","msg":"trace[504021498] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"102.762449ms","start":"2026-02-10T15:02:19.483698Z","end":"2026-02-10T15:02:19.586461Z","steps":["trace[504021498] 'read index received' (duration: 102.539254ms)","trace[504021498] 'applied index is now lower than readState.Index' (duration: 222.315µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:19.586592Z","caller":"traceutil/trace.go:171","msg":"trace[774647945] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"140.559587ms","start":"2026-02-10T15:02:19.446023Z","end":"2026-02-10T15:02:19.586583Z","steps":["trace[774647945] 'process raft request' (duration: 140.270041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:19.586968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.245128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:02:19.587008Z","caller":"traceutil/trace.go:171","msg":"trace[153176955] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:965; }","duration":"103.32576ms","start":"2026-02-10T15:02:19.48367Z","end":"2026-02-10T15:02:19.586996Z","steps":["trace[153176955] 'agreement among raft nodes before linearized reading' (duration: 103.166987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:23.668544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160958901s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:02:23.668779Z","caller":"traceutil/trace.go:171","msg":"trace[279151964] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.315865265s","start":"2026-02-10T15:02:22.352903Z","end":"2026-02-10T15:02:23.668769Z","steps":["trace[279151964] 'process raft request' (duration: 1.315791013s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:23.668848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:22.352892Z","time spent":"1.315940826s","remote":"127.0.0.1:51670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T15:02:23.669111Z","caller":"traceutil/trace.go:171","msg":"trace[1928142946] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.411099837s","start":"2026-02-10T15:02:22.258004Z","end":"2026-02-10T15:02:23.669104Z","steps":["trace[1928142946] 'process raft request' (duration: 249.509843ms)","trace[1928142946] 'compare' (duration: 1.158566212s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:23.66919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:22.257994Z","time spent":"1.411166139s","remote":"127.0.0.1:51670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T15:02:23.669291Z","caller":"traceutil/trace.go:171","msg":"trace[242859439] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"1.316663981s","start":"2026-02-10T15:02:22.352621Z","end":"2026-02-10T15:02:23.669285Z","steps":["trace[242859439] 'read index received' (duration: 154.902483ms)","trace[242859439] 'applied index is now lower than readState.Index' (duration: 1.161760808s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:23.669499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316872805s","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:508"} {"level":"info","ts":"2026-02-10T15:02:23.66954Z","caller":"traceutil/trace.go:171","msg":"trace[1883207657] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:993; }","duration":"1.316943506s","start":"2026-02-10T15:02:22.352591Z","end":"2026-02-10T15:02:23.669534Z","steps":["trace[1883207657] 'agreement among raft nodes before linearized reading' (duration: 1.316828884s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:23.669582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:22.352576Z","time spent":"1.317000337s","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-10T15:02:23.680722Z","caller":"traceutil/trace.go:171","msg":"trace[1837379460] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"113.003029ms","start":"2026-02-10T15:02:23.567697Z","end":"2026-02-10T15:02:23.6807Z","steps":["trace[1837379460] 'process raft request' (duration: 112.901967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:23.681099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262143507s","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-02-10T15:02:23.685834Z","caller":"traceutil/trace.go:171","msg":"trace[1807023661] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:994; }","duration":"1.266939875s","start":"2026-02-10T15:02:22.418878Z","end":"2026-02-10T15:02:23.685818Z","steps":["trace[1807023661] 'agreement among raft nodes before linearized reading' (duration: 1.26187753s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:23.68589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:22.418858Z","time spent":"1.267022157s","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-10T15:02:23.68204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093988561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T15:02:23.685978Z","caller":"traceutil/trace.go:171","msg":"trace[588372037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"1.097970083s","start":"2026-02-10T15:02:22.587997Z","end":"2026-02-10T15:02:23.685967Z","steps":["trace[588372037] 'agreement among raft nodes before linearized reading' (duration: 1.0939358s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:23.686003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:22.587973Z","time spent":"1.098023354s","remote":"127.0.0.1:51884","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":"warn","ts":"2026-02-10T15:02:23.685769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.158263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:23.686065Z","caller":"traceutil/trace.go:171","msg":"trace[1785783898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"880.50321ms","start":"2026-02-10T15:02:22.805555Z","end":"2026-02-10T15:02:23.686058Z","steps":["trace[1785783898] 'agreement among raft nodes before linearized reading' (duration: 880.161983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:23.686088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:22.805536Z","time spent":"880.54598ms","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T15:02:31.216263Z","caller":"traceutil/trace.go:171","msg":"trace[1716955186] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"150.270944ms","start":"2026-02-10T15:02:31.065966Z","end":"2026-02-10T15:02:31.216237Z","steps":["trace[1716955186] 'process raft request' (duration: 149.977608ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:54.533068Z","caller":"traceutil/trace.go:171","msg":"trace[1221060769] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"200.758401ms","start":"2026-02-10T15:02:54.332278Z","end":"2026-02-10T15:02:54.533036Z","steps":["trace[1221060769] 'process raft request' (duration: 139.276001ms)","trace[1221060769] 'compare' (duration: 61.144352ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:04:09.116834Z","caller":"traceutil/trace.go:171","msg":"trace[2146276717] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"120.331725ms","start":"2026-02-10T15:04:08.996486Z","end":"2026-02-10T15:04:09.116817Z","steps":["trace[2146276717] 'process raft request' (duration: 120.225793ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:04:09.117667Z","caller":"traceutil/trace.go:171","msg":"trace[1000693810] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"117.357906ms","start":"2026-02-10T15:04:09.000294Z","end":"2026-02-10T15:04:09.117652Z","steps":["trace[1000693810] 'process raft request' (duration: 117.275154ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:31.039202Z","caller":"traceutil/trace.go:171","msg":"trace[974548734] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"108.401868ms","start":"2026-02-10T15:05:30.930782Z","end":"2026-02-10T15:05:31.039184Z","steps":["trace[974548734] 'process raft request' (duration: 108.055611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:55.383693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.838232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-10T15:05:55.383774Z","caller":"traceutil/trace.go:171","msg":"trace[1521195134] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1914; }","duration":"142.961824ms","start":"2026-02-10T15:05:55.240794Z","end":"2026-02-10T15:05:55.383756Z","steps":["trace[1521195134] 'range keys from in-memory index tree' (duration: 142.729529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:55.383734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.155378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" ","response":"range_response_count:1 size:731"} {"level":"info","ts":"2026-02-10T15:05:55.385318Z","caller":"traceutil/trace.go:171","msg":"trace[780186047] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-controller; range_end:; response_count:1; response_revision:1914; }","duration":"140.76495ms","start":"2026-02-10T15:05:55.244535Z","end":"2026-02-10T15:05:55.385299Z","steps":["trace[780186047] 'range keys from in-memory index tree' (duration: 139.068235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:55.383997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.817979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-10T15:05:55.384039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.804692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:791"} {"level":"info","ts":"2026-02-10T15:05:55.388514Z","caller":"traceutil/trace.go:171","msg":"trace[1871603633] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:1914; }","duration":"191.288743ms","start":"2026-02-10T15:05:55.197215Z","end":"2026-02-10T15:05:55.388503Z","steps":["trace[1871603633] 'range keys from in-memory index tree' (duration: 186.762541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:55.384107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.390421ms","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-02-10T15:05:55.388816Z","caller":"traceutil/trace.go:171","msg":"trace[1526209113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1914; }","duration":"187.106827ms","start":"2026-02-10T15:05:55.2017Z","end":"2026-02-10T15:05:55.388807Z","steps":["trace[1526209113] 'range keys from in-memory index tree' (duration: 182.315089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:55.38429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.025562ms","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-02-10T15:05:55.389111Z","caller":"traceutil/trace.go:171","msg":"trace[1041356690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1914; }","duration":"138.868591ms","start":"2026-02-10T15:05:55.250233Z","end":"2026-02-10T15:05:55.389102Z","steps":["trace[1041356690] 'range keys from in-memory index tree' (duration: 133.876619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:55.384347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.072574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-10T15:05:55.389345Z","caller":"traceutil/trace.go:171","msg":"trace[1857390806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1914; }","duration":"141.099117ms","start":"2026-02-10T15:05:55.248239Z","end":"2026-02-10T15:05:55.389338Z","steps":["trace[1857390806] 'range keys from in-memory index tree' (duration: 135.992713ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:55.384529Z","caller":"traceutil/trace.go:171","msg":"trace[1180402462] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"132.753396ms","start":"2026-02-10T15:05:55.251762Z","end":"2026-02-10T15:05:55.384516Z","steps":["trace[1180402462] 'process raft request' (duration: 126.757363ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:55.443804Z","caller":"traceutil/trace.go:171","msg":"trace[1019042471] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1914; }","duration":"203.819319ms","start":"2026-02-10T15:05:55.182145Z","end":"2026-02-10T15:05:55.385965Z","steps":["trace[1019042471] 'count revisions from in-memory index tree' (duration: 201.683336ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:55.533096Z","caller":"traceutil/trace.go:171","msg":"trace[1998572570] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"131.49338ms","start":"2026-02-10T15:05:55.401588Z","end":"2026-02-10T15:05:55.533081Z","steps":["trace[1998572570] 'process raft request' (duration: 128.55888ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:55.533264Z","caller":"traceutil/trace.go:171","msg":"trace[2049089902] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"131.600762ms","start":"2026-02-10T15:05:55.401648Z","end":"2026-02-10T15:05:55.533249Z","steps":["trace[2049089902] 'process raft request' (duration: 131.411869ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:55.533347Z","caller":"traceutil/trace.go:171","msg":"trace[358431475] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"131.571161ms","start":"2026-02-10T15:05:55.40177Z","end":"2026-02-10T15:05:55.533342Z","steps":["trace[358431475] 'process raft request' (duration: 131.412698ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:59.03611Z","caller":"traceutil/trace.go:171","msg":"trace[1756819906] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"138.965562ms","start":"2026-02-10T15:05:58.897121Z","end":"2026-02-10T15:05:59.036087Z","steps":["trace[1756819906] 'process raft request' (duration: 138.83733ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:06:01.987566Z","caller":"traceutil/trace.go:171","msg":"trace[2107909133] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"129.148742ms","start":"2026-02-10T15:06:01.858397Z","end":"2026-02-10T15:06:01.987546Z","steps":["trace[2107909133] 'process raft request' (duration: 128.979669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:06:03.525551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.334564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-dns\" ","response":"range_response_count:1 size:761"} {"level":"info","ts":"2026-02-10T15:06:03.525616Z","caller":"traceutil/trace.go:171","msg":"trace[1551390262] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-dns; range_end:; response_count:1; response_revision:1947; }","duration":"103.419796ms","start":"2026-02-10T15:06:03.42218Z","end":"2026-02-10T15:06:03.5256Z","steps":["trace[1551390262] 'range keys from in-memory index tree' (duration: 103.234352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:06:03.525989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.451067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:944"} {"level":"info","ts":"2026-02-10T15:06:03.526034Z","caller":"traceutil/trace.go:171","msg":"trace[126087657] range","detail":"{range_begin:/registry/services/endpoints/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:1947; }","duration":"106.513639ms","start":"2026-02-10T15:06:03.419507Z","end":"2026-02-10T15:06:03.526021Z","steps":["trace[126087657] 'range keys from in-memory index tree' (duration: 106.367666ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:06:03.817611Z","caller":"traceutil/trace.go:171","msg":"trace[2115204953] linearizableReadLoop","detail":"{readStateIndex:2034; appliedIndex:2033; }","duration":"195.395827ms","start":"2026-02-10T15:06:03.62219Z","end":"2026-02-10T15:06:03.817586Z","steps":["trace[2115204953] 'read index received' (duration: 174.122002ms)","trace[2115204953] 'applied index is now lower than readState.Index' (duration: 21.272705ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:06:03.81812Z","caller":"traceutil/trace.go:171","msg":"trace[105748914] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"196.049451ms","start":"2026-02-10T15:06:03.622054Z","end":"2026-02-10T15:06:03.818103Z","steps":["trace[105748914] 'process raft request' (duration: 174.307007ms)","trace[105748914] 'compare' (duration: 20.958358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:06:03.818588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.375137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T15:06:03.818701Z","caller":"traceutil/trace.go:171","msg":"trace[359486859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1950; }","duration":"196.512201ms","start":"2026-02-10T15:06:03.622176Z","end":"2026-02-10T15:06:03.818689Z","steps":["trace[359486859] 'agreement among raft nodes before linearized reading' (duration: 196.243445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:06:03.819187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.010006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-dns\" ","response":"range_response_count:1 size:761"} {"level":"info","ts":"2026-02-10T15:06:03.819283Z","caller":"traceutil/trace.go:171","msg":"trace[2000849561] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-dns; range_end:; response_count:1; response_revision:1950; }","duration":"187.119899ms","start":"2026-02-10T15:06:03.632151Z","end":"2026-02-10T15:06:03.819271Z","steps":["trace[2000849561] 'agreement among raft nodes before linearized reading' (duration: 186.956485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:06:03.819833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.43334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-mc7sf\" ","response":"range_response_count:1 size:11187"} {"level":"info","ts":"2026-02-10T15:06:03.819925Z","caller":"traceutil/trace.go:171","msg":"trace[448184648] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-mc7sf; range_end:; response_count:1; response_revision:1950; }","duration":"133.542763ms","start":"2026-02-10T15:06:03.686371Z","end":"2026-02-10T15:06:03.819914Z","steps":["trace[448184648] 'agreement among raft nodes before linearized reading' (duration: 133.389559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:06:03.817313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.929712ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:06:03.824103Z","caller":"traceutil/trace.go:171","msg":"trace[1487945063] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1949; }","duration":"144.724022ms","start":"2026-02-10T15:06:03.679363Z","end":"2026-02-10T15:06:03.824087Z","steps":["trace[1487945063] 'range keys from in-memory index tree' (duration: 137.920842ms)"],"step_count":1}