{"level":"warn","ts":"2026-02-27T01:24:53.168868Z","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-27T01:24:53.168946Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-27T01:24:53.169011Z","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-27T01:24:53.16902Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-27T01:24:53.16904Z","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-27T01:24:53.16967Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-02-27T01:24:53.16974Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-27T01:24:53.176985Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.05728ms"} {"level":"info","ts":"2026-02-27T01:24:53.18338Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-02-27T01:24:53.183479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T01:24:53.183506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-02-27T01:24:53.18352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T01:24:53.183548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-02-27T01:24:53.183612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-02-27T01:24:53.191781Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T01:24:53.193162Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T01:24:53.194576Z","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-27T01:24:53.197247Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T01:24:53.197874Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T01:24:53.198476Z","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-27T01:24:53.19859Z","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-27T01:24:53.202774Z","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-27T01:24:53.203238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-02-27T01:24:53.209311Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-27T01:24:53.209871Z","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-27T01:24:53.20997Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-27T01:24:53.210014Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-27T01:24:53.210075Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T01:24:53.210104Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T01:24:54.083812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T01:24:54.083873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T01:24:54.083894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-02-27T01:24:54.083919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-02-27T01:24:54.083949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-27T01:24:54.083964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-02-27T01:24:54.083977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-27T01:24:54.084972Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T01:24:54.084983Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T01:24:54.08504Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:24:54.085055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T01:24:54.086672Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:24:54.08679Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:24:54.086819Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:24:54.087016Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T01:24:54.087061Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T01:24:54.089044Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T01:24:54.089341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-02-27T01:25:18.207079Z","caller":"traceutil/trace.go:171","msg":"trace[1231562092] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"211.810416ms","start":"2026-02-27T01:25:17.99525Z","end":"2026-02-27T01:25:18.207061Z","steps":["trace[1231562092] 'process raft request' (duration: 211.713964ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:24.523907Z","caller":"traceutil/trace.go:171","msg":"trace[1624055111] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"289.050103ms","start":"2026-02-27T01:25:24.234831Z","end":"2026-02-27T01:25:24.523881Z","steps":["trace[1624055111] 'process raft request' (duration: 288.899829ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:32.12148Z","caller":"traceutil/trace.go:171","msg":"trace[2028960617] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"315.508747ms","start":"2026-02-27T01:25:31.805951Z","end":"2026-02-27T01:25:32.12146Z","steps":["trace[2028960617] 'read index received' (duration: 315.374124ms)","trace[2028960617] 'applied index is now lower than readState.Index' (duration: 133.583µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:32.121527Z","caller":"traceutil/trace.go:171","msg":"trace[1086089362] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"343.353911ms","start":"2026-02-27T01:25:31.778141Z","end":"2026-02-27T01:25:32.121495Z","steps":["trace[1086089362] 'process raft request' (duration: 343.188687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:32.121675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.709748ms","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-27T01:25:32.121732Z","caller":"traceutil/trace.go:171","msg":"trace[1635792127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"214.829091ms","start":"2026-02-27T01:25:31.906895Z","end":"2026-02-27T01:25:32.121724Z","steps":["trace[1635792127] 'agreement among raft nodes before linearized reading' (duration: 214.684618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:32.121832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.859644ms","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-27T01:25:32.121918Z","caller":"traceutil/trace.go:171","msg":"trace[1897346262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"316.003037ms","start":"2026-02-27T01:25:31.80589Z","end":"2026-02-27T01:25:32.121893Z","steps":["trace[1897346262] 'agreement among raft nodes before linearized reading' (duration: 315.721481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:32.121963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:31.805824Z","time spent":"316.13093ms","remote":"127.0.0.1:48572","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\" "} {"level":"warn","ts":"2026-02-27T01:25:32.122169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:31.77813Z","time spent":"343.470293ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:32.863435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.986052ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T01:25:32.863531Z","caller":"traceutil/trace.go:171","msg":"trace[1435550309] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"362.897327ms","start":"2026-02-27T01:25:32.500617Z","end":"2026-02-27T01:25:32.863515Z","steps":["trace[1435550309] 'read index received' (duration: 28.761µs)","trace[1435550309] 'applied index is now lower than readState.Index' (duration: 362.867176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:32.863632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.999979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:32.863684Z","caller":"traceutil/trace.go:171","msg":"trace[2090802903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"363.093781ms","start":"2026-02-27T01:25:32.500578Z","end":"2026-02-27T01:25:32.863672Z","steps":["trace[2090802903] 'agreement among raft nodes before linearized reading' (duration: 363.002369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:32.863708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:32.500559Z","time spent":"363.144292ms","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:25:32.863725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.832208ms","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-27T01:25:32.863756Z","caller":"traceutil/trace.go:171","msg":"trace[431204455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"213.90853ms","start":"2026-02-27T01:25:32.649839Z","end":"2026-02-27T01:25:32.863747Z","steps":["trace[431204455] 'agreement among raft nodes before linearized reading' (duration: 213.767267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:40.655056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313094170313442356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:25:41.155944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313094170313442356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:25:41.52738Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.392240022s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T01:25:41.597749Z","caller":"traceutil/trace.go:171","msg":"trace[1484290561] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"1.443232268s","start":"2026-02-27T01:25:40.154492Z","end":"2026-02-27T01:25:41.597725Z","steps":["trace[1484290561] 'read index received' (duration: 1.373138274s)","trace[1484290561] 'applied index is now lower than readState.Index' (duration: 70.092944ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:41.597969Z","caller":"traceutil/trace.go:171","msg":"trace[219700735] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.462891898s","start":"2026-02-27T01:25:40.135045Z","end":"2026-02-27T01:25:41.597936Z","steps":["trace[219700735] 'process raft request' (duration: 1.392579179s)","trace[219700735] 'compare' (duration: 69.89836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:41.598055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443539245s","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-27T01:25:41.598118Z","caller":"traceutil/trace.go:171","msg":"trace[25048765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"1.443650197s","start":"2026-02-27T01:25:40.15445Z","end":"2026-02-27T01:25:41.5981Z","steps":["trace[25048765] 'agreement among raft nodes before linearized reading' (duration: 1.443436593s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.598133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436131717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-27T01:25:41.598162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.154428Z","time spent":"1.443725898s","remote":"127.0.0.1:48572","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-27T01:25:41.598173Z","caller":"traceutil/trace.go:171","msg":"trace[158791173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.436202259s","start":"2026-02-27T01:25:40.16196Z","end":"2026-02-27T01:25:41.598162Z","steps":["trace[158791173] 'agreement among raft nodes before linearized reading' (duration: 1.436052296s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.598178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.135032Z","time spent":"1.46300041s","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4441,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:41.598206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.161953Z","time spent":"1.4362449s","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T01:25:41.598396Z","caller":"traceutil/trace.go:171","msg":"trace[441180746] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"579.404343ms","start":"2026-02-27T01:25:41.018979Z","end":"2026-02-27T01:25:41.598383Z","steps":["trace[441180746] 'process raft request' (duration: 578.695918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.598485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:41.018951Z","time spent":"579.480274ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T01:25:41.598644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293475516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9kflz\" ","response":"range_response_count:1 size:18512"} {"level":"info","ts":"2026-02-27T01:25:41.598677Z","caller":"traceutil/trace.go:171","msg":"trace[1839817812] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9kflz; range_end:; response_count:1; response_revision:627; }","duration":"1.293595519s","start":"2026-02-27T01:25:40.305072Z","end":"2026-02-27T01:25:41.598668Z","steps":["trace[1839817812] 'agreement among raft nodes before linearized reading' (duration: 1.293484737s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.598688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264387235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"warn","ts":"2026-02-27T01:25:41.598702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.305046Z","time spent":"1.29364941s","remote":"127.0.0.1:48476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18536,"request content":"key:\"/registry/pods/kube-system/cilium-9kflz\" "} {"level":"info","ts":"2026-02-27T01:25:41.598725Z","caller":"traceutil/trace.go:171","msg":"trace[1206850969] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:627; }","duration":"1.264474327s","start":"2026-02-27T01:25:40.334239Z","end":"2026-02-27T01:25:41.598713Z","steps":["trace[1206850969] 'agreement among raft nodes before linearized reading' (duration: 1.264248122s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.59875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.334212Z","time spent":"1.264533369s","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188276,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-27T01:25:41.598892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436751421s","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-27T01:25:41.598936Z","caller":"traceutil/trace.go:171","msg":"trace[1566673060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"1.436779871s","start":"2026-02-27T01:25:40.162133Z","end":"2026-02-27T01:25:41.598912Z","steps":["trace[1566673060] 'agreement among raft nodes before linearized reading' (duration: 1.436693409s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.598968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.162115Z","time spent":"1.436846352s","remote":"127.0.0.1:48572","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-27T01:25:41.598995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097428927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T01:25:41.598994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.348257ms","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-27T01:25:41.599021Z","caller":"traceutil/trace.go:171","msg":"trace[668175110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"1.097491938s","start":"2026-02-27T01:25:40.501519Z","end":"2026-02-27T01:25:41.599011Z","steps":["trace[668175110] 'agreement among raft nodes before linearized reading' (duration: 1.097450387s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.599057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.501476Z","time spent":"1.0975761s","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T01:25:41.599051Z","caller":"traceutil/trace.go:171","msg":"trace[388227210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"667.441229ms","start":"2026-02-27T01:25:40.931594Z","end":"2026-02-27T01:25:41.599035Z","steps":["trace[388227210] 'agreement among raft nodes before linearized reading' (duration: 667.289456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.599094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.931572Z","time spent":"667.511051ms","remote":"127.0.0.1:48572","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-27T01:25:41.761709Z","caller":"traceutil/trace.go:171","msg":"trace[2131862629] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:645; }","duration":"146.880681ms","start":"2026-02-27T01:25:41.614797Z","end":"2026-02-27T01:25:41.761677Z","steps":["trace[2131862629] 'read index received' (duration: 12.574208ms)","trace[2131862629] 'applied index is now lower than readState.Index' (duration: 134.305083ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:41.761841Z","caller":"traceutil/trace.go:171","msg":"trace[2048039142] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"152.550382ms","start":"2026-02-27T01:25:41.609251Z","end":"2026-02-27T01:25:41.761801Z","steps":["trace[2048039142] 'process raft request' (duration: 151.928449ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.761921Z","caller":"traceutil/trace.go:171","msg":"trace[1800833814] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"152.083232ms","start":"2026-02-27T01:25:41.609765Z","end":"2026-02-27T01:25:41.761848Z","steps":["trace[1800833814] 'process raft request' (duration: 151.588511ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.762061Z","caller":"traceutil/trace.go:171","msg":"trace[326167802] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"152.886199ms","start":"2026-02-27T01:25:41.60916Z","end":"2026-02-27T01:25:41.762046Z","steps":["trace[326167802] 'process raft request' (duration: 151.795796ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.762205Z","caller":"traceutil/trace.go:171","msg":"trace[233945571] transaction","detail":"{read_only:false; number_of_response:0; response_revision:630; }","duration":"152.887579ms","start":"2026-02-27T01:25:41.609306Z","end":"2026-02-27T01:25:41.762193Z","steps":["trace[233945571] 'process raft request' (duration: 151.935409ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.762246Z","caller":"traceutil/trace.go:171","msg":"trace[1031280057] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"152.880779ms","start":"2026-02-27T01:25:41.609336Z","end":"2026-02-27T01:25:41.762216Z","steps":["trace[1031280057] 'process raft request' (duration: 151.940239ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.762371Z","caller":"traceutil/trace.go:171","msg":"trace[571805523] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"148.922625ms","start":"2026-02-27T01:25:41.613437Z","end":"2026-02-27T01:25:41.762359Z","steps":["trace[571805523] 'process raft request' (duration: 148.170269ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.762447Z","caller":"traceutil/trace.go:171","msg":"trace[256710080] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"152.590013ms","start":"2026-02-27T01:25:41.60985Z","end":"2026-02-27T01:25:41.76244Z","steps":["trace[256710080] 'process raft request' (duration: 151.645632ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.762436Z","caller":"traceutil/trace.go:171","msg":"trace[1222453575] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"152.667525ms","start":"2026-02-27T01:25:41.609732Z","end":"2026-02-27T01:25:41.7624Z","steps":["trace[1222453575] 'process raft request' (duration: 151.718204ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.762504Z","caller":"traceutil/trace.go:171","msg":"trace[158410845] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"152.708516ms","start":"2026-02-27T01:25:41.60979Z","end":"2026-02-27T01:25:41.762499Z","steps":["trace[158410845] 'process raft request' (duration: 151.616693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.762523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.573614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1667"} {"level":"warn","ts":"2026-02-27T01:25:41.762545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.7455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:41.762587Z","caller":"traceutil/trace.go:171","msg":"trace[1611359684] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:636; }","duration":"130.684116ms","start":"2026-02-27T01:25:41.631886Z","end":"2026-02-27T01:25:41.76257Z","steps":["trace[1611359684] 'agreement among raft nodes before linearized reading' (duration: 130.496171ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.762589Z","caller":"traceutil/trace.go:171","msg":"trace[1106592293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"147.811011ms","start":"2026-02-27T01:25:41.614765Z","end":"2026-02-27T01:25:41.762576Z","steps":["trace[1106592293] 'agreement among raft nodes before linearized reading' (duration: 147.74028ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:54.315161Z","caller":"traceutil/trace.go:171","msg":"trace[592417924] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"287.041921ms","start":"2026-02-27T01:25:54.028086Z","end":"2026-02-27T01:25:54.315128Z","steps":["trace[592417924] 'process raft request' (duration: 286.824167ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:54.352683Z","caller":"traceutil/trace.go:171","msg":"trace[983387327] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"324.512541ms","start":"2026-02-27T01:25:54.028142Z","end":"2026-02-27T01:25:54.352654Z","steps":["trace[983387327] 'process raft request' (duration: 324.330768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:54.352871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:54.028098Z","time spent":"324.664665ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:54.353009Z","caller":"traceutil/trace.go:171","msg":"trace[1405240334] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"324.586273ms","start":"2026-02-27T01:25:54.02838Z","end":"2026-02-27T01:25:54.352967Z","steps":["trace[1405240334] 'process raft request' (duration: 324.227616ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:54.353024Z","caller":"traceutil/trace.go:171","msg":"trace[370650971] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"324.379619ms","start":"2026-02-27T01:25:54.028614Z","end":"2026-02-27T01:25:54.352994Z","steps":["trace[370650971] 'process raft request' (duration: 324.238156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:54.353228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:54.028367Z","time spent":"324.743956ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:54.353368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:54.028575Z","time spent":"324.677495ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:56.639253Z","caller":"traceutil/trace.go:171","msg":"trace[1886670547] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"319.590546ms","start":"2026-02-27T01:25:56.319642Z","end":"2026-02-27T01:25:56.639232Z","steps":["trace[1886670547] 'read index received' (duration: 319.487044ms)","trace[1886670547] 'applied index is now lower than readState.Index' (duration: 102.522µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:56.639583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.142588ms","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-27T01:25:56.639611Z","caller":"traceutil/trace.go:171","msg":"trace[1386648819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"282.22833ms","start":"2026-02-27T01:25:56.357373Z","end":"2026-02-27T01:25:56.639601Z","steps":["trace[1386648819] 'agreement among raft nodes before linearized reading' (duration: 282.113067ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:56.639692Z","caller":"traceutil/trace.go:171","msg":"trace[1512989022] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"607.404264ms","start":"2026-02-27T01:25:56.03223Z","end":"2026-02-27T01:25:56.639634Z","steps":["trace[1512989022] 'process raft request' (duration: 606.883173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:56.639732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.040936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-27T01:25:56.639825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.524714ms","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-27T01:25:56.639848Z","caller":"traceutil/trace.go:171","msg":"trace[2042353622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"281.544705ms","start":"2026-02-27T01:25:56.358296Z","end":"2026-02-27T01:25:56.639841Z","steps":["trace[2042353622] 'agreement among raft nodes before linearized reading' (duration: 281.492333ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:56.639857Z","caller":"traceutil/trace.go:171","msg":"trace[275776656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"320.24728ms","start":"2026-02-27T01:25:56.319581Z","end":"2026-02-27T01:25:56.639828Z","steps":["trace[275776656] 'agreement among raft nodes before linearized reading' (duration: 319.836472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:56.639919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:56.319552Z","time spent":"320.356342ms","remote":"127.0.0.1:48572","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":"warn","ts":"2026-02-27T01:25:56.639975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.474725ms","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-27T01:25:56.639994Z","caller":"traceutil/trace.go:171","msg":"trace[1328142172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"282.539427ms","start":"2026-02-27T01:25:56.357449Z","end":"2026-02-27T01:25:56.639988Z","steps":["trace[1328142172] 'agreement among raft nodes before linearized reading' (duration: 282.484836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:56.640003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:56.032212Z","time spent":"607.623989ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:56.639768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.781919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:56.640078Z","caller":"traceutil/trace.go:171","msg":"trace[260229419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"138.146126ms","start":"2026-02-27T01:25:56.501916Z","end":"2026-02-27T01:25:56.640063Z","steps":["trace[260229419] 'agreement among raft nodes before linearized reading' (duration: 137.8325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:56.908794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.092992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:25:56.909052Z","caller":"traceutil/trace.go:171","msg":"trace[238328390] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"263.510651ms","start":"2026-02-27T01:25:56.645516Z","end":"2026-02-27T01:25:56.909027Z","steps":["trace[238328390] 'process raft request' (duration: 263.411309ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:56.909332Z","caller":"traceutil/trace.go:171","msg":"trace[1789655209] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"264.209195ms","start":"2026-02-27T01:25:56.645081Z","end":"2026-02-27T01:25:56.90929Z","steps":["trace[1789655209] 'process raft request' (duration: 158.48731ms)","trace[1789655209] 'compare' (duration: 104.906938ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:56.909547Z","caller":"traceutil/trace.go:171","msg":"trace[1870118452] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"262.492949ms","start":"2026-02-27T01:25:56.647012Z","end":"2026-02-27T01:25:56.909505Z","steps":["trace[1870118452] 'process raft request' (duration: 261.971387ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:57.69722Z","caller":"traceutil/trace.go:171","msg":"trace[1870274789] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"420.703452ms","start":"2026-02-27T01:25:57.276499Z","end":"2026-02-27T01:25:57.697203Z","steps":["trace[1870274789] 'read index received' (duration: 420.529788ms)","trace[1870274789] 'applied index is now lower than readState.Index' (duration: 172.814µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:57.697302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:57.163153Z","time spent":"534.146163ms","remote":"127.0.0.1:48366","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-27T01:25:57.697556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.055879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:57.697585Z","caller":"traceutil/trace.go:171","msg":"trace[1382745031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"421.141171ms","start":"2026-02-27T01:25:57.276436Z","end":"2026-02-27T01:25:57.697577Z","steps":["trace[1382745031] 'agreement among raft nodes before linearized reading' (duration: 421.085979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:57.697602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:57.276406Z","time spent":"421.191922ms","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:25:57.697722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.622031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:57.697742Z","caller":"traceutil/trace.go:171","msg":"trace[1623399600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"195.682423ms","start":"2026-02-27T01:25:57.502056Z","end":"2026-02-27T01:25:57.697738Z","steps":["trace[1623399600] 'agreement among raft nodes before linearized reading' (duration: 195.653122ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:57.872784Z","caller":"traceutil/trace.go:171","msg":"trace[1760957448] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"174.148445ms","start":"2026-02-27T01:25:57.698576Z","end":"2026-02-27T01:25:57.872725Z","steps":["trace[1760957448] 'process raft request' (duration: 130.158867ms)","trace[1760957448] 'compare' (duration: 43.864845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:58.705341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.741025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:58.705425Z","caller":"traceutil/trace.go:171","msg":"trace[1598852503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"203.880888ms","start":"2026-02-27T01:25:58.501524Z","end":"2026-02-27T01:25:58.705405Z","steps":["trace[1598852503] 'range keys from in-memory index tree' (duration: 203.669993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:58.705495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.138061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:58.705607Z","caller":"traceutil/trace.go:171","msg":"trace[839034387] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:739; }","duration":"338.302755ms","start":"2026-02-27T01:25:58.367282Z","end":"2026-02-27T01:25:58.705585Z","steps":["trace[839034387] 'count revisions from in-memory index tree' (duration: 338.002408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:58.705671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:58.367197Z","time spent":"338.462007ms","remote":"127.0.0.1:48610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-27T01:25:58.930398Z","caller":"traceutil/trace.go:171","msg":"trace[396983638] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"210.338476ms","start":"2026-02-27T01:25:58.720029Z","end":"2026-02-27T01:25:58.930367Z","steps":["trace[396983638] 'process raft request' (duration: 209.184791ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:21.305543Z","caller":"traceutil/trace.go:171","msg":"trace[127637345] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"430.309816ms","start":"2026-02-27T01:26:20.87521Z","end":"2026-02-27T01:26:21.30552Z","steps":["trace[127637345] 'read index received' (duration: 430.062571ms)","trace[127637345] 'applied index is now lower than readState.Index' (duration: 246.115µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:26:21.305675Z","caller":"traceutil/trace.go:171","msg":"trace[1269167751] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"645.351123ms","start":"2026-02-27T01:26:20.660284Z","end":"2026-02-27T01:26:21.305635Z","steps":["trace[1269167751] 'process raft request' (duration: 645.078627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:21.305841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:20.660254Z","time spent":"645.499226ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:26:21.305838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.597322ms","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-27T01:26:21.305866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.342445ms","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":"warn","ts":"2026-02-27T01:26:21.305901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.210114ms","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-27T01:26:21.30592Z","caller":"traceutil/trace.go:171","msg":"trace[288394565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"430.720435ms","start":"2026-02-27T01:26:20.875182Z","end":"2026-02-27T01:26:21.305902Z","steps":["trace[288394565] 'agreement among raft nodes before linearized reading' (duration: 430.484211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:21.305938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.271934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-27T01:26:21.305965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:20.875166Z","time spent":"430.790367ms","remote":"127.0.0.1:48572","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-27T01:26:21.30595Z","caller":"traceutil/trace.go:171","msg":"trace[1433507050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"264.301306ms","start":"2026-02-27T01:26:21.041634Z","end":"2026-02-27T01:26:21.305936Z","steps":["trace[1433507050] 'agreement among raft nodes before linearized reading' (duration: 264.0253ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:21.306003Z","caller":"traceutil/trace.go:171","msg":"trace[1722627831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"248.352266ms","start":"2026-02-27T01:26:21.057629Z","end":"2026-02-27T01:26:21.305981Z","steps":["trace[1722627831] 'agreement among raft nodes before linearized reading' (duration: 248.208873ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:21.305946Z","caller":"traceutil/trace.go:171","msg":"trace[727452342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"232.452117ms","start":"2026-02-27T01:26:21.073474Z","end":"2026-02-27T01:26:21.305926Z","steps":["trace[727452342] 'agreement among raft nodes before linearized reading' (duration: 232.236652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:21.30606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.374172ms","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-27T01:26:21.306202Z","caller":"traceutil/trace.go:171","msg":"trace[50292507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"237.565155ms","start":"2026-02-27T01:26:21.068603Z","end":"2026-02-27T01:26:21.306169Z","steps":["trace[50292507] 'agreement among raft nodes before linearized reading' (duration: 237.163097ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:21.761615Z","caller":"traceutil/trace.go:171","msg":"trace[277978845] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"448.160838ms","start":"2026-02-27T01:26:21.313426Z","end":"2026-02-27T01:26:21.761587Z","steps":["trace[277978845] 'process raft request' (duration: 444.364848ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:21.761675Z","caller":"traceutil/trace.go:171","msg":"trace[311388054] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"448.118147ms","start":"2026-02-27T01:26:21.31353Z","end":"2026-02-27T01:26:21.761648Z","steps":["trace[311388054] 'process raft request' (duration: 448.036545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:21.761777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:21.313395Z","time spent":"448.29369ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:26:21.761832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:21.313493Z","time spent":"448.242799ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:26:21.761907Z","caller":"traceutil/trace.go:171","msg":"trace[208126699] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:837; }","duration":"260.532236ms","start":"2026-02-27T01:26:21.501337Z","end":"2026-02-27T01:26:21.761869Z","steps":["trace[208126699] 'read index received' (duration: 256.418029ms)","trace[208126699] 'applied index is now lower than readState.Index' (duration: 4.112507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:26:21.762069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.71586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:26:21.76205Z","caller":"traceutil/trace.go:171","msg":"trace[242088609] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"447.983994ms","start":"2026-02-27T01:26:21.314041Z","end":"2026-02-27T01:26:21.762025Z","steps":["trace[242088609] 'process raft request' (duration: 447.556865ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:21.762112Z","caller":"traceutil/trace.go:171","msg":"trace[1474210830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"260.815612ms","start":"2026-02-27T01:26:21.501286Z","end":"2026-02-27T01:26:21.762101Z","steps":["trace[1474210830] 'agreement among raft nodes before linearized reading' (duration: 260.70975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:21.762218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:21.314028Z","time spent":"448.093896ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:26:21.761652Z","caller":"traceutil/trace.go:171","msg":"trace[322577447] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"448.183028ms","start":"2026-02-27T01:26:21.313451Z","end":"2026-02-27T01:26:21.761634Z","steps":["trace[322577447] 'process raft request' (duration: 448.026605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:21.76245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:21.313426Z","time spent":"448.918444ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:26:24.006641Z","caller":"traceutil/trace.go:171","msg":"trace[1031647230] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"215.350252ms","start":"2026-02-27T01:26:23.791267Z","end":"2026-02-27T01:26:24.006617Z","steps":["trace[1031647230] 'process raft request' (duration: 215.166679ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:24.574162Z","caller":"traceutil/trace.go:171","msg":"trace[2121534324] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:844; }","duration":"402.013135ms","start":"2026-02-27T01:26:24.172126Z","end":"2026-02-27T01:26:24.574139Z","steps":["trace[2121534324] 'read index received' (duration: 401.742909ms)","trace[2121534324] 'applied index is now lower than readState.Index' (duration: 269.235µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:26:24.574325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.164838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:26:24.574446Z","caller":"traceutil/trace.go:171","msg":"trace[1691486424] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:818; }","duration":"402.306391ms","start":"2026-02-27T01:26:24.172121Z","end":"2026-02-27T01:26:24.574427Z","steps":["trace[1691486424] 'agreement among raft nodes before linearized reading' (duration: 402.113717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:24.574512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:24.172067Z","time spent":"402.426783ms","remote":"127.0.0.1:48460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-27T01:26:24.574916Z","caller":"traceutil/trace.go:171","msg":"trace[179253308] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"783.468989ms","start":"2026-02-27T01:26:23.791434Z","end":"2026-02-27T01:26:24.574903Z","steps":["trace[179253308] 'process raft request' (duration: 782.506918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:24.575026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:23.791425Z","time spent":"783.53455ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:26:24.575256Z","caller":"traceutil/trace.go:171","msg":"trace[669773092] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"783.06921ms","start":"2026-02-27T01:26:23.79217Z","end":"2026-02-27T01:26:24.575239Z","steps":["trace[669773092] 'process raft request' (duration: 781.897105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:24.57535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:23.792148Z","time spent":"783.156972ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:26:24.575551Z","caller":"traceutil/trace.go:171","msg":"trace[830753666] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"782.757344ms","start":"2026-02-27T01:26:23.792782Z","end":"2026-02-27T01:26:24.57554Z","steps":["trace[830753666] 'process raft request' (duration: 781.324074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:24.575639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:23.79276Z","time spent":"782.834265ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:26:39.835051Z","caller":"traceutil/trace.go:171","msg":"trace[538646792] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"321.501147ms","start":"2026-02-27T01:26:39.513525Z","end":"2026-02-27T01:26:39.835027Z","steps":["trace[538646792] 'process raft request' (duration: 321.352164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:39.835204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:39.513501Z","time spent":"321.625989ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:26:40.415297Z","caller":"traceutil/trace.go:171","msg":"trace[31756692] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"141.734353ms","start":"2026-02-27T01:26:40.273542Z","end":"2026-02-27T01:26:40.415276Z","steps":["trace[31756692] 'process raft request' (duration: 141.59829ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:52.901964Z","caller":"traceutil/trace.go:171","msg":"trace[1883047088] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"136.676645ms","start":"2026-02-27T01:26:52.765078Z","end":"2026-02-27T01:26:52.901755Z","steps":["trace[1883047088] 'process raft request' (duration: 132.322242ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:54.562236Z","caller":"traceutil/trace.go:171","msg":"trace[1282298505] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"296.454932ms","start":"2026-02-27T01:26:54.265733Z","end":"2026-02-27T01:26:54.562188Z","steps":["trace[1282298505] 'process raft request' (duration: 296.210457ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:55.305314Z","caller":"traceutil/trace.go:171","msg":"trace[1817326428] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"395.29501ms","start":"2026-02-27T01:26:54.909999Z","end":"2026-02-27T01:26:55.305294Z","steps":["trace[1817326428] 'process raft request' (duration: 395.200488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:55.305413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:54.909979Z","time spent":"395.385372ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:26:57.280135Z","caller":"traceutil/trace.go:171","msg":"trace[801119586] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"155.607659ms","start":"2026-02-27T01:26:57.124511Z","end":"2026-02-27T01:26:57.280119Z","steps":["trace[801119586] 'process raft request' (duration: 116.626708ms)","trace[801119586] 'compare' (duration: 38.769807ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:26:57.553088Z","caller":"traceutil/trace.go:171","msg":"trace[674152493] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"241.115127ms","start":"2026-02-27T01:26:57.311943Z","end":"2026-02-27T01:26:57.553058Z","steps":["trace[674152493] 'process raft request' (duration: 209.42264ms)","trace[674152493] 'compare' (duration: 31.424691ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:26:58.693263Z","caller":"traceutil/trace.go:171","msg":"trace[150799508] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"107.205901ms","start":"2026-02-27T01:26:58.586042Z","end":"2026-02-27T01:26:58.693248Z","steps":["trace[150799508] 'read index received' (duration: 107.058078ms)","trace[150799508] 'applied index is now lower than readState.Index' (duration: 147.203µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:26:58.693349Z","caller":"traceutil/trace.go:171","msg":"trace[271525779] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"130.370366ms","start":"2026-02-27T01:26:58.562941Z","end":"2026-02-27T01:26:58.693311Z","steps":["trace[271525779] 'process raft request' (duration: 130.221893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:58.693399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.340623ms","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-27T01:26:58.693424Z","caller":"traceutil/trace.go:171","msg":"trace[1699285747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"107.417515ms","start":"2026-02-27T01:26:58.585999Z","end":"2026-02-27T01:26:58.693416Z","steps":["trace[1699285747] 'agreement among raft nodes before linearized reading' (duration: 107.305663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:59.285725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.441156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:26:59.285799Z","caller":"traceutil/trace.go:171","msg":"trace[1122197810] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"437.9472ms","start":"2026-02-27T01:26:58.847839Z","end":"2026-02-27T01:26:59.285786Z","steps":["trace[1122197810] 'process raft request' (duration: 61.384251ms)","trace[1122197810] 'compare' (duration: 376.306113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:26:59.285839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:58.847825Z","time spent":"437.99635ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:27:00.006332Z","caller":"traceutil/trace.go:171","msg":"trace[1719940026] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"132.372538ms","start":"2026-02-27T01:26:59.873936Z","end":"2026-02-27T01:27:00.006309Z","steps":["trace[1719940026] 'read index received' (duration: 132.118463ms)","trace[1719940026] 'applied index is now lower than readState.Index' (duration: 252.815µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:00.006459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.497641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:00.006439Z","caller":"traceutil/trace.go:171","msg":"trace[499228699] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"355.408945ms","start":"2026-02-27T01:26:59.651001Z","end":"2026-02-27T01:27:00.006409Z","steps":["trace[499228699] 'process raft request' (duration: 355.13841ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:00.006501Z","caller":"traceutil/trace.go:171","msg":"trace[1230464529] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:995; }","duration":"132.593003ms","start":"2026-02-27T01:26:59.873892Z","end":"2026-02-27T01:27:00.006485Z","steps":["trace[1230464529] 'agreement among raft nodes before linearized reading' (duration: 132.509731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:00.006603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:26:59.650986Z","time spent":"355.530419ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:27:00.169256Z","caller":"traceutil/trace.go:171","msg":"trace[932669826] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"153.499931ms","start":"2026-02-27T01:27:00.015727Z","end":"2026-02-27T01:27:00.169227Z","steps":["trace[932669826] 'process raft request' (duration: 132.206227ms)","trace[932669826] 'compare' (duration: 20.918786ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:00.413734Z","caller":"traceutil/trace.go:171","msg":"trace[1742681362] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"202.643942ms","start":"2026-02-27T01:27:00.211065Z","end":"2026-02-27T01:27:00.413709Z","steps":["trace[1742681362] 'process raft request' (duration: 126.689608ms)","trace[1742681362] 'compare' (duration: 75.772929ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:00.961452Z","caller":"traceutil/trace.go:171","msg":"trace[1421704621] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"110.374588ms","start":"2026-02-27T01:27:00.851051Z","end":"2026-02-27T01:27:00.961425Z","steps":["trace[1421704621] 'process raft request' (duration: 66.658594ms)","trace[1421704621] 'compare' (duration: 43.598862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:01.479299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.243324ms","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-27T01:27:01.47938Z","caller":"traceutil/trace.go:171","msg":"trace[578455608] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1002; }","duration":"254.361186ms","start":"2026-02-27T01:27:01.225001Z","end":"2026-02-27T01:27:01.479362Z","steps":["trace[578455608] 'range keys from in-memory index tree' (duration: 254.191102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:01.47938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.55422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:27:01.479453Z","caller":"traceutil/trace.go:171","msg":"trace[1845898816] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"190.535432ms","start":"2026-02-27T01:27:01.288905Z","end":"2026-02-27T01:27:01.47944Z","steps":["trace[1845898816] 'read index received' (duration: 45.811989ms)","trace[1845898816] 'applied index is now lower than readState.Index' (duration: 144.722473ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:01.479508Z","caller":"traceutil/trace.go:171","msg":"trace[1654345752] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"241.94229ms","start":"2026-02-27T01:27:01.23753Z","end":"2026-02-27T01:27:01.479473Z","steps":["trace[1654345752] 'process raft request' (duration: 97.241817ms)","trace[1654345752] 'compare' (duration: 144.438887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:01.479618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.450932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T01:27:01.479635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.729546ms","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-27T01:27:01.479667Z","caller":"traceutil/trace.go:171","msg":"trace[1519977011] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1003; }","duration":"177.545154ms","start":"2026-02-27T01:27:01.302106Z","end":"2026-02-27T01:27:01.479651Z","steps":["trace[1519977011] 'agreement among raft nodes before linearized reading' (duration: 177.476202ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:01.479673Z","caller":"traceutil/trace.go:171","msg":"trace[1571513488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"190.771357ms","start":"2026-02-27T01:27:01.288892Z","end":"2026-02-27T01:27:01.479664Z","steps":["trace[1571513488] 'agreement among raft nodes before linearized reading' (duration: 190.615124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:01.479749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.157871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:01.479791Z","caller":"traceutil/trace.go:171","msg":"trace[1985308568] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1003; }","duration":"159.221343ms","start":"2026-02-27T01:27:01.320556Z","end":"2026-02-27T01:27:01.479777Z","steps":["trace[1985308568] 'agreement among raft nodes before linearized reading' (duration: 159.163792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:01.981855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.783645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:27:01.981997Z","caller":"traceutil/trace.go:171","msg":"trace[1461981594] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"497.252707ms","start":"2026-02-27T01:27:01.484719Z","end":"2026-02-27T01:27:01.981972Z","steps":["trace[1461981594] 'process raft request' (duration: 162.272108ms)","trace[1461981594] 'compare' (duration: 334.5293ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:01.982075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:01.484702Z","time spent":"497.339209ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":862,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T01:27:02.925088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.69078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:27:02.925387Z","caller":"traceutil/trace.go:171","msg":"trace[1982368805] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1042; }","duration":"969.449039ms","start":"2026-02-27T01:27:01.955919Z","end":"2026-02-27T01:27:02.925368Z","steps":["trace[1982368805] 'read index received' (duration: 199.460972ms)","trace[1982368805] 'applied index is now lower than readState.Index' (duration: 769.987106ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:02.925457Z","caller":"traceutil/trace.go:171","msg":"trace[211476201] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"1.440430164s","start":"2026-02-27T01:27:01.485016Z","end":"2026-02-27T01:27:02.925446Z","steps":["trace[211476201] 'process raft request' (duration: 670.298365ms)","trace[211476201] 'compare' (duration: 769.558187ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:02.925514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:01.485007Z","time spent":"1.440473636s","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:27:02.925672Z","caller":"traceutil/trace.go:171","msg":"trace[235683329] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"1.440309492s","start":"2026-02-27T01:27:01.485353Z","end":"2026-02-27T01:27:02.925662Z","steps":["trace[235683329] 'process raft request' (duration: 1.439985585s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:02.925713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:01.485326Z","time spent":"1.440365533s","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T01:27:02.925696Z","caller":"traceutil/trace.go:171","msg":"trace[352831385] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"1.440569528s","start":"2026-02-27T01:27:01.485094Z","end":"2026-02-27T01:27:02.925664Z","steps":["trace[352831385] 'process raft request' (duration: 1.44021587s)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:02.925711Z","caller":"traceutil/trace.go:171","msg":"trace[1187098300] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"1.440614878s","start":"2026-02-27T01:27:01.485071Z","end":"2026-02-27T01:27:02.925686Z","steps":["trace[1187098300] 'process raft request' (duration: 1.440191709s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:02.925754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.823167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:02.925822Z","caller":"traceutil/trace.go:171","msg":"trace[1743310244] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1008; }","duration":"969.935309ms","start":"2026-02-27T01:27:01.955873Z","end":"2026-02-27T01:27:02.925808Z","steps":["trace[1743310244] 'agreement among raft nodes before linearized reading' (duration: 969.802716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:02.925833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:01.48508Z","time spent":"1.44068326s","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T01:27:02.92586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:01.955849Z","time spent":"969.999791ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-27T01:27:02.925851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:01.485064Z","time spent":"1.44072918s","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T01:27:02.93469Z","caller":"traceutil/trace.go:171","msg":"trace[263834600] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"826.816541ms","start":"2026-02-27T01:27:02.107857Z","end":"2026-02-27T01:27:02.934674Z","steps":["trace[263834600] 'process raft request' (duration: 826.782431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:02.934816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:02.107831Z","time spent":"826.920893ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:27:02.93496Z","caller":"traceutil/trace.go:171","msg":"trace[699215205] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"846.046202ms","start":"2026-02-27T01:27:02.088907Z","end":"2026-02-27T01:27:02.934953Z","steps":["trace[699215205] 'process raft request' (duration: 845.647883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:02.935001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:02.088889Z","time spent":"846.092133ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:27:03.163377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.767167ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T01:27:03.163614Z","caller":"traceutil/trace.go:171","msg":"trace[1727463750] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1047; }","duration":"238.16584ms","start":"2026-02-27T01:27:02.925416Z","end":"2026-02-27T01:27:03.163582Z","steps":["trace[1727463750] 'read index received' (duration: 9.235357ms)","trace[1727463750] 'applied index is now lower than readState.Index' (duration: 228.927553ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:03.163761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.271238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:03.163797Z","caller":"traceutil/trace.go:171","msg":"trace[1554198239] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"228.429211ms","start":"2026-02-27T01:27:02.935355Z","end":"2026-02-27T01:27:03.163785Z","steps":["trace[1554198239] 'process raft request' (duration: 228.285228ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:03.163793Z","caller":"traceutil/trace.go:171","msg":"trace[516147530] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"228.454372ms","start":"2026-02-27T01:27:02.935307Z","end":"2026-02-27T01:27:03.163761Z","steps":["trace[516147530] 'process raft request' (duration: 228.172196ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:03.163827Z","caller":"traceutil/trace.go:171","msg":"trace[1774094113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"665.34598ms","start":"2026-02-27T01:27:02.498466Z","end":"2026-02-27T01:27:03.163812Z","steps":["trace[1774094113] 'agreement among raft nodes before linearized reading' (duration: 665.192066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:03.163862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:02.4984Z","time spent":"665.454931ms","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T01:27:03.164037Z","caller":"traceutil/trace.go:171","msg":"trace[27292715] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"227.157925ms","start":"2026-02-27T01:27:02.936859Z","end":"2026-02-27T01:27:03.164017Z","steps":["trace[27292715] 'process raft request' (duration: 226.813248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:03.164211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153360539s","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-27T01:27:03.164252Z","caller":"traceutil/trace.go:171","msg":"trace[1396007951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"1.153435031s","start":"2026-02-27T01:27:02.010807Z","end":"2026-02-27T01:27:03.164242Z","steps":["trace[1396007951] 'agreement among raft nodes before linearized reading' (duration: 1.15294775s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:03.164282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:02.010789Z","time spent":"1.153483361s","remote":"127.0.0.1:48572","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":"warn","ts":"2026-02-27T01:27:03.164387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.121791ms","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-27T01:27:03.164443Z","caller":"traceutil/trace.go:171","msg":"trace[1127424449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"991.223294ms","start":"2026-02-27T01:27:02.173209Z","end":"2026-02-27T01:27:03.164432Z","steps":["trace[1127424449] 'agreement among raft nodes before linearized reading' (duration: 990.526629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:03.164476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:02.173193Z","time spent":"991.275094ms","remote":"127.0.0.1:48572","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-27T01:27:03.164775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.528046ms","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-27T01:27:03.164817Z","caller":"traceutil/trace.go:171","msg":"trace[723113687] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1014; }","duration":"229.590666ms","start":"2026-02-27T01:27:02.935215Z","end":"2026-02-27T01:27:03.164805Z","steps":["trace[723113687] 'agreement among raft nodes before linearized reading' (duration: 229.466594ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:03.16489Z","caller":"traceutil/trace.go:171","msg":"trace[1881902519] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"227.642986ms","start":"2026-02-27T01:27:02.937238Z","end":"2026-02-27T01:27:03.164881Z","steps":["trace[1881902519] 'process raft request' (duration: 226.737326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:03.165073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.902463ms","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-27T01:27:03.165102Z","caller":"traceutil/trace.go:171","msg":"trace[821406508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"199.945574ms","start":"2026-02-27T01:27:02.965149Z","end":"2026-02-27T01:27:03.165094Z","steps":["trace[821406508] 'agreement among raft nodes before linearized reading' (duration: 199.866082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:03.165247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.442494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T01:27:03.165271Z","caller":"traceutil/trace.go:171","msg":"trace[478720059] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1014; }","duration":"229.484325ms","start":"2026-02-27T01:27:02.935779Z","end":"2026-02-27T01:27:03.165263Z","steps":["trace[478720059] 'agreement among raft nodes before linearized reading' (duration: 229.410034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:03.165371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.933494ms","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:529"} {"level":"info","ts":"2026-02-27T01:27:03.165393Z","caller":"traceutil/trace.go:171","msg":"trace[1120040204] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1014; }","duration":"229.977225ms","start":"2026-02-27T01:27:02.935409Z","end":"2026-02-27T01:27:03.165386Z","steps":["trace[1120040204] 'agreement among raft nodes before linearized reading' (duration: 229.911953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:03.166056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.014149ms","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-27T01:27:03.166099Z","caller":"traceutil/trace.go:171","msg":"trace[50593789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"349.091071ms","start":"2026-02-27T01:27:02.816996Z","end":"2026-02-27T01:27:03.166087Z","steps":["trace[50593789] 'agreement among raft nodes before linearized reading' (duration: 346.856533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:03.166147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:02.816971Z","time spent":"349.168053ms","remote":"127.0.0.1:48572","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-27T01:27:03.465946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.874304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:27:03.466013Z","caller":"traceutil/trace.go:171","msg":"trace[1223406939] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"141.557315ms","start":"2026-02-27T01:27:03.324445Z","end":"2026-02-27T01:27:03.466003Z","steps":["trace[1223406939] 'read index received' (duration: 59.131µs)","trace[1223406939] 'applied index is now lower than readState.Index' (duration: 141.497474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:03.466062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.626116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:03.466076Z","caller":"traceutil/trace.go:171","msg":"trace[1978113651] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1023; }","duration":"141.677907ms","start":"2026-02-27T01:27:03.324393Z","end":"2026-02-27T01:27:03.466071Z","steps":["trace[1978113651] 'agreement among raft nodes before linearized reading' (duration: 141.635996ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:03.466226Z","caller":"traceutil/trace.go:171","msg":"trace[1773638276] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"214.160697ms","start":"2026-02-27T01:27:03.252033Z","end":"2026-02-27T01:27:03.466194Z","steps":["trace[1773638276] 'process raft request' (duration: 48.985007ms)","trace[1773638276] 'compare' (duration: 164.757581ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:03.535296Z","caller":"traceutil/trace.go:171","msg":"trace[432467452] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"129.843726ms","start":"2026-02-27T01:27:03.405429Z","end":"2026-02-27T01:27:03.535272Z","steps":["trace[432467452] 'process raft request' (duration: 129.644711ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:03.681552Z","caller":"traceutil/trace.go:171","msg":"trace[2087922352] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"110.640284ms","start":"2026-02-27T01:27:03.570879Z","end":"2026-02-27T01:27:03.681519Z","steps":["trace[2087922352] 'process raft request' (duration: 28.192792ms)","trace[2087922352] 'compare' (duration: 82.299279ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:03.866807Z","caller":"traceutil/trace.go:171","msg":"trace[1437731350] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"114.287672ms","start":"2026-02-27T01:27:03.752488Z","end":"2026-02-27T01:27:03.866776Z","steps":["trace[1437731350] 'process raft request' (duration: 44.95229ms)","trace[1437731350] 'compare' (duration: 69.2266ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:04.093346Z","caller":"traceutil/trace.go:171","msg":"trace[1779641705] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1066; }","duration":"334.337365ms","start":"2026-02-27T01:27:03.75898Z","end":"2026-02-27T01:27:04.093318Z","steps":["trace[1779641705] 'read index received' (duration: 38.446041ms)","trace[1779641705] 'applied index is now lower than readState.Index' (duration: 295.890404ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:04.093464Z","caller":"traceutil/trace.go:171","msg":"trace[578335531] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"334.541769ms","start":"2026-02-27T01:27:03.758912Z","end":"2026-02-27T01:27:04.093454Z","steps":["trace[578335531] 'process raft request' (duration: 334.257753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:04.09358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:03.758902Z","time spent":"334.600481ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":792,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T01:27:04.093938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.956138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-27T01:27:04.093981Z","caller":"traceutil/trace.go:171","msg":"trace[1482881738] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1029; }","duration":"335.02955ms","start":"2026-02-27T01:27:03.758941Z","end":"2026-02-27T01:27:04.093971Z","steps":["trace[1482881738] 'agreement among raft nodes before linearized reading' (duration: 334.874347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:04.09402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:03.758928Z","time spent":"335.084801ms","remote":"127.0.0.1:48422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":645,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-27T01:27:04.140991Z","caller":"traceutil/trace.go:171","msg":"trace[1805616822] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"270.300427ms","start":"2026-02-27T01:27:03.870669Z","end":"2026-02-27T01:27:04.14097Z","steps":["trace[1805616822] 'process raft request' (duration: 270.187034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:04.141648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.44693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:04.141761Z","caller":"traceutil/trace.go:171","msg":"trace[1870008201] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1030; }","duration":"190.712366ms","start":"2026-02-27T01:27:03.951024Z","end":"2026-02-27T01:27:04.141736Z","steps":["trace[1870008201] 'agreement among raft nodes before linearized reading' (duration: 190.358408ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:04.363896Z","caller":"traceutil/trace.go:171","msg":"trace[821208908] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"217.359736ms","start":"2026-02-27T01:27:04.146507Z","end":"2026-02-27T01:27:04.363867Z","steps":["trace[821208908] 'process raft request' (duration: 162.81786ms)","trace[821208908] 'compare' (duration: 54.398383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:05.132482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.184881ms","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-27T01:27:05.13257Z","caller":"traceutil/trace.go:171","msg":"trace[2032162326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1032; }","duration":"202.328883ms","start":"2026-02-27T01:27:04.930222Z","end":"2026-02-27T01:27:05.132551Z","steps":["trace[2032162326] 'range keys from in-memory index tree' (duration: 201.983937ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:05.290073Z","caller":"traceutil/trace.go:171","msg":"trace[1821372144] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"152.640322ms","start":"2026-02-27T01:27:05.137409Z","end":"2026-02-27T01:27:05.290049Z","steps":["trace[1821372144] 'process raft request' (duration: 152.376136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:06.088835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.747559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:27:06.089114Z","caller":"traceutil/trace.go:171","msg":"trace[556350154] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"794.739975ms","start":"2026-02-27T01:27:05.294353Z","end":"2026-02-27T01:27:06.089093Z","steps":["trace[556350154] 'process raft request' (duration: 794.631442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:06.089222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:05.294317Z","time spent":"794.854947ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:27:06.089209Z","caller":"traceutil/trace.go:171","msg":"trace[941865426] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"798.489825ms","start":"2026-02-27T01:27:05.29068Z","end":"2026-02-27T01:27:06.08917Z","steps":["trace[941865426] 'read index received' (duration: 51.309597ms)","trace[941865426] 'applied index is now lower than readState.Index' (duration: 747.174958ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:06.08938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.62323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T01:27:06.089426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.733487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:06.089458Z","caller":"traceutil/trace.go:171","msg":"trace[1824778072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1035; }","duration":"835.726092ms","start":"2026-02-27T01:27:05.253711Z","end":"2026-02-27T01:27:06.089437Z","steps":["trace[1824778072] 'agreement among raft nodes before linearized reading' (duration: 835.475785ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:06.089473Z","caller":"traceutil/trace.go:171","msg":"trace[490063350] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1035; }","duration":"627.838789ms","start":"2026-02-27T01:27:05.461616Z","end":"2026-02-27T01:27:06.089455Z","steps":["trace[490063350] 'agreement among raft nodes before linearized reading' (duration: 627.724487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:06.0895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:05.253703Z","time spent":"835.789783ms","remote":"127.0.0.1:48572","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":"warn","ts":"2026-02-27T01:27:06.089509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.819574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-27T01:27:06.089521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:05.461594Z","time spent":"627.917071ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-27T01:27:06.089543Z","caller":"traceutil/trace.go:171","msg":"trace[159751470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"835.932307ms","start":"2026-02-27T01:27:05.253598Z","end":"2026-02-27T01:27:06.089531Z","steps":["trace[159751470] 'agreement among raft nodes before linearized reading' (duration: 835.854815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:06.089573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:05.25359Z","time spent":"835.977468ms","remote":"127.0.0.1:48572","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-27T01:27:06.089602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.712832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:06.089652Z","caller":"traceutil/trace.go:171","msg":"trace[996881935] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"861.35233ms","start":"2026-02-27T01:27:05.228289Z","end":"2026-02-27T01:27:06.089642Z","steps":["trace[996881935] 'process raft request' (duration: 113.706101ms)","trace[996881935] 'compare' (duration: 746.259879ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:06.089679Z","caller":"traceutil/trace.go:171","msg":"trace[1187368245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"376.818434ms","start":"2026-02-27T01:27:05.712839Z","end":"2026-02-27T01:27:06.089658Z","steps":["trace[1187368245] 'agreement among raft nodes before linearized reading' (duration: 376.702462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:06.089436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.444028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-27T01:27:06.089723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:05.712816Z","time spent":"376.898326ms","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:27:06.089727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.897312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:06.089751Z","caller":"traceutil/trace.go:171","msg":"trace[1321935287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"836.790534ms","start":"2026-02-27T01:27:05.252947Z","end":"2026-02-27T01:27:06.089738Z","steps":["trace[1321935287] 'agreement among raft nodes before linearized reading' (duration: 836.379705ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:06.089762Z","caller":"traceutil/trace.go:171","msg":"trace[1515428870] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1035; }","duration":"585.934722ms","start":"2026-02-27T01:27:05.503819Z","end":"2026-02-27T01:27:06.089754Z","steps":["trace[1515428870] 'agreement among raft nodes before linearized reading' (duration: 585.880071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:06.089784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:05.252929Z","time spent":"836.845426ms","remote":"127.0.0.1:48572","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-27T01:27:06.089791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:05.503768Z","time spent":"586.015824ms","remote":"127.0.0.1:48594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-27T01:27:06.089695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:05.228265Z","time spent":"861.408701ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":798,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T01:27:06.306942Z","caller":"traceutil/trace.go:171","msg":"trace[2048509706] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"211.765805ms","start":"2026-02-27T01:27:06.095136Z","end":"2026-02-27T01:27:06.306901Z","steps":["trace[2048509706] 'process raft request' (duration: 155.267058ms)","trace[2048509706] 'compare' (duration: 56.299993ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:06.307322Z","caller":"traceutil/trace.go:171","msg":"trace[784348794] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"212.131933ms","start":"2026-02-27T01:27:06.095167Z","end":"2026-02-27T01:27:06.307299Z","steps":["trace[784348794] 'process raft request' (duration: 211.659293ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:06.657635Z","caller":"traceutil/trace.go:171","msg":"trace[1384341865] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"289.526987ms","start":"2026-02-27T01:27:06.368079Z","end":"2026-02-27T01:27:06.657606Z","steps":["trace[1384341865] 'read index received' (duration: 217.182922ms)","trace[1384341865] 'applied index is now lower than readState.Index' (duration: 72.342505ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:06.657766Z","caller":"traceutil/trace.go:171","msg":"trace[2039382421] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"335.747665ms","start":"2026-02-27T01:27:06.322004Z","end":"2026-02-27T01:27:06.657752Z","steps":["trace[2039382421] 'process raft request' (duration: 263.318738ms)","trace[2039382421] 'compare' (duration: 72.005108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:06.657881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:06.321969Z","time spent":"335.830186ms","remote":"127.0.0.1:48476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12992,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:27:06.657879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.475257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:06.657945Z","caller":"traceutil/trace.go:171","msg":"trace[814933416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"159.584041ms","start":"2026-02-27T01:27:06.498343Z","end":"2026-02-27T01:27:06.657927Z","steps":["trace[814933416] 'agreement among raft nodes before linearized reading' (duration: 159.481388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:06.658274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.202203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-27T01:27:06.658322Z","caller":"traceutil/trace.go:171","msg":"trace[788700340] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1039; }","duration":"290.284134ms","start":"2026-02-27T01:27:06.368024Z","end":"2026-02-27T01:27:06.658308Z","steps":["trace[788700340] 'agreement among raft nodes before linearized reading' (duration: 290.138871ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:06.962557Z","caller":"traceutil/trace.go:171","msg":"trace[1188272450] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"298.633462ms","start":"2026-02-27T01:27:06.663883Z","end":"2026-02-27T01:27:06.962516Z","steps":["trace[1188272450] 'process raft request' (duration: 230.378783ms)","trace[1188272450] 'compare' (duration: 67.926572ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:06.962849Z","caller":"traceutil/trace.go:171","msg":"trace[1227965240] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"297.553989ms","start":"2026-02-27T01:27:06.66527Z","end":"2026-02-27T01:27:06.962824Z","steps":["trace[1227965240] 'process raft request' (duration: 297.167061ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:06.962908Z","caller":"traceutil/trace.go:171","msg":"trace[993029135] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"296.154188ms","start":"2026-02-27T01:27:06.666724Z","end":"2026-02-27T01:27:06.962878Z","steps":["trace[993029135] 'process raft request' (duration: 296.030926ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:07.145638Z","caller":"traceutil/trace.go:171","msg":"trace[2069808677] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"169.696387ms","start":"2026-02-27T01:27:06.975896Z","end":"2026-02-27T01:27:07.145593Z","steps":["trace[2069808677] 'process raft request' (duration: 166.59863ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:07.14614Z","caller":"traceutil/trace.go:171","msg":"trace[1591485856] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"170.158597ms","start":"2026-02-27T01:27:06.975934Z","end":"2026-02-27T01:27:07.146093Z","steps":["trace[1591485856] 'process raft request' (duration: 169.730428ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:08.175343Z","caller":"traceutil/trace.go:171","msg":"trace[1924711863] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"200.171987ms","start":"2026-02-27T01:27:07.975151Z","end":"2026-02-27T01:27:08.175322Z","steps":["trace[1924711863] 'process raft request' (duration: 199.651806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:09.120143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.949364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:27:09.120349Z","caller":"traceutil/trace.go:171","msg":"trace[1287192056] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1090; }","duration":"861.554544ms","start":"2026-02-27T01:27:08.258777Z","end":"2026-02-27T01:27:09.120332Z","steps":["trace[1287192056] 'read index received' (duration: 230.100418ms)","trace[1287192056] 'applied index is now lower than readState.Index' (duration: 631.453226ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:09.120451Z","caller":"traceutil/trace.go:171","msg":"trace[1438429225] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"864.481896ms","start":"2026-02-27T01:27:08.255956Z","end":"2026-02-27T01:27:09.120437Z","steps":["trace[1438429225] 'process raft request' (duration: 232.898247ms)","trace[1438429225] 'compare' (duration: 630.836932ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:09.120523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:08.255943Z","time spent":"864.542997ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:27:09.120682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.887511ms","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-27T01:27:09.120741Z","caller":"traceutil/trace.go:171","msg":"trace[695816783] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1052; }","duration":"861.960512ms","start":"2026-02-27T01:27:08.258762Z","end":"2026-02-27T01:27:09.120722Z","steps":["trace[695816783] 'agreement among raft nodes before linearized reading' (duration: 861.740597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:09.120778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:08.258757Z","time spent":"862.013083ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-27T01:27:09.120812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.893911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:09.120857Z","caller":"traceutil/trace.go:171","msg":"trace[1110344604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"621.983043ms","start":"2026-02-27T01:27:08.498861Z","end":"2026-02-27T01:27:09.120844Z","steps":["trace[1110344604] 'agreement among raft nodes before linearized reading' (duration: 621.906432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:09.120872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.028436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-02-27T01:27:09.120889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:08.498838Z","time spent":"622.043634ms","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T01:27:09.120905Z","caller":"traceutil/trace.go:171","msg":"trace[833129395] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1052; }","duration":"645.108428ms","start":"2026-02-27T01:27:08.475786Z","end":"2026-02-27T01:27:09.120894Z","steps":["trace[833129395] 'agreement among raft nodes before linearized reading' (duration: 645.006865ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:09.120911Z","caller":"traceutil/trace.go:171","msg":"trace[333675228] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"862.639147ms","start":"2026-02-27T01:27:08.25826Z","end":"2026-02-27T01:27:09.120899Z","steps":["trace[333675228] 'process raft request' (duration: 862.002362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:09.120899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.588283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-27T01:27:09.120937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:08.475763Z","time spent":"645.167239ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-27T01:27:09.120923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.066537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:27:09.120968Z","caller":"traceutil/trace.go:171","msg":"trace[562934731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1052; }","duration":"809.706545ms","start":"2026-02-27T01:27:08.311243Z","end":"2026-02-27T01:27:09.12095Z","steps":["trace[562934731] 'agreement among raft nodes before linearized reading' (duration: 809.48187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:09.120977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:08.258249Z","time spent":"862.685567ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:27:09.121016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:08.311227Z","time spent":"809.780707ms","remote":"127.0.0.1:48572","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-27T01:27:09.120992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.518171ms","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-27T01:27:09.121024Z","caller":"traceutil/trace.go:171","msg":"trace[817568963] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1052; }","duration":"492.229151ms","start":"2026-02-27T01:27:08.628767Z","end":"2026-02-27T01:27:09.120996Z","steps":["trace[817568963] 'agreement among raft nodes before linearized reading' (duration: 492.038357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:09.121043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.223502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T01:27:09.121067Z","caller":"traceutil/trace.go:171","msg":"trace[1670740710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"809.659164ms","start":"2026-02-27T01:27:08.311388Z","end":"2026-02-27T01:27:09.121047Z","steps":["trace[1670740710] 'agreement among raft nodes before linearized reading' (duration: 809.423949ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:27:09.121081Z","caller":"traceutil/trace.go:171","msg":"trace[1203349374] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1052; }","duration":"559.305154ms","start":"2026-02-27T01:27:08.561766Z","end":"2026-02-27T01:27:09.121071Z","steps":["trace[1203349374] 'agreement among raft nodes before linearized reading' (duration: 559.239723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:09.121082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:08.628751Z","time spent":"492.320002ms","remote":"127.0.0.1:48584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-27T01:27:09.121111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:08.561745Z","time spent":"559.357565ms","remote":"127.0.0.1:41678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-27T01:27:09.121112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:27:08.311356Z","time spent":"809.746986ms","remote":"127.0.0.1:48572","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":"warn","ts":"2026-02-27T01:30:10.136757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.401617ms","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-27T01:30:10.136843Z","caller":"traceutil/trace.go:171","msg":"trace[794437353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1906; }","duration":"135.533059ms","start":"2026-02-27T01:30:10.001292Z","end":"2026-02-27T01:30:10.136825Z","steps":["trace[794437353] 'range keys from in-memory index tree' (duration: 135.240493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:30:10.136847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.830208ms","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-27T01:30:10.136936Z","caller":"traceutil/trace.go:171","msg":"trace[194773694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1906; }","duration":"130.965251ms","start":"2026-02-27T01:30:10.005945Z","end":"2026-02-27T01:30:10.136911Z","steps":["trace[194773694] 'range keys from in-memory index tree' (duration: 130.620493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:30:18.41834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.928406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T01:30:18.418433Z","caller":"traceutil/trace.go:171","msg":"trace[1174683867] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1949; }","duration":"165.11094ms","start":"2026-02-27T01:30:18.253301Z","end":"2026-02-27T01:30:18.418412Z","steps":["trace[1174683867] 'count revisions from in-memory index tree' (duration: 164.833374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:30:18.418764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.293336ms","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-27T01:30:18.418863Z","caller":"traceutil/trace.go:171","msg":"trace[1825812432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1949; }","duration":"139.44677ms","start":"2026-02-27T01:30:18.279391Z","end":"2026-02-27T01:30:18.418837Z","steps":["trace[1825812432] 'range keys from in-memory index tree' (duration: 138.829887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:30:18.809991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.527096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T01:30:18.810064Z","caller":"traceutil/trace.go:171","msg":"trace[227484646] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1950; }","duration":"177.612198ms","start":"2026-02-27T01:30:18.632434Z","end":"2026-02-27T01:30:18.810046Z","steps":["trace[227484646] 'count revisions from in-memory index tree' (duration: 177.441385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:30:19.521006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.012647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:30:19.521097Z","caller":"traceutil/trace.go:171","msg":"trace[298606063] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2030; }","duration":"346.600982ms","start":"2026-02-27T01:30:19.174482Z","end":"2026-02-27T01:30:19.521083Z","steps":["trace[298606063] 'read index received' (duration: 154.241617ms)","trace[298606063] 'applied index is now lower than readState.Index' (duration: 192.358245ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:30:19.521204Z","caller":"traceutil/trace.go:171","msg":"trace[1419493589] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"551.200178ms","start":"2026-02-27T01:30:18.969993Z","end":"2026-02-27T01:30:19.521193Z","steps":["trace[1419493589] 'process raft request' (duration: 358.797233ms)","trace[1419493589] 'compare' (duration: 191.708769ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:30:19.52135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.874967ms","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-27T01:30:19.521376Z","caller":"traceutil/trace.go:171","msg":"trace[1409000977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1952; }","duration":"346.938249ms","start":"2026-02-27T01:30:19.17443Z","end":"2026-02-27T01:30:19.521368Z","steps":["trace[1409000977] 'agreement among raft nodes before linearized reading' (duration: 346.824446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:30:19.521403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:30:19.174407Z","time spent":"346.98952ms","remote":"127.0.0.1:48572","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\" "} {"level":"warn","ts":"2026-02-27T01:30:19.521413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.74276ms","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-27T01:30:19.521498Z","caller":"traceutil/trace.go:171","msg":"trace[82711160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1952; }","duration":"343.875533ms","start":"2026-02-27T01:30:19.1776Z","end":"2026-02-27T01:30:19.521476Z","steps":["trace[82711160] 'agreement among raft nodes before linearized reading' (duration: 343.671439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:30:19.521553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:30:19.177569Z","time spent":"343.966315ms","remote":"127.0.0.1:48572","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":"warn","ts":"2026-02-27T01:30:19.521818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:30:18.969974Z","time spent":"551.253609ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:30:19.669467Z","caller":"traceutil/trace.go:171","msg":"trace[1549887298] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"106.536875ms","start":"2026-02-27T01:30:19.562911Z","end":"2026-02-27T01:30:19.669448Z","steps":["trace[1549887298] 'process raft request' (duration: 106.374201ms)"],"step_count":1}