{"level":"warn","ts":"2026-02-27T21:01:40.473225Z","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-27T21:01:40.473356Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-27T21:01:40.473444Z","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-27T21:01:40.473461Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-02-27T21:01:40.473494Z","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-27T21:01:40.474282Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-02-27T21:01:40.47441Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-27T21:01:40.478539Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.856549ms"} {"level":"info","ts":"2026-02-27T21:01:40.490042Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-02-27T21:01:40.490224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T21:01:40.490261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-02-27T21:01:40.490283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T21:01:40.490296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-02-27T21:01:40.490352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-02-27T21:01:40.49839Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T21:01:40.500493Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T21:01:40.501879Z","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-27T21:01:40.505269Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T21:01:40.506178Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T21:01:40.506402Z","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-27T21:01:40.506629Z","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-27T21:01:40.506654Z","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-27T21:01:40.513108Z","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-27T21:01:40.513582Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T21:01:40.513668Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T21:01:40.513997Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-02-27T21:01:40.514022Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-02-27T21:01:40.517092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-02-27T21:01:40.517402Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-02-27T21:01:40.591134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T21:01:40.591193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T21:01:40.591213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-02-27T21:01:40.591228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-02-27T21:01:40.591236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-02-27T21:01:40.591248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-02-27T21:01:40.591259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-02-27T21:01:40.592026Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T21:01:40.592027Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:01:40.592171Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T21:01:40.592214Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T21:01:40.593014Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:01:40.593188Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:01:40.593673Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:01:40.594413Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T21:01:40.594451Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T21:01:40.595096Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T21:01:40.595565Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-02-27T21:02:08.863698Z","caller":"traceutil/trace.go:171","msg":"trace[675202978] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"301.15062ms","start":"2026-02-27T21:02:08.562524Z","end":"2026-02-27T21:02:08.863674Z","steps":["trace[675202978] 'read index received' (duration: 300.953654ms)","trace[675202978] 'applied index is now lower than readState.Index' (duration: 196.176µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:02:08.863845Z","caller":"traceutil/trace.go:171","msg":"trace[217688869] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"412.433399ms","start":"2026-02-27T21:02:08.451373Z","end":"2026-02-27T21:02:08.863806Z","steps":["trace[217688869] 'process raft request' (duration: 412.16518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:08.864271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.709129ms","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-27T21:02:08.864397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:08.451334Z","time spent":"412.539762ms","remote":"127.0.0.1:39260","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-27T21:02:08.864453Z","caller":"traceutil/trace.go:171","msg":"trace[897951933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"301.941357ms","start":"2026-02-27T21:02:08.562479Z","end":"2026-02-27T21:02:08.86442Z","steps":["trace[897951933] 'agreement among raft nodes before linearized reading' (duration: 301.649387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:08.864531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:08.562458Z","time spent":"302.05943ms","remote":"127.0.0.1:39260","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-27T21:02:09.170578Z","caller":"traceutil/trace.go:171","msg":"trace[2114109292] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"302.370172ms","start":"2026-02-27T21:02:08.868161Z","end":"2026-02-27T21:02:09.170531Z","steps":["trace[2114109292] 'process raft request' (duration: 240.481645ms)","trace[2114109292] 'compare' (duration: 61.536215ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:09.170803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:08.868135Z","time spent":"302.560889ms","remote":"127.0.0.1:39260","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-27T21:02:12.111203Z","caller":"traceutil/trace.go:171","msg":"trace[1319766034] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"370.063204ms","start":"2026-02-27T21:02:11.741114Z","end":"2026-02-27T21:02:12.111178Z","steps":["trace[1319766034] 'process raft request' (duration: 369.516735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:12.11138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:11.741096Z","time spent":"370.201949ms","remote":"127.0.0.1:39260","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-27T21:02:14.220482Z","caller":"traceutil/trace.go:171","msg":"trace[747253415] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"105.036709ms","start":"2026-02-27T21:02:14.115414Z","end":"2026-02-27T21:02:14.220451Z","steps":["trace[747253415] 'read index received' (duration: 104.76375ms)","trace[747253415] 'applied index is now lower than readState.Index' (duration: 271.259µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:14.220765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.327629ms","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-27T21:02:14.220858Z","caller":"traceutil/trace.go:171","msg":"trace[328726362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"105.435182ms","start":"2026-02-27T21:02:14.115401Z","end":"2026-02-27T21:02:14.220836Z","steps":["trace[328726362] 'agreement among raft nodes before linearized reading' (duration: 105.122932ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:17.860427Z","caller":"traceutil/trace.go:171","msg":"trace[737156617] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"135.841768ms","start":"2026-02-27T21:02:17.724542Z","end":"2026-02-27T21:02:17.860384Z","steps":["trace[737156617] 'process raft request' (duration: 135.618241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:19.696059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.822527ms","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-27T21:02:19.696194Z","caller":"traceutil/trace.go:171","msg":"trace[801140499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"790.017043ms","start":"2026-02-27T21:02:18.90614Z","end":"2026-02-27T21:02:19.696158Z","steps":["trace[801140499] 'range keys from in-memory index tree' (duration: 789.569469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:19.696253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:18.906113Z","time spent":"790.125257ms","remote":"127.0.0.1:39260","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-27T21:02:19.696327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.309666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:02:19.696411Z","caller":"traceutil/trace.go:171","msg":"trace[1419979407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"911.460341ms","start":"2026-02-27T21:02:18.78493Z","end":"2026-02-27T21:02:19.69639Z","steps":["trace[1419979407] 'range keys from in-memory index tree' (duration: 911.210182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:19.696488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:18.7849Z","time spent":"911.578595ms","remote":"127.0.0.1:38974","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-27T21:02:19.775364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.867038ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-27T21:02:19.775987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.034239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-27T21:02:19.776097Z","caller":"traceutil/trace.go:171","msg":"trace[885306424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"510.204605ms","start":"2026-02-27T21:02:19.265872Z","end":"2026-02-27T21:02:19.776077Z","steps":["trace[885306424] 'agreement among raft nodes before linearized reading' (duration: 509.891275ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:19.776286Z","caller":"traceutil/trace.go:171","msg":"trace[1433086353] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"509.672118ms","start":"2026-02-27T21:02:19.265932Z","end":"2026-02-27T21:02:19.775604Z","steps":["trace[1433086353] 'read index received' (duration: 312.038575ms)","trace[1433086353] 'applied index is now lower than readState.Index' (duration: 197.630643ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:19.777058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:19.265843Z","time spent":"511.174798ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T21:02:32.139392Z","caller":"traceutil/trace.go:171","msg":"trace[1596860130] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"277.051316ms","start":"2026-02-27T21:02:31.862305Z","end":"2026-02-27T21:02:32.139361Z","steps":["trace[1596860130] 'process raft request' (duration: 276.832819ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:32.139949Z","caller":"traceutil/trace.go:171","msg":"trace[106974758] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"276.110934ms","start":"2026-02-27T21:02:31.863813Z","end":"2026-02-27T21:02:32.139924Z","steps":["trace[106974758] 'process raft request' (duration: 275.966479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:38.293921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493031,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:02:38.794577Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493031,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:02:39.250568Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.81746618s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T21:02:39.251115Z","caller":"traceutil/trace.go:171","msg":"trace[1117194752] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"1.457240324s","start":"2026-02-27T21:02:37.793837Z","end":"2026-02-27T21:02:39.251077Z","steps":["trace[1117194752] 'read index received' (duration: 1.456975286s)","trace[1117194752] 'applied index is now lower than readState.Index' (duration: 258.858µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:02:39.251248Z","caller":"traceutil/trace.go:171","msg":"trace[366791285] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"1.818206073s","start":"2026-02-27T21:02:37.432963Z","end":"2026-02-27T21:02:39.25117Z","steps":["trace[366791285] 'process raft request' (duration: 1.817875963s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:39.25136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457487102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:02:39.251429Z","caller":"traceutil/trace.go:171","msg":"trace[251846792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"1.457603606s","start":"2026-02-27T21:02:37.793796Z","end":"2026-02-27T21:02:39.251399Z","steps":["trace[251846792] 'agreement among raft nodes before linearized reading' (duration: 1.457461941s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:39.251428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:37.432942Z","time spent":"1.818389298s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:02:39.251469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:37.793776Z","time spent":"1.457685408s","remote":"127.0.0.1:38974","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-27T21:02:39.751846Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493034,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:02:40.252885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493034,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:02:40.753427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493034,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:02:40.832654Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.581875616s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T21:02:41.114086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.204748ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T21:02:41.114199Z","caller":"traceutil/trace.go:171","msg":"trace[687817190] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"1.86295988s","start":"2026-02-27T21:02:39.251219Z","end":"2026-02-27T21:02:41.114179Z","steps":["trace[687817190] 'read index received' (duration: 1.581587327s)","trace[687817190] 'applied index is now lower than readState.Index' (duration: 281.371042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:41.114565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.952242846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-27T21:02:41.114608Z","caller":"traceutil/trace.go:171","msg":"trace[380947939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:714; }","duration":"2.952324809s","start":"2026-02-27T21:02:38.16227Z","end":"2026-02-27T21:02:41.114595Z","steps":["trace[380947939] 'agreement among raft nodes before linearized reading' (duration: 2.952125842s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.114643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:38.162246Z","time spent":"2.952387901s","remote":"127.0.0.1:39260","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-27T21:02:41.114624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.754441302s","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-27T21:02:41.114608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.747267946s","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-27T21:02:41.114705Z","caller":"traceutil/trace.go:171","msg":"trace[311323437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"2.754567606s","start":"2026-02-27T21:02:38.360118Z","end":"2026-02-27T21:02:41.114686Z","steps":["trace[311323437] 'agreement among raft nodes before linearized reading' (duration: 2.754389281s)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:41.114733Z","caller":"traceutil/trace.go:171","msg":"trace[505081925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:714; }","duration":"2.747444292s","start":"2026-02-27T21:02:38.36726Z","end":"2026-02-27T21:02:41.114704Z","steps":["trace[505081925] 'agreement among raft nodes before linearized reading' (duration: 2.747093341s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.114751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:38.360095Z","time spent":"2.754648988s","remote":"127.0.0.1:39260","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-27T21:02:41.114634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.919862747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-27T21:02:41.114789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:38.367246Z","time spent":"2.747530464s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T21:02:41.114798Z","caller":"traceutil/trace.go:171","msg":"trace[1743846144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"2.920052673s","start":"2026-02-27T21:02:38.194726Z","end":"2026-02-27T21:02:41.114779Z","steps":["trace[1743846144] 'agreement among raft nodes before linearized reading' (duration: 2.919669541s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.114844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:38.194669Z","time spent":"2.920164696s","remote":"127.0.0.1:39260","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":"info","ts":"2026-02-27T21:02:41.20732Z","caller":"traceutil/trace.go:171","msg":"trace[1743066976] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"131.245579ms","start":"2026-02-27T21:02:41.076001Z","end":"2026-02-27T21:02:41.207247Z","steps":["trace[1743066976] 'process raft request' (duration: 131.054433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.207373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.994096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:02:41.207456Z","caller":"traceutil/trace.go:171","msg":"trace[936584343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:715; }","duration":"127.12131ms","start":"2026-02-27T21:02:41.080317Z","end":"2026-02-27T21:02:41.207438Z","steps":["trace[936584343] 'agreement among raft nodes before linearized reading' (duration: 126.942075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.20832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.955028237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:02:41.208396Z","caller":"traceutil/trace.go:171","msg":"trace[1058136706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:715; }","duration":"1.955187902s","start":"2026-02-27T21:02:39.253188Z","end":"2026-02-27T21:02:41.208376Z","steps":["trace[1058136706] 'agreement among raft nodes before linearized reading' (duration: 1.954031786s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.208446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:39.253149Z","time spent":"1.955286835s","remote":"127.0.0.1:38962","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-27T21:02:41.520059Z","caller":"traceutil/trace.go:171","msg":"trace[1382316326] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"399.951246ms","start":"2026-02-27T21:02:41.120085Z","end":"2026-02-27T21:02:41.520036Z","steps":["trace[1382316326] 'process raft request' (duration: 399.689657ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:41.52007Z","caller":"traceutil/trace.go:171","msg":"trace[774434727] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"399.848092ms","start":"2026-02-27T21:02:41.120197Z","end":"2026-02-27T21:02:41.520045Z","steps":["trace[774434727] 'process raft request' (duration: 399.763899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.520225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:41.120055Z","time spent":"400.082ms","remote":"127.0.0.1:39260","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-27T21:02:41.520245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:41.120192Z","time spent":"399.965835ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:02:41.520358Z","caller":"traceutil/trace.go:171","msg":"trace[876278519] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"398.425087ms","start":"2026-02-27T21:02:41.121918Z","end":"2026-02-27T21:02:41.520343Z","steps":["trace[876278519] 'process raft request' (duration: 398.083856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.520416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:41.121888Z","time spent":"398.49883ms","remote":"127.0.0.1:39260","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-27T21:02:41.520505Z","caller":"traceutil/trace.go:171","msg":"trace[858273614] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"397.478588ms","start":"2026-02-27T21:02:41.12301Z","end":"2026-02-27T21:02:41.520488Z","steps":["trace[858273614] 'process raft request' (duration: 397.278611ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:41.520477Z","caller":"traceutil/trace.go:171","msg":"trace[1973391005] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:739; }","duration":"303.660395ms","start":"2026-02-27T21:02:41.216782Z","end":"2026-02-27T21:02:41.520443Z","steps":["trace[1973391005] 'read index received' (duration: 302.901671ms)","trace[1973391005] 'applied index is now lower than readState.Index' (duration: 757.144µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:41.520582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:41.122988Z","time spent":"397.545129ms","remote":"127.0.0.1:39260","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-27T21:02:41.520628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.838621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:02:41.520677Z","caller":"traceutil/trace.go:171","msg":"trace[88370409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"303.919393ms","start":"2026-02-27T21:02:41.216745Z","end":"2026-02-27T21:02:41.520664Z","steps":["trace[88370409] 'agreement among raft nodes before linearized reading' (duration: 303.833071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.520714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:41.216725Z","time spent":"303.982725ms","remote":"127.0.0.1:38962","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-27T21:02:41.520726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.073189ms","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-27T21:02:41.520756Z","caller":"traceutil/trace.go:171","msg":"trace[186668841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:719; }","duration":"264.154282ms","start":"2026-02-27T21:02:41.256591Z","end":"2026-02-27T21:02:41.520745Z","steps":["trace[186668841] 'agreement among raft nodes before linearized reading' (duration: 264.059449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.683983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.630568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:02:41.684167Z","caller":"traceutil/trace.go:171","msg":"trace[1384497408] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"135.476033ms","start":"2026-02-27T21:02:41.548676Z","end":"2026-02-27T21:02:41.684152Z","steps":["trace[1384497408] 'read index received' (duration: 26.602517ms)","trace[1384497408] 'applied index is now lower than readState.Index' (duration: 108.872416ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:41.684238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.564506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:02:41.684264Z","caller":"traceutil/trace.go:171","msg":"trace[1601853297] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:720; }","duration":"135.625838ms","start":"2026-02-27T21:02:41.548629Z","end":"2026-02-27T21:02:41.684255Z","steps":["trace[1601853297] 'agreement among raft nodes before linearized reading' (duration: 135.568086ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:41.684267Z","caller":"traceutil/trace.go:171","msg":"trace[385354150] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"158.365543ms","start":"2026-02-27T21:02:41.525865Z","end":"2026-02-27T21:02:41.684231Z","steps":["trace[385354150] 'process raft request' (duration: 49.402515ms)","trace[385354150] 'compare' (duration: 108.531635ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:44.007114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.554098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:02:44.007249Z","caller":"traceutil/trace.go:171","msg":"trace[1284790841] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"478.468155ms","start":"2026-02-27T21:02:43.528763Z","end":"2026-02-27T21:02:44.007231Z","steps":["trace[1284790841] 'process raft request' (duration: 172.732551ms)","trace[1284790841] 'compare' (duration: 305.445245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:44.007317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:43.528749Z","time spent":"478.539807ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:02:44.0081Z","caller":"traceutil/trace.go:171","msg":"trace[960944254] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"478.471485ms","start":"2026-02-27T21:02:43.529613Z","end":"2026-02-27T21:02:44.008084Z","steps":["trace[960944254] 'process raft request' (duration: 477.571937ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:44.008102Z","caller":"traceutil/trace.go:171","msg":"trace[1427096452] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"477.881236ms","start":"2026-02-27T21:02:43.530196Z","end":"2026-02-27T21:02:44.008077Z","steps":["trace[1427096452] 'process raft request' (duration: 477.786473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:44.008166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:43.529602Z","time spent":"478.534497ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:02:44.008247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:43.530166Z","time spent":"478.0118ms","remote":"127.0.0.1:39260","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-27T21:02:44.052304Z","caller":"traceutil/trace.go:171","msg":"trace[747773944] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:745; }","duration":"363.598551ms","start":"2026-02-27T21:02:43.688688Z","end":"2026-02-27T21:02:44.052286Z","steps":["trace[747773944] 'read index received' (duration: 12.824769ms)","trace[747773944] 'applied index is now lower than readState.Index' (duration: 350.772592ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:02:44.052431Z","caller":"traceutil/trace.go:171","msg":"trace[1057793149] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"404.178515ms","start":"2026-02-27T21:02:43.64815Z","end":"2026-02-27T21:02:44.052328Z","steps":["trace[1057793149] 'process raft request' (duration: 404.04005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:44.052583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.498559ms","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":"warn","ts":"2026-02-27T21:02:44.05259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.89505ms","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-27T21:02:44.052627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:43.648119Z","time spent":"404.400852ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:02:44.052659Z","caller":"traceutil/trace.go:171","msg":"trace[2019875430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:725; }","duration":"363.974042ms","start":"2026-02-27T21:02:43.688671Z","end":"2026-02-27T21:02:44.052645Z","steps":["trace[2019875430] 'agreement among raft nodes before linearized reading' (duration: 363.724174ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:44.052637Z","caller":"traceutil/trace.go:171","msg":"trace[889217525] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:725; }","duration":"138.625222ms","start":"2026-02-27T21:02:43.914003Z","end":"2026-02-27T21:02:44.052628Z","steps":["trace[889217525] 'agreement among raft nodes before linearized reading' (duration: 138.485948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:44.052698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:43.688609Z","time spent":"364.081265ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T21:02:44.052533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.818245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:02:44.052814Z","caller":"traceutil/trace.go:171","msg":"trace[935695536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"268.160615ms","start":"2026-02-27T21:02:43.784641Z","end":"2026-02-27T21:02:44.052802Z","steps":["trace[935695536] 'agreement among raft nodes before linearized reading' (duration: 267.821124ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:45.676429Z","caller":"traceutil/trace.go:171","msg":"trace[65551460] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"152.95118ms","start":"2026-02-27T21:02:45.523435Z","end":"2026-02-27T21:02:45.676386Z","steps":["trace[65551460] 'process raft request' (duration: 152.719292ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:46.127581Z","caller":"traceutil/trace.go:171","msg":"trace[1662071295] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"115.562767ms","start":"2026-02-27T21:02:46.011996Z","end":"2026-02-27T21:02:46.127558Z","steps":["trace[1662071295] 'read index received' (duration: 95.362082ms)","trace[1662071295] 'applied index is now lower than readState.Index' (duration: 20.199655ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:02:46.127748Z","caller":"traceutil/trace.go:171","msg":"trace[347394299] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"130.325668ms","start":"2026-02-27T21:02:45.997374Z","end":"2026-02-27T21:02:46.127699Z","steps":["trace[347394299] 'process raft request' (duration: 110.034451ms)","trace[347394299] 'compare' (duration: 20.038189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:46.127884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.603888ms","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-27T21:02:46.127923Z","caller":"traceutil/trace.go:171","msg":"trace[1001072861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"115.648039ms","start":"2026-02-27T21:02:46.012262Z","end":"2026-02-27T21:02:46.12791Z","steps":["trace[1001072861] 'agreement among raft nodes before linearized reading' (duration: 115.570837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:46.128097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.275286ms","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-27T21:02:46.128124Z","caller":"traceutil/trace.go:171","msg":"trace[788157793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"114.314727ms","start":"2026-02-27T21:02:46.013801Z","end":"2026-02-27T21:02:46.128116Z","steps":["trace[788157793] 'agreement among raft nodes before linearized reading' (duration: 114.242814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:46.127805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.790454ms","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-27T21:02:46.128228Z","caller":"traceutil/trace.go:171","msg":"trace[1832003572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"116.269429ms","start":"2026-02-27T21:02:46.011947Z","end":"2026-02-27T21:02:46.128217Z","steps":["trace[1832003572] 'agreement among raft nodes before linearized reading' (duration: 115.728922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:46.536103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.055112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-27T21:02:46.536342Z","caller":"traceutil/trace.go:171","msg":"trace[398745228] transaction","detail":"{read_only:false; number_of_response:1; response_revision:734; }","duration":"405.810008ms","start":"2026-02-27T21:02:46.130425Z","end":"2026-02-27T21:02:46.536235Z","steps":["trace[398745228] 'process raft request' (duration: 106.415906ms)","trace[398745228] 'compare' (duration: 298.834544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:46.536446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:46.130403Z","time spent":"405.988473ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-02-27T21:02:46.536954Z","caller":"traceutil/trace.go:171","msg":"trace[684350406] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"404.815676ms","start":"2026-02-27T21:02:46.132114Z","end":"2026-02-27T21:02:46.536929Z","steps":["trace[684350406] 'process raft request' (duration: 404.63567ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:46.536957Z","caller":"traceutil/trace.go:171","msg":"trace[125426773] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:758; }","duration":"373.103954ms","start":"2026-02-27T21:02:46.163833Z","end":"2026-02-27T21:02:46.536937Z","steps":["trace[125426773] 'read index received' (duration: 72.990219ms)","trace[125426773] 'applied index is now lower than readState.Index' (duration: 300.113005ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:02:46.537067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:46.132095Z","time spent":"404.918078ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:02:46.537084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.233508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:02:46.537112Z","caller":"traceutil/trace.go:171","msg":"trace[1088759660] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:738; }","duration":"373.31614ms","start":"2026-02-27T21:02:46.163787Z","end":"2026-02-27T21:02:46.537103Z","steps":["trace[1088759660] 'agreement among raft nodes before linearized reading' (duration: 373.230528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:46.537137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:46.163767Z","time spent":"373.363052ms","remote":"127.0.0.1:39164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-02-27T21:02:46.537232Z","caller":"traceutil/trace.go:171","msg":"trace[1035719286] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"404.709822ms","start":"2026-02-27T21:02:46.132502Z","end":"2026-02-27T21:02:46.537212Z","steps":["trace[1035719286] 'process raft request' (duration: 404.383301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:46.537314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:46.132493Z","time spent":"404.779015ms","remote":"127.0.0.1:39260","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-27T21:02:46.537308Z","caller":"traceutil/trace.go:171","msg":"trace[1978819471] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"403.543665ms","start":"2026-02-27T21:02:46.133755Z","end":"2026-02-27T21:02:46.537299Z","steps":["trace[1978819471] 'process raft request' (duration: 403.156202ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:46.537421Z","caller":"traceutil/trace.go:171","msg":"trace[277245966] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"405.240869ms","start":"2026-02-27T21:02:46.132168Z","end":"2026-02-27T21:02:46.537409Z","steps":["trace[277245966] 'process raft request' (duration: 404.683121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:46.537444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:46.133727Z","time spent":"403.633628ms","remote":"127.0.0.1:39260","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-27T21:02:46.537489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:02:46.132151Z","time spent":"405.308942ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:02:46.663968Z","caller":"traceutil/trace.go:171","msg":"trace[399088802] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"237.412455ms","start":"2026-02-27T21:02:46.426525Z","end":"2026-02-27T21:02:46.663938Z","steps":["trace[399088802] 'process raft request' (duration: 236.94612ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:51.832639Z","caller":"traceutil/trace.go:171","msg":"trace[925523665] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"106.959092ms","start":"2026-02-27T21:02:51.725652Z","end":"2026-02-27T21:02:51.832611Z","steps":["trace[925523665] 'process raft request' (duration: 106.725525ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:52.737103Z","caller":"traceutil/trace.go:171","msg":"trace[1198354024] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"130.977519ms","start":"2026-02-27T21:02:52.606101Z","end":"2026-02-27T21:02:52.737078Z","steps":["trace[1198354024] 'process raft request' (duration: 130.828684ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:52.770718Z","caller":"traceutil/trace.go:171","msg":"trace[1574230793] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"161.985268ms","start":"2026-02-27T21:02:52.608709Z","end":"2026-02-27T21:02:52.770694Z","steps":["trace[1574230793] 'process raft request' (duration: 161.942987ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:52.770899Z","caller":"traceutil/trace.go:171","msg":"trace[844074285] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"163.256099ms","start":"2026-02-27T21:02:52.607622Z","end":"2026-02-27T21:02:52.770878Z","steps":["trace[844074285] 'process raft request' (duration: 133.995295ms)","trace[844074285] 'compare' (duration: 28.872742ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:02:52.770989Z","caller":"traceutil/trace.go:171","msg":"trace[1858493526] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"162.454633ms","start":"2026-02-27T21:02:52.608523Z","end":"2026-02-27T21:02:52.770978Z","steps":["trace[1858493526] 'process raft request' (duration: 162.079651ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:04.416393Z","caller":"traceutil/trace.go:171","msg":"trace[214762810] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"204.064161ms","start":"2026-02-27T21:03:04.212311Z","end":"2026-02-27T21:03:04.416375Z","steps":["trace[214762810] 'process raft request' (duration: 203.947797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:05.083471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.712637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:03:05.083793Z","caller":"traceutil/trace.go:171","msg":"trace[631158151] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"223.46982ms","start":"2026-02-27T21:03:04.8603Z","end":"2026-02-27T21:03:05.08377Z","steps":["trace[631158151] 'process raft request' (duration: 223.414128ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:05.083797Z","caller":"traceutil/trace.go:171","msg":"trace[776807557] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"223.48422ms","start":"2026-02-27T21:03:04.860269Z","end":"2026-02-27T21:03:05.083753Z","steps":["trace[776807557] 'process raft request' (duration: 104.340109ms)","trace[776807557] 'compare' (duration: 118.578163ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:05.083853Z","caller":"traceutil/trace.go:171","msg":"trace[1418764916] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"223.520811ms","start":"2026-02-27T21:03:04.860312Z","end":"2026-02-27T21:03:05.083832Z","steps":["trace[1418764916] 'process raft request' (duration: 223.324175ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:06.146425Z","caller":"traceutil/trace.go:171","msg":"trace[1099339706] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"228.986535ms","start":"2026-02-27T21:03:05.917417Z","end":"2026-02-27T21:03:06.146403Z","steps":["trace[1099339706] 'process raft request' (duration: 228.849091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:07.85401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.018746ms","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-27T21:03:07.854093Z","caller":"traceutil/trace.go:171","msg":"trace[769339237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"764.135549ms","start":"2026-02-27T21:03:07.089937Z","end":"2026-02-27T21:03:07.854073Z","steps":["trace[769339237] 'range keys from in-memory index tree' (duration: 763.815959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:07.854134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:07.089921Z","time spent":"764.204341ms","remote":"127.0.0.1:39260","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-27T21:03:07.854008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.015606ms","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-27T21:03:07.854344Z","caller":"traceutil/trace.go:171","msg":"trace[865089770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"764.397888ms","start":"2026-02-27T21:03:07.089934Z","end":"2026-02-27T21:03:07.854331Z","steps":["trace[865089770] 'range keys from in-memory index tree' (duration: 763.788429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:07.854382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:07.089918Z","time spent":"764.454669ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T21:03:07.860246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.250314ms","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-27T21:03:07.86052Z","caller":"traceutil/trace.go:171","msg":"trace[1351153740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"770.362608ms","start":"2026-02-27T21:03:07.08993Z","end":"2026-02-27T21:03:07.860292Z","steps":["trace[1351153740] 'range keys from in-memory index tree' (duration: 763.631983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:07.860622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:07.08991Z","time spent":"770.702399ms","remote":"127.0.0.1:39260","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":"info","ts":"2026-02-27T21:03:07.94749Z","caller":"traceutil/trace.go:171","msg":"trace[623748091] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"337.651243ms","start":"2026-02-27T21:03:07.609809Z","end":"2026-02-27T21:03:07.947461Z","steps":["trace[623748091] 'process raft request' (duration: 337.515039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:07.947621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:07.609767Z","time spent":"337.782167ms","remote":"127.0.0.1:39260","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-27T21:03:08.146783Z","caller":"traceutil/trace.go:171","msg":"trace[573738138] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"287.651077ms","start":"2026-02-27T21:03:07.859108Z","end":"2026-02-27T21:03:08.146759Z","steps":["trace[573738138] 'process raft request' (duration: 287.574005ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:08.146794Z","caller":"traceutil/trace.go:171","msg":"trace[657150860] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"287.966967ms","start":"2026-02-27T21:03:07.858795Z","end":"2026-02-27T21:03:08.146762Z","steps":["trace[657150860] 'process raft request' (duration: 287.73024ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:08.14696Z","caller":"traceutil/trace.go:171","msg":"trace[1878498726] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"283.368851ms","start":"2026-02-27T21:03:07.863557Z","end":"2026-02-27T21:03:08.146926Z","steps":["trace[1878498726] 'process raft request' (duration: 283.168944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:10.682684Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493280,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:03:11.183651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493280,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:03:11.684685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493280,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:03:11.789666Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.633083472s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T21:03:11.790144Z","caller":"traceutil/trace.go:171","msg":"trace[1478925544] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"1.63367416s","start":"2026-02-27T21:03:10.156445Z","end":"2026-02-27T21:03:11.790119Z","steps":["trace[1478925544] 'process raft request' (duration: 1.633517525s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:11.790298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:10.156428Z","time spent":"1.633788644s","remote":"127.0.0.1:39260","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-27T21:03:12.368721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.009372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:03:12.369256Z","caller":"traceutil/trace.go:171","msg":"trace[507811059] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"2.212476787s","start":"2026-02-27T21:03:10.156746Z","end":"2026-02-27T21:03:12.369223Z","steps":["trace[507811059] 'process raft request' (duration: 1.932786724s)","trace[507811059] 'compare' (duration: 278.549767ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:12.369316Z","caller":"traceutil/trace.go:171","msg":"trace[1151829770] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:828; }","duration":"2.187279763s","start":"2026-02-27T21:03:10.182019Z","end":"2026-02-27T21:03:12.369299Z","steps":["trace[1151829770] 'read index received' (duration: 1.607868097s)","trace[1151829770] 'applied index is now lower than readState.Index' (duration: 579.410545ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:12.369369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:10.156739Z","time spent":"2.2125799s","remote":"127.0.0.1:39260","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-27T21:03:12.369436Z","caller":"traceutil/trace.go:171","msg":"trace[59758655] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"2.211520826s","start":"2026-02-27T21:03:10.157834Z","end":"2026-02-27T21:03:12.369355Z","steps":["trace[59758655] 'process raft request' (duration: 2.210979459s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:12.369859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.187842251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-27T21:03:12.369886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:10.157808Z","time spent":"2.211800865s","remote":"127.0.0.1:39260","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-27T21:03:12.369925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.33477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T21:03:12.369931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572668194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:12.369988Z","caller":"traceutil/trace.go:171","msg":"trace[1603512670] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:801; }","duration":"861.452664ms","start":"2026-02-27T21:03:11.508513Z","end":"2026-02-27T21:03:12.369966Z","steps":["trace[1603512670] 'agreement among raft nodes before linearized reading' (duration: 861.366371ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:12.37004Z","caller":"traceutil/trace.go:171","msg":"trace[442864897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"1.572830099s","start":"2026-02-27T21:03:10.797184Z","end":"2026-02-27T21:03:12.370014Z","steps":["trace[442864897] 'agreement among raft nodes before linearized reading' (duration: 1.572674314s)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:12.369909Z","caller":"traceutil/trace.go:171","msg":"trace[317219366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"2.187930923s","start":"2026-02-27T21:03:10.181963Z","end":"2026-02-27T21:03:12.369894Z","steps":["trace[317219366] 'agreement among raft nodes before linearized reading' (duration: 2.187589243s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:12.370093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:10.797162Z","time spent":"1.572923143s","remote":"127.0.0.1:38974","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-27T21:03:12.370072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427883744s","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-27T21:03:12.370158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:11.508485Z","time spent":"861.609559ms","remote":"127.0.0.1:39392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-27T21:03:12.370119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:10.181948Z","time spent":"2.188158801s","remote":"127.0.0.1:39260","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":"info","ts":"2026-02-27T21:03:12.370157Z","caller":"traceutil/trace.go:171","msg":"trace[1351569843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"1.428035009s","start":"2026-02-27T21:03:10.942099Z","end":"2026-02-27T21:03:12.370134Z","steps":["trace[1351569843] 'agreement among raft nodes before linearized reading' (duration: 1.427783991s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:12.370221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:10.942068Z","time spent":"1.428135532s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T21:03:12.679627Z","caller":"traceutil/trace.go:171","msg":"trace[882599241] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"304.247597ms","start":"2026-02-27T21:03:12.375339Z","end":"2026-02-27T21:03:12.679587Z","steps":["trace[882599241] 'process raft request' (duration: 291.951035ms)","trace[882599241] 'compare' (duration: 12.039674ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:12.679814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:12.375326Z","time spent":"304.396461ms","remote":"127.0.0.1:39260","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-27T21:03:12.931461Z","caller":"traceutil/trace.go:171","msg":"trace[851188801] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"555.240674ms","start":"2026-02-27T21:03:12.376179Z","end":"2026-02-27T21:03:12.93142Z","steps":["trace[851188801] 'read index received' (duration: 291.122868ms)","trace[851188801] 'applied index is now lower than readState.Index' (duration: 264.116016ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:12.931658Z","caller":"traceutil/trace.go:171","msg":"trace[150816876] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"555.812543ms","start":"2026-02-27T21:03:12.375811Z","end":"2026-02-27T21:03:12.931623Z","steps":["trace[150816876] 'process raft request' (duration: 484.022592ms)","trace[150816876] 'compare' (duration: 71.208002ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:12.931809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:12.375797Z","time spent":"555.930466ms","remote":"127.0.0.1:39260","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-27T21:03:12.932038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.818233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:12.932144Z","caller":"traceutil/trace.go:171","msg":"trace[1402707846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"555.956157ms","start":"2026-02-27T21:03:12.376163Z","end":"2026-02-27T21:03:12.932119Z","steps":["trace[1402707846] 'agreement among raft nodes before linearized reading' (duration: 555.467222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:12.932203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:12.376155Z","time spent":"556.03695ms","remote":"127.0.0.1:38974","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-27T21:03:12.932221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.305675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:03:12.932325Z","caller":"traceutil/trace.go:171","msg":"trace[1284639599] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:803; }","duration":"397.44977ms","start":"2026-02-27T21:03:12.53485Z","end":"2026-02-27T21:03:12.9323Z","steps":["trace[1284639599] 'agreement among raft nodes before linearized reading' (duration: 397.272145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:12.932415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:12.534836Z","time spent":"397.560793ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-02-27T21:03:15.914617Z","caller":"traceutil/trace.go:171","msg":"trace[28997018] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"106.225109ms","start":"2026-02-27T21:03:15.80837Z","end":"2026-02-27T21:03:15.914595Z","steps":["trace[28997018] 'process raft request' (duration: 106.049694ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:16.750939Z","caller":"traceutil/trace.go:171","msg":"trace[1631330547] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"344.898862ms","start":"2026-02-27T21:03:16.406007Z","end":"2026-02-27T21:03:16.750906Z","steps":["trace[1631330547] 'process raft request' (duration: 344.452028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:16.751102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:16.405981Z","time spent":"345.034017ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:03:16.924992Z","caller":"traceutil/trace.go:171","msg":"trace[1725290556] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"132.990783ms","start":"2026-02-27T21:03:16.791976Z","end":"2026-02-27T21:03:16.924967Z","steps":["trace[1725290556] 'read index received' (duration: 121.084853ms)","trace[1725290556] 'applied index is now lower than readState.Index' (duration: 11.90522ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:16.925114Z","caller":"traceutil/trace.go:171","msg":"trace[1102261375] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"169.853039ms","start":"2026-02-27T21:03:16.755228Z","end":"2026-02-27T21:03:16.925081Z","steps":["trace[1102261375] 'process raft request' (duration: 157.861686ms)","trace[1102261375] 'compare' (duration: 11.708944ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:16.925218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.208191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:16.925343Z","caller":"traceutil/trace.go:171","msg":"trace[830830108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"133.362225ms","start":"2026-02-27T21:03:16.791935Z","end":"2026-02-27T21:03:16.925297Z","steps":["trace[830830108] 'agreement among raft nodes before linearized reading' (duration: 133.143958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:17.209031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.563115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:03:17.209182Z","caller":"traceutil/trace.go:171","msg":"trace[1055704462] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"258.994072ms","start":"2026-02-27T21:03:16.950156Z","end":"2026-02-27T21:03:17.20915Z","steps":["trace[1055704462] 'process raft request' (duration: 112.194829ms)","trace[1055704462] 'compare' (duration: 146.40219ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:22.904135Z","caller":"traceutil/trace.go:171","msg":"trace[1770948446] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"119.775532ms","start":"2026-02-27T21:03:22.784335Z","end":"2026-02-27T21:03:22.90411Z","steps":["trace[1770948446] 'read index received' (duration: 119.612546ms)","trace[1770948446] 'applied index is now lower than readState.Index' (duration: 161.626µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:22.90427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.913526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:22.904306Z","caller":"traceutil/trace.go:171","msg":"trace[1372637795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:829; }","duration":"119.980647ms","start":"2026-02-27T21:03:22.784311Z","end":"2026-02-27T21:03:22.904292Z","steps":["trace[1372637795] 'agreement among raft nodes before linearized reading' (duration: 119.908135ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:22.904462Z","caller":"traceutil/trace.go:171","msg":"trace[818874221] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"120.632049ms","start":"2026-02-27T21:03:22.783802Z","end":"2026-02-27T21:03:22.904434Z","steps":["trace[818874221] 'process raft request' (duration: 120.139073ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:28.586829Z","caller":"traceutil/trace.go:171","msg":"trace[1037806372] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"216.239889ms","start":"2026-02-27T21:03:28.370552Z","end":"2026-02-27T21:03:28.586792Z","steps":["trace[1037806372] 'process raft request' (duration: 159.417246ms)","trace[1037806372] 'compare' (duration: 56.516883ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:28.726125Z","caller":"traceutil/trace.go:171","msg":"trace[1418087635] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"135.283746ms","start":"2026-02-27T21:03:28.590805Z","end":"2026-02-27T21:03:28.726088Z","steps":["trace[1418087635] 'process raft request' (duration: 127.2578ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:32.257407Z","caller":"traceutil/trace.go:171","msg":"trace[1150076624] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"120.569176ms","start":"2026-02-27T21:03:32.136819Z","end":"2026-02-27T21:03:32.257388Z","steps":["trace[1150076624] 'process raft request' (duration: 120.459653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:33.61637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493549,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:03:34.702092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.094181ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:34.702225Z","caller":"traceutil/trace.go:171","msg":"trace[1305609833] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:921; }","duration":"304.234856ms","start":"2026-02-27T21:03:34.39797Z","end":"2026-02-27T21:03:34.702204Z","steps":["trace[1305609833] 'range keys from in-memory index tree' (duration: 304.07853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:34.702362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.401977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:03:34.702446Z","caller":"traceutil/trace.go:171","msg":"trace[431719053] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"1.587199908s","start":"2026-02-27T21:03:33.115233Z","end":"2026-02-27T21:03:34.702433Z","steps":["trace[431719053] 'read index received' (duration: 864.581274ms)","trace[431719053] 'applied index is now lower than readState.Index' (duration: 722.617634ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:34.702464Z","caller":"traceutil/trace.go:171","msg":"trace[432372743] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"1.634566209s","start":"2026-02-27T21:03:33.06787Z","end":"2026-02-27T21:03:34.702437Z","steps":["trace[432372743] 'process raft request' (duration: 912.018008ms)","trace[432372743] 'compare' (duration: 722.316164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:34.702538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587323332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:03:34.702562Z","caller":"traceutil/trace.go:171","msg":"trace[1979068317] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:922; }","duration":"1.587351133s","start":"2026-02-27T21:03:33.115203Z","end":"2026-02-27T21:03:34.702554Z","steps":["trace[1979068317] 'agreement among raft nodes before linearized reading' (duration: 1.587295441s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:34.702584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:33.11512Z","time spent":"1.587458436s","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-27T21:03:34.70259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:33.067819Z","time spent":"1.634702953s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:03:34.702681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.917747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-27T21:03:34.70272Z","caller":"traceutil/trace.go:171","msg":"trace[986116724] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:922; }","duration":"729.996309ms","start":"2026-02-27T21:03:33.972714Z","end":"2026-02-27T21:03:34.70271Z","steps":["trace[986116724] 'agreement among raft nodes before linearized reading' (duration: 729.849784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:34.702694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409974793s","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-27T21:03:34.702755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:33.972691Z","time spent":"730.055801ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"info","ts":"2026-02-27T21:03:34.702781Z","caller":"traceutil/trace.go:171","msg":"trace[1157010887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:922; }","duration":"1.410102028s","start":"2026-02-27T21:03:33.29266Z","end":"2026-02-27T21:03:34.702762Z","steps":["trace[1157010887] 'agreement among raft nodes before linearized reading' (duration: 1.40986953s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:34.702814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:33.29264Z","time spent":"1.41016815s","remote":"127.0.0.1:39260","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-27T21:03:34.702966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.617137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:34.702981Z","caller":"traceutil/trace.go:171","msg":"trace[643152283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"918.72286ms","start":"2026-02-27T21:03:33.784252Z","end":"2026-02-27T21:03:34.702975Z","steps":["trace[643152283] 'agreement among raft nodes before linearized reading' (duration: 918.693109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:34.702993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:33.784227Z","time spent":"918.763572ms","remote":"127.0.0.1:38974","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-27T21:03:34.702996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.311159ms","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-27T21:03:34.703025Z","caller":"traceutil/trace.go:171","msg":"trace[907846708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:922; }","duration":"441.381161ms","start":"2026-02-27T21:03:34.261635Z","end":"2026-02-27T21:03:34.703016Z","steps":["trace[907846708] 'agreement among raft nodes before linearized reading' (duration: 441.270827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:34.703055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:34.261604Z","time spent":"441.445243ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T21:03:35.274405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493554,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:03:35.715295Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.011264284s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T21:03:35.914413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.819143ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-27T21:03:35.914814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:34.70392Z","time spent":"1.210886483s","remote":"127.0.0.1:38996","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-27T21:03:35.914865Z","caller":"traceutil/trace.go:171","msg":"trace[798007267] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:958; }","duration":"1.141106996s","start":"2026-02-27T21:03:34.773735Z","end":"2026-02-27T21:03:35.914842Z","steps":["trace[798007267] 'read index received' (duration: 941.827588ms)","trace[798007267] 'applied index is now lower than readState.Index' (duration: 199.278408ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:35.914894Z","caller":"traceutil/trace.go:171","msg":"trace[843212966] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"1.207874767s","start":"2026-02-27T21:03:34.706982Z","end":"2026-02-27T21:03:35.914856Z","steps":["trace[843212966] 'process raft request' (duration: 1.207604788s)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:35.914898Z","caller":"traceutil/trace.go:171","msg":"trace[1222532183] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"1.207565227s","start":"2026-02-27T21:03:34.707288Z","end":"2026-02-27T21:03:35.914853Z","steps":["trace[1222532183] 'process raft request' (duration: 1.207477574s)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:35.914968Z","caller":"traceutil/trace.go:171","msg":"trace[2085580302] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"1.165957029s","start":"2026-02-27T21:03:34.74897Z","end":"2026-02-27T21:03:35.914927Z","steps":["trace[2085580302] 'process raft request' (duration: 1.165831085s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:35.915049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:34.706965Z","time spent":"1.2079903s","remote":"127.0.0.1:39260","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-27T21:03:35.915072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:34.707281Z","time spent":"1.20768601s","remote":"127.0.0.1:39260","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-27T21:03:35.915102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141351854s","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-27T21:03:35.915136Z","caller":"traceutil/trace.go:171","msg":"trace[1842939375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"1.141442097s","start":"2026-02-27T21:03:34.773683Z","end":"2026-02-27T21:03:35.915125Z","steps":["trace[1842939375] 'agreement among raft nodes before linearized reading' (duration: 1.141282242s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:35.915169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:34.77366Z","time spent":"1.141501999s","remote":"127.0.0.1:39260","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-27T21:03:35.915255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129181526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:35.915283Z","caller":"traceutil/trace.go:171","msg":"trace[1787489149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"1.129238868s","start":"2026-02-27T21:03:34.786036Z","end":"2026-02-27T21:03:35.915275Z","steps":["trace[1787489149] 'agreement among raft nodes before linearized reading' (duration: 1.129179366s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:35.915305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:34.786015Z","time spent":"1.129286389s","remote":"127.0.0.1:38974","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-27T21:03:35.91541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.466084ms","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-27T21:03:35.915507Z","caller":"traceutil/trace.go:171","msg":"trace[1918636095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"861.61556ms","start":"2026-02-27T21:03:35.053865Z","end":"2026-02-27T21:03:35.91548Z","steps":["trace[1918636095] 'agreement among raft nodes before linearized reading' (duration: 861.363922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:35.915556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:35.053845Z","time spent":"861.701392ms","remote":"127.0.0.1:39260","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-27T21:03:35.916389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:34.748943Z","time spent":"1.166100694s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:03:35.917221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.806752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:03:35.917255Z","caller":"traceutil/trace.go:171","msg":"trace[301758841] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:925; }","duration":"964.882734ms","start":"2026-02-27T21:03:34.952365Z","end":"2026-02-27T21:03:35.917248Z","steps":["trace[301758841] 'agreement among raft nodes before linearized reading' (duration: 963.061206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:35.917274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:34.95234Z","time spent":"964.928416ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-27T21:03:36.023738Z","caller":"traceutil/trace.go:171","msg":"trace[416275685] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"108.021047ms","start":"2026-02-27T21:03:35.915695Z","end":"2026-02-27T21:03:36.023717Z","steps":["trace[416275685] 'process raft request' (duration: 107.19349ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:36.161648Z","caller":"traceutil/trace.go:171","msg":"trace[1310937989] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"242.427614ms","start":"2026-02-27T21:03:35.91919Z","end":"2026-02-27T21:03:36.161618Z","steps":["trace[1310937989] 'process raft request' (duration: 220.529166ms)","trace[1310937989] 'compare' (duration: 21.650331ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:36.161863Z","caller":"traceutil/trace.go:171","msg":"trace[1418933025] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:962; }","duration":"238.82289ms","start":"2026-02-27T21:03:35.923023Z","end":"2026-02-27T21:03:36.161846Z","steps":["trace[1418933025] 'read index received' (duration: 99.817895ms)","trace[1418933025] 'applied index is now lower than readState.Index' (duration: 139.004455ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:36.161947Z","caller":"traceutil/trace.go:171","msg":"trace[2073837998] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"242.435365ms","start":"2026-02-27T21:03:35.919506Z","end":"2026-02-27T21:03:36.161941Z","steps":["trace[2073837998] 'process raft request' (duration: 242.035042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:36.162078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.044997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:36.162107Z","caller":"traceutil/trace.go:171","msg":"trace[2039029145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"239.117339ms","start":"2026-02-27T21:03:35.922983Z","end":"2026-02-27T21:03:36.1621Z","steps":["trace[2039029145] 'agreement among raft nodes before linearized reading' (duration: 239.052747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:36.162193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.02025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-27T21:03:36.162322Z","caller":"traceutil/trace.go:171","msg":"trace[245452041] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:928; }","duration":"136.193825ms","start":"2026-02-27T21:03:36.026104Z","end":"2026-02-27T21:03:36.162297Z","steps":["trace[245452041] 'agreement among raft nodes before linearized reading' (duration: 135.937947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:39.410713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.435602ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T21:03:44.279862Z","caller":"traceutil/trace.go:171","msg":"trace[1809910482] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"257.193766ms","start":"2026-02-27T21:03:44.022646Z","end":"2026-02-27T21:03:44.279839Z","steps":["trace[1809910482] 'read index received' (duration: 257.00134ms)","trace[1809910482] 'applied index is now lower than readState.Index' (duration: 191.536µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:44.279908Z","caller":"traceutil/trace.go:171","msg":"trace[216017723] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"274.122426ms","start":"2026-02-27T21:03:44.005758Z","end":"2026-02-27T21:03:44.279881Z","steps":["trace[216017723] 'process raft request' (duration: 273.943471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:44.280029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.359141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:44.280068Z","caller":"traceutil/trace.go:171","msg":"trace[1151433119] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:946; }","duration":"257.442704ms","start":"2026-02-27T21:03:44.022613Z","end":"2026-02-27T21:03:44.280056Z","steps":["trace[1151433119] 'agreement among raft nodes before linearized reading' (duration: 257.346151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:44.280448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.460677ms","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-27T21:03:44.280489Z","caller":"traceutil/trace.go:171","msg":"trace[791360418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"237.546539ms","start":"2026-02-27T21:03:44.04293Z","end":"2026-02-27T21:03:44.280476Z","steps":["trace[791360418] 'agreement among raft nodes before linearized reading' (duration: 237.385154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:44.280745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.438729ms","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-27T21:03:44.280775Z","caller":"traceutil/trace.go:171","msg":"trace[699272473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"233.46972ms","start":"2026-02-27T21:03:44.047296Z","end":"2026-02-27T21:03:44.280766Z","steps":["trace[699272473] 'agreement among raft nodes before linearized reading' (duration: 233.381387ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:48.057832Z","caller":"traceutil/trace.go:171","msg":"trace[465830254] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"275.548351ms","start":"2026-02-27T21:03:47.782262Z","end":"2026-02-27T21:03:48.057811Z","steps":["trace[465830254] 'read index received' (duration: 275.460098ms)","trace[465830254] 'applied index is now lower than readState.Index' (duration: 87.333µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:48.057859Z","caller":"traceutil/trace.go:171","msg":"trace[6891] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"287.634706ms","start":"2026-02-27T21:03:47.770197Z","end":"2026-02-27T21:03:48.057832Z","steps":["trace[6891] 'process raft request' (duration: 287.478911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:48.058018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.740398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:48.058089Z","caller":"traceutil/trace.go:171","msg":"trace[2056876202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"275.858081ms","start":"2026-02-27T21:03:47.782215Z","end":"2026-02-27T21:03:48.058073Z","steps":["trace[2056876202] 'agreement among raft nodes before linearized reading' (duration: 275.706536ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:52.962869Z","caller":"traceutil/trace.go:171","msg":"trace[277135827] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"635.915222ms","start":"2026-02-27T21:03:52.326728Z","end":"2026-02-27T21:03:52.962643Z","steps":["trace[277135827] 'process raft request' (duration: 635.644802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:52.962995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:52.326685Z","time spent":"636.242884ms","remote":"127.0.0.1:39260","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-27T21:03:52.970767Z","caller":"traceutil/trace.go:171","msg":"trace[1715610487] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1021; }","duration":"185.357716ms","start":"2026-02-27T21:03:52.785392Z","end":"2026-02-27T21:03:52.97075Z","steps":["trace[1715610487] 'read index received' (duration: 177.070954ms)","trace[1715610487] 'applied index is now lower than readState.Index' (duration: 8.285882ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:52.9708Z","caller":"traceutil/trace.go:171","msg":"trace[470988562] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"642.951799ms","start":"2026-02-27T21:03:52.327832Z","end":"2026-02-27T21:03:52.970783Z","steps":["trace[470988562] 'process raft request' (duration: 642.864616ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:52.970857Z","caller":"traceutil/trace.go:171","msg":"trace[751902925] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"641.741375ms","start":"2026-02-27T21:03:52.329108Z","end":"2026-02-27T21:03:52.970849Z","steps":["trace[751902925] 'process raft request' (duration: 641.61562ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:52.970862Z","caller":"traceutil/trace.go:171","msg":"trace[189700545] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"643.310142ms","start":"2026-02-27T21:03:52.327529Z","end":"2026-02-27T21:03:52.970839Z","steps":["trace[189700545] 'process raft request' (duration: 643.041102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:52.970892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:52.327812Z","time spent":"643.030811ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:03:52.970947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:52.329093Z","time spent":"641.786457ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:03:52.970977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:52.327516Z","time spent":"643.402665ms","remote":"127.0.0.1:39260","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-27T21:03:52.971061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.676348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:52.971091Z","caller":"traceutil/trace.go:171","msg":"trace[1837585184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"185.74213ms","start":"2026-02-27T21:03:52.785341Z","end":"2026-02-27T21:03:52.971083Z","steps":["trace[1837585184] 'agreement among raft nodes before linearized reading' (duration: 185.699589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:53.213523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.705993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:03:53.213694Z","caller":"traceutil/trace.go:171","msg":"trace[1176382753] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"237.986357ms","start":"2026-02-27T21:03:52.975675Z","end":"2026-02-27T21:03:53.213661Z","steps":["trace[1176382753] 'process raft request' (duration: 76.009084ms)","trace[1176382753] 'compare' (duration: 161.487705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:55.185342Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.209781021s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T21:03:55.185756Z","caller":"traceutil/trace.go:171","msg":"trace[793526237] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"400.78405ms","start":"2026-02-27T21:03:54.784947Z","end":"2026-02-27T21:03:55.185731Z","steps":["trace[793526237] 'read index received' (duration: 400.557632ms)","trace[793526237] 'applied index is now lower than readState.Index' (duration: 224.278µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:55.185739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:53.975464Z","time spent":"1.210267078s","remote":"127.0.0.1:38996","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-27T21:03:55.185916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.942556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:03:55.185999Z","caller":"traceutil/trace.go:171","msg":"trace[1342257722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"401.098381ms","start":"2026-02-27T21:03:54.78488Z","end":"2026-02-27T21:03:55.185978Z","steps":["trace[1342257722] 'agreement among raft nodes before linearized reading' (duration: 400.941065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:55.186021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.59579ms","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-27T21:03:55.18605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:54.784847Z","time spent":"401.195424ms","remote":"127.0.0.1:38962","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-27T21:03:55.186087Z","caller":"traceutil/trace.go:171","msg":"trace[320316980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"209.727205ms","start":"2026-02-27T21:03:54.976343Z","end":"2026-02-27T21:03:55.18607Z","steps":["trace[320316980] 'agreement among raft nodes before linearized reading' (duration: 209.545659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:55.186021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.786376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-27T21:03:55.186202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.824419ms","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-27T21:03:55.186135Z","caller":"traceutil/trace.go:171","msg":"trace[1228368891] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:986; }","duration":"218.949912ms","start":"2026-02-27T21:03:54.967172Z","end":"2026-02-27T21:03:55.186122Z","steps":["trace[1228368891] 'agreement among raft nodes before linearized reading' (duration: 218.667772ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:55.186272Z","caller":"traceutil/trace.go:171","msg":"trace[1861320347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"209.960774ms","start":"2026-02-27T21:03:54.976293Z","end":"2026-02-27T21:03:55.186254Z","steps":["trace[1861320347] 'agreement among raft nodes before linearized reading' (duration: 209.711955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:55.186781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.56499ms","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-27T21:03:55.186844Z","caller":"traceutil/trace.go:171","msg":"trace[313282299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"209.655823ms","start":"2026-02-27T21:03:54.977175Z","end":"2026-02-27T21:03:55.186831Z","steps":["trace[313282299] 'agreement among raft nodes before linearized reading' (duration: 209.418384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:55.794546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.512092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:03:55.794742Z","caller":"traceutil/trace.go:171","msg":"trace[1241477522] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"607.430213ms","start":"2026-02-27T21:03:55.187291Z","end":"2026-02-27T21:03:55.794721Z","steps":["trace[1241477522] 'process raft request' (duration: 301.661332ms)","trace[1241477522] 'compare' (duration: 305.294393ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:55.794881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:55.187265Z","time spent":"607.579168ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:03:56.220626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.321219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:03:56.220901Z","caller":"traceutil/trace.go:171","msg":"trace[1646594521] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"1.01966335s","start":"2026-02-27T21:03:55.201182Z","end":"2026-02-27T21:03:56.220845Z","steps":["trace[1646594521] 'process raft request' (duration: 881.000558ms)","trace[1646594521] 'compare' (duration: 138.127882ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:56.220986Z","caller":"traceutil/trace.go:171","msg":"trace[1532875397] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1027; }","duration":"1.003281712s","start":"2026-02-27T21:03:55.21768Z","end":"2026-02-27T21:03:56.220962Z","steps":["trace[1532875397] 'read index received' (duration: 271.382127ms)","trace[1532875397] 'applied index is now lower than readState.Index' (duration: 731.898285ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:56.22104Z","caller":"traceutil/trace.go:171","msg":"trace[650713743] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.019600987s","start":"2026-02-27T21:03:55.201431Z","end":"2026-02-27T21:03:56.221032Z","steps":["trace[650713743] 'process raft request' (duration: 1.019317227s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:56.221038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:55.201147Z","time spent":"1.019816455s","remote":"127.0.0.1:39260","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-27T21:03:56.221034Z","caller":"traceutil/trace.go:171","msg":"trace[591936607] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.020062914s","start":"2026-02-27T21:03:55.200936Z","end":"2026-02-27T21:03:56.220999Z","steps":["trace[591936607] 'process raft request' (duration: 1.019967321s)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:56.220989Z","caller":"traceutil/trace.go:171","msg":"trace[887509888] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.019490913s","start":"2026-02-27T21:03:55.201472Z","end":"2026-02-27T21:03:56.220963Z","steps":["trace[887509888] 'process raft request' (duration: 1.019348838s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:56.221144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:55.201418Z","time spent":"1.019653089s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:03:56.221173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:55.200898Z","time spent":"1.020205959s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:03:56.221173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.583466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T21:03:56.221188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.240965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-02-27T21:03:56.221187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003507889s","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-27T21:03:56.221218Z","caller":"traceutil/trace.go:171","msg":"trace[2113412785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"436.663639ms","start":"2026-02-27T21:03:55.78454Z","end":"2026-02-27T21:03:56.221203Z","steps":["trace[2113412785] 'agreement among raft nodes before linearized reading' (duration: 436.581516ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:56.221221Z","caller":"traceutil/trace.go:171","msg":"trace[1276135323] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:991; }","duration":"424.312818ms","start":"2026-02-27T21:03:55.796898Z","end":"2026-02-27T21:03:56.221211Z","steps":["trace[1276135323] 'agreement among raft nodes before linearized reading' (duration: 424.247726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:56.221246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:55.784526Z","time spent":"436.71441ms","remote":"127.0.0.1:38974","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-27T21:03:56.221239Z","caller":"traceutil/trace.go:171","msg":"trace[306884720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"1.003603833s","start":"2026-02-27T21:03:55.21762Z","end":"2026-02-27T21:03:56.221224Z","steps":["trace[306884720] 'agreement among raft nodes before linearized reading' (duration: 1.003423787s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:56.221249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:55.796871Z","time spent":"424.37288ms","remote":"127.0.0.1:39160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-27T21:03:56.221164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:55.201456Z","time spent":"1.019657579s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:03:56.221278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:55.217597Z","time spent":"1.003674006s","remote":"127.0.0.1:39260","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-27T21:03:56.817256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.863198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:03:56.817993Z","caller":"traceutil/trace.go:171","msg":"trace[1014098459] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"592.181136ms","start":"2026-02-27T21:03:56.225783Z","end":"2026-02-27T21:03:56.817964Z","steps":["trace[1014098459] 'process raft request' (duration: 200.448287ms)","trace[1014098459] 'compare' (duration: 390.660559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:56.818146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:56.225765Z","time spent":"592.2929ms","remote":"127.0.0.1:39260","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-27T21:03:56.818228Z","caller":"traceutil/trace.go:171","msg":"trace[1067245739] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"437.981207ms","start":"2026-02-27T21:03:56.380217Z","end":"2026-02-27T21:03:56.818198Z","steps":["trace[1067245739] 'process raft request' (duration: 437.707877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:56.818379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:56.380193Z","time spent":"438.108172ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:03:57.483544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.725649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:03:57.483809Z","caller":"traceutil/trace.go:171","msg":"trace[1258395748] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:993; }","duration":"289.049421ms","start":"2026-02-27T21:03:57.194746Z","end":"2026-02-27T21:03:57.483796Z","steps":["trace[1258395748] 'count revisions from in-memory index tree' (duration: 288.596145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:57.483805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.667083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:03:57.483874Z","caller":"traceutil/trace.go:171","msg":"trace[310817063] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:993; }","duration":"149.783698ms","start":"2026-02-27T21:03:57.334073Z","end":"2026-02-27T21:03:57.483857Z","steps":["trace[310817063] 'count revisions from in-memory index tree' (duration: 149.59009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:59.030869Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:03:59.531427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:03:59.534261Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.304093423s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T21:03:59.534869Z","caller":"traceutil/trace.go:171","msg":"trace[927200257] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.30484213s","start":"2026-02-27T21:03:58.229991Z","end":"2026-02-27T21:03:59.534833Z","steps":["trace[927200257] 'process raft request' (duration: 1.304601321s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:59.535081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:58.229954Z","time spent":"1.305009507s","remote":"127.0.0.1:39260","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-27T21:03:59.540235Z","caller":"traceutil/trace.go:171","msg":"trace[717155575] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"1.309998029s","start":"2026-02-27T21:03:58.230199Z","end":"2026-02-27T21:03:59.540197Z","steps":["trace[717155575] 'process raft request' (duration: 1.30976223s)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:59.540254Z","caller":"traceutil/trace.go:171","msg":"trace[1176331495] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"1.309750409s","start":"2026-02-27T21:03:58.230482Z","end":"2026-02-27T21:03:59.540233Z","steps":["trace[1176331495] 'process raft request' (duration: 1.309606484s)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:59.540304Z","caller":"traceutil/trace.go:171","msg":"trace[778286078] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.309634076s","start":"2026-02-27T21:03:58.230641Z","end":"2026-02-27T21:03:59.540276Z","steps":["trace[778286078] 'process raft request' (duration: 1.309491961s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:59.540409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:58.230471Z","time spent":"1.309868484s","remote":"127.0.0.1:39260","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-27T21:03:59.540441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:58.230182Z","time spent":"1.310142614s","remote":"127.0.0.1:39260","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-27T21:03:59.54045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:58.230619Z","time spent":"1.309753039s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:04:00.211628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.424228ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T21:04:00.212429Z","caller":"traceutil/trace.go:171","msg":"trace[1208043261] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1034; }","duration":"1.681909563s","start":"2026-02-27T21:03:58.530487Z","end":"2026-02-27T21:04:00.212397Z","steps":["trace[1208043261] 'read index received' (duration: 1.004191075s)","trace[1208043261] 'applied index is now lower than readState.Index' (duration: 677.716448ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:04:00.21261Z","caller":"traceutil/trace.go:171","msg":"trace[1233385945] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"918.830919ms","start":"2026-02-27T21:03:59.293766Z","end":"2026-02-27T21:04:00.212597Z","steps":["trace[1233385945] 'process raft request' (duration: 918.000739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:00.21272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:59.293729Z","time spent":"918.915772ms","remote":"127.0.0.1:39260","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-27T21:04:00.245715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016572056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T21:04:00.245775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.71524544s","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-27T21:04:00.245809Z","caller":"traceutil/trace.go:171","msg":"trace[342695632] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:998; }","duration":"1.01668532s","start":"2026-02-27T21:03:59.229098Z","end":"2026-02-27T21:04:00.245783Z","steps":["trace[342695632] 'agreement among raft nodes before linearized reading' (duration: 983.79922ms)","trace[342695632] 'count revisions from in-memory index tree' (duration: 32.746965ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:04:00.245841Z","caller":"traceutil/trace.go:171","msg":"trace[898091956] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:998; }","duration":"1.715355504s","start":"2026-02-27T21:03:58.530467Z","end":"2026-02-27T21:04:00.245823Z","steps":["trace[898091956] 'agreement among raft nodes before linearized reading' (duration: 1.682412042s)","trace[898091956] 'count revisions from in-memory index tree' (duration: 32.806527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:00.245827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.421533738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-27T21:04:00.245881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:58.530391Z","time spent":"1.715478539s","remote":"127.0.0.1:39438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-27T21:04:00.245929Z","caller":"traceutil/trace.go:171","msg":"trace[553432818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"1.421709375s","start":"2026-02-27T21:03:58.824196Z","end":"2026-02-27T21:04:00.245906Z","steps":["trace[553432818] 'agreement among raft nodes before linearized reading' (duration: 1.388658999s)","trace[553432818] 'range keys from in-memory index tree' (duration: 32.777136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:00.245864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:59.229028Z","time spent":"1.016825096s","remote":"127.0.0.1:34996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-27T21:04:00.245853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.278865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T21:04:00.245973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:58.82417Z","time spent":"1.421795028s","remote":"127.0.0.1:39260","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":"info","ts":"2026-02-27T21:04:00.246125Z","caller":"traceutil/trace.go:171","msg":"trace[1971705790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"459.607657ms","start":"2026-02-27T21:03:59.786447Z","end":"2026-02-27T21:04:00.246054Z","steps":["trace[1971705790] 'agreement among raft nodes before linearized reading' (duration: 426.441907ms)","trace[1971705790] 'range keys from in-memory index tree' (duration: 32.824458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:00.246177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:59.786429Z","time spent":"459.736411ms","remote":"127.0.0.1:38974","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-27T21:04:01.86863Z","caller":"traceutil/trace.go:171","msg":"trace[1955622227] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"324.261807ms","start":"2026-02-27T21:04:01.544344Z","end":"2026-02-27T21:04:01.868606Z","steps":["trace[1955622227] 'read index received' (duration: 324.007948ms)","trace[1955622227] 'applied index is now lower than readState.Index' (duration: 252.769µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:04:01.868757Z","caller":"traceutil/trace.go:171","msg":"trace[1105846434] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"324.951212ms","start":"2026-02-27T21:04:01.543795Z","end":"2026-02-27T21:04:01.868747Z","steps":["trace[1105846434] 'process raft request' (duration: 324.62732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:01.868867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:01.543767Z","time spent":"325.015044ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:04:01.868967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.551577ms","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-27T21:04:01.86934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.999903ms","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-27T21:04:01.869385Z","caller":"traceutil/trace.go:171","msg":"trace[1956070629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"325.062196ms","start":"2026-02-27T21:04:01.544311Z","end":"2026-02-27T21:04:01.869374Z","steps":["trace[1956070629] 'agreement among raft nodes before linearized reading' (duration: 324.939381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:01.869417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:01.5443Z","time spent":"325.110137ms","remote":"127.0.0.1:39260","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-27T21:04:01.86954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.208594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-27T21:04:01.86963Z","caller":"traceutil/trace.go:171","msg":"trace[148866403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"324.294138ms","start":"2026-02-27T21:04:01.545296Z","end":"2026-02-27T21:04:01.86959Z","steps":["trace[148866403] 'agreement among raft nodes before linearized reading' (duration: 324.150212ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:04:01.869057Z","caller":"traceutil/trace.go:171","msg":"trace[639311354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"324.780685ms","start":"2026-02-27T21:04:01.544256Z","end":"2026-02-27T21:04:01.869036Z","steps":["trace[639311354] 'agreement among raft nodes before linearized reading' (duration: 324.572408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:01.869678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:01.545285Z","time spent":"324.38097ms","remote":"127.0.0.1:39260","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-27T21:04:01.869705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:01.54424Z","time spent":"325.44993ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T21:04:02.806557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277137128493755,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:04:02.934749Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058870661s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T21:04:02.957476Z","caller":"traceutil/trace.go:171","msg":"trace[1942299315] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.081639983s","start":"2026-02-27T21:04:01.875804Z","end":"2026-02-27T21:04:02.957444Z","steps":["trace[1942299315] 'process raft request' (duration: 1.059177743s)","trace[1942299315] 'compare' (duration: 21.91413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:02.957646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:01.875788Z","time spent":"1.081776037s","remote":"127.0.0.1:39260","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-27T21:04:03.5887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.156265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:04:03.588888Z","caller":"traceutil/trace.go:171","msg":"trace[1875381210] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1042; }","duration":"1.283015213s","start":"2026-02-27T21:04:02.305853Z","end":"2026-02-27T21:04:03.588869Z","steps":["trace[1875381210] 'read index received' (duration: 629.222699ms)","trace[1875381210] 'applied index is now lower than readState.Index' (duration: 653.791194ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:04:03.588979Z","caller":"traceutil/trace.go:171","msg":"trace[1665500862] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.71302942s","start":"2026-02-27T21:04:01.875939Z","end":"2026-02-27T21:04:03.588969Z","steps":["trace[1665500862] 'process raft request' (duration: 1.566488131s)","trace[1665500862] 'compare' (duration: 145.966268ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:03.589035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:01.875911Z","time spent":"1.713099092s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:04:03.58922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283348715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-27T21:04:03.589347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173840457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:04:03.589373Z","caller":"traceutil/trace.go:171","msg":"trace[824593745] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1003; }","duration":"1.17390748s","start":"2026-02-27T21:04:02.415458Z","end":"2026-02-27T21:04:03.589365Z","steps":["trace[824593745] 'agreement among raft nodes before linearized reading' (duration: 1.173829897s)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:04:03.589362Z","caller":"traceutil/trace.go:171","msg":"trace[1608502469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"1.283539841s","start":"2026-02-27T21:04:02.305799Z","end":"2026-02-27T21:04:03.589339Z","steps":["trace[1608502469] 'agreement among raft nodes before linearized reading' (duration: 1.283253071s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:03.589396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:02.415433Z","time spent":"1.173957482s","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-27T21:04:03.589414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:02.305777Z","time spent":"1.283624124s","remote":"127.0.0.1:39260","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-27T21:04:03.589544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.739333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:04:03.589571Z","caller":"traceutil/trace.go:171","msg":"trace[629359737] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1003; }","duration":"770.804125ms","start":"2026-02-27T21:04:02.818761Z","end":"2026-02-27T21:04:03.589565Z","steps":["trace[629359737] 'agreement among raft nodes before linearized reading' (duration: 770.758644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:03.589591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:02.818741Z","time spent":"770.845017ms","remote":"127.0.0.1:39486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-02-27T21:04:03.589636Z","caller":"traceutil/trace.go:171","msg":"trace[1393764762] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.713566799s","start":"2026-02-27T21:04:01.876033Z","end":"2026-02-27T21:04:03.5896Z","steps":["trace[1393764762] 'process raft request' (duration: 1.71277595s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:03.589741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:01.876Z","time spent":"1.713669833s","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:04:03.589948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.07989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:04:03.590109Z","caller":"traceutil/trace.go:171","msg":"trace[1999647104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"804.310818ms","start":"2026-02-27T21:04:02.785772Z","end":"2026-02-27T21:04:03.590083Z","steps":["trace[1999647104] 'agreement among raft nodes before linearized reading' (duration: 803.970486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:03.590336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:02.785756Z","time spent":"804.542647ms","remote":"127.0.0.1:38974","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-27T21:04:03.800547Z","caller":"traceutil/trace.go:171","msg":"trace[1409358858] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"183.718647ms","start":"2026-02-27T21:04:03.616799Z","end":"2026-02-27T21:04:03.800517Z","steps":["trace[1409358858] 'process raft request' (duration: 93.551505ms)","trace[1409358858] 'compare' (duration: 89.892562ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:04:04.056481Z","caller":"traceutil/trace.go:171","msg":"trace[921666121] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"177.219238ms","start":"2026-02-27T21:04:03.879216Z","end":"2026-02-27T21:04:04.056435Z","steps":["trace[921666121] 'process raft request' (duration: 99.72309ms)","trace[921666121] 'compare' (duration: 76.927167ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:04:04.37183Z","caller":"traceutil/trace.go:171","msg":"trace[213025234] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"106.0107ms","start":"2026-02-27T21:04:04.265788Z","end":"2026-02-27T21:04:04.371799Z","steps":["trace[213025234] 'read index received' (duration: 8.305633ms)","trace[213025234] 'applied index is now lower than readState.Index' (duration: 97.703557ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:04.37188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:04.057962Z","time spent":"313.906579ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-27T21:04:04.372024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.212377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:04:04.372066Z","caller":"traceutil/trace.go:171","msg":"trace[776404646] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1005; }","duration":"106.366953ms","start":"2026-02-27T21:04:04.265689Z","end":"2026-02-27T21:04:04.372056Z","steps":["trace[776404646] 'agreement among raft nodes before linearized reading' (duration: 106.222247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:06.405861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.604658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:04:06.405975Z","caller":"traceutil/trace.go:171","msg":"trace[1750370832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"619.815916ms","start":"2026-02-27T21:04:05.786132Z","end":"2026-02-27T21:04:06.405948Z","steps":["trace[1750370832] 'range keys from in-memory index tree' (duration: 619.514125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:06.406032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:05.786108Z","time spent":"619.909199ms","remote":"127.0.0.1:38974","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-27T21:04:06.406238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.282369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-27T21:04:06.406327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.032431ms","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-27T21:04:06.406378Z","caller":"traceutil/trace.go:171","msg":"trace[991978453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"601.484156ms","start":"2026-02-27T21:04:05.804859Z","end":"2026-02-27T21:04:06.406343Z","steps":["trace[991978453] 'range keys from in-memory index tree' (duration: 601.032169ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:04:06.406423Z","caller":"traceutil/trace.go:171","msg":"trace[1925986066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"346.133815ms","start":"2026-02-27T21:04:06.060264Z","end":"2026-02-27T21:04:06.406397Z","steps":["trace[1925986066] 'range keys from in-memory index tree' (duration: 345.840114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:06.406449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:05.804838Z","time spent":"601.599621ms","remote":"127.0.0.1:39260","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-27T21:04:06.406469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:06.060188Z","time spent":"346.27125ms","remote":"127.0.0.1:39260","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-27T21:04:06.917527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.704349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:04:06.917634Z","caller":"traceutil/trace.go:171","msg":"trace[1303311521] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"100.902524ms","start":"2026-02-27T21:04:06.816712Z","end":"2026-02-27T21:04:06.917615Z","steps":["trace[1303311521] 'read index received' (duration: 39.672µs)","trace[1303311521] 'applied index is now lower than readState.Index' (duration: 100.861552ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:06.917714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.009837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:04:06.917746Z","caller":"traceutil/trace.go:171","msg":"trace[546278002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"101.09362ms","start":"2026-02-27T21:04:06.816642Z","end":"2026-02-27T21:04:06.917736Z","steps":["trace[546278002] 'agreement among raft nodes before linearized reading' (duration: 101.020918ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:04:06.917993Z","caller":"traceutil/trace.go:171","msg":"trace[1947045353] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"506.277579ms","start":"2026-02-27T21:04:06.411682Z","end":"2026-02-27T21:04:06.91796Z","steps":["trace[1947045353] 'process raft request' (duration: 103.037371ms)","trace[1947045353] 'compare' (duration: 402.588705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:06.918153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:06.411657Z","time spent":"506.412094ms","remote":"127.0.0.1:39260","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-27T21:04:07.31721Z","caller":"traceutil/trace.go:171","msg":"trace[1159484687] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"312.004849ms","start":"2026-02-27T21:04:07.005184Z","end":"2026-02-27T21:04:07.317189Z","steps":["trace[1159484687] 'process raft request' (duration: 311.898595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:07.317306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:04:07.005164Z","time spent":"312.090263ms","remote":"127.0.0.1:39260","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-27T21:04:07.318163Z","caller":"traceutil/trace.go:171","msg":"trace[1356806259] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"121.067789ms","start":"2026-02-27T21:04:07.197072Z","end":"2026-02-27T21:04:07.31814Z","steps":["trace[1356806259] 'process raft request' (duration: 120.908593ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:04:09.062675Z","caller":"traceutil/trace.go:171","msg":"trace[1158636565] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"137.332973ms","start":"2026-02-27T21:04:08.92531Z","end":"2026-02-27T21:04:09.062643Z","steps":["trace[1158636565] 'process raft request' (duration: 137.135616ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:04:10.561745Z","caller":"traceutil/trace.go:171","msg":"trace[2111804243] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"118.721534ms","start":"2026-02-27T21:04:10.442949Z","end":"2026-02-27T21:04:10.56167Z","steps":["trace[2111804243] 'process raft request' (duration: 111.206009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:22.900777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.78514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:04:22.900903Z","caller":"traceutil/trace.go:171","msg":"trace[447351049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"114.980587ms","start":"2026-02-27T21:04:22.785894Z","end":"2026-02-27T21:04:22.900875Z","steps":["trace[447351049] 'range keys from in-memory index tree' (duration: 114.663755ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:05:16.405242Z","caller":"traceutil/trace.go:171","msg":"trace[810674258] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"100.726397ms","start":"2026-02-27T21:05:16.30448Z","end":"2026-02-27T21:05:16.405207Z","steps":["trace[810674258] 'process raft request' (duration: 100.435529ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:05:16.405303Z","caller":"traceutil/trace.go:171","msg":"trace[1979323742] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"101.779227ms","start":"2026-02-27T21:05:16.303454Z","end":"2026-02-27T21:05:16.405234Z","steps":["trace[1979323742] 'process raft request' (duration: 37.917102ms)","trace[1979323742] 'compare' (duration: 63.423532ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:05:18.356743Z","caller":"traceutil/trace.go:171","msg":"trace[399840570] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"114.549256ms","start":"2026-02-27T21:05:18.242166Z","end":"2026-02-27T21:05:18.356715Z","steps":["trace[399840570] 'process raft request' (duration: 114.33371ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:06:33.006272Z","caller":"traceutil/trace.go:171","msg":"trace[1971451906] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"105.986274ms","start":"2026-02-27T21:06:32.900251Z","end":"2026-02-27T21:06:33.006237Z","steps":["trace[1971451906] 'process raft request' (duration: 35.684869ms)","trace[1971451906] 'compare' (duration: 69.959685ms)"],"step_count":2}