{"level":"warn","ts":"2026-04-09T11:19:57.335935Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T11:19:57.336041Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-04-09T11:19:57.336142Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T11:19:57.336153Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-09T11:19:57.336178Z","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-04-09T11:19:57.337053Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-04-09T11:19:57.337151Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-04-09T11:19:57.344286Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.764487ms"} {"level":"info","ts":"2026-04-09T11:19:57.351599Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-04-09T11:19:57.351657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T11:19:57.351673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-04-09T11:19:57.351684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T11:19:57.351692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-04-09T11:19:57.351732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-04-09T11:19:57.36476Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T11:19:57.365948Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T11:19:57.367099Z","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-04-09T11:19:57.369639Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T11:19:57.369823Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T11:19:57.370407Z","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-04-09T11:19:57.371329Z","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-04-09T11:19:57.371377Z","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-04-09T11:19:57.371972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-04-09T11:19:57.37214Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-09T11:19:57.373352Z","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-04-09T11:19:57.37353Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T11:19:57.37356Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T11:19:57.374066Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-09T11:19:57.374087Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-09T11:19:58.052305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T11:19:58.052358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T11:19:58.052393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-04-09T11:19:58.052415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-04-09T11:19:58.052425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-09T11:19:58.052467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-04-09T11:19:58.052481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-09T11:19:58.053515Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T11:19:58.053726Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T11:19:58.053815Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T11:19:58.053846Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T11:19:58.054996Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T11:19:58.055119Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T11:19:58.055154Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T11:19:58.059789Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T11:19:58.060095Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T11:19:58.060203Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T11:19:58.063252Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"warn","ts":"2026-04-09T11:20:03.535168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280302851s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T11:20:03.53538Z","caller":"traceutil/trace.go:171","msg":"trace[1296899591] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:237; }","duration":"1.17805508s","start":"2026-04-09T11:20:02.35731Z","end":"2026-04-09T11:20:03.535365Z","steps":["trace[1296899591] 'read index received' (duration: 261.32593ms)","trace[1296899591] 'applied index is now lower than readState.Index' (duration: 916.72826ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:20:03.535521Z","caller":"traceutil/trace.go:171","msg":"trace[1263567630] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"1.282605884s","start":"2026-04-09T11:20:02.252878Z","end":"2026-04-09T11:20:03.535483Z","steps":["trace[1263567630] 'process raft request' (duration: 1.282418929s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:03.535639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.400787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:20:03.535683Z","caller":"traceutil/trace.go:171","msg":"trace[1616809386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:233; }","duration":"645.505489ms","start":"2026-04-09T11:20:02.890166Z","end":"2026-04-09T11:20:03.535671Z","steps":["trace[1616809386] 'agreement among raft nodes before linearized reading' (duration: 645.387686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:03.535676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:02.252861Z","time spent":"1.282738248s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":356,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T11:20:03.535729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:02.890141Z","time spent":"645.580341ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T11:20:03.535725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.985122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:20:03.535889Z","caller":"traceutil/trace.go:171","msg":"trace[263069241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:233; }","duration":"131.223898ms","start":"2026-04-09T11:20:03.404639Z","end":"2026-04-09T11:20:03.535863Z","steps":["trace[263069241] 'agreement among raft nodes before linearized reading' (duration: 130.947621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:03.535967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178663164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:20:03.536006Z","caller":"traceutil/trace.go:171","msg":"trace[920652154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:233; }","duration":"1.178723666s","start":"2026-04-09T11:20:02.357274Z","end":"2026-04-09T11:20:03.535997Z","steps":["trace[920652154] 'agreement among raft nodes before linearized reading' (duration: 1.178195193s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:03.536039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:02.357259Z","time spent":"1.178771267s","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T11:20:03.535819Z","caller":"traceutil/trace.go:171","msg":"trace[2058781099] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"1.283426753s","start":"2026-04-09T11:20:02.252201Z","end":"2026-04-09T11:20:03.535627Z","steps":["trace[2058781099] 'compare' (duration: 1.280013294s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:03.537102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:02.252176Z","time spent":"1.284813916s","remote":"127.0.0.1:52064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:20:04.636528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.186541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T11:20:04.636735Z","caller":"traceutil/trace.go:171","msg":"trace[955291403] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"1.0966566s","start":"2026-04-09T11:20:03.540049Z","end":"2026-04-09T11:20:04.636706Z","steps":["trace[955291403] 'process raft request' (duration: 396.124851ms)","trace[955291403] 'compare' (duration: 699.527996ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:20:04.636807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:03.540031Z","time spent":"1.096748643s","remote":"127.0.0.1:52332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":481,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:20:04.636846Z","caller":"traceutil/trace.go:171","msg":"trace[279471420] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:239; }","duration":"1.096571268s","start":"2026-04-09T11:20:03.540239Z","end":"2026-04-09T11:20:04.636811Z","steps":["trace[279471420] 'read index received' (duration: 395.893846ms)","trace[279471420] 'applied index is now lower than readState.Index' (duration: 700.674282ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:20:04.637054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096792744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a4ac4c0f79f96d\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-04-09T11:20:04.637092Z","caller":"traceutil/trace.go:171","msg":"trace[1991875033] range","detail":"{range_begin:/registry/events/default/instance.18a4ac4c0f79f96d; range_end:; response_count:1; response_revision:234; }","duration":"1.096878785s","start":"2026-04-09T11:20:03.540203Z","end":"2026-04-09T11:20:04.637082Z","steps":["trace[1991875033] 'agreement among raft nodes before linearized reading' (duration: 1.096750532s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:04.637128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:03.540185Z","time spent":"1.096935217s","remote":"127.0.0.1:52064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":675,"request content":"key:\"/registry/events/default/instance.18a4ac4c0f79f96d\" "} {"level":"warn","ts":"2026-04-09T11:20:04.637972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.183543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-09T11:20:04.637997Z","caller":"traceutil/trace.go:171","msg":"trace[68721420] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"1.097610883s","start":"2026-04-09T11:20:03.54037Z","end":"2026-04-09T11:20:04.637981Z","steps":["trace[68721420] 'process raft request' (duration: 1.09706412s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:04.638079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:03.540363Z","time spent":"1.097668234s","remote":"127.0.0.1:52240","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-04-09T11:20:04.638168Z","caller":"traceutil/trace.go:171","msg":"trace[1417000337] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"1.097749086s","start":"2026-04-09T11:20:03.540409Z","end":"2026-04-09T11:20:04.638159Z","steps":["trace[1417000337] 'process raft request' (duration: 1.097425708s)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:20:04.638159Z","caller":"traceutil/trace.go:171","msg":"trace[1911811780] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"1.097721325s","start":"2026-04-09T11:20:03.540409Z","end":"2026-04-09T11:20:04.63813Z","steps":["trace[1911811780] 'process raft request' (duration: 1.097380047s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:04.638211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:03.540395Z","time spent":"1.097797467s","remote":"127.0.0.1:52240","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-04-09T11:20:04.638281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:03.540404Z","time spent":"1.097822548s","remote":"127.0.0.1:52240","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-04-09T11:20:04.638367Z","caller":"traceutil/trace.go:171","msg":"trace[650009125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:237; }","duration":"467.271325ms","start":"2026-04-09T11:20:04.170737Z","end":"2026-04-09T11:20:04.638008Z","steps":["trace[650009125] 'agreement among raft nodes before linearized reading' (duration: 467.139052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:04.638425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:04.170717Z","time spent":"467.694955ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T11:20:38.126418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339914399001548,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T11:20:38.62656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339914399001548,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T11:20:38.770686Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.388946012s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T11:20:38.77216Z","caller":"traceutil/trace.go:171","msg":"trace[959899252] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"1.146727229s","start":"2026-04-09T11:20:37.625406Z","end":"2026-04-09T11:20:38.772134Z","steps":["trace[959899252] 'read index received' (duration: 1.145520511s)","trace[959899252] 'applied index is now lower than readState.Index' (duration: 1.205748ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:20:38.772249Z","caller":"traceutil/trace.go:171","msg":"trace[1720484014] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.391909922s","start":"2026-04-09T11:20:37.380288Z","end":"2026-04-09T11:20:38.772198Z","steps":["trace[1720484014] 'process raft request' (duration: 1.391204825s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:38.772398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146967155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T11:20:38.772409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:37.38026Z","time spent":"1.392078755s","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:20:38.772466Z","caller":"traceutil/trace.go:171","msg":"trace[1065359110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.147045877s","start":"2026-04-09T11:20:37.625376Z","end":"2026-04-09T11:20:38.772422Z","steps":["trace[1065359110] 'agreement among raft nodes before linearized reading' (duration: 1.146876883s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:38.772502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:37.625362Z","time spent":"1.147131309s","remote":"127.0.0.1:51976","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-04-09T11:20:44.863582Z","caller":"traceutil/trace.go:171","msg":"trace[37813938] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"175.753149ms","start":"2026-04-09T11:20:44.687808Z","end":"2026-04-09T11:20:44.863561Z","steps":["trace[37813938] 'process raft request' (duration: 175.335139ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:20:45.308076Z","caller":"traceutil/trace.go:171","msg":"trace[939697227] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"408.686455ms","start":"2026-04-09T11:20:44.899368Z","end":"2026-04-09T11:20:45.308055Z","steps":["trace[939697227] 'process raft request' (duration: 408.47447ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:20:45.308075Z","caller":"traceutil/trace.go:171","msg":"trace[1123644457] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"403.875963ms","start":"2026-04-09T11:20:44.904164Z","end":"2026-04-09T11:20:45.308039Z","steps":["trace[1123644457] 'process raft request' (duration: 403.827391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:45.308194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:44.899358Z","time spent":"408.769867ms","remote":"127.0.0.1:52240","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-04-09T11:20:45.308233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:44.904151Z","time spent":"403.993005ms","remote":"127.0.0.1:52240","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-04-09T11:20:45.319205Z","caller":"traceutil/trace.go:171","msg":"trace[75601930] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:657; }","duration":"118.91586ms","start":"2026-04-09T11:20:45.200276Z","end":"2026-04-09T11:20:45.319192Z","steps":["trace[75601930] 'read index received' (duration: 107.614156ms)","trace[75601930] 'applied index is now lower than readState.Index' (duration: 11.301264ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:20:45.3192Z","caller":"traceutil/trace.go:171","msg":"trace[1738926242] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"407.037036ms","start":"2026-04-09T11:20:44.912129Z","end":"2026-04-09T11:20:45.319166Z","steps":["trace[1738926242] 'process raft request' (duration: 406.832241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:45.31927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.989782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-whc4b\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T11:20:45.31929Z","caller":"traceutil/trace.go:171","msg":"trace[470748778] range","detail":"{range_begin:/registry/pods/kube-system/cilium-whc4b; range_end:; response_count:1; response_revision:636; }","duration":"119.044313ms","start":"2026-04-09T11:20:45.20024Z","end":"2026-04-09T11:20:45.319284Z","steps":["trace[470748778] 'agreement among raft nodes before linearized reading' (duration: 118.989222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:45.319343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:44.912084Z","time spent":"407.181099ms","remote":"127.0.0.1:52240","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-04-09T11:20:45.319233Z","caller":"traceutil/trace.go:171","msg":"trace[1781652759] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"380.193659ms","start":"2026-04-09T11:20:44.939021Z","end":"2026-04-09T11:20:45.319215Z","steps":["trace[1781652759] 'process raft request' (duration: 380.044095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:45.320187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:44.939005Z","time spent":"381.040698ms","remote":"127.0.0.1:52332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T11:20:47.105698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479287063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:20:47.105782Z","caller":"traceutil/trace.go:171","msg":"trace[752962387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"1.479415086s","start":"2026-04-09T11:20:45.626346Z","end":"2026-04-09T11:20:47.105761Z","steps":["trace[752962387] 'range keys from in-memory index tree' (duration: 1.47919722s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:47.105821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:45.626307Z","time spent":"1.479504788s","remote":"127.0.0.1:51976","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-04-09T11:20:47.182672Z","caller":"traceutil/trace.go:171","msg":"trace[1636819535] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.458225421s","start":"2026-04-09T11:20:45.724422Z","end":"2026-04-09T11:20:47.182647Z","steps":["trace[1636819535] 'process raft request' (duration: 1.457662998s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:47.182806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:45.724385Z","time spent":"1.458349124s","remote":"127.0.0.1:52070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T11:20:47.195748Z","caller":"traceutil/trace.go:171","msg":"trace[1150438662] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.398928604s","start":"2026-04-09T11:20:45.79679Z","end":"2026-04-09T11:20:47.195719Z","steps":["trace[1150438662] 'process raft request' (duration: 1.3987449s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:47.195898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:20:45.796763Z","time spent":"1.399053038s","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T11:20:47.21215Z","caller":"traceutil/trace.go:171","msg":"trace[466844710] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:662; }","duration":"297.732911ms","start":"2026-04-09T11:20:46.914396Z","end":"2026-04-09T11:20:47.212129Z","steps":["trace[466844710] 'read index received' (duration: 267.688389ms)","trace[466844710] 'applied index is now lower than readState.Index' (duration: 30.043362ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:20:47.212308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.926505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T11:20:47.21231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.23631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:20:47.212326Z","caller":"traceutil/trace.go:171","msg":"trace[1518749967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"297.978607ms","start":"2026-04-09T11:20:46.914342Z","end":"2026-04-09T11:20:47.21232Z","steps":["trace[1518749967] 'agreement among raft nodes before linearized reading' (duration: 297.882074ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:20:47.212343Z","caller":"traceutil/trace.go:171","msg":"trace[432950559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"105.340723ms","start":"2026-04-09T11:20:47.106994Z","end":"2026-04-09T11:20:47.212334Z","steps":["trace[432950559] 'agreement among raft nodes before linearized reading' (duration: 105.248621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:20:48.787106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.403926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:20:48.787201Z","caller":"traceutil/trace.go:171","msg":"trace[1687411869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"150.53576ms","start":"2026-04-09T11:20:48.636646Z","end":"2026-04-09T11:20:48.787182Z","steps":["trace[1687411869] 'range keys from in-memory index tree' (duration: 150.349165ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:20:48.798003Z","caller":"traceutil/trace.go:171","msg":"trace[1699926259] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"149.418103ms","start":"2026-04-09T11:20:48.648545Z","end":"2026-04-09T11:20:48.797963Z","steps":["trace[1699926259] 'process raft request' (duration: 142.603173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:02.534045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.347006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:21:02.534159Z","caller":"traceutil/trace.go:171","msg":"trace[181660431] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:806; }","duration":"313.53248ms","start":"2026-04-09T11:21:02.220608Z","end":"2026-04-09T11:21:02.53414Z","steps":["trace[181660431] 'count revisions from in-memory index tree' (duration: 313.195293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:02.534211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:02.220583Z","time spent":"313.609851ms","remote":"127.0.0.1:52068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-09T11:21:02.53431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.919024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T11:21:02.534417Z","caller":"traceutil/trace.go:171","msg":"trace[232425374] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:806; }","duration":"360.078658ms","start":"2026-04-09T11:21:02.174319Z","end":"2026-04-09T11:21:02.534398Z","steps":["trace[232425374] 'count revisions from in-memory index tree' (duration: 359.825072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:02.534309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.438278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q7v8z\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-09T11:21:02.534461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:02.1743Z","time spent":"360.149509ms","remote":"127.0.0.1:52366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-04-09T11:21:02.534579Z","caller":"traceutil/trace.go:171","msg":"trace[1668701263] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q7v8z; range_end:; response_count:1; response_revision:806; }","duration":"239.685594ms","start":"2026-04-09T11:21:02.294813Z","end":"2026-04-09T11:21:02.534498Z","steps":["trace[1668701263] 'range keys from in-memory index tree' (duration: 239.262104ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:21:03.168062Z","caller":"traceutil/trace.go:171","msg":"trace[1547916311] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"116.367371ms","start":"2026-04-09T11:21:03.05167Z","end":"2026-04-09T11:21:03.168038Z","steps":["trace[1547916311] 'read index received' (duration: 116.172146ms)","trace[1547916311] 'applied index is now lower than readState.Index' (duration: 194.355µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:21:03.168269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.571956ms","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-04-09T11:21:03.168318Z","caller":"traceutil/trace.go:171","msg":"trace[577757628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:807; }","duration":"116.688638ms","start":"2026-04-09T11:21:03.051616Z","end":"2026-04-09T11:21:03.168305Z","steps":["trace[577757628] 'agreement among raft nodes before linearized reading' (duration: 116.513264ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:21:03.168469Z","caller":"traceutil/trace.go:171","msg":"trace[187536839] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"625.77093ms","start":"2026-04-09T11:21:02.542655Z","end":"2026-04-09T11:21:03.168426Z","steps":["trace[187536839] 'process raft request' (duration: 625.243738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:03.168684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:02.542637Z","time spent":"625.960404ms","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T11:21:35.620124Z","caller":"traceutil/trace.go:171","msg":"trace[1521902354] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"126.267326ms","start":"2026-04-09T11:21:35.493834Z","end":"2026-04-09T11:21:35.620102Z","steps":["trace[1521902354] 'process raft request' (duration: 126.101392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:36.691665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.174317ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-09T11:21:37.61608Z","caller":"traceutil/trace.go:171","msg":"trace[1569725328] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"126.678366ms","start":"2026-04-09T11:21:37.489377Z","end":"2026-04-09T11:21:37.616056Z","steps":["trace[1569725328] 'read index received' (duration: 126.498962ms)","trace[1569725328] 'applied index is now lower than readState.Index' (duration: 178.544µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:21:37.616186Z","caller":"traceutil/trace.go:171","msg":"trace[1154603023] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"275.146494ms","start":"2026-04-09T11:21:37.34101Z","end":"2026-04-09T11:21:37.616157Z","steps":["trace[1154603023] 'process raft request' (duration: 274.910539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:37.616267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.87991ms","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-04-09T11:21:37.616305Z","caller":"traceutil/trace.go:171","msg":"trace[160036989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"126.967962ms","start":"2026-04-09T11:21:37.489326Z","end":"2026-04-09T11:21:37.616294Z","steps":["trace[160036989] 'agreement among raft nodes before linearized reading' (duration: 126.828579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:38.142615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339914399002538,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T11:21:38.554067Z","caller":"traceutil/trace.go:171","msg":"trace[816338186] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"934.464264ms","start":"2026-04-09T11:21:37.619567Z","end":"2026-04-09T11:21:38.554031Z","steps":["trace[816338186] 'process raft request' (duration: 934.308891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:38.554198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:37.619548Z","time spent":"934.581417ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:21:38.880887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.733497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T11:21:38.88105Z","caller":"traceutil/trace.go:171","msg":"trace[859910041] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1056; }","duration":"1.239391918s","start":"2026-04-09T11:21:37.641641Z","end":"2026-04-09T11:21:38.881033Z","steps":["trace[859910041] 'read index received' (duration: 912.223131ms)","trace[859910041] 'applied index is now lower than readState.Index' (duration: 327.167857ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:21:38.881143Z","caller":"traceutil/trace.go:171","msg":"trace[1040674020] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"632.134751ms","start":"2026-04-09T11:21:38.248994Z","end":"2026-04-09T11:21:38.881128Z","steps":["trace[1040674020] 'process raft request' (duration: 631.989008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:38.881243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:38.248969Z","time spent":"632.222773ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:21:38.881342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.671017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:21:38.88166Z","caller":"traceutil/trace.go:171","msg":"trace[1408898928] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1020; }","duration":"382.012485ms","start":"2026-04-09T11:21:38.499627Z","end":"2026-04-09T11:21:38.88164Z","steps":["trace[1408898928] 'agreement among raft nodes before linearized reading' (duration: 381.677557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:38.8817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:38.499603Z","time spent":"382.085966ms","remote":"127.0.0.1:52128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-09T11:21:38.881342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229358362s","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-04-09T11:21:38.881809Z","caller":"traceutil/trace.go:171","msg":"trace[542712712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"1.229846663s","start":"2026-04-09T11:21:37.65193Z","end":"2026-04-09T11:21:38.881777Z","steps":["trace[542712712] 'agreement among raft nodes before linearized reading' (duration: 1.229223179s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:38.881391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.991121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:21:38.881959Z","caller":"traceutil/trace.go:171","msg":"trace[2146993075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"255.597385ms","start":"2026-04-09T11:21:38.626346Z","end":"2026-04-09T11:21:38.881944Z","steps":["trace[2146993075] 'agreement among raft nodes before linearized reading' (duration: 255.020062ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:21:38.881139Z","caller":"traceutil/trace.go:171","msg":"trace[1415576589] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"1.2556268s","start":"2026-04-09T11:21:37.625489Z","end":"2026-04-09T11:21:38.881116Z","steps":["trace[1415576589] 'process raft request' (duration: 1.091577225s)","trace[1415576589] 'compare' (duration: 163.631795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:21:38.882085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:37.625472Z","time spent":"1.256569972s","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:21:38.881404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239757886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T11:21:38.882181Z","caller":"traceutil/trace.go:171","msg":"trace[2023344775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"1.240580956s","start":"2026-04-09T11:21:37.641589Z","end":"2026-04-09T11:21:38.88217Z","steps":["trace[2023344775] 'agreement among raft nodes before linearized reading' (duration: 1.239725116s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:38.882217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:37.641573Z","time spent":"1.240636798s","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T11:21:38.88191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:37.651917Z","time spent":"1.229974817s","remote":"127.0.0.1:52240","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-04-09T11:21:38.881431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.425776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:21:38.882293Z","caller":"traceutil/trace.go:171","msg":"trace[1634745145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"727.314457ms","start":"2026-04-09T11:21:38.154971Z","end":"2026-04-09T11:21:38.882285Z","steps":["trace[1634745145] 'agreement among raft nodes before linearized reading' (duration: 726.430807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:38.882317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:38.154958Z","time spent":"727.354138ms","remote":"127.0.0.1:51976","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-04-09T11:21:38.881509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228608225s","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-04-09T11:21:38.882375Z","caller":"traceutil/trace.go:171","msg":"trace[310225741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"1.229487335s","start":"2026-04-09T11:21:37.65288Z","end":"2026-04-09T11:21:38.882367Z","steps":["trace[310225741] 'agreement among raft nodes before linearized reading' (duration: 1.228540533s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:38.882406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:37.652873Z","time spent":"1.229522406s","remote":"127.0.0.1:52240","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":"info","ts":"2026-04-09T11:21:42.137611Z","caller":"traceutil/trace.go:171","msg":"trace[15311727] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"479.498595ms","start":"2026-04-09T11:21:41.658092Z","end":"2026-04-09T11:21:42.13759Z","steps":["trace[15311727] 'process raft request' (duration: 479.374492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:21:42.137744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:21:41.658062Z","time spent":"479.609758ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T11:21:42.138697Z","caller":"traceutil/trace.go:171","msg":"trace[116403724] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"167.728121ms","start":"2026-04-09T11:21:41.970948Z","end":"2026-04-09T11:21:42.138676Z","steps":["trace[116403724] 'process raft request' (duration: 167.626618ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:02.518133Z","caller":"traceutil/trace.go:171","msg":"trace[1762751020] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"198.164796ms","start":"2026-04-09T11:22:02.319938Z","end":"2026-04-09T11:22:02.518103Z","steps":["trace[1762751020] 'process raft request' (duration: 198.022292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:02.784471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.487881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T11:22:02.78457Z","caller":"traceutil/trace.go:171","msg":"trace[1485627583] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"126.705397ms","start":"2026-04-09T11:22:02.657848Z","end":"2026-04-09T11:22:02.784554Z","steps":["trace[1485627583] 'read index received' (duration: 20.79µs)","trace[1485627583] 'applied index is now lower than readState.Index' (duration: 126.683177ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:22:02.78468Z","caller":"traceutil/trace.go:171","msg":"trace[1103400653] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"276.110857ms","start":"2026-04-09T11:22:02.508518Z","end":"2026-04-09T11:22:02.784629Z","steps":["trace[1103400653] 'process raft request' (duration: 95.078763ms)","trace[1103400653] 'compare' (duration: 180.385118ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:22:02.784777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.921562ms","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-04-09T11:22:02.784811Z","caller":"traceutil/trace.go:171","msg":"trace[423448676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"126.981894ms","start":"2026-04-09T11:22:02.65782Z","end":"2026-04-09T11:22:02.784802Z","steps":["trace[423448676] 'agreement among raft nodes before linearized reading' (duration: 126.84729ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:04.776699Z","caller":"traceutil/trace.go:171","msg":"trace[2104828182] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"251.377305ms","start":"2026-04-09T11:22:04.525296Z","end":"2026-04-09T11:22:04.776673Z","steps":["trace[2104828182] 'process raft request' (duration: 251.225022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:05.021417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.810262ms","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-04-09T11:22:05.021506Z","caller":"traceutil/trace.go:171","msg":"trace[1425913513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1104; }","duration":"167.958106ms","start":"2026-04-09T11:22:04.853528Z","end":"2026-04-09T11:22:05.021486Z","steps":["trace[1425913513] 'range keys from in-memory index tree' (duration: 167.623718ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:05.332106Z","caller":"traceutil/trace.go:171","msg":"trace[839559298] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"305.809614ms","start":"2026-04-09T11:22:05.02627Z","end":"2026-04-09T11:22:05.332079Z","steps":["trace[839559298] 'process raft request' (duration: 305.608099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:05.332261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:05.026234Z","time spent":"305.942567ms","remote":"127.0.0.1:52240","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-04-09T11:22:05.342996Z","caller":"traceutil/trace.go:171","msg":"trace[1426859525] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1148; }","duration":"262.171878ms","start":"2026-04-09T11:22:05.08079Z","end":"2026-04-09T11:22:05.342962Z","steps":["trace[1426859525] 'read index received' (duration: 251.102108ms)","trace[1426859525] 'applied index is now lower than readState.Index' (duration: 11.06841ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:22:05.343747Z","caller":"traceutil/trace.go:171","msg":"trace[769590504] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"279.981906ms","start":"2026-04-09T11:22:05.063744Z","end":"2026-04-09T11:22:05.343726Z","steps":["trace[769590504] 'process raft request' (duration: 279.039224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:05.343788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.681718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-09T11:22:05.34385Z","caller":"traceutil/trace.go:171","msg":"trace[1921210704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"221.789341ms","start":"2026-04-09T11:22:05.122046Z","end":"2026-04-09T11:22:05.343835Z","steps":["trace[1921210704] 'agreement among raft nodes before linearized reading' (duration: 221.523145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:05.343976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.167942ms","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-04-09T11:22:05.344099Z","caller":"traceutil/trace.go:171","msg":"trace[1680153346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1106; }","duration":"263.322126ms","start":"2026-04-09T11:22:05.080755Z","end":"2026-04-09T11:22:05.344077Z","steps":["trace[1680153346] 'agreement among raft nodes before linearized reading' (duration: 262.944797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:05.343976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.997941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-09T11:22:05.344221Z","caller":"traceutil/trace.go:171","msg":"trace[927440124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1106; }","duration":"212.282368ms","start":"2026-04-09T11:22:05.131925Z","end":"2026-04-09T11:22:05.344208Z","steps":["trace[927440124] 'agreement among raft nodes before linearized reading' (duration: 211.874238ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:07.563672Z","caller":"traceutil/trace.go:171","msg":"trace[147508583] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"121.659218ms","start":"2026-04-09T11:22:07.441988Z","end":"2026-04-09T11:22:07.563647Z","steps":["trace[147508583] 'process raft request' (duration: 35.429733ms)","trace[147508583] 'compare' (duration: 86.073602ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:22:07.563875Z","caller":"traceutil/trace.go:171","msg":"trace[992148826] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"121.785401ms","start":"2026-04-09T11:22:07.442061Z","end":"2026-04-09T11:22:07.563847Z","steps":["trace[992148826] 'process raft request' (duration: 121.545425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:13.055941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.136997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-04-09T11:22:13.056088Z","caller":"traceutil/trace.go:171","msg":"trace[581582773] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1130; }","duration":"114.291651ms","start":"2026-04-09T11:22:12.941768Z","end":"2026-04-09T11:22:13.05606Z","steps":["trace[581582773] 'range keys from in-memory index tree' (duration: 113.83357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:13.574737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.181712ms","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-04-09T11:22:13.574833Z","caller":"traceutil/trace.go:171","msg":"trace[1241438778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1131; }","duration":"208.296675ms","start":"2026-04-09T11:22:13.366497Z","end":"2026-04-09T11:22:13.574793Z","steps":["trace[1241438778] 'range keys from in-memory index tree' (duration: 208.012539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:13.575003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.150587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:22:13.575064Z","caller":"traceutil/trace.go:171","msg":"trace[1233180029] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1131; }","duration":"302.249809ms","start":"2026-04-09T11:22:13.2728Z","end":"2026-04-09T11:22:13.57505Z","steps":["trace[1233180029] 'count revisions from in-memory index tree' (duration: 302.084965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:13.575099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:13.272782Z","time spent":"302.30682ms","remote":"127.0.0.1:47508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-04-09T11:22:13.575094Z","caller":"traceutil/trace.go:171","msg":"trace[704225750] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"178.354734ms","start":"2026-04-09T11:22:13.396711Z","end":"2026-04-09T11:22:13.575066Z","steps":["trace[704225750] 'read index received' (duration: 131.034024ms)","trace[704225750] 'applied index is now lower than readState.Index' (duration: 47.31933ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:22:13.575161Z","caller":"traceutil/trace.go:171","msg":"trace[1329217505] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"208.214644ms","start":"2026-04-09T11:22:13.366884Z","end":"2026-04-09T11:22:13.575099Z","steps":["trace[1329217505] 'process raft request' (duration: 160.903914ms)","trace[1329217505] 'compare' (duration: 47.000612ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:22:13.575316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.584259ms","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-04-09T11:22:13.575359Z","caller":"traceutil/trace.go:171","msg":"trace[1238869557] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1132; }","duration":"178.67531ms","start":"2026-04-09T11:22:13.396673Z","end":"2026-04-09T11:22:13.575348Z","steps":["trace[1238869557] 'agreement among raft nodes before linearized reading' (duration: 178.486556ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:17.8152Z","caller":"traceutil/trace.go:171","msg":"trace[1268063302] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"189.567337ms","start":"2026-04-09T11:22:17.62561Z","end":"2026-04-09T11:22:17.815177Z","steps":["trace[1268063302] 'read index received' (duration: 189.375242ms)","trace[1268063302] 'applied index is now lower than readState.Index' (duration: 190.815µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:22:17.815317Z","caller":"traceutil/trace.go:171","msg":"trace[884073750] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"946.020279ms","start":"2026-04-09T11:22:16.869255Z","end":"2026-04-09T11:22:17.815275Z","steps":["trace[884073750] 'process raft request' (duration: 945.762263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:17.815526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:16.86924Z","time spent":"946.189772ms","remote":"127.0.0.1:52240","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-04-09T11:22:17.815349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.72795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:22:17.815684Z","caller":"traceutil/trace.go:171","msg":"trace[692809235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"190.067588ms","start":"2026-04-09T11:22:17.62557Z","end":"2026-04-09T11:22:17.815637Z","steps":["trace[692809235] 'agreement among raft nodes before linearized reading' (duration: 189.71756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:18.315879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339914399002962,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T11:22:18.57679Z","caller":"traceutil/trace.go:171","msg":"trace[1379032217] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"761.506531ms","start":"2026-04-09T11:22:17.815262Z","end":"2026-04-09T11:22:18.576769Z","steps":["trace[1379032217] 'read index received' (duration: 761.389338ms)","trace[1379032217] 'applied index is now lower than readState.Index' (duration: 116.483µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:22:18.576939Z","caller":"traceutil/trace.go:171","msg":"trace[2101102652] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"918.827301ms","start":"2026-04-09T11:22:17.658106Z","end":"2026-04-09T11:22:18.576934Z","steps":["trace[2101102652] 'process raft request' (duration: 918.580965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:18.576997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:17.658085Z","time spent":"918.868512ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:22:18.577144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.111698ms","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-04-09T11:22:18.577159Z","caller":"traceutil/trace.go:171","msg":"trace[1448101477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"870.139969ms","start":"2026-04-09T11:22:17.707015Z","end":"2026-04-09T11:22:18.577155Z","steps":["trace[1448101477] 'agreement among raft nodes before linearized reading' (duration: 870.072597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:18.577172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:17.707008Z","time spent":"870.160949ms","remote":"127.0.0.1:52240","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-04-09T11:22:18.577256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.322067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T11:22:18.577269Z","caller":"traceutil/trace.go:171","msg":"trace[508411751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1195; }","duration":"871.360427ms","start":"2026-04-09T11:22:17.705905Z","end":"2026-04-09T11:22:18.577265Z","steps":["trace[508411751] 'agreement among raft nodes before linearized reading' (duration: 871.323996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:18.57728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:17.705889Z","time spent":"871.388329ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T11:22:18.57737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.581667ms","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-04-09T11:22:18.577383Z","caller":"traceutil/trace.go:171","msg":"trace[1541916695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1195; }","duration":"878.620948ms","start":"2026-04-09T11:22:17.698759Z","end":"2026-04-09T11:22:18.57738Z","steps":["trace[1541916695] 'agreement among raft nodes before linearized reading' (duration: 878.579487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:18.577396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:17.698718Z","time spent":"878.674149ms","remote":"127.0.0.1:52240","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-04-09T11:22:18.577476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.122197ms","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-04-09T11:22:18.577487Z","caller":"traceutil/trace.go:171","msg":"trace[745445582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1195; }","duration":"881.168857ms","start":"2026-04-09T11:22:17.696315Z","end":"2026-04-09T11:22:18.577484Z","steps":["trace[745445582] 'agreement among raft nodes before linearized reading' (duration: 881.136487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:18.577498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:17.696297Z","time spent":"881.198189ms","remote":"127.0.0.1:52240","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-04-09T11:22:18.577555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.645131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:22:18.577568Z","caller":"traceutil/trace.go:171","msg":"trace[760265618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"760.689472ms","start":"2026-04-09T11:22:17.816873Z","end":"2026-04-09T11:22:18.577562Z","steps":["trace[760265618] 'agreement among raft nodes before linearized reading' (duration: 760.672702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:18.577578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:17.816857Z","time spent":"760.718563ms","remote":"127.0.0.1:51990","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-04-09T11:22:30.779374Z","caller":"traceutil/trace.go:171","msg":"trace[1301214005] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"106.969029ms","start":"2026-04-09T11:22:30.67238Z","end":"2026-04-09T11:22:30.779349Z","steps":["trace[1301214005] 'process raft request' (duration: 106.890507ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:30.779396Z","caller":"traceutil/trace.go:171","msg":"trace[1872697585] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"106.977829ms","start":"2026-04-09T11:22:30.672351Z","end":"2026-04-09T11:22:30.779329Z","steps":["trace[1872697585] 'process raft request' (duration: 62.470536ms)","trace[1872697585] 'compare' (duration: 44.322899ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:22:30.779642Z","caller":"traceutil/trace.go:171","msg":"trace[410774874] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"107.147903ms","start":"2026-04-09T11:22:30.672474Z","end":"2026-04-09T11:22:30.779621Z","steps":["trace[410774874] 'process raft request' (duration: 107.072832ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:30.779649Z","caller":"traceutil/trace.go:171","msg":"trace[836634233] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"107.215825ms","start":"2026-04-09T11:22:30.672424Z","end":"2026-04-09T11:22:30.779639Z","steps":["trace[836634233] 'process raft request' (duration: 106.887157ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:32.272858Z","caller":"traceutil/trace.go:171","msg":"trace[5738794] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"380.488915ms","start":"2026-04-09T11:22:31.892334Z","end":"2026-04-09T11:22:32.272822Z","steps":["trace[5738794] 'process raft request' (duration: 380.3026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:32.2731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:31.892316Z","time spent":"380.698009ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T11:22:32.988008Z","caller":"traceutil/trace.go:171","msg":"trace[141112959] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"199.666413ms","start":"2026-04-09T11:22:32.788312Z","end":"2026-04-09T11:22:32.987978Z","steps":["trace[141112959] 'process raft request' (duration: 199.462688ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:32.995602Z","caller":"traceutil/trace.go:171","msg":"trace[59320866] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"207.028366ms","start":"2026-04-09T11:22:32.788551Z","end":"2026-04-09T11:22:32.99558Z","steps":["trace[59320866] 'process raft request' (duration: 206.885673ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:32.995825Z","caller":"traceutil/trace.go:171","msg":"trace[1070286433] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"207.130858ms","start":"2026-04-09T11:22:32.788668Z","end":"2026-04-09T11:22:32.995799Z","steps":["trace[1070286433] 'process raft request' (duration: 206.878902ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:32.995849Z","caller":"traceutil/trace.go:171","msg":"trace[1156592593] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"206.849721ms","start":"2026-04-09T11:22:32.788948Z","end":"2026-04-09T11:22:32.995798Z","steps":["trace[1156592593] 'process raft request' (duration: 206.758629ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:33.794858Z","caller":"traceutil/trace.go:171","msg":"trace[82526177] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"170.060548ms","start":"2026-04-09T11:22:33.624773Z","end":"2026-04-09T11:22:33.794834Z","steps":["trace[82526177] 'read index received' (duration: 169.771782ms)","trace[82526177] 'applied index is now lower than readState.Index' (duration: 287.516µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:22:33.794976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.180861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:22:33.795008Z","caller":"traceutil/trace.go:171","msg":"trace[656639141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"170.267283ms","start":"2026-04-09T11:22:33.624729Z","end":"2026-04-09T11:22:33.794996Z","steps":["trace[656639141] 'agreement among raft nodes before linearized reading' (duration: 170.198132ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:33.794966Z","caller":"traceutil/trace.go:171","msg":"trace[679386162] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"415.099586ms","start":"2026-04-09T11:22:33.37979Z","end":"2026-04-09T11:22:33.79489Z","steps":["trace[679386162] 'process raft request' (duration: 414.85753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:33.795353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:33.379771Z","time spent":"415.426173ms","remote":"127.0.0.1:52240","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-04-09T11:22:36.391862Z","caller":"traceutil/trace.go:171","msg":"trace[747768642] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"101.730196ms","start":"2026-04-09T11:22:36.290108Z","end":"2026-04-09T11:22:36.391838Z","steps":["trace[747768642] 'process raft request' (duration: 101.571742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:37.177937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.176502ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-09T11:22:37.178171Z","caller":"traceutil/trace.go:171","msg":"trace[1394030430] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"552.238712ms","start":"2026-04-09T11:22:36.625884Z","end":"2026-04-09T11:22:37.178123Z","steps":["trace[1394030430] 'read index received' (duration: 269.071781ms)","trace[1394030430] 'applied index is now lower than readState.Index' (duration: 283.166061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:22:37.178354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.458567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:22:37.178435Z","caller":"traceutil/trace.go:171","msg":"trace[86229670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"552.58303ms","start":"2026-04-09T11:22:36.625835Z","end":"2026-04-09T11:22:37.178418Z","steps":["trace[86229670] 'agreement among raft nodes before linearized reading' (duration: 552.449067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:37.178469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:36.625814Z","time spent":"552.648302ms","remote":"127.0.0.1:51976","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-04-09T11:22:37.178603Z","caller":"traceutil/trace.go:171","msg":"trace[2010660482] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"493.691069ms","start":"2026-04-09T11:22:36.684898Z","end":"2026-04-09T11:22:37.178589Z","steps":["trace[2010660482] 'process raft request' (duration: 493.144397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:37.178701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:36.684875Z","time spent":"493.763271ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:22:37.45169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.283705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:22:37.451773Z","caller":"traceutil/trace.go:171","msg":"trace[269547311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"272.407108ms","start":"2026-04-09T11:22:37.179345Z","end":"2026-04-09T11:22:37.451753Z","steps":["trace[269547311] 'range keys from in-memory index tree' (duration: 272.224274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:37.452049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.836736ms","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-04-09T11:22:37.452159Z","caller":"traceutil/trace.go:171","msg":"trace[1341377138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"451.99134ms","start":"2026-04-09T11:22:37.000147Z","end":"2026-04-09T11:22:37.452138Z","steps":["trace[1341377138] 'agreement among raft nodes before linearized reading' (duration: 178.421945ms)","trace[1341377138] 'range keys from in-memory index tree' (duration: 273.34735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:22:37.452187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.723443ms","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-04-09T11:22:37.452236Z","caller":"traceutil/trace.go:171","msg":"trace[1549106600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"445.806545ms","start":"2026-04-09T11:22:37.006417Z","end":"2026-04-09T11:22:37.452223Z","steps":["trace[1549106600] 'agreement among raft nodes before linearized reading' (duration: 172.316522ms)","trace[1549106600] 'range keys from in-memory index tree' (duration: 273.36073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:22:37.452273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:37.006397Z","time spent":"445.867786ms","remote":"127.0.0.1:52240","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-04-09T11:22:37.452255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:37.000132Z","time spent":"452.085312ms","remote":"127.0.0.1:52240","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-04-09T11:22:37.452286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.335254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T11:22:37.452385Z","caller":"traceutil/trace.go:171","msg":"trace[881857118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"442.474577ms","start":"2026-04-09T11:22:37.009887Z","end":"2026-04-09T11:22:37.452362Z","steps":["trace[881857118] 'agreement among raft nodes before linearized reading' (duration: 168.820729ms)","trace[881857118] 'range keys from in-memory index tree' (duration: 273.417641ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:22:37.452472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:37.009873Z","time spent":"442.58835ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T11:22:37.452886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.18231ms","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-04-09T11:22:37.452964Z","caller":"traceutil/trace.go:171","msg":"trace[1569698886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"442.278612ms","start":"2026-04-09T11:22:37.01067Z","end":"2026-04-09T11:22:37.452949Z","steps":["trace[1569698886] 'agreement among raft nodes before linearized reading' (duration: 168.055662ms)","trace[1569698886] 'range keys from in-memory index tree' (duration: 274.069977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:22:37.453021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:37.010661Z","time spent":"442.349724ms","remote":"127.0.0.1:52240","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-04-09T11:22:38.694321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.790488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:22:38.694399Z","caller":"traceutil/trace.go:171","msg":"trace[1434964537] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1282; }","duration":"709.90338ms","start":"2026-04-09T11:22:37.984478Z","end":"2026-04-09T11:22:38.694382Z","steps":["trace[1434964537] 'range keys from in-memory index tree' (duration: 709.708156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:38.694444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:37.984463Z","time spent":"709.969443ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-09T11:22:38.695546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.89751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T11:22:38.696416Z","caller":"traceutil/trace.go:171","msg":"trace[60811556] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"541.574782ms","start":"2026-04-09T11:22:38.15482Z","end":"2026-04-09T11:22:38.696395Z","steps":["trace[60811556] 'read index received' (duration: 270.794431ms)","trace[60811556] 'applied index is now lower than readState.Index' (duration: 270.778471ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:22:38.696625Z","caller":"traceutil/trace.go:171","msg":"trace[1762281541] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"640.785579ms","start":"2026-04-09T11:22:38.05582Z","end":"2026-04-09T11:22:38.696605Z","steps":["trace[1762281541] 'process raft request' (duration: 369.773493ms)","trace[1762281541] 'compare' (duration: 269.45024ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:22:38.696717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:38.055799Z","time spent":"640.876892ms","remote":"127.0.0.1:52064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T11:22:38.697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.182886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:22:38.697047Z","caller":"traceutil/trace.go:171","msg":"trace[137734424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"542.261948ms","start":"2026-04-09T11:22:38.154772Z","end":"2026-04-09T11:22:38.697034Z","steps":["trace[137734424] 'agreement among raft nodes before linearized reading' (duration: 542.160086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:38.697084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:38.154753Z","time spent":"542.32084ms","remote":"127.0.0.1:51976","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-04-09T11:22:38.69729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.865009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T11:22:38.697331Z","caller":"traceutil/trace.go:171","msg":"trace[1413560044] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1283; }","duration":"379.949301ms","start":"2026-04-09T11:22:38.317371Z","end":"2026-04-09T11:22:38.69732Z","steps":["trace[1413560044] 'agreement among raft nodes before linearized reading' (duration: 379.854269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:38.697361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:38.317351Z","time spent":"380.001603ms","remote":"127.0.0.1:52332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-09T11:22:38.697823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.279417ms","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-04-09T11:22:38.697862Z","caller":"traceutil/trace.go:171","msg":"trace[1635597166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1283; }","duration":"301.355128ms","start":"2026-04-09T11:22:38.396498Z","end":"2026-04-09T11:22:38.697853Z","steps":["trace[1635597166] 'agreement among raft nodes before linearized reading' (duration: 301.240625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:38.697905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:38.396477Z","time spent":"301.42074ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T11:22:39.275562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.937946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T11:22:39.275803Z","caller":"traceutil/trace.go:171","msg":"trace[1282274078] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"573.03681ms","start":"2026-04-09T11:22:38.702749Z","end":"2026-04-09T11:22:39.275786Z","steps":["trace[1282274078] 'process raft request' (duration: 573.00155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:39.275867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:38.702711Z","time spent":"573.131843ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:22:39.275869Z","caller":"traceutil/trace.go:171","msg":"trace[1324568515] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"573.805249ms","start":"2026-04-09T11:22:38.702031Z","end":"2026-04-09T11:22:39.275837Z","steps":["trace[1324568515] 'process raft request' (duration: 368.518464ms)","trace[1324568515] 'compare' (duration: 204.743522ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:22:39.275932Z","caller":"traceutil/trace.go:171","msg":"trace[1416864161] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"573.463821ms","start":"2026-04-09T11:22:38.702458Z","end":"2026-04-09T11:22:39.275922Z","steps":["trace[1416864161] 'process raft request' (duration: 573.206065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:39.275987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:38.702449Z","time spent":"573.507221ms","remote":"127.0.0.1:52240","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-04-09T11:22:39.276013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:38.702012Z","time spent":"573.932782ms","remote":"127.0.0.1:52064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:22:39.307345Z","caller":"traceutil/trace.go:171","msg":"trace[2104431580] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"124.227153ms","start":"2026-04-09T11:22:39.183099Z","end":"2026-04-09T11:22:39.307326Z","steps":["trace[2104431580] 'process raft request' (duration: 124.130441ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:22:49.09388Z","caller":"traceutil/trace.go:171","msg":"trace[253265571] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"468.600981ms","start":"2026-04-09T11:22:48.625257Z","end":"2026-04-09T11:22:49.093858Z","steps":["trace[253265571] 'read index received' (duration: 468.472198ms)","trace[253265571] 'applied index is now lower than readState.Index' (duration: 127.713µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:22:49.093938Z","caller":"traceutil/trace.go:171","msg":"trace[88341713] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"631.426589ms","start":"2026-04-09T11:22:48.462481Z","end":"2026-04-09T11:22:49.093907Z","steps":["trace[88341713] 'process raft request' (duration: 631.228754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:49.094093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:48.462455Z","time spent":"631.555091ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:22:49.094029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.756254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:22:49.094262Z","caller":"traceutil/trace.go:171","msg":"trace[532057003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"469.029511ms","start":"2026-04-09T11:22:48.625221Z","end":"2026-04-09T11:22:49.09425Z","steps":["trace[532057003] 'agreement among raft nodes before linearized reading' (duration: 468.763535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:22:49.094401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:22:48.625205Z","time spent":"469.182864ms","remote":"127.0.0.1:51976","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-04-09T11:22:49.094523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.459053ms","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-04-09T11:22:49.094645Z","caller":"traceutil/trace.go:171","msg":"trace[1282434879] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1357; }","duration":"218.638428ms","start":"2026-04-09T11:22:48.875985Z","end":"2026-04-09T11:22:49.094623Z","steps":["trace[1282434879] 'agreement among raft nodes before linearized reading' (duration: 218.229068ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:24:56.474277Z","caller":"traceutil/trace.go:171","msg":"trace[1905858549] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"146.180417ms","start":"2026-04-09T11:24:56.328075Z","end":"2026-04-09T11:24:56.474255Z","steps":["trace[1905858549] 'process raft request' (duration: 129.346843ms)","trace[1905858549] 'compare' (duration: 16.617099ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:24:56.523819Z","caller":"traceutil/trace.go:171","msg":"trace[356839902] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2025; }","duration":"154.600209ms","start":"2026-04-09T11:24:56.369185Z","end":"2026-04-09T11:24:56.523785Z","steps":["trace[356839902] 'read index received' (duration: 48.677301ms)","trace[356839902] 'applied index is now lower than readState.Index' (duration: 105.921028ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:24:56.523951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.743392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:24:56.523986Z","caller":"traceutil/trace.go:171","msg":"trace[1983854902] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1946; }","duration":"154.821954ms","start":"2026-04-09T11:24:56.369154Z","end":"2026-04-09T11:24:56.523976Z","steps":["trace[1983854902] 'agreement among raft nodes before linearized reading' (duration: 154.742522ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:24:58.633229Z","caller":"traceutil/trace.go:171","msg":"trace[2040855340] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"151.470188ms","start":"2026-04-09T11:24:58.481733Z","end":"2026-04-09T11:24:58.633203Z","steps":["trace[2040855340] 'process raft request' (duration: 151.329825ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:25:59.139897Z","caller":"traceutil/trace.go:171","msg":"trace[1555889445] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"220.525052ms","start":"2026-04-09T11:25:58.919346Z","end":"2026-04-09T11:25:59.139871Z","steps":["trace[1555889445] 'process raft request' (duration: 220.384018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:26:15.413883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.260748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-04-09T11:26:15.413964Z","caller":"traceutil/trace.go:171","msg":"trace[1541310623] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:2346; }","duration":"146.391571ms","start":"2026-04-09T11:26:15.267557Z","end":"2026-04-09T11:26:15.413949Z","steps":["trace[1541310623] 'range keys from in-memory index tree' (duration: 146.113815ms)"],"step_count":1}