{"level":"warn","ts":"2026-02-25T02:11:01.541218Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T02:11:01.541351Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T02:11:01.541425Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T02:11:01.541433Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-25T02:11:01.54145Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T02:11:01.542085Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-02-25T02:11:01.542156Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T02:11:01.545982Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.603712ms"} {"level":"info","ts":"2026-02-25T02:11:01.552686Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-02-25T02:11:01.552938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:11:01.552963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-02-25T02:11:01.552973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:11:01.552991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-02-25T02:11:01.553148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-02-25T02:11:01.560927Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:11:01.562592Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:11:01.563873Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T02:11:01.567727Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:11:01.567905Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T02:11:01.56801Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T02:11:01.568021Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T02:11:01.568125Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:11:01.570011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-02-25T02:11:01.570257Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-25T02:11:01.573506Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T02:11:01.573617Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-25T02:11:01.573652Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-25T02:11:01.573885Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:11:01.573937Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:11:01.654298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:11:01.654374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:11:01.654401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-02-25T02:11:01.654426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:11:01.654448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-25T02:11:01.654524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-02-25T02:11:01.654594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-25T02:11:01.65587Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:11:01.655886Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:11:01.656165Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:11:01.656231Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:11:01.656542Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:11:01.656571Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:11:01.657974Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:11:01.658308Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:11:01.658416Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:11:01.65846Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:11:01.66871Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-02-25T02:11:25.593629Z","caller":"traceutil/trace.go:171","msg":"trace[1390491456] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"101.278568ms","start":"2026-02-25T02:11:25.492329Z","end":"2026-02-25T02:11:25.593607Z","steps":["trace[1390491456] 'read index received' (duration: 101.116924ms)","trace[1390491456] 'applied index is now lower than readState.Index' (duration: 160.684µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:25.593686Z","caller":"traceutil/trace.go:171","msg":"trace[269708262] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"153.674782ms","start":"2026-02-25T02:11:25.439986Z","end":"2026-02-25T02:11:25.593661Z","steps":["trace[269708262] 'process raft request' (duration: 153.461447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:25.593799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.450982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:11:25.593886Z","caller":"traceutil/trace.go:171","msg":"trace[1812280623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"101.584115ms","start":"2026-02-25T02:11:25.492288Z","end":"2026-02-25T02:11:25.593872Z","steps":["trace[1812280623] 'agreement among raft nodes before linearized reading' (duration: 101.455352ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:30.520363Z","caller":"traceutil/trace.go:171","msg":"trace[2137993332] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"219.569093ms","start":"2026-02-25T02:11:30.300767Z","end":"2026-02-25T02:11:30.520336Z","steps":["trace[2137993332] 'read index received' (duration: 212.167934ms)","trace[2137993332] 'applied index is now lower than readState.Index' (duration: 7.399859ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:30.520547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.738688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-25T02:11:30.520629Z","caller":"traceutil/trace.go:171","msg":"trace[332266987] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:462; }","duration":"219.88437ms","start":"2026-02-25T02:11:30.300729Z","end":"2026-02-25T02:11:30.520613Z","steps":["trace[332266987] 'agreement among raft nodes before linearized reading' (duration: 219.693576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:31.205323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906747122948,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:11:31.222528Z","caller":"traceutil/trace.go:171","msg":"trace[401729101] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"517.798589ms","start":"2026-02-25T02:11:30.704704Z","end":"2026-02-25T02:11:31.222502Z","steps":["trace[401729101] 'read index received' (duration: 517.563084ms)","trace[401729101] 'applied index is now lower than readState.Index' (duration: 234.505µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:31.2226Z","caller":"traceutil/trace.go:171","msg":"trace[1926709039] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"694.693551ms","start":"2026-02-25T02:11:30.527871Z","end":"2026-02-25T02:11:31.222564Z","steps":["trace[1926709039] 'process raft request' (duration: 694.457535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:31.222732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.979393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T02:11:31.222784Z","caller":"traceutil/trace.go:171","msg":"trace[1827992268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"518.098076ms","start":"2026-02-25T02:11:30.70467Z","end":"2026-02-25T02:11:31.222768Z","steps":["trace[1827992268] 'agreement among raft nodes before linearized reading' (duration: 517.968713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:31.222832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:30.704653Z","time spent":"518.169697ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:11:31.223223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:30.527859Z","time spent":"694.824643ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:11:32.458115Z","caller":"traceutil/trace.go:171","msg":"trace[701023888] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"486.838614ms","start":"2026-02-25T02:11:31.971252Z","end":"2026-02-25T02:11:32.458091Z","steps":["trace[701023888] 'process raft request' (duration: 486.70108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:32.45828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:31.971225Z","time spent":"486.958457ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:11:35.852232Z","caller":"traceutil/trace.go:171","msg":"trace[1981870764] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"205.633325ms","start":"2026-02-25T02:11:35.646571Z","end":"2026-02-25T02:11:35.852205Z","steps":["trace[1981870764] 'process raft request' (duration: 202.490303ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:37.454732Z","caller":"traceutil/trace.go:171","msg":"trace[1268329289] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"199.222399ms","start":"2026-02-25T02:11:37.255479Z","end":"2026-02-25T02:11:37.454701Z","steps":["trace[1268329289] 'process raft request' (duration: 147.825438ms)","trace[1268329289] 'compare' (duration: 51.285629ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:37.915832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.089123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T02:11:37.915917Z","caller":"traceutil/trace.go:171","msg":"trace[302305222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:482; }","duration":"273.210965ms","start":"2026-02-25T02:11:37.642681Z","end":"2026-02-25T02:11:37.915892Z","steps":["trace[302305222] 'range keys from in-memory index tree' (duration: 272.868608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:37.916144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.44558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gksdr\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-25T02:11:37.916179Z","caller":"traceutil/trace.go:171","msg":"trace[91910870] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gksdr; range_end:; response_count:1; response_revision:482; }","duration":"215.521382ms","start":"2026-02-25T02:11:37.700649Z","end":"2026-02-25T02:11:37.91617Z","steps":["trace[91910870] 'range keys from in-memory index tree' (duration: 215.300746ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:38.279147Z","caller":"traceutil/trace.go:171","msg":"trace[287547132] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"358.127351ms","start":"2026-02-25T02:11:37.920995Z","end":"2026-02-25T02:11:38.279122Z","steps":["trace[287547132] 'process raft request' (duration: 357.991067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:38.279371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:37.92097Z","time spent":"358.327596ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:11:38.979763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906747123002,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:11:39.480387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906747123002,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:11:39.614582Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.335556353s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:11:39.615049Z","caller":"traceutil/trace.go:171","msg":"trace[340626611] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.693630392s","start":"2026-02-25T02:11:37.921395Z","end":"2026-02-25T02:11:39.615026Z","steps":["trace[340626611] 'process raft request' (duration: 1.693408467s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:39.615186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:37.921369Z","time spent":"1.693728115s","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:11:39.61519Z","caller":"traceutil/trace.go:171","msg":"trace[1637097557] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:499; }","duration":"1.136437515s","start":"2026-02-25T02:11:38.478725Z","end":"2026-02-25T02:11:39.615163Z","steps":["trace[1637097557] 'read index received' (duration: 1.136028786s)","trace[1637097557] 'applied index is now lower than readState.Index' (duration: 406.489µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:39.61531Z","caller":"traceutil/trace.go:171","msg":"trace[250093260] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.687540143s","start":"2026-02-25T02:11:37.927707Z","end":"2026-02-25T02:11:39.615247Z","steps":["trace[250093260] 'process raft request' (duration: 1.687259017s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:39.615457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136719532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T02:11:39.615503Z","caller":"traceutil/trace.go:171","msg":"trace[843828394] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:485; }","duration":"1.136819205s","start":"2026-02-25T02:11:38.478671Z","end":"2026-02-25T02:11:39.61549Z","steps":["trace[843828394] 'agreement among raft nodes before linearized reading' (duration: 1.136648981s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:39.615537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:38.478649Z","time spent":"1.136878346s","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:11:39.615506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.207922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:11:39.615631Z","caller":"traceutil/trace.go:171","msg":"trace[897561246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"114.379456ms","start":"2026-02-25T02:11:39.50122Z","end":"2026-02-25T02:11:39.6156Z","steps":["trace[897561246] 'agreement among raft nodes before linearized reading' (duration: 114.164581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:39.616032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:37.927684Z","time spent":"1.687714988s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:11:39.616878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.667655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T02:11:39.616935Z","caller":"traceutil/trace.go:171","msg":"trace[1290626859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:485; }","duration":"158.774348ms","start":"2026-02-25T02:11:39.458144Z","end":"2026-02-25T02:11:39.616918Z","steps":["trace[1290626859] 'agreement among raft nodes before linearized reading' (duration: 157.194382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:58.990001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906747123365,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:11:59.340692Z","caller":"traceutil/trace.go:171","msg":"trace[1415106854] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"850.989243ms","start":"2026-02-25T02:11:58.489678Z","end":"2026-02-25T02:11:59.340667Z","steps":["trace[1415106854] 'read index received' (duration: 850.796249ms)","trace[1415106854] 'applied index is now lower than readState.Index' (duration: 192.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:59.340753Z","caller":"traceutil/trace.go:171","msg":"trace[292855029] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"977.504213ms","start":"2026-02-25T02:11:58.363212Z","end":"2026-02-25T02:11:59.340716Z","steps":["trace[292855029] 'process raft request' (duration: 977.313029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:59.340938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:58.363189Z","time spent":"977.654016ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:11:59.340928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.201438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:11:59.34103Z","caller":"traceutil/trace.go:171","msg":"trace[1706833002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"851.366692ms","start":"2026-02-25T02:11:58.489635Z","end":"2026-02-25T02:11:59.341002Z","steps":["trace[1706833002] 'agreement among raft nodes before linearized reading' (duration: 851.146607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:59.341102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:58.489615Z","time spent":"851.472914ms","remote":"127.0.0.1:36740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:11:59.871027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.591916ms","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":"warn","ts":"2026-02-25T02:11:59.871051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.986801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T02:11:59.871119Z","caller":"traceutil/trace.go:171","msg":"trace[318510479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:672; }","duration":"340.727619ms","start":"2026-02-25T02:11:59.530371Z","end":"2026-02-25T02:11:59.871099Z","steps":["trace[318510479] 'range keys from in-memory index tree' (duration: 340.395191ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:59.871129Z","caller":"traceutil/trace.go:171","msg":"trace[1565204636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:672; }","duration":"161.109524ms","start":"2026-02-25T02:11:59.709998Z","end":"2026-02-25T02:11:59.871108Z","steps":["trace[1565204636] 'range keys from in-memory index tree' (duration: 160.824837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:59.871172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:59.530351Z","time spent":"340.810541ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:11:59.871045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.57277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:11:59.87126Z","caller":"traceutil/trace.go:171","msg":"trace[1147600485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:672; }","duration":"170.829365ms","start":"2026-02-25T02:11:59.700407Z","end":"2026-02-25T02:11:59.871236Z","steps":["trace[1147600485] 'range keys from in-memory index tree' (duration: 170.390605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:59.871425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.0584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T02:11:59.871455Z","caller":"traceutil/trace.go:171","msg":"trace[862096694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:672; }","duration":"155.110041ms","start":"2026-02-25T02:11:59.716338Z","end":"2026-02-25T02:11:59.871448Z","steps":["trace[862096694] 'range keys from in-memory index tree' (duration: 154.722783ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:00.092646Z","caller":"traceutil/trace.go:171","msg":"trace[1530479592] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"217.576008ms","start":"2026-02-25T02:11:59.875045Z","end":"2026-02-25T02:12:00.092621Z","steps":["trace[1530479592] 'process raft request' (duration: 217.365683ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:00.092905Z","caller":"traceutil/trace.go:171","msg":"trace[719544395] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"217.552908ms","start":"2026-02-25T02:11:59.875333Z","end":"2026-02-25T02:12:00.092886Z","steps":["trace[719544395] 'process raft request' (duration: 217.2394ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:00.092894Z","caller":"traceutil/trace.go:171","msg":"trace[135496705] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"216.747908ms","start":"2026-02-25T02:11:59.876129Z","end":"2026-02-25T02:12:00.092877Z","steps":["trace[135496705] 'process raft request' (duration: 216.672527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:00.694993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.310829ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T02:12:00.695073Z","caller":"traceutil/trace.go:171","msg":"trace[1958201188] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"206.511975ms","start":"2026-02-25T02:12:00.488547Z","end":"2026-02-25T02:12:00.695059Z","steps":["trace[1958201188] 'read index received' (duration: 54.611µs)","trace[1958201188] 'applied index is now lower than readState.Index' (duration: 206.456124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:00.695129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.582966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:00.695146Z","caller":"traceutil/trace.go:171","msg":"trace[442077621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"206.657388ms","start":"2026-02-25T02:12:00.488483Z","end":"2026-02-25T02:12:00.695141Z","steps":["trace[442077621] 'agreement among raft nodes before linearized reading' (duration: 206.611227ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:22.377821Z","caller":"traceutil/trace.go:171","msg":"trace[739800612] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"107.696534ms","start":"2026-02-25T02:12:22.270109Z","end":"2026-02-25T02:12:22.377805Z","steps":["trace[739800612] 'process raft request' (duration: 107.603462ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:24.028298Z","caller":"traceutil/trace.go:171","msg":"trace[1834448330] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"533.771243ms","start":"2026-02-25T02:12:23.49447Z","end":"2026-02-25T02:12:24.028241Z","steps":["trace[1834448330] 'process raft request' (duration: 533.363494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:24.028671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:23.494422Z","time spent":"533.981188ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:12:24.290187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.62346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:12:24.290321Z","caller":"traceutil/trace.go:171","msg":"trace[1594108583] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"256.722509ms","start":"2026-02-25T02:12:24.033578Z","end":"2026-02-25T02:12:24.290301Z","steps":["trace[1594108583] 'process raft request' (duration: 77.911485ms)","trace[1594108583] 'compare' (duration: 178.519627ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:24.556835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.517622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:12:24.556983Z","caller":"traceutil/trace.go:171","msg":"trace[566341936] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"175.384064ms","start":"2026-02-25T02:12:24.381576Z","end":"2026-02-25T02:12:24.55696Z","steps":["trace[566341936] 'read index received' (duration: 74.582574ms)","trace[566341936] 'applied index is now lower than readState.Index' (duration: 100.79962ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:24.557108Z","caller":"traceutil/trace.go:171","msg":"trace[1487058560] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"218.994862ms","start":"2026-02-25T02:12:24.338073Z","end":"2026-02-25T02:12:24.557068Z","steps":["trace[1487058560] 'process raft request' (duration: 118.06939ms)","trace[1487058560] 'compare' (duration: 100.154454ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:24.557297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.715263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T02:12:24.557355Z","caller":"traceutil/trace.go:171","msg":"trace[1951021345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"175.795904ms","start":"2026-02-25T02:12:24.381542Z","end":"2026-02-25T02:12:24.557338Z","steps":["trace[1951021345] 'agreement among raft nodes before linearized reading' (duration: 175.591969ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:25.135521Z","caller":"traceutil/trace.go:171","msg":"trace[970590270] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"134.932371ms","start":"2026-02-25T02:12:25.000567Z","end":"2026-02-25T02:12:25.135499Z","steps":["trace[970590270] 'process raft request' (duration: 99.001096ms)","trace[970590270] 'compare' (duration: 35.827463ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:25.69417Z","caller":"traceutil/trace.go:171","msg":"trace[1911591314] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"204.201989ms","start":"2026-02-25T02:12:25.48994Z","end":"2026-02-25T02:12:25.694142Z","steps":["trace[1911591314] 'read index received' (duration: 203.867761ms)","trace[1911591314] 'applied index is now lower than readState.Index' (duration: 332.788µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:25.694416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.461786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:25.694525Z","caller":"traceutil/trace.go:171","msg":"trace[1187372475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"204.62899ms","start":"2026-02-25T02:12:25.489878Z","end":"2026-02-25T02:12:25.694507Z","steps":["trace[1187372475] 'agreement among raft nodes before linearized reading' (duration: 204.472776ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:25.694785Z","caller":"traceutil/trace.go:171","msg":"trace[277222978] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"480.479814ms","start":"2026-02-25T02:12:25.213794Z","end":"2026-02-25T02:12:25.694274Z","steps":["trace[277222978] 'process raft request' (duration: 480.086184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:25.695079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:25.213759Z","time spent":"481.15909ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:12:26.237566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.102257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T02:12:26.237679Z","caller":"traceutil/trace.go:171","msg":"trace[217168355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"204.285601ms","start":"2026-02-25T02:12:26.03338Z","end":"2026-02-25T02:12:26.237666Z","steps":["trace[217168355] 'range keys from in-memory index tree' (duration: 203.881602ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:26.356339Z","caller":"traceutil/trace.go:171","msg":"trace[610295465] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"114.873194ms","start":"2026-02-25T02:12:26.24143Z","end":"2026-02-25T02:12:26.356304Z","steps":["trace[610295465] 'process raft request' (duration: 114.674559ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:26.538663Z","caller":"traceutil/trace.go:171","msg":"trace[1448711269] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"172.316581ms","start":"2026-02-25T02:12:26.366305Z","end":"2026-02-25T02:12:26.538622Z","steps":["trace[1448711269] 'process raft request' (duration: 101.645759ms)","trace[1448711269] 'compare' (duration: 70.438837ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:26.747423Z","caller":"traceutil/trace.go:171","msg":"trace[1602650979] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"145.7892ms","start":"2026-02-25T02:12:26.601605Z","end":"2026-02-25T02:12:26.747395Z","steps":["trace[1602650979] 'read index received' (duration: 96.114668ms)","trace[1602650979] 'applied index is now lower than readState.Index' (duration: 49.673432ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:26.747563Z","caller":"traceutil/trace.go:171","msg":"trace[2088380169] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"182.625616ms","start":"2026-02-25T02:12:26.564899Z","end":"2026-02-25T02:12:26.747525Z","steps":["trace[2088380169] 'process raft request' (duration: 132.912753ms)","trace[2088380169] 'compare' (duration: 49.449236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:26.747731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.096847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T02:12:26.74778Z","caller":"traceutil/trace.go:171","msg":"trace[1150421299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"146.186969ms","start":"2026-02-25T02:12:26.601578Z","end":"2026-02-25T02:12:26.747765Z","steps":["trace[1150421299] 'agreement among raft nodes before linearized reading' (duration: 145.985625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:40.278152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.28089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:12:40.278164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268512008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:40.278273Z","caller":"traceutil/trace.go:171","msg":"trace[302930729] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:839; }","duration":"1.268683253s","start":"2026-02-25T02:12:39.009566Z","end":"2026-02-25T02:12:40.278249Z","steps":["trace[302930729] 'count revisions from in-memory index tree' (duration: 1.268412186s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:40.278318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481454576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:12:40.278343Z","caller":"traceutil/trace.go:171","msg":"trace[1205592971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:839; }","duration":"1.481520208s","start":"2026-02-25T02:12:38.796816Z","end":"2026-02-25T02:12:40.278336Z","steps":["trace[1205592971] 'range keys from in-memory index tree' (duration: 1.481327644s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:40.278332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:39.009547Z","time spent":"1.268769254s","remote":"127.0.0.1:36834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:12:40.278363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:38.796794Z","time spent":"1.481564099s","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:12:40.278353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.974074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:40.278397Z","caller":"traceutil/trace.go:171","msg":"trace[809442564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"791.040305ms","start":"2026-02-25T02:12:39.487345Z","end":"2026-02-25T02:12:40.278385Z","steps":["trace[809442564] 'range keys from in-memory index tree' (duration: 790.921013ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:40.278215Z","caller":"traceutil/trace.go:171","msg":"trace[892742507] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:839; }","duration":"330.392573ms","start":"2026-02-25T02:12:39.947809Z","end":"2026-02-25T02:12:40.278202Z","steps":["trace[892742507] 'count revisions from in-memory index tree' (duration: 330.212268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:40.278431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:39.487331Z","time spent":"791.088637ms","remote":"127.0.0.1:36740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:12:40.278366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383763531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T02:12:40.278451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:39.94779Z","time spent":"330.654199ms","remote":"127.0.0.1:36952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:12:40.278461Z","caller":"traceutil/trace.go:171","msg":"trace[1496803409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:839; }","duration":"1.383888255s","start":"2026-02-25T02:12:38.894567Z","end":"2026-02-25T02:12:40.278456Z","steps":["trace[1496803409] 'range keys from in-memory index tree' (duration: 1.38368282s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:40.278474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:38.894549Z","time spent":"1.383920825s","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:12:40.558444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.130283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T02:12:40.558651Z","caller":"traceutil/trace.go:171","msg":"trace[371530331] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:871; }","duration":"278.931468ms","start":"2026-02-25T02:12:40.279687Z","end":"2026-02-25T02:12:40.558618Z","steps":["trace[371530331] 'read index received' (duration: 62.871736ms)","trace[371530331] 'applied index is now lower than readState.Index' (duration: 216.055212ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:40.558747Z","caller":"traceutil/trace.go:171","msg":"trace[1305242406] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"277.134916ms","start":"2026-02-25T02:12:40.281593Z","end":"2026-02-25T02:12:40.558728Z","steps":["trace[1305242406] 'process raft request' (duration: 277.074404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:40.558849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.129423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:40.558934Z","caller":"traceutil/trace.go:171","msg":"trace[1978423114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:841; }","duration":"279.239415ms","start":"2026-02-25T02:12:40.279674Z","end":"2026-02-25T02:12:40.558913Z","steps":["trace[1978423114] 'agreement among raft nodes before linearized reading' (duration: 279.03101ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:40.559036Z","caller":"traceutil/trace.go:171","msg":"trace[1228097139] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"277.689829ms","start":"2026-02-25T02:12:40.281336Z","end":"2026-02-25T02:12:40.559026Z","steps":["trace[1228097139] 'process raft request' (duration: 277.223098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:40.558894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.429991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T02:12:40.55915Z","caller":"traceutil/trace.go:171","msg":"trace[1812851139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:841; }","duration":"137.732228ms","start":"2026-02-25T02:12:40.42141Z","end":"2026-02-25T02:12:40.559142Z","steps":["trace[1812851139] 'agreement among raft nodes before linearized reading' (duration: 137.335728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:56.944965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.519007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:12:56.945181Z","caller":"traceutil/trace.go:171","msg":"trace[1178330272] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:941; }","duration":"269.998596ms","start":"2026-02-25T02:12:56.675166Z","end":"2026-02-25T02:12:56.945164Z","steps":["trace[1178330272] 'read index received' (duration: 24.977535ms)","trace[1178330272] 'applied index is now lower than readState.Index' (duration: 245.020181ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:56.945318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.14721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T02:12:56.945348Z","caller":"traceutil/trace.go:171","msg":"trace[1149279904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:908; }","duration":"270.194411ms","start":"2026-02-25T02:12:56.675145Z","end":"2026-02-25T02:12:56.94534Z","steps":["trace[1149279904] 'agreement among raft nodes before linearized reading' (duration: 270.073108ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:56.945407Z","caller":"traceutil/trace.go:171","msg":"trace[1163701269] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"272.012183ms","start":"2026-02-25T02:12:56.673366Z","end":"2026-02-25T02:12:56.945378Z","steps":["trace[1163701269] 'process raft request' (duration: 124.978844ms)","trace[1163701269] 'compare' (duration: 146.376664ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:56.945481Z","caller":"traceutil/trace.go:171","msg":"trace[735873209] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"271.468281ms","start":"2026-02-25T02:12:56.674Z","end":"2026-02-25T02:12:56.945468Z","steps":["trace[735873209] 'process raft request' (duration: 271.067501ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:56.945489Z","caller":"traceutil/trace.go:171","msg":"trace[1001851150] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"270.437147ms","start":"2026-02-25T02:12:56.675034Z","end":"2026-02-25T02:12:56.945471Z","steps":["trace[1001851150] 'process raft request' (duration: 270.093668ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:57.100619Z","caller":"traceutil/trace.go:171","msg":"trace[462335906] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"151.735171ms","start":"2026-02-25T02:12:56.948856Z","end":"2026-02-25T02:12:57.100591Z","steps":["trace[462335906] 'process raft request' (duration: 109.348783ms)","trace[462335906] 'compare' (duration: 42.117292ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:59.26098Z","caller":"traceutil/trace.go:171","msg":"trace[1100387334] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"555.405898ms","start":"2026-02-25T02:12:58.705558Z","end":"2026-02-25T02:12:59.260964Z","steps":["trace[1100387334] 'process raft request' (duration: 555.298995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:59.261094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:58.705542Z","time spent":"555.485719ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:12:59.341147Z","caller":"traceutil/trace.go:171","msg":"trace[322659369] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:945; }","duration":"392.381267ms","start":"2026-02-25T02:12:58.94874Z","end":"2026-02-25T02:12:59.341121Z","steps":["trace[322659369] 'read index received' (duration: 312.153268ms)","trace[322659369] 'applied index is now lower than readState.Index' (duration: 80.226839ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:59.341295Z","caller":"traceutil/trace.go:171","msg":"trace[1725783587] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"401.857293ms","start":"2026-02-25T02:12:58.939384Z","end":"2026-02-25T02:12:59.341241Z","steps":["trace[1725783587] 'process raft request' (duration: 401.599757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:59.341423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.658954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T02:12:59.341469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:58.939365Z","time spent":"402.013937ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:12:59.341479Z","caller":"traceutil/trace.go:171","msg":"trace[1984162454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"392.761867ms","start":"2026-02-25T02:12:58.948701Z","end":"2026-02-25T02:12:59.341463Z","steps":["trace[1984162454] 'agreement among raft nodes before linearized reading' (duration: 392.574632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:59.341525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.450259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T02:12:59.341528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:58.948683Z","time spent":"392.835628ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:12:59.34156Z","caller":"traceutil/trace.go:171","msg":"trace[1197164751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:911; }","duration":"392.49398ms","start":"2026-02-25T02:12:58.949055Z","end":"2026-02-25T02:12:59.341549Z","steps":["trace[1197164751] 'agreement among raft nodes before linearized reading' (duration: 392.399377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:59.341585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:58.949051Z","time spent":"392.529031ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:12:59.34155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.253064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:12:59.341616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.793378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:12:59.341668Z","caller":"traceutil/trace.go:171","msg":"trace[1452938288] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:911; }","duration":"255.398487ms","start":"2026-02-25T02:12:59.086245Z","end":"2026-02-25T02:12:59.341643Z","steps":["trace[1452938288] 'agreement among raft nodes before linearized reading' (duration: 255.202212ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:59.341715Z","caller":"traceutil/trace.go:171","msg":"trace[856556444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"392.87565ms","start":"2026-02-25T02:12:58.948791Z","end":"2026-02-25T02:12:59.341667Z","steps":["trace[856556444] 'agreement among raft nodes before linearized reading' (duration: 392.618663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:59.341789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:58.948786Z","time spent":"392.988852ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:12:59.341911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.270982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T02:12:59.34201Z","caller":"traceutil/trace.go:171","msg":"trace[991255415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"236.411585ms","start":"2026-02-25T02:12:59.105579Z","end":"2026-02-25T02:12:59.341991Z","steps":["trace[991255415] 'agreement among raft nodes before linearized reading' (duration: 235.963425ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:59.514108Z","caller":"traceutil/trace.go:171","msg":"trace[2140450583] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"168.653564ms","start":"2026-02-25T02:12:59.345427Z","end":"2026-02-25T02:12:59.51408Z","steps":["trace[2140450583] 'process raft request' (duration: 168.483029ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:59.562646Z","caller":"traceutil/trace.go:171","msg":"trace[874005825] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"216.549174ms","start":"2026-02-25T02:12:59.346078Z","end":"2026-02-25T02:12:59.562627Z","steps":["trace[874005825] 'process raft request' (duration: 216.435321ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:59.563001Z","caller":"traceutil/trace.go:171","msg":"trace[213354765] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"217.080456ms","start":"2026-02-25T02:12:59.345903Z","end":"2026-02-25T02:12:59.562983Z","steps":["trace[213354765] 'process raft request' (duration: 216.512663ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:59.563178Z","caller":"traceutil/trace.go:171","msg":"trace[1631241170] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"216.531494ms","start":"2026-02-25T02:12:59.346628Z","end":"2026-02-25T02:12:59.563159Z","steps":["trace[1631241170] 'process raft request' (duration: 215.95158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:01.24698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.814319ms","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-02-25T02:13:01.247064Z","caller":"traceutil/trace.go:171","msg":"trace[1645852435] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:917; }","duration":"714.964782ms","start":"2026-02-25T02:13:00.532082Z","end":"2026-02-25T02:13:01.247047Z","steps":["trace[1645852435] 'range keys from in-memory index tree' (duration: 714.751427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:01.247111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:00.532067Z","time spent":"715.035114ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T02:13:01.247211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.941053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:13:01.247282Z","caller":"traceutil/trace.go:171","msg":"trace[2016438710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"734.030246ms","start":"2026-02-25T02:13:00.513221Z","end":"2026-02-25T02:13:01.247251Z","steps":["trace[2016438710] 'range keys from in-memory index tree' (duration: 733.892082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:01.24731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:00.513208Z","time spent":"734.092877ms","remote":"127.0.0.1:36740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:13:01.665541Z","caller":"traceutil/trace.go:171","msg":"trace[1792162951] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"400.921715ms","start":"2026-02-25T02:13:01.264594Z","end":"2026-02-25T02:13:01.665515Z","steps":["trace[1792162951] 'read index received' (duration: 400.445225ms)","trace[1792162951] 'applied index is now lower than readState.Index' (duration: 475.26µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:01.665665Z","caller":"traceutil/trace.go:171","msg":"trace[1807154829] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"412.560693ms","start":"2026-02-25T02:13:01.253078Z","end":"2026-02-25T02:13:01.665639Z","steps":["trace[1807154829] 'process raft request' (duration: 412.241906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:01.665799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:01.253056Z","time spent":"412.674336ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:13:01.665826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.208881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T02:13:01.665865Z","caller":"traceutil/trace.go:171","msg":"trace[115696724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"401.294004ms","start":"2026-02-25T02:13:01.26456Z","end":"2026-02-25T02:13:01.665854Z","steps":["trace[115696724] 'agreement among raft nodes before linearized reading' (duration: 401.103189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:01.665875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.827847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T02:13:01.665902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:01.26454Z","time spent":"401.354804ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T02:13:01.665948Z","caller":"traceutil/trace.go:171","msg":"trace[279540434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"146.96597ms","start":"2026-02-25T02:13:01.518963Z","end":"2026-02-25T02:13:01.665929Z","steps":["trace[279540434] 'agreement among raft nodes before linearized reading' (duration: 146.752396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:01.969132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.921803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:13:01.969205Z","caller":"traceutil/trace.go:171","msg":"trace[1073074268] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"293.366359ms","start":"2026-02-25T02:13:01.675826Z","end":"2026-02-25T02:13:01.969193Z","steps":["trace[1073074268] 'read index received' (duration: 181.330473ms)","trace[1073074268] 'applied index is now lower than readState.Index' (duration: 112.035116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:01.96934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.510602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-25T02:13:01.969359Z","caller":"traceutil/trace.go:171","msg":"trace[1564778699] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:919; }","duration":"293.552843ms","start":"2026-02-25T02:13:01.6758Z","end":"2026-02-25T02:13:01.969352Z","steps":["trace[1564778699] 'agreement among raft nodes before linearized reading' (duration: 293.42524ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:01.969412Z","caller":"traceutil/trace.go:171","msg":"trace[732890974] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"296.763774ms","start":"2026-02-25T02:13:01.672622Z","end":"2026-02-25T02:13:01.969385Z","steps":["trace[732890974] 'process raft request' (duration: 184.521814ms)","trace[732890974] 'compare' (duration: 111.827051ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:01.988738Z","caller":"traceutil/trace.go:171","msg":"trace[1449484624] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"286.436865ms","start":"2026-02-25T02:13:01.702286Z","end":"2026-02-25T02:13:01.988723Z","steps":["trace[1449484624] 'process raft request' (duration: 286.402304ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:01.989161Z","caller":"traceutil/trace.go:171","msg":"trace[664771129] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"311.816069ms","start":"2026-02-25T02:13:01.677331Z","end":"2026-02-25T02:13:01.989147Z","steps":["trace[664771129] 'process raft request' (duration: 311.268006ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:01.989327Z","caller":"traceutil/trace.go:171","msg":"trace[1886561832] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"311.144483ms","start":"2026-02-25T02:13:01.678173Z","end":"2026-02-25T02:13:01.989317Z","steps":["trace[1886561832] 'process raft request' (duration: 310.486969ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:01.989451Z","caller":"traceutil/trace.go:171","msg":"trace[195329033] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"312.217027ms","start":"2026-02-25T02:13:01.677229Z","end":"2026-02-25T02:13:01.989446Z","steps":["trace[195329033] 'process raft request' (duration: 311.294297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:01.989489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:01.67722Z","time spent":"312.244648ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:01.989477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:01.678139Z","time spent":"311.241246ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:01.989564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:01.677314Z","time spent":"312.012803ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:01.989258Z","caller":"traceutil/trace.go:171","msg":"trace[1088639882] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"311.202454ms","start":"2026-02-25T02:13:01.677986Z","end":"2026-02-25T02:13:01.989188Z","steps":["trace[1088639882] 'process raft request' (duration: 310.647742ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:01.989345Z","caller":"traceutil/trace.go:171","msg":"trace[409278401] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"313.355653ms","start":"2026-02-25T02:13:01.67598Z","end":"2026-02-25T02:13:01.989335Z","steps":["trace[409278401] 'process raft request' (duration: 312.470803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:01.989722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:01.677976Z","time spent":"311.724416ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:01.989769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:01.67597Z","time spent":"313.769972ms","remote":"127.0.0.1:36786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:13:09.940147Z","caller":"traceutil/trace.go:171","msg":"trace[1639427437] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"449.709587ms","start":"2026-02-25T02:13:09.490405Z","end":"2026-02-25T02:13:09.940115Z","steps":["trace[1639427437] 'read index received' (duration: 449.434271ms)","trace[1639427437] 'applied index is now lower than readState.Index' (duration: 273.786µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:09.940339Z","caller":"traceutil/trace.go:171","msg":"trace[912183948] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"536.677737ms","start":"2026-02-25T02:13:09.40365Z","end":"2026-02-25T02:13:09.940328Z","steps":["trace[912183948] 'process raft request' (duration: 536.252047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:09.940437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.015634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:13:09.940486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:09.403634Z","time spent":"536.742599ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:09.940507Z","caller":"traceutil/trace.go:171","msg":"trace[180279725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"450.127916ms","start":"2026-02-25T02:13:09.490364Z","end":"2026-02-25T02:13:09.940492Z","steps":["trace[180279725] 'agreement among raft nodes before linearized reading' (duration: 450.011834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:09.940546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:09.490353Z","time spent":"450.185238ms","remote":"127.0.0.1:36740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:13:10.285858Z","caller":"traceutil/trace.go:171","msg":"trace[1124249627] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"345.562986ms","start":"2026-02-25T02:13:09.940274Z","end":"2026-02-25T02:13:10.285837Z","steps":["trace[1124249627] 'read index received' (duration: 302.085041ms)","trace[1124249627] 'applied index is now lower than readState.Index' (duration: 43.477125ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:10.285943Z","caller":"traceutil/trace.go:171","msg":"trace[736678323] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"724.556495ms","start":"2026-02-25T02:13:09.561376Z","end":"2026-02-25T02:13:10.285932Z","steps":["trace[736678323] 'process raft request' (duration: 681.161892ms)","trace[736678323] 'compare' (duration: 43.198829ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:10.286025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:09.56136Z","time spent":"724.605816ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:10.286137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.392063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:13:10.28627Z","caller":"traceutil/trace.go:171","msg":"trace[522675847] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:954; }","duration":"359.521316ms","start":"2026-02-25T02:13:09.926706Z","end":"2026-02-25T02:13:10.286227Z","steps":["trace[522675847] 'agreement among raft nodes before linearized reading' (duration: 359.28393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:10.28633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:09.926645Z","time spent":"359.668319ms","remote":"127.0.0.1:37080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:13:10.291427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.871492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:13:10.291472Z","caller":"traceutil/trace.go:171","msg":"trace[1106708644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"349.954404ms","start":"2026-02-25T02:13:09.941507Z","end":"2026-02-25T02:13:10.291461Z","steps":["trace[1106708644] 'agreement among raft nodes before linearized reading' (duration: 349.883682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:10.291496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:09.941482Z","time spent":"350.009045ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:13:10.291664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.546233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:13:10.291686Z","caller":"traceutil/trace.go:171","msg":"trace[613323916] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:954; }","duration":"183.613674ms","start":"2026-02-25T02:13:10.108064Z","end":"2026-02-25T02:13:10.291678Z","steps":["trace[613323916] 'agreement among raft nodes before linearized reading' (duration: 183.559003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:10.291857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.114455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T02:13:10.291875Z","caller":"traceutil/trace.go:171","msg":"trace[1927190718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"225.143396ms","start":"2026-02-25T02:13:10.066726Z","end":"2026-02-25T02:13:10.291869Z","steps":["trace[1927190718] 'agreement among raft nodes before linearized reading' (duration: 225.059554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:10.291997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.911582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T02:13:10.292014Z","caller":"traceutil/trace.go:171","msg":"trace[1533515396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"225.938713ms","start":"2026-02-25T02:13:10.06607Z","end":"2026-02-25T02:13:10.292008Z","steps":["trace[1533515396] 'agreement among raft nodes before linearized reading' (duration: 225.883991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:10.292133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.201888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T02:13:10.292151Z","caller":"traceutil/trace.go:171","msg":"trace[147116358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"226.23019ms","start":"2026-02-25T02:13:10.065915Z","end":"2026-02-25T02:13:10.292145Z","steps":["trace[147116358] 'agreement among raft nodes before linearized reading' (duration: 226.181449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:10.292262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.351952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T02:13:10.292278Z","caller":"traceutil/trace.go:171","msg":"trace[1615017812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"226.384493ms","start":"2026-02-25T02:13:10.065889Z","end":"2026-02-25T02:13:10.292273Z","steps":["trace[1615017812] 'agreement among raft nodes before linearized reading' (duration: 226.333771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:10.292376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.578687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T02:13:10.292392Z","caller":"traceutil/trace.go:171","msg":"trace[1144842828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"226.654619ms","start":"2026-02-25T02:13:10.065733Z","end":"2026-02-25T02:13:10.292387Z","steps":["trace[1144842828] 'agreement among raft nodes before linearized reading' (duration: 226.610608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:10.292485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.705132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:13:10.292501Z","caller":"traceutil/trace.go:171","msg":"trace[1840036231] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:954; }","duration":"342.748494ms","start":"2026-02-25T02:13:09.949748Z","end":"2026-02-25T02:13:10.292497Z","steps":["trace[1840036231] 'agreement among raft nodes before linearized reading' (duration: 342.706723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:10.292519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:09.949733Z","time spent":"342.780844ms","remote":"127.0.0.1:36910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:13:12.596305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.882969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:13:12.596572Z","caller":"traceutil/trace.go:171","msg":"trace[1277211681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"106.131355ms","start":"2026-02-25T02:13:12.490344Z","end":"2026-02-25T02:13:12.596475Z","steps":["trace[1277211681] 'range keys from in-memory index tree' (duration: 105.792647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:22.419593Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906747124840,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:14:22.574857Z","caller":"traceutil/trace.go:171","msg":"trace[1958996202] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"656.309681ms","start":"2026-02-25T02:14:21.918521Z","end":"2026-02-25T02:14:22.574831Z","steps":["trace[1958996202] 'read index received' (duration: 656.056935ms)","trace[1958996202] 'applied index is now lower than readState.Index' (duration: 251.686µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:22.575006Z","caller":"traceutil/trace.go:171","msg":"trace[245970217] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"773.463247ms","start":"2026-02-25T02:14:21.801529Z","end":"2026-02-25T02:14:22.574992Z","steps":["trace[245970217] 'process raft request' (duration: 773.148889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:22.575124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:21.801503Z","time spent":"773.549768ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:22.575452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.931906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-25T02:14:22.575499Z","caller":"traceutil/trace.go:171","msg":"trace[1061040530] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1270; }","duration":"657.019279ms","start":"2026-02-25T02:14:21.918466Z","end":"2026-02-25T02:14:22.575485Z","steps":["trace[1061040530] 'agreement among raft nodes before linearized reading' (duration: 656.759042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:22.575537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:21.91845Z","time spent":"657.076859ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T02:15:25.13Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906747125501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:25.137632Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.120838556s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:15:25.137926Z","caller":"traceutil/trace.go:171","msg":"trace[1641266374] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"1.121218354s","start":"2026-02-25T02:15:24.016687Z","end":"2026-02-25T02:15:25.137905Z","steps":["trace[1641266374] 'process raft request' (duration: 1.121079551s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.138054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:24.016659Z","time spent":"1.121321937s","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:25.138603Z","caller":"traceutil/trace.go:171","msg":"trace[1091992009] linearizableReadLoop","detail":"{readStateIndex:1590; appliedIndex:1588; }","duration":"645.244644ms","start":"2026-02-25T02:15:24.493337Z","end":"2026-02-25T02:15:25.138582Z","steps":["trace[1091992009] 'read index received' (duration: 644.424155ms)","trace[1091992009] 'applied index is now lower than readState.Index' (duration: 819.579µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:25.138612Z","caller":"traceutil/trace.go:171","msg":"trace[1355175918] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"1.062613988s","start":"2026-02-25T02:15:24.075987Z","end":"2026-02-25T02:15:25.138601Z","steps":["trace[1355175918] 'process raft request' (duration: 1.062474805s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.138659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:24.075964Z","time spent":"1.06267539s","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:25.138726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.358846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:15:25.138756Z","caller":"traceutil/trace.go:171","msg":"trace[546974345] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1525; }","duration":"645.461179ms","start":"2026-02-25T02:15:24.493286Z","end":"2026-02-25T02:15:25.138747Z","steps":["trace[546974345] 'agreement among raft nodes before linearized reading' (duration: 645.375227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.13878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:24.493263Z","time spent":"645.51052ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:15:26.274176Z","caller":"traceutil/trace.go:171","msg":"trace[628974548] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"132.485406ms","start":"2026-02-25T02:15:26.141654Z","end":"2026-02-25T02:15:26.27414Z","steps":["trace[628974548] 'process raft request' (duration: 132.332213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:25.820409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.605855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:25.820525Z","caller":"traceutil/trace.go:171","msg":"trace[158306966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1801; }","duration":"332.753708ms","start":"2026-02-25T02:16:25.487748Z","end":"2026-02-25T02:16:25.820502Z","steps":["trace[158306966] 'range keys from in-memory index tree' (duration: 332.531103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:25.820568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:25.487739Z","time spent":"332.82052ms","remote":"127.0.0.1:36740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:16:25.820533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.116019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-25T02:16:25.820663Z","caller":"traceutil/trace.go:171","msg":"trace[206440327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1801; }","duration":"345.326544ms","start":"2026-02-25T02:16:25.475308Z","end":"2026-02-25T02:16:25.820635Z","steps":["trace[206440327] 'range keys from in-memory index tree' (duration: 344.847582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:25.820664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.329568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-02-25T02:16:25.820723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:25.475259Z","time spent":"345.450706ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T02:16:25.820741Z","caller":"traceutil/trace.go:171","msg":"trace[1506378725] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1801; }","duration":"559.455921ms","start":"2026-02-25T02:16:25.261264Z","end":"2026-02-25T02:16:25.82072Z","steps":["trace[1506378725] 'range keys from in-memory index tree' (duration: 559.120883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:25.820779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:25.261244Z","time spent":"559.528403ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T02:16:25.821047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.19046ms","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:533"} {"level":"info","ts":"2026-02-25T02:16:25.821089Z","caller":"traceutil/trace.go:171","msg":"trace[1413299035] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1801; }","duration":"345.253252ms","start":"2026-02-25T02:16:25.475823Z","end":"2026-02-25T02:16:25.821077Z","steps":["trace[1413299035] 'range keys from in-memory index tree' (duration: 344.911684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:25.821125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:25.475812Z","time spent":"345.306623ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-25T02:16:25.821225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.186837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-25T02:16:25.821256Z","caller":"traceutil/trace.go:171","msg":"trace[950770911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1801; }","duration":"344.236248ms","start":"2026-02-25T02:16:25.477013Z","end":"2026-02-25T02:16:25.821249Z","steps":["trace[950770911] 'range keys from in-memory index tree' (duration: 344.056603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:25.821277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:25.477004Z","time spent":"344.268599ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:16:25.821372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.979866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-25T02:16:25.821317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.421706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-25T02:16:25.82154Z","caller":"traceutil/trace.go:171","msg":"trace[1057351446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1801; }","duration":"342.707432ms","start":"2026-02-25T02:16:25.478811Z","end":"2026-02-25T02:16:25.821519Z","steps":["trace[1057351446] 'range keys from in-memory index tree' (duration: 342.131139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:25.82164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:25.478791Z","time spent":"342.833745ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T02:16:25.821428Z","caller":"traceutil/trace.go:171","msg":"trace[1188216097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1801; }","duration":"348.070618ms","start":"2026-02-25T02:16:25.473342Z","end":"2026-02-25T02:16:25.821412Z","steps":["trace[1188216097] 'range keys from in-memory index tree' (duration: 347.848023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:25.821689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.554476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-25T02:16:25.821801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:25.47328Z","time spent":"348.510339ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:16:25.821811Z","caller":"traceutil/trace.go:171","msg":"trace[533878760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1801; }","duration":"347.71758ms","start":"2026-02-25T02:16:25.474076Z","end":"2026-02-25T02:16:25.821794Z","steps":["trace[533878760] 'range keys from in-memory index tree' (duration: 347.110165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:25.82185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:25.474065Z","time spent":"347.775051ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}