{"level":"warn","ts":"2026-08-14T09:04:07.817906Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:04:07.81804Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.199: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.19.213.199:2380","--initial-cluster=instance=https://199.19.213.199:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.199:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.199: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-08-14T09:04:07.818192Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:04:07.818215Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.199:2380"]} {"level":"info","ts":"2026-08-14T09:04:07.818247Z","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-08-14T09:04:07.819087Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.199:2379"]} {"level":"info","ts":"2026-08-14T09:04:07.819184Z","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.19.213.199:2380"],"listen-peer-urls":["https://199.19.213.199:2380"],"advertise-client-urls":["https://199.19.213.199:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.199:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.199: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-08-14T09:04:07.824583Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.106656ms"} {"level":"info","ts":"2026-08-14T09:04:07.832755Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6d660142aa1b7ce9","cluster-id":"6964c025ad43a005"} {"level":"info","ts":"2026-08-14T09:04:07.832826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T09:04:07.83285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 became follower at term 0"} {"level":"info","ts":"2026-08-14T09:04:07.832954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6d660142aa1b7ce9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T09:04:07.832977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 became follower at term 1"} {"level":"info","ts":"2026-08-14T09:04:07.833024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 switched to configuration voters=(7882989583592029417)"} {"level":"warn","ts":"2026-08-14T09:04:07.85043Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T09:04:07.857034Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T09:04:07.860066Z","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-08-14T09:04:07.864831Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6d660142aa1b7ce9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T09:04:07.865049Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6d660142aa1b7ce9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T09:04:07.865194Z","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-08-14T09:04:07.865288Z","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-08-14T09:04:07.865324Z","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-08-14T09:04:07.866268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 switched to configuration voters=(7882989583592029417)"} {"level":"info","ts":"2026-08-14T09:04:07.87315Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6964c025ad43a005","local-member-id":"6d660142aa1b7ce9","added-peer-id":"6d660142aa1b7ce9","added-peer-peer-urls":["https://199.19.213.199:2380"]} {"level":"info","ts":"2026-08-14T09:04:07.88735Z","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-08-14T09:04:07.887462Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.199:2380"} {"level":"info","ts":"2026-08-14T09:04:07.887513Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.199:2380"} {"level":"info","ts":"2026-08-14T09:04:07.887588Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6d660142aa1b7ce9","initial-advertise-peer-urls":["https://199.19.213.199:2380"],"listen-peer-urls":["https://199.19.213.199:2380"],"advertise-client-urls":["https://199.19.213.199:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.199:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T09:04:07.887624Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T09:04:08.433869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T09:04:08.433939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T09:04:08.433986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 received MsgPreVoteResp from 6d660142aa1b7ce9 at term 1"} {"level":"info","ts":"2026-08-14T09:04:08.434182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 became candidate at term 2"} {"level":"info","ts":"2026-08-14T09:04:08.434273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 received MsgVoteResp from 6d660142aa1b7ce9 at term 2"} {"level":"info","ts":"2026-08-14T09:04:08.434306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d660142aa1b7ce9 became leader at term 2"} {"level":"info","ts":"2026-08-14T09:04:08.434324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6d660142aa1b7ce9 elected leader 6d660142aa1b7ce9 at term 2"} {"level":"info","ts":"2026-08-14T09:04:08.435532Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6d660142aa1b7ce9","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.199:2379]}","request-path":"/0/members/6d660142aa1b7ce9/attributes","cluster-id":"6964c025ad43a005","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T09:04:08.435775Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:04:08.435788Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:04:08.436163Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:04:08.436556Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T09:04:08.43664Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T09:04:08.437749Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.199:2379"} {"level":"info","ts":"2026-08-14T09:04:08.438197Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6964c025ad43a005","local-member-id":"6d660142aa1b7ce9","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:04:08.438444Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:04:08.438488Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:04:08.439322Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-14T09:04:18.093205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.910352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-14T09:04:18.093398Z","caller":"traceutil/trace.go:171","msg":"trace[385503924] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:298; }","duration":"122.113007ms","start":"2026-08-14T09:04:17.971202Z","end":"2026-08-14T09:04:18.093315Z","steps":["trace[385503924] 'range keys from in-memory index tree' (duration: 121.804469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:46.785452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.885934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:04:46.785556Z","caller":"traceutil/trace.go:171","msg":"trace[126827976] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:490; }","duration":"236.038528ms","start":"2026-08-14T09:04:46.549494Z","end":"2026-08-14T09:04:46.785532Z","steps":["trace[126827976] 'range keys from in-memory index tree' (duration: 235.814113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:46.785568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.852341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:04:46.785645Z","caller":"traceutil/trace.go:171","msg":"trace[1228303552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"252.966194ms","start":"2026-08-14T09:04:46.532658Z","end":"2026-08-14T09:04:46.785624Z","steps":["trace[1228303552] 'range keys from in-memory index tree' (duration: 252.78504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:51.26535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.071822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-08-14T09:04:51.26549Z","caller":"traceutil/trace.go:171","msg":"trace[797770304] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:574; }","duration":"354.321028ms","start":"2026-08-14T09:04:50.911135Z","end":"2026-08-14T09:04:51.265456Z","steps":["trace[797770304] 'range keys from in-memory index tree' (duration: 353.812646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:51.265578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:50.911115Z","time spent":"354.444771ms","remote":"127.0.0.1:33534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29592,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-08-14T09:04:54.766206Z","caller":"traceutil/trace.go:171","msg":"trace[1243542161] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"687.695741ms","start":"2026-08-14T09:04:54.078477Z","end":"2026-08-14T09:04:54.766173Z","steps":["trace[1243542161] 'process raft request' (duration: 686.340378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:54.767805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:54.078442Z","time spent":"687.864645ms","remote":"127.0.0.1:33590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:04:55.225607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.717485ms","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-08-14T09:04:55.225746Z","caller":"traceutil/trace.go:171","msg":"trace[108920963] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:589; }","duration":"286.869649ms","start":"2026-08-14T09:04:54.938855Z","end":"2026-08-14T09:04:55.225725Z","steps":["trace[108920963] 'range keys from in-memory index tree' (duration: 286.698265ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:55.226454Z","caller":"traceutil/trace.go:171","msg":"trace[699516526] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"878.002884ms","start":"2026-08-14T09:04:54.348406Z","end":"2026-08-14T09:04:55.226409Z","steps":["trace[699516526] 'process raft request' (duration: 846.612521ms)","trace[699516526] 'compare' (duration: 30.455229ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:04:55.226608Z","caller":"traceutil/trace.go:171","msg":"trace[735673138] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"844.50157ms","start":"2026-08-14T09:04:54.381542Z","end":"2026-08-14T09:04:55.226044Z","steps":["trace[735673138] 'read index received' (duration: 382.318457ms)","trace[735673138] 'applied index is now lower than readState.Index' (duration: 462.182093ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:04:55.226654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:54.348374Z","time spent":"878.175728ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:04:55.226833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.237777ms","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-08-14T09:04:55.226864Z","caller":"traceutil/trace.go:171","msg":"trace[552059150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"845.37133ms","start":"2026-08-14T09:04:54.381483Z","end":"2026-08-14T09:04:55.226854Z","steps":["trace[552059150] 'agreement among raft nodes before linearized reading' (duration: 845.179266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:55.226893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:54.381453Z","time spent":"845.431622ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:04:55.261637Z","caller":"traceutil/trace.go:171","msg":"trace[1826334293] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"425.290604ms","start":"2026-08-14T09:04:54.836312Z","end":"2026-08-14T09:04:55.261602Z","steps":["trace[1826334293] 'process raft request' (duration: 425.1214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:55.261892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.913155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:04:55.261977Z","caller":"traceutil/trace.go:171","msg":"trace[584343455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"729.044608ms","start":"2026-08-14T09:04:54.532908Z","end":"2026-08-14T09:04:55.261953Z","steps":["trace[584343455] 'agreement among raft nodes before linearized reading' (duration: 728.856813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:55.262026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:54.53288Z","time spent":"729.1347ms","remote":"127.0.0.1:33474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:04:55.261898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:54.836172Z","time spent":"425.601722ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:04:55.262088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.802227ms","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-08-14T09:04:55.262148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.602186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-45mf4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-14T09:04:55.262204Z","caller":"traceutil/trace.go:171","msg":"trace[188220619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"832.024252ms","start":"2026-08-14T09:04:54.430154Z","end":"2026-08-14T09:04:55.262179Z","steps":["trace[188220619] 'agreement among raft nodes before linearized reading' (duration: 831.596792ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:55.262209Z","caller":"traceutil/trace.go:171","msg":"trace[1183837369] range","detail":"{range_begin:/registry/pods/kube-system/cilium-45mf4; range_end:; response_count:1; response_revision:591; }","duration":"767.70411ms","start":"2026-08-14T09:04:54.494482Z","end":"2026-08-14T09:04:55.262186Z","steps":["trace[1183837369] 'agreement among raft nodes before linearized reading' (duration: 767.473194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:55.262263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:54.494459Z","time spent":"767.792851ms","remote":"127.0.0.1:33672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-45mf4\" "} {"level":"warn","ts":"2026-08-14T09:04:55.262264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:54.430127Z","time spent":"832.126064ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:04:55.389216Z","caller":"traceutil/trace.go:171","msg":"trace[1672167705] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"158.10996ms","start":"2026-08-14T09:04:55.231063Z","end":"2026-08-14T09:04:55.389173Z","steps":["trace[1672167705] 'process raft request' (duration: 113.485532ms)","trace[1672167705] 'compare' (duration: 44.415093ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:04:55.399933Z","caller":"traceutil/trace.go:171","msg":"trace[1085825081] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"126.553104ms","start":"2026-08-14T09:04:55.273354Z","end":"2026-08-14T09:04:55.399907Z","steps":["trace[1085825081] 'read index received' (duration: 71.156221ms)","trace[1085825081] 'applied index is now lower than readState.Index' (duration: 55.395743ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:04:55.400087Z","caller":"traceutil/trace.go:171","msg":"trace[1617251570] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"121.496759ms","start":"2026-08-14T09:04:55.278571Z","end":"2026-08-14T09:04:55.400068Z","steps":["trace[1617251570] 'process raft request' (duration: 121.190352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:55.400133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.745549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:04:55.400166Z","caller":"traceutil/trace.go:171","msg":"trace[1141885128] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"132.156322ms","start":"2026-08-14T09:04:55.267946Z","end":"2026-08-14T09:04:55.400102Z","steps":["trace[1141885128] 'process raft request' (duration: 131.66424ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:55.400233Z","caller":"traceutil/trace.go:171","msg":"trace[1011428482] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:594; }","duration":"126.919623ms","start":"2026-08-14T09:04:55.273298Z","end":"2026-08-14T09:04:55.400218Z","steps":["trace[1011428482] 'agreement among raft nodes before linearized reading' (duration: 126.705168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:56.616664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000901250051382873,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:04:56.994172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459791937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:04:56.994295Z","caller":"traceutil/trace.go:171","msg":"trace[524295574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"1.459983303s","start":"2026-08-14T09:04:55.534275Z","end":"2026-08-14T09:04:56.994259Z","steps":["trace[524295574] 'range keys from in-memory index tree' (duration: 1.459667104s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:56.994354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:55.534245Z","time spent":"1.460093715s","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:04:56.994374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26726864s","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-08-14T09:04:56.994477Z","caller":"traceutil/trace.go:171","msg":"trace[1303154991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"1.267411814s","start":"2026-08-14T09:04:55.727034Z","end":"2026-08-14T09:04:56.994446Z","steps":["trace[1303154991] 'range keys from in-memory index tree' (duration: 1.266923782s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:56.994531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:55.727005Z","time spent":"1.267515096s","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:04:57.000443Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.153595334s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:04:57.002642Z","caller":"traceutil/trace.go:171","msg":"trace[310505026] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"886.243135ms","start":"2026-08-14T09:04:56.11632Z","end":"2026-08-14T09:04:57.002564Z","steps":["trace[310505026] 'read index received' (duration: 884.441381ms)","trace[310505026] 'applied index is now lower than readState.Index' (duration: 1.800664ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:04:57.002652Z","caller":"traceutil/trace.go:171","msg":"trace[117281694] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.157243953s","start":"2026-08-14T09:04:55.84537Z","end":"2026-08-14T09:04:57.002614Z","steps":["trace[117281694] 'process raft request' (duration: 1.156218508s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:57.002938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:55.845338Z","time spent":"1.157478049s","remote":"127.0.0.1:33590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:04:57.003033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.643755ms","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-08-14T09:04:57.003112Z","caller":"traceutil/trace.go:171","msg":"trace[291204702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"886.809369ms","start":"2026-08-14T09:04:56.116277Z","end":"2026-08-14T09:04:57.003087Z","steps":["trace[291204702] 'agreement among raft nodes before linearized reading' (duration: 886.482431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:57.003174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:56.116257Z","time spent":"886.904782ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:04:59.465683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.775602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:04:59.46613Z","caller":"traceutil/trace.go:171","msg":"trace[671712475] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"806.793451ms","start":"2026-08-14T09:04:58.659196Z","end":"2026-08-14T09:04:59.465989Z","steps":["trace[671712475] 'process raft request' (duration: 179.446205ms)","trace[671712475] 'compare' (duration: 626.28961ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:04:59.466276Z","caller":"traceutil/trace.go:171","msg":"trace[1353639060] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"800.578318ms","start":"2026-08-14T09:04:58.66565Z","end":"2026-08-14T09:04:59.466229Z","steps":["trace[1353639060] 'process raft request' (duration: 800.158508ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:59.466422Z","caller":"traceutil/trace.go:171","msg":"trace[25719841] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"658.239526ms","start":"2026-08-14T09:04:58.80817Z","end":"2026-08-14T09:04:59.466409Z","steps":["trace[25719841] 'process raft request' (duration: 658.191275ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:59.466521Z","caller":"traceutil/trace.go:171","msg":"trace[1350462088] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"769.157865ms","start":"2026-08-14T09:04:58.697346Z","end":"2026-08-14T09:04:59.466504Z","steps":["trace[1350462088] 'process raft request' (duration: 768.807066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:59.466486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:58.808137Z","time spent":"658.319548ms","remote":"127.0.0.1:33534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:04:59.466533Z","caller":"traceutil/trace.go:171","msg":"trace[525409507] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"762.85049ms","start":"2026-08-14T09:04:58.703622Z","end":"2026-08-14T09:04:59.466473Z","steps":["trace[525409507] 'process raft request' (duration: 762.677846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:59.466877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:58.703579Z","time spent":"763.269931ms","remote":"127.0.0.1:33572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:04:59.466581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:58.665619Z","time spent":"800.844385ms","remote":"127.0.0.1:33534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:04:59.467012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:58.659145Z","time spent":"807.111459ms","remote":"127.0.0.1:33590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:04:59.466607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:58.697317Z","time spent":"769.235977ms","remote":"127.0.0.1:33952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4387,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:04:59.48552Z","caller":"traceutil/trace.go:171","msg":"trace[1151324642] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:649; }","duration":"455.790044ms","start":"2026-08-14T09:04:59.0297Z","end":"2026-08-14T09:04:59.48549Z","steps":["trace[1151324642] 'read index received' (duration: 435.231228ms)","trace[1151324642] 'applied index is now lower than readState.Index' (duration: 20.557056ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:04:59.485944Z","caller":"traceutil/trace.go:171","msg":"trace[20474261] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"477.768265ms","start":"2026-08-14T09:04:59.008165Z","end":"2026-08-14T09:04:59.485933Z","steps":["trace[20474261] 'process raft request' (duration: 477.092789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:59.486118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:59.008118Z","time spent":"477.870918ms","remote":"127.0.0.1:33534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:04:59.486386Z","caller":"traceutil/trace.go:171","msg":"trace[1917317643] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"277.008395ms","start":"2026-08-14T09:04:59.20934Z","end":"2026-08-14T09:04:59.486348Z","steps":["trace[1917317643] 'process raft request' (duration: 276.050792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:59.486577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.862341ms","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-08-14T09:04:59.486635Z","caller":"traceutil/trace.go:171","msg":"trace[1031786684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"457.023605ms","start":"2026-08-14T09:04:59.029597Z","end":"2026-08-14T09:04:59.48662Z","steps":["trace[1031786684] 'agreement among raft nodes before linearized reading' (duration: 456.777009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:59.486692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:59.02954Z","time spent":"457.144368ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:04:59.49823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.412896ms","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-08-14T09:04:59.498428Z","caller":"traceutil/trace.go:171","msg":"trace[2015342357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"468.626231ms","start":"2026-08-14T09:04:59.029758Z","end":"2026-08-14T09:04:59.498384Z","steps":["trace[2015342357] 'agreement among raft nodes before linearized reading' (duration: 468.112498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:59.498564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:59.029718Z","time spent":"468.836926ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:05:01.04567Z","caller":"traceutil/trace.go:171","msg":"trace[1642752506] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"108.812077ms","start":"2026-08-14T09:05:00.93683Z","end":"2026-08-14T09:05:01.045642Z","steps":["trace[1642752506] 'read index received' (duration: 108.12058ms)","trace[1642752506] 'applied index is now lower than readState.Index' (duration: 690.317µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:05:01.045772Z","caller":"traceutil/trace.go:171","msg":"trace[784665420] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"511.979797ms","start":"2026-08-14T09:05:00.533777Z","end":"2026-08-14T09:05:01.045757Z","steps":["trace[784665420] 'process raft request' (duration: 511.129466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:01.04589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:00.533764Z","time spent":"512.046519ms","remote":"127.0.0.1:33572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:05:01.0457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.49226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-45mf4\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-08-14T09:05:01.046429Z","caller":"traceutil/trace.go:171","msg":"trace[1476984924] range","detail":"{range_begin:/registry/pods/kube-system/cilium-45mf4; range_end:; response_count:1; response_revision:663; }","duration":"513.292999ms","start":"2026-08-14T09:05:00.533117Z","end":"2026-08-14T09:05:01.04641Z","steps":["trace[1476984924] 'range keys from in-memory index tree' (duration: 512.265674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:01.04647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:00.533087Z","time spent":"513.372182ms","remote":"127.0.0.1:33672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17812,"request content":"key:\"/registry/pods/kube-system/cilium-45mf4\" "} {"level":"warn","ts":"2026-08-14T09:05:01.046724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.793872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-08-14T09:05:01.046751Z","caller":"traceutil/trace.go:171","msg":"trace[1518676959] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:664; }","duration":"109.819662ms","start":"2026-08-14T09:05:00.93692Z","end":"2026-08-14T09:05:01.04674Z","steps":["trace[1518676959] 'agreement among raft nodes before linearized reading' (duration: 109.71371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:01.046768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.938485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-08-14T09:05:01.046803Z","caller":"traceutil/trace.go:171","msg":"trace[1735572189] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:664; }","duration":"109.973036ms","start":"2026-08-14T09:05:00.936818Z","end":"2026-08-14T09:05:01.046791Z","steps":["trace[1735572189] 'agreement among raft nodes before linearized reading' (duration: 109.809622ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:14.968734Z","caller":"traceutil/trace.go:171","msg":"trace[77422380] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"436.996391ms","start":"2026-08-14T09:05:14.531711Z","end":"2026-08-14T09:05:14.968708Z","steps":["trace[77422380] 'read index received' (duration: 436.781016ms)","trace[77422380] 'applied index is now lower than readState.Index' (duration: 213.625µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:05:14.96894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.199287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:05:14.968984Z","caller":"traceutil/trace.go:171","msg":"trace[1967585518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"437.298569ms","start":"2026-08-14T09:05:14.531675Z","end":"2026-08-14T09:05:14.968974Z","steps":["trace[1967585518] 'agreement among raft nodes before linearized reading' (duration: 437.183186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:14.969026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:14.531658Z","time spent":"437.360461ms","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:05:14.969156Z","caller":"traceutil/trace.go:171","msg":"trace[1128372094] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"451.946079ms","start":"2026-08-14T09:05:14.517171Z","end":"2026-08-14T09:05:14.969117Z","steps":["trace[1128372094] 'process raft request' (duration: 451.342264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:14.969378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:14.517142Z","time spent":"452.100383ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:05:15.471654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000901250051383213,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:05:15.972488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000901250051383213,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:05:16.15188Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.183334946s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:05:16.152367Z","caller":"traceutil/trace.go:171","msg":"trace[958331003] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"1.423839914s","start":"2026-08-14T09:05:14.728494Z","end":"2026-08-14T09:05:16.152334Z","steps":["trace[958331003] 'process raft request' (duration: 1.423638229s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:16.152564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:14.728465Z","time spent":"1.424003587s","remote":"127.0.0.1:33730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:05:16.287517Z","caller":"traceutil/trace.go:171","msg":"trace[504158332] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:803; }","duration":"1.316637746s","start":"2026-08-14T09:05:14.970853Z","end":"2026-08-14T09:05:16.287491Z","steps":["trace[504158332] 'read index received' (duration: 1.181261605s)","trace[504158332] 'applied index is now lower than readState.Index' (duration: 135.375221ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:05:16.287532Z","caller":"traceutil/trace.go:171","msg":"trace[1861124473] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"1.198762656s","start":"2026-08-14T09:05:15.088729Z","end":"2026-08-14T09:05:16.287492Z","steps":["trace[1861124473] 'process raft request' (duration: 1.164064892s)","trace[1861124473] 'compare' (duration: 34.305235ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:05:16.287675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316811821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:05:16.287703Z","caller":"traceutil/trace.go:171","msg":"trace[744339481] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"913.688952ms","start":"2026-08-14T09:05:15.37398Z","end":"2026-08-14T09:05:16.287669Z","steps":["trace[744339481] 'process raft request' (duration: 913.410055ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:16.287776Z","caller":"traceutil/trace.go:171","msg":"trace[2074305879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"1.316973255s","start":"2026-08-14T09:05:14.970789Z","end":"2026-08-14T09:05:16.287762Z","steps":["trace[2074305879] 'agreement among raft nodes before linearized reading' (duration: 1.316807701s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:16.287728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243923267s","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":"warn","ts":"2026-08-14T09:05:16.287856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:15.373947Z","time spent":"913.834515ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:05:16.287866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.207416ms","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-08-14T09:05:16.287784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.589982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:05:16.287921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.383841ms","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-08-14T09:05:16.287953Z","caller":"traceutil/trace.go:171","msg":"trace[988287575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"478.412201ms","start":"2026-08-14T09:05:15.809531Z","end":"2026-08-14T09:05:16.287943Z","steps":["trace[988287575] 'agreement among raft nodes before linearized reading' (duration: 478.281618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:16.287992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:15.809489Z","time spent":"478.496423ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:05:16.287861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.483318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T09:05:16.288108Z","caller":"traceutil/trace.go:171","msg":"trace[1873105095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"529.795236ms","start":"2026-08-14T09:05:15.758303Z","end":"2026-08-14T09:05:16.288098Z","steps":["trace[1873105095] 'agreement among raft nodes before linearized reading' (duration: 529.450968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:16.288138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:15.758283Z","time spent":"529.847357ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:05:16.28786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:14.970762Z","time spent":"1.317088457s","remote":"127.0.0.1:33474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:05:16.287949Z","caller":"traceutil/trace.go:171","msg":"trace[1902959515] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:777; }","duration":"690.776527ms","start":"2026-08-14T09:05:15.597157Z","end":"2026-08-14T09:05:16.287934Z","steps":["trace[1902959515] 'agreement among raft nodes before linearized reading' (duration: 690.598693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:16.288284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:15.597137Z","time spent":"691.123095ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:05:16.287889Z","caller":"traceutil/trace.go:171","msg":"trace[418641668] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:777; }","duration":"1.244139952s","start":"2026-08-14T09:05:15.043724Z","end":"2026-08-14T09:05:16.287864Z","steps":["trace[418641668] 'agreement among raft nodes before linearized reading' (duration: 1.243935527s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:16.288465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:15.043704Z","time spent":"1.244744358s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:05:16.287739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:15.088703Z","time spent":"1.198950781s","remote":"127.0.0.1:33666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:05:16.287948Z","caller":"traceutil/trace.go:171","msg":"trace[778263421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"478.320959ms","start":"2026-08-14T09:05:15.809605Z","end":"2026-08-14T09:05:16.287926Z","steps":["trace[778263421] 'agreement among raft nodes before linearized reading' (duration: 478.068693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:16.288737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:15.809599Z","time spent":"479.13102ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:05:16.288536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.538155ms","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-08-14T09:05:16.290197Z","caller":"traceutil/trace.go:171","msg":"trace[101998257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"480.334708ms","start":"2026-08-14T09:05:15.809811Z","end":"2026-08-14T09:05:16.290145Z","steps":["trace[101998257] 'agreement among raft nodes before linearized reading' (duration: 478.066153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:16.290318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:15.809779Z","time spent":"480.519013ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:05:18.422692Z","caller":"traceutil/trace.go:171","msg":"trace[1157660504] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"107.900124ms","start":"2026-08-14T09:05:18.31474Z","end":"2026-08-14T09:05:18.42264Z","steps":["trace[1157660504] 'process raft request' (duration: 107.621497ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:20.324777Z","caller":"traceutil/trace.go:171","msg":"trace[688104096] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"108.593262ms","start":"2026-08-14T09:05:20.216145Z","end":"2026-08-14T09:05:20.324738Z","steps":["trace[688104096] 'process raft request' (duration: 84.237453ms)","trace[688104096] 'compare' (duration: 23.924978ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:05:21.290618Z","caller":"traceutil/trace.go:171","msg":"trace[268083130] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"176.354749ms","start":"2026-08-14T09:05:21.114231Z","end":"2026-08-14T09:05:21.290585Z","steps":["trace[268083130] 'process raft request' (duration: 114.653501ms)","trace[268083130] 'compare' (duration: 61.476072ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:05:21.825395Z","caller":"traceutil/trace.go:171","msg":"trace[1990626003] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:866; }","duration":"121.367996ms","start":"2026-08-14T09:05:21.703999Z","end":"2026-08-14T09:05:21.825367Z","steps":["trace[1990626003] 'read index received' (duration: 71.252863ms)","trace[1990626003] 'applied index is now lower than readState.Index' (duration: 50.113993ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:05:21.82575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.731406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/\" range_end:\"/registry/services/specs/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:1 size:1914"} {"level":"info","ts":"2026-08-14T09:05:21.825791Z","caller":"traceutil/trace.go:171","msg":"trace[576431809] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/; range_end:/registry/services/specs/envoy-gateway-system0; response_count:1; response_revision:836; }","duration":"121.861099ms","start":"2026-08-14T09:05:21.70392Z","end":"2026-08-14T09:05:21.825781Z","steps":["trace[576431809] 'agreement among raft nodes before linearized reading' (duration: 121.651583ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:21.826028Z","caller":"traceutil/trace.go:171","msg":"trace[122776339] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"127.551328ms","start":"2026-08-14T09:05:21.698468Z","end":"2026-08-14T09:05:21.826019Z","steps":["trace[122776339] 'process raft request' (duration: 76.978934ms)","trace[122776339] 'compare' (duration: 49.820126ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:05:28.313874Z","caller":"traceutil/trace.go:171","msg":"trace[242019000] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"117.559002ms","start":"2026-08-14T09:05:28.196285Z","end":"2026-08-14T09:05:28.313844Z","steps":["trace[242019000] 'process raft request' (duration: 117.372548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:28.843436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.37567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:05:28.843668Z","caller":"traceutil/trace.go:171","msg":"trace[2032136109] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"233.729111ms","start":"2026-08-14T09:05:28.609921Z","end":"2026-08-14T09:05:28.84365Z","steps":["trace[2032136109] 'process raft request' (duration: 233.68835ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:28.843811Z","caller":"traceutil/trace.go:171","msg":"trace[613534634] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"234.115861ms","start":"2026-08-14T09:05:28.609687Z","end":"2026-08-14T09:05:28.843803Z","steps":["trace[613534634] 'process raft request' (duration: 233.866185ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:28.843944Z","caller":"traceutil/trace.go:171","msg":"trace[609457664] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"234.375537ms","start":"2026-08-14T09:05:28.609519Z","end":"2026-08-14T09:05:28.843895Z","steps":["trace[609457664] 'process raft request' (duration: 107.454694ms)","trace[609457664] 'compare' (duration: 126.244527ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:05:31.886257Z","caller":"traceutil/trace.go:171","msg":"trace[2031794898] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"120.882595ms","start":"2026-08-14T09:05:31.765341Z","end":"2026-08-14T09:05:31.886223Z","steps":["trace[2031794898] 'process raft request' (duration: 94.653399ms)","trace[2031794898] 'compare' (duration: 26.053321ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:05:33.007683Z","caller":"traceutil/trace.go:171","msg":"trace[1862819398] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"105.959448ms","start":"2026-08-14T09:05:32.901685Z","end":"2026-08-14T09:05:33.007644Z","steps":["trace[1862819398] 'process raft request' (duration: 105.721322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:33.535736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.940389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:05:33.536136Z","caller":"traceutil/trace.go:171","msg":"trace[166347900] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"523.761707ms","start":"2026-08-14T09:05:33.012338Z","end":"2026-08-14T09:05:33.5361Z","steps":["trace[166347900] 'process raft request' (duration: 206.365988ms)","trace[166347900] 'compare' (duration: 316.747223ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:05:33.536299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:33.012316Z","time spent":"523.9094ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:05:33.536305Z","caller":"traceutil/trace.go:171","msg":"trace[822473583] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"523.518481ms","start":"2026-08-14T09:05:33.012765Z","end":"2026-08-14T09:05:33.536284Z","steps":["trace[822473583] 'process raft request' (duration: 523.08266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:33.536396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:33.012756Z","time spent":"523.597024ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:05:34.249973Z","caller":"traceutil/trace.go:171","msg":"trace[1725300284] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"483.083286ms","start":"2026-08-14T09:05:33.766866Z","end":"2026-08-14T09:05:34.24995Z","steps":["trace[1725300284] 'process raft request' (duration: 482.930972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:34.25018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:33.766838Z","time spent":"483.206339ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:05:34.755303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.441424ms","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-08-14T09:05:34.755455Z","caller":"traceutil/trace.go:171","msg":"trace[1094736440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"120.635879ms","start":"2026-08-14T09:05:34.634798Z","end":"2026-08-14T09:05:34.755434Z","steps":["trace[1094736440] 'range keys from in-memory index tree' (duration: 120.28625ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:35.302725Z","caller":"traceutil/trace.go:171","msg":"trace[1038918870] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"285.461684ms","start":"2026-08-14T09:05:35.017238Z","end":"2026-08-14T09:05:35.3027Z","steps":["trace[1038918870] 'process raft request' (duration: 285.323561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:35.678935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.881587ms","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-08-14T09:05:35.678952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.76196ms","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-08-14T09:05:35.679023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.418847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:05:35.67905Z","caller":"traceutil/trace.go:171","msg":"trace[1612482508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"147.473779ms","start":"2026-08-14T09:05:35.531565Z","end":"2026-08-14T09:05:35.679039Z","steps":["trace[1612482508] 'range keys from in-memory index tree' (duration: 147.359055ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:35.679064Z","caller":"traceutil/trace.go:171","msg":"trace[439248582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"137.936954ms","start":"2026-08-14T09:05:35.541097Z","end":"2026-08-14T09:05:35.679034Z","steps":["trace[439248582] 'range keys from in-memory index tree' (duration: 137.534854ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:35.679073Z","caller":"traceutil/trace.go:171","msg":"trace[875048233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"139.042791ms","start":"2026-08-14T09:05:35.539975Z","end":"2026-08-14T09:05:35.679018Z","steps":["trace[875048233] 'range keys from in-memory index tree' (duration: 138.611711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:35.678952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.23371ms","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-08-14T09:05:35.679169Z","caller":"traceutil/trace.go:171","msg":"trace[1900505673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"129.475625ms","start":"2026-08-14T09:05:35.54966Z","end":"2026-08-14T09:05:35.679136Z","steps":["trace[1900505673] 'range keys from in-memory index tree' (duration: 129.060175ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:36.980805Z","caller":"traceutil/trace.go:171","msg":"trace[1167159596] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"725.660004ms","start":"2026-08-14T09:05:36.255117Z","end":"2026-08-14T09:05:36.980777Z","steps":["trace[1167159596] 'process raft request' (duration: 725.473129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:36.980987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:36.255099Z","time spent":"725.795868ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:05:36.981217Z","caller":"traceutil/trace.go:171","msg":"trace[381539855] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:971; }","duration":"447.661875ms","start":"2026-08-14T09:05:36.533507Z","end":"2026-08-14T09:05:36.981169Z","steps":["trace[381539855] 'read index received' (duration: 447.635494ms)","trace[381539855] 'applied index is now lower than readState.Index' (duration: 18.69µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:05:36.981695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.174238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:05:36.981788Z","caller":"traceutil/trace.go:171","msg":"trace[745375732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"448.315891ms","start":"2026-08-14T09:05:36.533451Z","end":"2026-08-14T09:05:36.981767Z","steps":["trace[745375732] 'agreement among raft nodes before linearized reading' (duration: 448.169478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:36.98184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:36.533428Z","time spent":"448.402793ms","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:05:37.208236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.169761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:05:37.208569Z","caller":"traceutil/trace.go:171","msg":"trace[2050678030] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"471.274976ms","start":"2026-08-14T09:05:36.737278Z","end":"2026-08-14T09:05:37.208553Z","steps":["trace[2050678030] 'process raft request' (duration: 347.730126ms)","trace[2050678030] 'compare' (duration: 123.009756ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:05:37.208627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:36.737254Z","time spent":"471.348798ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:05:37.350151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.574983ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-14T09:05:37.350281Z","caller":"traceutil/trace.go:171","msg":"trace[1785211558] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:971; }","duration":"368.938548ms","start":"2026-08-14T09:05:36.981325Z","end":"2026-08-14T09:05:37.350263Z","steps":["trace[1785211558] 'read index received' (duration: 103.642381ms)","trace[1785211558] 'applied index is now lower than readState.Index' (duration: 265.295007ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:05:37.350465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.3155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-14T09:05:37.350439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.149268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:05:37.350485Z","caller":"traceutil/trace.go:171","msg":"trace[1195608668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:939; }","duration":"552.373742ms","start":"2026-08-14T09:05:36.798104Z","end":"2026-08-14T09:05:37.350477Z","steps":["trace[1195608668] 'agreement among raft nodes before linearized reading' (duration: 552.244028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:37.350517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:36.798089Z","time spent":"552.422412ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:05:37.350523Z","caller":"traceutil/trace.go:171","msg":"trace[1106084614] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:939; }","duration":"459.295141ms","start":"2026-08-14T09:05:36.891208Z","end":"2026-08-14T09:05:37.350503Z","steps":["trace[1106084614] 'agreement among raft nodes before linearized reading' (duration: 459.134287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:37.350495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.581994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:05:37.35056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:36.891185Z","time spent":"459.367513ms","remote":"127.0.0.1:33630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:05:37.350574Z","caller":"traceutil/trace.go:171","msg":"trace[764724296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"367.706767ms","start":"2026-08-14T09:05:36.982849Z","end":"2026-08-14T09:05:37.350556Z","steps":["trace[764724296] 'agreement among raft nodes before linearized reading' (duration: 367.580694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:37.350609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:36.98283Z","time spent":"367.771409ms","remote":"127.0.0.1:33474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:05:37.553708Z","caller":"traceutil/trace.go:171","msg":"trace[1224692538] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"199.56499ms","start":"2026-08-14T09:05:37.354117Z","end":"2026-08-14T09:05:37.553682Z","steps":["trace[1224692538] 'process raft request' (duration: 199.371256ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:37.625456Z","caller":"traceutil/trace.go:171","msg":"trace[565049012] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"270.650359ms","start":"2026-08-14T09:05:37.354782Z","end":"2026-08-14T09:05:37.625432Z","steps":["trace[565049012] 'process raft request' (duration: 270.512956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:37.813147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.207448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:05:37.813221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.660066ms","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-08-14T09:05:37.813231Z","caller":"traceutil/trace.go:171","msg":"trace[181876783] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:941; }","duration":"120.365011ms","start":"2026-08-14T09:05:37.692848Z","end":"2026-08-14T09:05:37.813213Z","steps":["trace[181876783] 'count revisions from in-memory index tree' (duration: 120.111195ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:37.813257Z","caller":"traceutil/trace.go:171","msg":"trace[491480260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"113.708408ms","start":"2026-08-14T09:05:37.699537Z","end":"2026-08-14T09:05:37.813245Z","steps":["trace[491480260] 'range keys from in-memory index tree' (duration: 113.476393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:37.813332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.697033ms","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-08-14T09:05:37.813355Z","caller":"traceutil/trace.go:171","msg":"trace[1629953041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"125.755365ms","start":"2026-08-14T09:05:37.687592Z","end":"2026-08-14T09:05:37.813347Z","steps":["trace[1629953041] 'range keys from in-memory index tree' (duration: 125.571709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:37.813351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.792704ms","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-08-14T09:05:37.813442Z","caller":"traceutil/trace.go:171","msg":"trace[1390874718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"116.945487ms","start":"2026-08-14T09:05:37.696474Z","end":"2026-08-14T09:05:37.81342Z","steps":["trace[1390874718] 'range keys from in-memory index tree' (duration: 116.594238ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:39.679785Z","caller":"traceutil/trace.go:171","msg":"trace[1075255677] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"118.311671ms","start":"2026-08-14T09:05:39.561436Z","end":"2026-08-14T09:05:39.679747Z","steps":["trace[1075255677] 'process raft request' (duration: 117.989313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:04.405958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000901250051383784,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:06:04.472034Z","caller":"traceutil/trace.go:171","msg":"trace[2100040973] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"566.638961ms","start":"2026-08-14T09:06:03.90537Z","end":"2026-08-14T09:06:04.472009Z","steps":["trace[2100040973] 'read index received' (duration: 566.454787ms)","trace[2100040973] 'applied index is now lower than readState.Index' (duration: 183.474µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:04.472177Z","caller":"traceutil/trace.go:171","msg":"trace[1892699089] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"659.005135ms","start":"2026-08-14T09:06:03.813131Z","end":"2026-08-14T09:06:04.472136Z","steps":["trace[1892699089] 'process raft request' (duration: 658.750249ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:04.472355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.399107ms","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-08-14T09:06:04.472385Z","caller":"traceutil/trace.go:171","msg":"trace[690548625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"294.489749ms","start":"2026-08-14T09:06:04.177888Z","end":"2026-08-14T09:06:04.472378Z","steps":["trace[690548625] 'agreement among raft nodes before linearized reading' (duration: 294.407637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:04.472392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.759167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:06:04.472417Z","caller":"traceutil/trace.go:171","msg":"trace[1318927439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"148.814098ms","start":"2026-08-14T09:06:04.323595Z","end":"2026-08-14T09:06:04.472409Z","steps":["trace[1318927439] 'agreement among raft nodes before linearized reading' (duration: 148.780747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:04.472388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:03.81311Z","time spent":"659.160809ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:04.472357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.199862ms","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-08-14T09:06:04.472486Z","caller":"traceutil/trace.go:171","msg":"trace[1821249150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1030; }","duration":"283.376547ms","start":"2026-08-14T09:06:04.189105Z","end":"2026-08-14T09:06:04.472481Z","steps":["trace[1821249150] 'agreement among raft nodes before linearized reading' (duration: 283.183432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:04.472461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.585476ms","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-08-14T09:06:04.47254Z","caller":"traceutil/trace.go:171","msg":"trace[1723483665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"304.72456ms","start":"2026-08-14T09:06:04.167798Z","end":"2026-08-14T09:06:04.472522Z","steps":["trace[1723483665] 'agreement among raft nodes before linearized reading' (duration: 304.478704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:04.472572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:04.167775Z","time spent":"304.790232ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:04.472687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.321698ms","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-08-14T09:06:04.47272Z","caller":"traceutil/trace.go:171","msg":"trace[227424498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"567.389299ms","start":"2026-08-14T09:06:03.905322Z","end":"2026-08-14T09:06:04.472711Z","steps":["trace[227424498] 'agreement among raft nodes before linearized reading' (duration: 566.827146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:04.472745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:03.905284Z","time spent":"567.453601ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:06:04.600364Z","caller":"traceutil/trace.go:171","msg":"trace[1025014346] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"122.468962ms","start":"2026-08-14T09:06:04.477869Z","end":"2026-08-14T09:06:04.600338Z","steps":["trace[1025014346] 'process raft request' (duration: 122.406741ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:04.600435Z","caller":"traceutil/trace.go:171","msg":"trace[1163242158] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"123.548659ms","start":"2026-08-14T09:06:04.47685Z","end":"2026-08-14T09:06:04.600399Z","steps":["trace[1163242158] 'process raft request' (duration: 90.353715ms)","trace[1163242158] 'compare' (duration: 32.876146ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:04.600736Z","caller":"traceutil/trace.go:171","msg":"trace[986358097] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"122.080193ms","start":"2026-08-14T09:06:04.478646Z","end":"2026-08-14T09:06:04.600726Z","steps":["trace[986358097] 'process raft request' (duration: 121.668473ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:08.945577Z","caller":"traceutil/trace.go:171","msg":"trace[1222144394] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"422.438796ms","start":"2026-08-14T09:06:08.523104Z","end":"2026-08-14T09:06:08.945542Z","steps":["trace[1222144394] 'read index received' (duration: 422.119778ms)","trace[1222144394] 'applied index is now lower than readState.Index' (duration: 317.238µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:08.945672Z","caller":"traceutil/trace.go:171","msg":"trace[541028255] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"430.195456ms","start":"2026-08-14T09:06:08.515434Z","end":"2026-08-14T09:06:08.945629Z","steps":["trace[541028255] 'process raft request' (duration: 429.93415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:08.945838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:08.515416Z","time spent":"430.3475ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:08.945882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.752844ms","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-08-14T09:06:08.945922Z","caller":"traceutil/trace.go:171","msg":"trace[1566010655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"422.829825ms","start":"2026-08-14T09:06:08.52308Z","end":"2026-08-14T09:06:08.94591Z","steps":["trace[1566010655] 'agreement among raft nodes before linearized reading' (duration: 422.668891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:08.945962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:08.52307Z","time spent":"422.882896ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:08.94594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.733242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:06:08.946022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.055548ms","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-08-14T09:06:08.946037Z","caller":"traceutil/trace.go:171","msg":"trace[244290810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"413.860646ms","start":"2026-08-14T09:06:08.532152Z","end":"2026-08-14T09:06:08.946012Z","steps":["trace[244290810] 'agreement among raft nodes before linearized reading' (duration: 413.710412ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:08.946113Z","caller":"traceutil/trace.go:171","msg":"trace[537908541] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"314.162782ms","start":"2026-08-14T09:06:08.631928Z","end":"2026-08-14T09:06:08.946091Z","steps":["trace[537908541] 'agreement among raft nodes before linearized reading' (duration: 313.974957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:08.946178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:08.631917Z","time spent":"314.245383ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:08.946215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.582311ms","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-08-14T09:06:08.946234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.574239ms","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-08-14T09:06:08.946254Z","caller":"traceutil/trace.go:171","msg":"trace[692987679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"314.644263ms","start":"2026-08-14T09:06:08.631599Z","end":"2026-08-14T09:06:08.946244Z","steps":["trace[692987679] 'agreement among raft nodes before linearized reading' (duration: 314.52881ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:08.946266Z","caller":"traceutil/trace.go:171","msg":"trace[81131638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1043; }","duration":"329.646081ms","start":"2026-08-14T09:06:08.61661Z","end":"2026-08-14T09:06:08.946256Z","steps":["trace[81131638] 'agreement among raft nodes before linearized reading' (duration: 329.544108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:08.946296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:08.631589Z","time spent":"314.700964ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:08.9463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:08.61659Z","time spent":"329.700292ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:06:08.946332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.128278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:06:08.946123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:08.532137Z","time spent":"413.973638ms","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:06:08.946364Z","caller":"traceutil/trace.go:171","msg":"trace[1088881488] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1043; }","duration":"216.164259ms","start":"2026-08-14T09:06:08.730191Z","end":"2026-08-14T09:06:08.946355Z","steps":["trace[1088881488] 'agreement among raft nodes before linearized reading' (duration: 216.096247ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:09.051713Z","caller":"traceutil/trace.go:171","msg":"trace[1950490690] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"101.531029ms","start":"2026-08-14T09:06:08.950146Z","end":"2026-08-14T09:06:09.051677Z","steps":["trace[1950490690] 'process raft request' (duration: 101.372685ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:09.089219Z","caller":"traceutil/trace.go:171","msg":"trace[1496892198] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"138.127976ms","start":"2026-08-14T09:06:08.951063Z","end":"2026-08-14T09:06:09.089191Z","steps":["trace[1496892198] 'process raft request' (duration: 138.056514ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:09.089252Z","caller":"traceutil/trace.go:171","msg":"trace[983309027] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"138.230568ms","start":"2026-08-14T09:06:08.950988Z","end":"2026-08-14T09:06:09.089218Z","steps":["trace[983309027] 'process raft request' (duration: 138.007303ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:09.089954Z","caller":"traceutil/trace.go:171","msg":"trace[913240793] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"138.2981ms","start":"2026-08-14T09:06:08.951641Z","end":"2026-08-14T09:06:09.089939Z","steps":["trace[913240793] 'process raft request' (duration: 137.513901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:09.760957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.702108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:06:09.76105Z","caller":"traceutil/trace.go:171","msg":"trace[237710399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1048; }","duration":"226.856452ms","start":"2026-08-14T09:06:09.534171Z","end":"2026-08-14T09:06:09.761027Z","steps":["trace[237710399] 'range keys from in-memory index tree' (duration: 226.612845ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:11.366468Z","caller":"traceutil/trace.go:171","msg":"trace[1764947647] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"310.539173ms","start":"2026-08-14T09:06:11.055899Z","end":"2026-08-14T09:06:11.366438Z","steps":["trace[1764947647] 'read index received' (duration: 310.371629ms)","trace[1764947647] 'applied index is now lower than readState.Index' (duration: 166.214µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:11.366629Z","caller":"traceutil/trace.go:171","msg":"trace[1202304315] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"408.282879ms","start":"2026-08-14T09:06:10.958283Z","end":"2026-08-14T09:06:11.366566Z","steps":["trace[1202304315] 'process raft request' (duration: 407.93598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:11.366772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.83604ms","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-08-14T09:06:11.366839Z","caller":"traceutil/trace.go:171","msg":"trace[491362472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1049; }","duration":"310.972403ms","start":"2026-08-14T09:06:11.055851Z","end":"2026-08-14T09:06:11.366823Z","steps":["trace[491362472] 'agreement among raft nodes before linearized reading' (duration: 310.735577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:11.366878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:10.958252Z","time spent":"408.490534ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:11.366916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.055827Z","time spent":"311.056586ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T09:06:11.833282Z","caller":"traceutil/trace.go:171","msg":"trace[1746290472] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"466.698651ms","start":"2026-08-14T09:06:11.36655Z","end":"2026-08-14T09:06:11.833249Z","steps":["trace[1746290472] 'read index received' (duration: 466.64834ms)","trace[1746290472] 'applied index is now lower than readState.Index' (duration: 48.881µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:06:11.833609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.186941ms","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-08-14T09:06:11.833714Z","caller":"traceutil/trace.go:171","msg":"trace[900635755] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1050; }","duration":"739.347164ms","start":"2026-08-14T09:06:11.094347Z","end":"2026-08-14T09:06:11.833694Z","steps":["trace[900635755] 'agreement among raft nodes before linearized reading' (duration: 739.057517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:11.833762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.094331Z","time spent":"739.420527ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:11.833652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.228842ms","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-08-14T09:06:11.833972Z","caller":"traceutil/trace.go:171","msg":"trace[1692623217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1050; }","duration":"739.554529ms","start":"2026-08-14T09:06:11.094347Z","end":"2026-08-14T09:06:11.833901Z","steps":["trace[1692623217] 'agreement among raft nodes before linearized reading' (duration: 739.050937ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:11.834098Z","caller":"traceutil/trace.go:171","msg":"trace[324104549] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"545.141234ms","start":"2026-08-14T09:06:11.288873Z","end":"2026-08-14T09:06:11.834015Z","steps":["trace[324104549] 'process raft request' (duration: 544.195341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:11.834159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.09433Z","time spent":"739.746385ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:11.834335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.28885Z","time spent":"545.35571ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:11.834112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.525589ms","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-08-14T09:06:11.834483Z","caller":"traceutil/trace.go:171","msg":"trace[1103537932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1050; }","duration":"740.031342ms","start":"2026-08-14T09:06:11.094419Z","end":"2026-08-14T09:06:11.83445Z","steps":["trace[1103537932] 'agreement among raft nodes before linearized reading' (duration: 738.885794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:11.834623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.094386Z","time spent":"740.197525ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:06:12.656252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.79021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:06:12.656692Z","caller":"traceutil/trace.go:171","msg":"trace[425758889] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"823.312582ms","start":"2026-08-14T09:06:11.833365Z","end":"2026-08-14T09:06:12.656677Z","steps":["trace[425758889] 'read index received' (duration: 412.100141ms)","trace[425758889] 'applied index is now lower than readState.Index' (duration: 411.211041ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:06:12.656812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.276517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:06:12.656872Z","caller":"traceutil/trace.go:171","msg":"trace[2017690327] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1051; }","duration":"986.37667ms","start":"2026-08-14T09:06:11.670488Z","end":"2026-08-14T09:06:12.656864Z","steps":["trace[2017690327] 'agreement among raft nodes before linearized reading' (duration: 986.293188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:12.656945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.670458Z","time spent":"986.470151ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:06:12.657184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090223535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.199\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T09:06:12.657256Z","caller":"traceutil/trace.go:171","msg":"trace[585957371] range","detail":"{range_begin:/registry/masterleases/199.19.213.199; range_end:; response_count:1; response_revision:1051; }","duration":"1.090338908s","start":"2026-08-14T09:06:11.566894Z","end":"2026-08-14T09:06:12.657233Z","steps":["trace[585957371] 'agreement among raft nodes before linearized reading' (duration: 1.090217275s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:12.657352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.56688Z","time spent":"1.090462811s","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.199\" "} {"level":"warn","ts":"2026-08-14T09:06:12.657195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123677485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:06:12.657333Z","caller":"traceutil/trace.go:171","msg":"trace[1476002973] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"1.285428181s","start":"2026-08-14T09:06:11.371877Z","end":"2026-08-14T09:06:12.657305Z","steps":["trace[1476002973] 'process raft request' (duration: 873.514993ms)","trace[1476002973] 'compare' (duration: 410.611766ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:12.657431Z","caller":"traceutil/trace.go:171","msg":"trace[2041055396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"1.123973562s","start":"2026-08-14T09:06:11.533444Z","end":"2026-08-14T09:06:12.657417Z","steps":["trace[2041055396] 'agreement among raft nodes before linearized reading' (duration: 1.123679125s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:12.657497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.53342Z","time spent":"1.124056895s","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:06:12.65754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.371837Z","time spent":"1.285637416s","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:06:12.66455Z","caller":"traceutil/trace.go:171","msg":"trace[862354552] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"824.534102ms","start":"2026-08-14T09:06:11.839993Z","end":"2026-08-14T09:06:12.664527Z","steps":["trace[862354552] 'process raft request' (duration: 824.425409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:12.664605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.93954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:06:12.664622Z","caller":"traceutil/trace.go:171","msg":"trace[1832536174] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"824.949682ms","start":"2026-08-14T09:06:11.839635Z","end":"2026-08-14T09:06:12.664584Z","steps":["trace[1832536174] 'process raft request' (duration: 824.677555ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:12.664673Z","caller":"traceutil/trace.go:171","msg":"trace[1401994436] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1054; }","duration":"255.061153ms","start":"2026-08-14T09:06:12.409598Z","end":"2026-08-14T09:06:12.664659Z","steps":["trace[1401994436] 'agreement among raft nodes before linearized reading' (duration: 254.96072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:12.664682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.839987Z","time spent":"824.628045ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:06:12.664944Z","caller":"traceutil/trace.go:171","msg":"trace[1097682976] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"822.976023ms","start":"2026-08-14T09:06:11.841545Z","end":"2026-08-14T09:06:12.664521Z","steps":["trace[1097682976] 'process raft request' (duration: 822.916252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:12.665075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.841539Z","time spent":"823.433525ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:12.665622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:11.839614Z","time spent":"825.101626ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:06:13.101824Z","caller":"traceutil/trace.go:171","msg":"trace[1848181354] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"437.30612ms","start":"2026-08-14T09:06:12.664493Z","end":"2026-08-14T09:06:13.101799Z","steps":["trace[1848181354] 'read index received' (duration: 404.27499ms)","trace[1848181354] 'applied index is now lower than readState.Index' (duration: 33.03004ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:06:13.101844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:12.658278Z","time spent":"443.556644ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-14T09:06:13.101963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.547474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:06:13.101987Z","caller":"traceutil/trace.go:171","msg":"trace[320124143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"443.615125ms","start":"2026-08-14T09:06:12.658364Z","end":"2026-08-14T09:06:13.101979Z","steps":["trace[320124143] 'agreement among raft nodes before linearized reading' (duration: 443.554173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:13.102009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:12.658345Z","time spent":"443.658396ms","remote":"127.0.0.1:33474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:06:13.102204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.421665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:06:13.102255Z","caller":"traceutil/trace.go:171","msg":"trace[217213471] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1054; }","duration":"433.518668ms","start":"2026-08-14T09:06:12.66872Z","end":"2026-08-14T09:06:13.102238Z","steps":["trace[217213471] 'agreement among raft nodes before linearized reading' (duration: 433.391105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:13.102347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.294532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:06:13.102365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:12.668706Z","time spent":"433.645901ms","remote":"127.0.0.1:39056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:06:13.10238Z","caller":"traceutil/trace.go:171","msg":"trace[1610560618] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1054; }","duration":"103.386384ms","start":"2026-08-14T09:06:12.998986Z","end":"2026-08-14T09:06:13.102373Z","steps":["trace[1610560618] 'agreement among raft nodes before linearized reading' (duration: 103.331913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:13.694205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000901250051383845,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:06:13.819884Z","caller":"traceutil/trace.go:171","msg":"trace[2065986609] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"626.362995ms","start":"2026-08-14T09:06:13.193492Z","end":"2026-08-14T09:06:13.819855Z","steps":["trace[2065986609] 'read index received' (duration: 626.207031ms)","trace[2065986609] 'applied index is now lower than readState.Index' (duration: 154.364µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:13.819947Z","caller":"traceutil/trace.go:171","msg":"trace[1730474036] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"661.178518ms","start":"2026-08-14T09:06:13.158728Z","end":"2026-08-14T09:06:13.819906Z","steps":["trace[1730474036] 'process raft request' (duration: 660.925312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:13.82008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.5712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-14T09:06:13.820117Z","caller":"traceutil/trace.go:171","msg":"trace[1724287613] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1056; }","duration":"626.665302ms","start":"2026-08-14T09:06:13.193441Z","end":"2026-08-14T09:06:13.820106Z","steps":["trace[1724287613] 'agreement among raft nodes before linearized reading' (duration: 626.56872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:13.820152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:13.193421Z","time spent":"626.722544ms","remote":"127.0.0.1:33660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-14T09:06:13.820135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:13.158702Z","time spent":"661.333673ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:13.820413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.799732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T09:06:13.820506Z","caller":"traceutil/trace.go:171","msg":"trace[1112564754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"448.933996ms","start":"2026-08-14T09:06:13.371548Z","end":"2026-08-14T09:06:13.820482Z","steps":["trace[1112564754] 'agreement among raft nodes before linearized reading' (duration: 448.659929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:13.820559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:13.371511Z","time spent":"449.036598ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:06:32.11009Z","caller":"traceutil/trace.go:171","msg":"trace[2092592668] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"192.54301ms","start":"2026-08-14T09:06:31.917492Z","end":"2026-08-14T09:06:32.110035Z","steps":["trace[2092592668] 'process raft request' (duration: 192.427697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:33.033948Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000901250051384100,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:06:33.130953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.020935128s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-14T09:06:33.533013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.756537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:06:33.533097Z","caller":"traceutil/trace.go:171","msg":"trace[511334743] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"999.399619ms","start":"2026-08-14T09:06:32.533684Z","end":"2026-08-14T09:06:33.533084Z","steps":["trace[511334743] 'read index received' (duration: 597.446416ms)","trace[511334743] 'applied index is now lower than readState.Index' (duration: 401.952253ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:33.533167Z","caller":"traceutil/trace.go:171","msg":"trace[1576652551] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"1.58400142s","start":"2026-08-14T09:06:31.949157Z","end":"2026-08-14T09:06:33.533158Z","steps":["trace[1576652551] 'process raft request' (duration: 1.182032827s)","trace[1576652551] 'compare' (duration: 401.006629ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:06:33.533208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:31.949132Z","time spent":"1.5840533s","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:33.53336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.671665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:06:33.533381Z","caller":"traceutil/trace.go:171","msg":"trace[1535692989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1171; }","duration":"999.725877ms","start":"2026-08-14T09:06:32.533648Z","end":"2026-08-14T09:06:33.533374Z","steps":["trace[1535692989] 'agreement among raft nodes before linearized reading' (duration: 999.687496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:33.533399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:32.533631Z","time spent":"999.764297ms","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:06:33.533569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.553829ms","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-08-14T09:06:33.533589Z","caller":"traceutil/trace.go:171","msg":"trace[1610203978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"750.61071ms","start":"2026-08-14T09:06:32.782973Z","end":"2026-08-14T09:06:33.533583Z","steps":["trace[1610203978] 'agreement among raft nodes before linearized reading' (duration: 750.527948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:33.533612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:32.782955Z","time spent":"750.650371ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:33.533656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.633891ms","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-08-14T09:06:33.533662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.197547ms","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-08-14T09:06:33.533685Z","caller":"traceutil/trace.go:171","msg":"trace[1230618058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1171; }","duration":"708.694702ms","start":"2026-08-14T09:06:32.824982Z","end":"2026-08-14T09:06:33.533677Z","steps":["trace[1230618058] 'agreement among raft nodes before linearized reading' (duration: 708.61602ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:33.53369Z","caller":"traceutil/trace.go:171","msg":"trace[1989250355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1171; }","duration":"714.268088ms","start":"2026-08-14T09:06:32.819413Z","end":"2026-08-14T09:06:33.533681Z","steps":["trace[1989250355] 'agreement among raft nodes before linearized reading' (duration: 714.189697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:33.533706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:32.824968Z","time spent":"708.732433ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:33.533709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:32.819391Z","time spent":"714.31257ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:06:33.533798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.229277ms","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-08-14T09:06:33.53389Z","caller":"traceutil/trace.go:171","msg":"trace[347688054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1171; }","duration":"714.385212ms","start":"2026-08-14T09:06:32.819484Z","end":"2026-08-14T09:06:33.533869Z","steps":["trace[347688054] 'agreement among raft nodes before linearized reading' (duration: 714.112485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:33.533944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:32.819447Z","time spent":"714.482705ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:34.275157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.127176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:06:34.275455Z","caller":"traceutil/trace.go:171","msg":"trace[830467461] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1218; }","duration":"734.344512ms","start":"2026-08-14T09:06:33.54109Z","end":"2026-08-14T09:06:34.275434Z","steps":["trace[830467461] 'read index received' (duration: 223.774016ms)","trace[830467461] 'applied index is now lower than readState.Index' (duration: 510.566576ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:34.27549Z","caller":"traceutil/trace.go:171","msg":"trace[1450529287] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"737.972921ms","start":"2026-08-14T09:06:33.5375Z","end":"2026-08-14T09:06:34.275473Z","steps":["trace[1450529287] 'process raft request' (duration: 737.782067ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:34.275543Z","caller":"traceutil/trace.go:171","msg":"trace[66428296] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"738.019823ms","start":"2026-08-14T09:06:33.537513Z","end":"2026-08-14T09:06:34.275532Z","steps":["trace[66428296] 'process raft request' (duration: 737.846528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:34.275558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:33.537493Z","time spent":"738.035713ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:34.275608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:33.537488Z","time spent":"738.081604ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:06:34.275701Z","caller":"traceutil/trace.go:171","msg":"trace[1985781223] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"737.342436ms","start":"2026-08-14T09:06:33.538347Z","end":"2026-08-14T09:06:34.27569Z","steps":["trace[1985781223] 'process raft request' (duration: 737.045128ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:34.275731Z","caller":"traceutil/trace.go:171","msg":"trace[915471120] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"738.279968ms","start":"2026-08-14T09:06:33.537438Z","end":"2026-08-14T09:06:34.275718Z","steps":["trace[915471120] 'process raft request' (duration: 227.484646ms)","trace[915471120] 'compare' (duration: 509.654084ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:06:34.275756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:33.538337Z","time spent":"737.389556ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:34.275774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:33.537419Z","time spent":"738.33134ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:34.275871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.780833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:06:34.275904Z","caller":"traceutil/trace.go:171","msg":"trace[776589815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1175; }","duration":"734.814084ms","start":"2026-08-14T09:06:33.541081Z","end":"2026-08-14T09:06:34.275895Z","steps":["trace[776589815] 'agreement among raft nodes before linearized reading' (duration: 734.741622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:34.275934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:33.541048Z","time spent":"734.879095ms","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:06:34.276163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.581411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T09:06:34.276199Z","caller":"traceutil/trace.go:171","msg":"trace[1156556435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1175; }","duration":"161.661063ms","start":"2026-08-14T09:06:34.11453Z","end":"2026-08-14T09:06:34.276191Z","steps":["trace[1156556435] 'agreement among raft nodes before linearized reading' (duration: 161.545ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:44.298544Z","caller":"traceutil/trace.go:171","msg":"trace[600127356] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"722.316226ms","start":"2026-08-14T09:06:43.576194Z","end":"2026-08-14T09:06:44.29851Z","steps":["trace[600127356] 'process raft request' (duration: 722.126342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:44.298737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:43.576178Z","time spent":"722.46375ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:06:46.495504Z","caller":"traceutil/trace.go:171","msg":"trace[737533166] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"108.661289ms","start":"2026-08-14T09:06:46.386819Z","end":"2026-08-14T09:06:46.49548Z","steps":["trace[737533166] 'process raft request' (duration: 108.627098ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:46.496089Z","caller":"traceutil/trace.go:171","msg":"trace[10877354] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"109.347286ms","start":"2026-08-14T09:06:46.386721Z","end":"2026-08-14T09:06:46.496068Z","steps":["trace[10877354] 'process raft request' (duration: 108.661199ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:46.496274Z","caller":"traceutil/trace.go:171","msg":"trace[1014348999] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"109.603962ms","start":"2026-08-14T09:06:46.386659Z","end":"2026-08-14T09:06:46.496262Z","steps":["trace[1014348999] 'process raft request' (duration: 108.594298ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:46.496354Z","caller":"traceutil/trace.go:171","msg":"trace[953854408] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"109.558611ms","start":"2026-08-14T09:06:46.38675Z","end":"2026-08-14T09:06:46.496308Z","steps":["trace[953854408] 'process raft request' (duration: 108.66676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:48.906998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000901250051384218,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:06:48.958771Z","caller":"traceutil/trace.go:171","msg":"trace[1376453912] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"571.990785ms","start":"2026-08-14T09:06:48.386735Z","end":"2026-08-14T09:06:48.958726Z","steps":["trace[1376453912] 'process raft request' (duration: 571.828841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:48.958943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:48.386709Z","time spent":"572.141999ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:49.2455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.83109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:06:49.245576Z","caller":"traceutil/trace.go:171","msg":"trace[546614190] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1266; }","duration":"839.141198ms","start":"2026-08-14T09:06:48.406423Z","end":"2026-08-14T09:06:49.245564Z","steps":["trace[546614190] 'read index received' (duration: 552.190782ms)","trace[546614190] 'applied index is now lower than readState.Index' (duration: 286.949606ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:06:49.245751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.329313ms","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-08-14T09:06:49.245784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.45325ms","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-08-14T09:06:49.245814Z","caller":"traceutil/trace.go:171","msg":"trace[326187351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1218; }","duration":"744.535222ms","start":"2026-08-14T09:06:48.501272Z","end":"2026-08-14T09:06:49.245808Z","steps":["trace[326187351] 'agreement among raft nodes before linearized reading' (duration: 744.44775ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:49.245822Z","caller":"traceutil/trace.go:171","msg":"trace[1038010144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1218; }","duration":"839.420025ms","start":"2026-08-14T09:06:48.406372Z","end":"2026-08-14T09:06:49.245792Z","steps":["trace[1038010144] 'agreement among raft nodes before linearized reading' (duration: 839.271031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:49.245865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:48.406353Z","time spent":"839.502797ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:06:49.245899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.436589ms","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-08-14T09:06:49.245923Z","caller":"traceutil/trace.go:171","msg":"trace[2097897567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"744.47443ms","start":"2026-08-14T09:06:48.501442Z","end":"2026-08-14T09:06:49.245917Z","steps":["trace[2097897567] 'agreement among raft nodes before linearized reading' (duration: 744.414808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:49.245941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:48.501435Z","time spent":"744.501601ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:49.246023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.633544ms","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-08-14T09:06:49.246047Z","caller":"traceutil/trace.go:171","msg":"trace[642413485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1218; }","duration":"744.670755ms","start":"2026-08-14T09:06:48.501371Z","end":"2026-08-14T09:06:49.246042Z","steps":["trace[642413485] 'agreement among raft nodes before linearized reading' (duration: 744.617683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:49.246091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:48.501366Z","time spent":"744.719966ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:06:49.246137Z","caller":"traceutil/trace.go:171","msg":"trace[1560752038] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"833.351576ms","start":"2026-08-14T09:06:48.412766Z","end":"2026-08-14T09:06:49.246118Z","steps":["trace[1560752038] 'process raft request' (duration: 614.780608ms)","trace[1560752038] 'compare' (duration: 217.494762ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:06:49.24621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.88782ms","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-08-14T09:06:49.246216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:48.412747Z","time spent":"833.432699ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:06:49.246235Z","caller":"traceutil/trace.go:171","msg":"trace[505935736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1218; }","duration":"744.933511ms","start":"2026-08-14T09:06:48.501295Z","end":"2026-08-14T09:06:49.246229Z","steps":["trace[505935736] 'agreement among raft nodes before linearized reading' (duration: 744.87337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:49.246254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:48.501284Z","time spent":"744.963662ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:49.245836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:48.501252Z","time spent":"744.579052ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-14T09:06:49.405774Z","caller":"traceutil/trace.go:171","msg":"trace[1572834018] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"155.318357ms","start":"2026-08-14T09:06:49.250414Z","end":"2026-08-14T09:06:49.405732Z","steps":["trace[1572834018] 'process raft request' (duration: 117.525926ms)","trace[1572834018] 'compare' (duration: 37.573426ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:49.460064Z","caller":"traceutil/trace.go:171","msg":"trace[896914826] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"209.249112ms","start":"2026-08-14T09:06:49.250786Z","end":"2026-08-14T09:06:49.460035Z","steps":["trace[896914826] 'process raft request' (duration: 209.059837ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:49.460241Z","caller":"traceutil/trace.go:171","msg":"trace[1119498664] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1268; }","duration":"125.48518ms","start":"2026-08-14T09:06:49.334743Z","end":"2026-08-14T09:06:49.460228Z","steps":["trace[1119498664] 'read index received' (duration: 33.152869ms)","trace[1119498664] 'applied index is now lower than readState.Index' (duration: 92.331451ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:49.460308Z","caller":"traceutil/trace.go:171","msg":"trace[1353991404] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"205.554911ms","start":"2026-08-14T09:06:49.254746Z","end":"2026-08-14T09:06:49.4603Z","steps":["trace[1353991404] 'process raft request' (duration: 205.436928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:49.460359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.604162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:06:49.460353Z","caller":"traceutil/trace.go:171","msg":"trace[720470104] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"206.875924ms","start":"2026-08-14T09:06:49.253417Z","end":"2026-08-14T09:06:49.460293Z","steps":["trace[720470104] 'process raft request' (duration: 206.620988ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:49.460118Z","caller":"traceutil/trace.go:171","msg":"trace[1581391748] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"208.219217ms","start":"2026-08-14T09:06:49.251851Z","end":"2026-08-14T09:06:49.46007Z","steps":["trace[1581391748] 'process raft request' (duration: 208.117984ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:49.460401Z","caller":"traceutil/trace.go:171","msg":"trace[716518449] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1223; }","duration":"125.718625ms","start":"2026-08-14T09:06:49.334673Z","end":"2026-08-14T09:06:49.460391Z","steps":["trace[716518449] 'agreement among raft nodes before linearized reading' (duration: 125.615372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:51.910715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9000901250051384231,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:06:52.034746Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.070909007s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:06:52.03508Z","caller":"traceutil/trace.go:171","msg":"trace[345037947] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"625.068508ms","start":"2026-08-14T09:06:51.40999Z","end":"2026-08-14T09:06:52.035059Z","steps":["trace[345037947] 'read index received' (duration: 624.891544ms)","trace[345037947] 'applied index is now lower than readState.Index' (duration: 175.984µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:52.03535Z","caller":"traceutil/trace.go:171","msg":"trace[1392973111] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"1.071646046s","start":"2026-08-14T09:06:50.963696Z","end":"2026-08-14T09:06:52.035342Z","steps":["trace[1392973111] 'process raft request' (duration: 1.071208025s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:52.035432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:50.963663Z","time spent":"1.071707968s","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:52.035625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.648083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T09:06:52.035652Z","caller":"traceutil/trace.go:171","msg":"trace[1547018134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1224; }","duration":"625.716664ms","start":"2026-08-14T09:06:51.409929Z","end":"2026-08-14T09:06:52.035646Z","steps":["trace[1547018134] 'agreement among raft nodes before linearized reading' (duration: 625.628022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:52.035671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:51.409905Z","time spent":"625.762285ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:06:52.035909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.475619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:06:52.035958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.988945ms","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-08-14T09:06:52.035971Z","caller":"traceutil/trace.go:171","msg":"trace[1252617348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"504.573752ms","start":"2026-08-14T09:06:51.531379Z","end":"2026-08-14T09:06:52.035952Z","steps":["trace[1252617348] 'agreement among raft nodes before linearized reading' (duration: 504.48662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:52.036009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:51.531358Z","time spent":"504.643253ms","remote":"127.0.0.1:33474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:06:52.036031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.662988ms","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-08-14T09:06:52.036048Z","caller":"traceutil/trace.go:171","msg":"trace[1994949287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1224; }","duration":"571.699378ms","start":"2026-08-14T09:06:51.464344Z","end":"2026-08-14T09:06:52.036043Z","steps":["trace[1994949287] 'agreement among raft nodes before linearized reading' (duration: 571.652017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:52.036074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:51.464338Z","time spent":"571.731889ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:06:52.036093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.370484ms","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-08-14T09:06:52.03598Z","caller":"traceutil/trace.go:171","msg":"trace[1104968255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1224; }","duration":"572.024926ms","start":"2026-08-14T09:06:51.463949Z","end":"2026-08-14T09:06:52.035974Z","steps":["trace[1104968255] 'agreement among raft nodes before linearized reading' (duration: 571.955735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:52.036288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:51.463942Z","time spent":"572.334994ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:52.036372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.651092ms","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-08-14T09:06:52.0364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.698599ms","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-08-14T09:06:52.036433Z","caller":"traceutil/trace.go:171","msg":"trace[2127931821] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1224; }","duration":"286.779242ms","start":"2026-08-14T09:06:51.749645Z","end":"2026-08-14T09:06:52.036424Z","steps":["trace[2127931821] 'agreement among raft nodes before linearized reading' (duration: 286.66736ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:52.036427Z","caller":"traceutil/trace.go:171","msg":"trace[778855779] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"572.716613ms","start":"2026-08-14T09:06:51.463697Z","end":"2026-08-14T09:06:52.036414Z","steps":["trace[778855779] 'agreement among raft nodes before linearized reading' (duration: 572.57546ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:52.036238Z","caller":"traceutil/trace.go:171","msg":"trace[919354261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1224; }","duration":"572.563219ms","start":"2026-08-14T09:06:51.46365Z","end":"2026-08-14T09:06:52.036213Z","steps":["trace[919354261] 'agreement among raft nodes before linearized reading' (duration: 572.133519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:52.036489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:51.463694Z","time spent":"572.782595ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:52.036507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:51.463632Z","time spent":"572.862926ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:06:52.036298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.306318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.199\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T09:06:52.036614Z","caller":"traceutil/trace.go:171","msg":"trace[922604815] range","detail":"{range_begin:/registry/masterleases/199.19.213.199; range_end:; response_count:1; response_revision:1224; }","duration":"466.662977ms","start":"2026-08-14T09:06:51.569945Z","end":"2026-08-14T09:06:52.036608Z","steps":["trace[922604815] 'agreement among raft nodes before linearized reading' (duration: 466.313358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:52.036636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:51.569929Z","time spent":"466.700298ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.199\" "} {"level":"warn","ts":"2026-08-14T09:06:52.490649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.767585ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-08-14T09:06:52.490722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:52.037704Z","time spent":"453.015444ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-14T09:06:52.53499Z","caller":"traceutil/trace.go:171","msg":"trace[545905597] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"493.442191ms","start":"2026-08-14T09:06:52.041519Z","end":"2026-08-14T09:06:52.534961Z","steps":["trace[545905597] 'process raft request' (duration: 493.191124ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:52.535032Z","caller":"traceutil/trace.go:171","msg":"trace[788701242] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1275; }","duration":"117.804342ms","start":"2026-08-14T09:06:52.417208Z","end":"2026-08-14T09:06:52.535012Z","steps":["trace[788701242] 'read index received' (duration: 117.511375ms)","trace[788701242] 'applied index is now lower than readState.Index' (duration: 292.087µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:52.535045Z","caller":"traceutil/trace.go:171","msg":"trace[201583633] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"493.256686ms","start":"2026-08-14T09:06:52.041749Z","end":"2026-08-14T09:06:52.535005Z","steps":["trace[201583633] 'process raft request' (duration: 493.130993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:52.535119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:52.041487Z","time spent":"493.562283ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:06:52.53513Z","caller":"traceutil/trace.go:171","msg":"trace[201570664] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"492.62235ms","start":"2026-08-14T09:06:52.042497Z","end":"2026-08-14T09:06:52.535119Z","steps":["trace[201570664] 'process raft request' (duration: 492.481507ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:52.535203Z","caller":"traceutil/trace.go:171","msg":"trace[1927770931] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"493.280986ms","start":"2026-08-14T09:06:52.041908Z","end":"2026-08-14T09:06:52.535189Z","steps":["trace[1927770931] 'process raft request' (duration: 493.004949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:52.535216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:52.041728Z","time spent":"493.40553ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:52.535228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:52.042485Z","time spent":"492.669641ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:52.535253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:52.041895Z","time spent":"493.333607ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:52.535342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.097659ms","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-08-14T09:06:52.535429Z","caller":"traceutil/trace.go:171","msg":"trace[1691064405] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1229; }","duration":"118.241613ms","start":"2026-08-14T09:06:52.417173Z","end":"2026-08-14T09:06:52.535415Z","steps":["trace[1691064405] 'agreement among raft nodes before linearized reading' (duration: 117.979996ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:52.535114Z","caller":"traceutil/trace.go:171","msg":"trace[1496334155] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"493.104212ms","start":"2026-08-14T09:06:52.041967Z","end":"2026-08-14T09:06:52.535071Z","steps":["trace[1496334155] 'process raft request' (duration: 492.980499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:52.536045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:52.041948Z","time spent":"494.017774ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:06:55.027436Z","caller":"traceutil/trace.go:171","msg":"trace[1218004098] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"483.018425ms","start":"2026-08-14T09:06:54.544396Z","end":"2026-08-14T09:06:55.027415Z","steps":["trace[1218004098] 'process raft request' (duration: 482.728558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:55.027565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:54.544387Z","time spent":"483.116638ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:06:55.027717Z","caller":"traceutil/trace.go:171","msg":"trace[1177098788] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"482.665426ms","start":"2026-08-14T09:06:54.545027Z","end":"2026-08-14T09:06:55.027692Z","steps":["trace[1177098788] 'process raft request' (duration: 482.313228ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:55.027785Z","caller":"traceutil/trace.go:171","msg":"trace[1119714799] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"483.518447ms","start":"2026-08-14T09:06:54.544256Z","end":"2026-08-14T09:06:55.027774Z","steps":["trace[1119714799] 'process raft request' (duration: 438.171153ms)","trace[1119714799] 'compare' (duration: 44.467273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:06:55.027829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:54.544245Z","time spent":"483.559058ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:55.027894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:54.545016Z","time spent":"482.790489ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:06:55.028192Z","caller":"traceutil/trace.go:171","msg":"trace[1855912383] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1282; }","duration":"460.061005ms","start":"2026-08-14T09:06:54.568106Z","end":"2026-08-14T09:06:55.028167Z","steps":["trace[1855912383] 'read index received' (duration: 49.992558ms)","trace[1855912383] 'applied index is now lower than readState.Index' (duration: 410.067267ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:06:55.02836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.24972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:06:55.028411Z","caller":"traceutil/trace.go:171","msg":"trace[1563491622] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1236; }","duration":"460.363602ms","start":"2026-08-14T09:06:54.568034Z","end":"2026-08-14T09:06:55.028397Z","steps":["trace[1563491622] 'agreement among raft nodes before linearized reading' (duration: 460.231699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:55.028459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:54.568005Z","time spent":"460.439945ms","remote":"127.0.0.1:33742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:06:55.028728Z","caller":"traceutil/trace.go:171","msg":"trace[336666139] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"483.307292ms","start":"2026-08-14T09:06:54.545408Z","end":"2026-08-14T09:06:55.028715Z","steps":["trace[336666139] 'process raft request' (duration: 482.203125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:55.028826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:54.545397Z","time spent":"483.364893ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:07:02.74363Z","caller":"traceutil/trace.go:171","msg":"trace[470433607] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"103.083694ms","start":"2026-08-14T09:07:02.640512Z","end":"2026-08-14T09:07:02.743596Z","steps":["trace[470433607] 'process raft request' (duration: 102.897829ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:07:03.399258Z","caller":"traceutil/trace.go:171","msg":"trace[1729782749] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"328.711734ms","start":"2026-08-14T09:07:03.070524Z","end":"2026-08-14T09:07:03.399235Z","steps":["trace[1729782749] 'process raft request' (duration: 328.524189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:07:03.399405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:07:03.070512Z","time spent":"328.825917ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:07:03.400875Z","caller":"traceutil/trace.go:171","msg":"trace[673670038] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"327.497905ms","start":"2026-08-14T09:07:03.073363Z","end":"2026-08-14T09:07:03.400861Z","steps":["trace[673670038] 'process raft request' (duration: 327.3123ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:07:03.400864Z","caller":"traceutil/trace.go:171","msg":"trace[2141296997] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1329; }","duration":"323.179279ms","start":"2026-08-14T09:07:03.077661Z","end":"2026-08-14T09:07:03.40084Z","steps":["trace[2141296997] 'read index received' (duration: 321.400286ms)","trace[2141296997] 'applied index is now lower than readState.Index' (duration: 1.777703ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:07:03.400942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:07:03.073348Z","time spent":"327.559846ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:07:03.400974Z","caller":"traceutil/trace.go:171","msg":"trace[506791663] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"327.513955ms","start":"2026-08-14T09:07:03.073421Z","end":"2026-08-14T09:07:03.400935Z","steps":["trace[506791663] 'process raft request' (duration: 327.379491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:07:03.401042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.406507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:07:03.401052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.937756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:07:03.401093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.406565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-j6lgn\" ","response":"range_response_count:1 size:11061"} {"level":"warn","ts":"2026-08-14T09:07:03.401088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:07:03.073415Z","time spent":"327.618267ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:07:03.40113Z","caller":"traceutil/trace.go:171","msg":"trace[315409667] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-j6lgn; range_end:; response_count:1; response_revision:1279; }","duration":"323.487076ms","start":"2026-08-14T09:07:03.077633Z","end":"2026-08-14T09:07:03.40112Z","steps":["trace[315409667] 'agreement among raft nodes before linearized reading' (duration: 323.318522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:07:03.401177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:07:03.077622Z","time spent":"323.547118ms","remote":"127.0.0.1:33672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11083,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-j6lgn\" "} {"level":"info","ts":"2026-08-14T09:07:03.401117Z","caller":"traceutil/trace.go:171","msg":"trace[1162066420] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1279; }","duration":"310.042649ms","start":"2026-08-14T09:07:03.091059Z","end":"2026-08-14T09:07:03.401102Z","steps":["trace[1162066420] 'agreement among raft nodes before linearized reading' (duration: 309.938006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:07:03.40126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:07:03.09104Z","time spent":"310.206803ms","remote":"127.0.0.1:37406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:07:03.401079Z","caller":"traceutil/trace.go:171","msg":"trace[507566591] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1279; }","duration":"118.485809ms","start":"2026-08-14T09:07:03.282584Z","end":"2026-08-14T09:07:03.40107Z","steps":["trace[507566591] 'agreement among raft nodes before linearized reading' (duration: 118.426267ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:07:07.268375Z","caller":"traceutil/trace.go:171","msg":"trace[257525577] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"184.276273ms","start":"2026-08-14T09:07:07.084067Z","end":"2026-08-14T09:07:07.268343Z","steps":["trace[257525577] 'process raft request' (duration: 184.084948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:07:10.638316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.997776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:07:10.638408Z","caller":"traceutil/trace.go:171","msg":"trace[405873924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"104.14347ms","start":"2026-08-14T09:07:10.534241Z","end":"2026-08-14T09:07:10.638385Z","steps":["trace[405873924] 'range keys from in-memory index tree' (duration: 103.898013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:07:19.461911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.711832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-14T09:07:19.462044Z","caller":"traceutil/trace.go:171","msg":"trace[1429598547] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1384; }","duration":"113.901977ms","start":"2026-08-14T09:07:19.348114Z","end":"2026-08-14T09:07:19.462016Z","steps":["trace[1429598547] 'range keys from in-memory index tree' (duration: 113.494477ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:07:28.392397Z","caller":"traceutil/trace.go:171","msg":"trace[87330390] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"182.209123ms","start":"2026-08-14T09:07:28.210165Z","end":"2026-08-14T09:07:28.392375Z","steps":["trace[87330390] 'process raft request' (duration: 181.887565ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:07:59.900643Z","caller":"traceutil/trace.go:171","msg":"trace[1055860153] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"152.025713ms","start":"2026-08-14T09:07:59.748573Z","end":"2026-08-14T09:07:59.900599Z","steps":["trace[1055860153] 'process raft request' (duration: 151.611963ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:00.852998Z","caller":"traceutil/trace.go:171","msg":"trace[838936860] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"234.654476ms","start":"2026-08-14T09:08:00.618042Z","end":"2026-08-14T09:08:00.852697Z","steps":["trace[838936860] 'process raft request' (duration: 141.594379ms)","trace[838936860] 'compare' (duration: 92.735749ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:26.343156Z","caller":"traceutil/trace.go:171","msg":"trace[1766953992] linearizableReadLoop","detail":"{readStateIndex:1758; appliedIndex:1757; }","duration":"329.819164ms","start":"2026-08-14T09:08:26.013304Z","end":"2026-08-14T09:08:26.343123Z","steps":["trace[1766953992] 'read index received' (duration: 329.66296ms)","trace[1766953992] 'applied index is now lower than readState.Index' (duration: 155.334µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:26.343206Z","caller":"traceutil/trace.go:171","msg":"trace[1642133443] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"524.769333ms","start":"2026-08-14T09:08:25.818389Z","end":"2026-08-14T09:08:26.343158Z","steps":["trace[1642133443] 'process raft request' (duration: 524.576388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:26.343387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:25.818362Z","time spent":"524.928046ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:08:26.34329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.979378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:08:26.343568Z","caller":"traceutil/trace.go:171","msg":"trace[905572691] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1689; }","duration":"330.248524ms","start":"2026-08-14T09:08:26.01325Z","end":"2026-08-14T09:08:26.343499Z","steps":["trace[905572691] 'agreement among raft nodes before linearized reading' (duration: 330.000808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:26.343574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.053486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-14T09:08:26.343586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.432065ms","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-08-14T09:08:26.343655Z","caller":"traceutil/trace.go:171","msg":"trace[892855503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1689; }","duration":"251.163949ms","start":"2026-08-14T09:08:26.09247Z","end":"2026-08-14T09:08:26.343634Z","steps":["trace[892855503] 'agreement among raft nodes before linearized reading' (duration: 250.966564ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:26.343691Z","caller":"traceutil/trace.go:171","msg":"trace[1406887783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1689; }","duration":"251.61443ms","start":"2026-08-14T09:08:26.092056Z","end":"2026-08-14T09:08:26.343671Z","steps":["trace[1406887783] 'agreement among raft nodes before linearized reading' (duration: 251.250131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:26.343641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:26.013229Z","time spent":"330.400679ms","remote":"127.0.0.1:33632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:08:26.343553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.87395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-14T09:08:26.343879Z","caller":"traceutil/trace.go:171","msg":"trace[1561873497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1689; }","duration":"283.249629ms","start":"2026-08-14T09:08:26.060615Z","end":"2026-08-14T09:08:26.343865Z","steps":["trace[1561873497] 'agreement among raft nodes before linearized reading' (duration: 282.801368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:26.344022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.678436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-08-14T09:08:26.344058Z","caller":"traceutil/trace.go:171","msg":"trace[703566845] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1689; }","duration":"298.735957ms","start":"2026-08-14T09:08:26.045309Z","end":"2026-08-14T09:08:26.344045Z","steps":["trace[703566845] 'agreement among raft nodes before linearized reading' (duration: 297.975978ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:26.47813Z","caller":"traceutil/trace.go:171","msg":"trace[657058365] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"129.455783ms","start":"2026-08-14T09:08:26.348645Z","end":"2026-08-14T09:08:26.478101Z","steps":["trace[657058365] 'process raft request' (duration: 129.35181ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:26.478424Z","caller":"traceutil/trace.go:171","msg":"trace[13101889] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"129.73913ms","start":"2026-08-14T09:08:26.34866Z","end":"2026-08-14T09:08:26.478399Z","steps":["trace[13101889] 'process raft request' (duration: 129.374931ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:26.478179Z","caller":"traceutil/trace.go:171","msg":"trace[1447137348] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"129.568866ms","start":"2026-08-14T09:08:26.34858Z","end":"2026-08-14T09:08:26.478149Z","steps":["trace[1447137348] 'process raft request' (duration: 90.509165ms)","trace[1447137348] 'compare' (duration: 38.798065ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:52.883681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.189389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-14T09:08:52.88381Z","caller":"traceutil/trace.go:171","msg":"trace[1604611763] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1810; }","duration":"118.348963ms","start":"2026-08-14T09:08:52.765441Z","end":"2026-08-14T09:08:52.88379Z","steps":["trace[1604611763] 'range keys from in-memory index tree' (duration: 118.040876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.88383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.121322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:08:52.883923Z","caller":"traceutil/trace.go:171","msg":"trace[1187378598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1810; }","duration":"119.258996ms","start":"2026-08-14T09:08:52.764642Z","end":"2026-08-14T09:08:52.883901Z","steps":["trace[1187378598] 'range keys from in-memory index tree' (duration: 118.953048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.883979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.307134ms","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-08-14T09:08:52.884069Z","caller":"traceutil/trace.go:171","msg":"trace[1890528668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1810; }","duration":"139.438326ms","start":"2026-08-14T09:08:52.744613Z","end":"2026-08-14T09:08:52.884051Z","steps":["trace[1890528668] 'range keys from in-memory index tree' (duration: 138.888883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.08879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.848051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:09:07.089039Z","caller":"traceutil/trace.go:171","msg":"trace[1208247832] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"283.527923ms","start":"2026-08-14T09:09:06.805459Z","end":"2026-08-14T09:09:07.088987Z","steps":["trace[1208247832] 'process raft request' (duration: 177.398105ms)","trace[1208247832] 'compare' (duration: 105.686707ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:07.089069Z","caller":"traceutil/trace.go:171","msg":"trace[311954577] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"273.110468ms","start":"2026-08-14T09:09:06.815933Z","end":"2026-08-14T09:09:07.089043Z","steps":["trace[311954577] 'read index received' (duration: 166.94605ms)","trace[311954577] 'applied index is now lower than readState.Index' (duration: 106.162578ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:07.122355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.39513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:07.122458Z","caller":"traceutil/trace.go:171","msg":"trace[1823121774] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1874; }","duration":"306.537203ms","start":"2026-08-14T09:09:06.815895Z","end":"2026-08-14T09:09:07.122432Z","steps":["trace[1823121774] 'agreement among raft nodes before linearized reading' (duration: 273.338554ms)","trace[1823121774] 'count revisions from in-memory index tree' (duration: 32.81745ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:07.122518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:06.815879Z","time spent":"306.619526ms","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:09:07.122509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.874512ms","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-08-14T09:09:07.122582Z","caller":"traceutil/trace.go:171","msg":"trace[651752681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1874; }","duration":"169.996524ms","start":"2026-08-14T09:09:06.95257Z","end":"2026-08-14T09:09:07.122567Z","steps":["trace[651752681] 'agreement among raft nodes before linearized reading' (duration: 169.787609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.122764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.638989ms","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-08-14T09:09:07.122824Z","caller":"traceutil/trace.go:171","msg":"trace[225901537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1874; }","duration":"108.760492ms","start":"2026-08-14T09:09:07.014045Z","end":"2026-08-14T09:09:07.122805Z","steps":["trace[225901537] 'agreement among raft nodes before linearized reading' (duration: 108.587878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.122854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.376272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-14T09:09:07.122877Z","caller":"traceutil/trace.go:171","msg":"trace[1158821805] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1874; }","duration":"149.428183ms","start":"2026-08-14T09:09:06.973442Z","end":"2026-08-14T09:09:07.122871Z","steps":["trace[1158821805] 'agreement among raft nodes before linearized reading' (duration: 149.361261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.589397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.840833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:09:07.589473Z","caller":"traceutil/trace.go:171","msg":"trace[720390704] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1878; }","duration":"294.005967ms","start":"2026-08-14T09:09:07.295449Z","end":"2026-08-14T09:09:07.589455Z","steps":["trace[720390704] 'count revisions from in-memory index tree' (duration: 293.751401ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:07.589492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.426071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:07.589555Z","caller":"traceutil/trace.go:171","msg":"trace[885522670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1878; }","duration":"144.542844ms","start":"2026-08-14T09:09:07.444997Z","end":"2026-08-14T09:09:07.589539Z","steps":["trace[885522670] 'range keys from in-memory index tree' (duration: 144.312339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:41.467143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.666862ms","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-08-14T09:09:41.467252Z","caller":"traceutil/trace.go:171","msg":"trace[710055339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2032; }","duration":"127.822386ms","start":"2026-08-14T09:09:41.339407Z","end":"2026-08-14T09:09:41.46723Z","steps":["trace[710055339] 'range keys from in-memory index tree' (duration: 127.376506ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:41.596119Z","caller":"traceutil/trace.go:171","msg":"trace[55187351] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"124.040314ms","start":"2026-08-14T09:09:41.472051Z","end":"2026-08-14T09:09:41.596091Z","steps":["trace[55187351] 'process raft request' (duration: 123.503841ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:41.596513Z","caller":"traceutil/trace.go:171","msg":"trace[904260272] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"124.033924ms","start":"2026-08-14T09:09:41.472452Z","end":"2026-08-14T09:09:41.596486Z","steps":["trace[904260272] 'process raft request' (duration: 123.568962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:43.24574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.48874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:43.245849Z","caller":"traceutil/trace.go:171","msg":"trace[1860107650] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2041; }","duration":"123.660384ms","start":"2026-08-14T09:09:43.122165Z","end":"2026-08-14T09:09:43.245825Z","steps":["trace[1860107650] 'count revisions from in-memory index tree' (duration: 123.404078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:00.13066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.734804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-08-14T09:10:00.130736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.430373ms","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-08-14T09:10:00.130847Z","caller":"traceutil/trace.go:171","msg":"trace[822710631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2134; }","duration":"512.588017ms","start":"2026-08-14T09:09:59.618235Z","end":"2026-08-14T09:10:00.130823Z","steps":["trace[822710631] 'range keys from in-memory index tree' (duration: 512.251699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:00.1309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:59.618216Z","time spent":"512.67647ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:10:00.131091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.773045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-14T09:10:00.131209Z","caller":"traceutil/trace.go:171","msg":"trace[1760726471] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2134; }","duration":"451.939919ms","start":"2026-08-14T09:09:59.679243Z","end":"2026-08-14T09:10:00.131183Z","steps":["trace[1760726471] 'range keys from in-memory index tree' (duration: 451.493048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:00.131264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:59.679225Z","time spent":"452.029611ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-08-14T09:10:00.131274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.888743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:10:00.131336Z","caller":"traceutil/trace.go:171","msg":"trace[714383069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2134; }","duration":"450.966965ms","start":"2026-08-14T09:09:59.680353Z","end":"2026-08-14T09:10:00.13132Z","steps":["trace[714383069] 'range keys from in-memory index tree' (duration: 450.423212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:00.131379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:59.680346Z","time spent":"451.022686ms","remote":"127.0.0.1:33752","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-08-14T09:10:00.130777Z","caller":"traceutil/trace.go:171","msg":"trace[1326144394] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2134; }","duration":"248.924839ms","start":"2026-08-14T09:09:59.881818Z","end":"2026-08-14T09:10:00.130743Z","steps":["trace[1326144394] 'range keys from in-memory index tree' (duration: 248.341765ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:02.955104Z","caller":"traceutil/trace.go:171","msg":"trace[524401661] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"127.872078ms","start":"2026-08-14T09:10:02.827205Z","end":"2026-08-14T09:10:02.955077Z","steps":["trace[524401661] 'read index received' (duration: 127.600211ms)","trace[524401661] 'applied index is now lower than readState.Index' (duration: 270.397µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:02.95524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.013531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:10:02.955209Z","caller":"traceutil/trace.go:171","msg":"trace[349903757] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"159.306434ms","start":"2026-08-14T09:10:02.795862Z","end":"2026-08-14T09:10:02.955168Z","steps":["trace[349903757] 'process raft request' (duration: 158.969956ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:02.955278Z","caller":"traceutil/trace.go:171","msg":"trace[224132428] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2149; }","duration":"128.079373ms","start":"2026-08-14T09:10:02.827186Z","end":"2026-08-14T09:10:02.955265Z","steps":["trace[224132428] 'agreement among raft nodes before linearized reading' (duration: 127.989841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:03.264352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.178683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-08-14T09:10:03.26449Z","caller":"traceutil/trace.go:171","msg":"trace[1061194325] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:2149; }","duration":"203.373058ms","start":"2026-08-14T09:10:03.061097Z","end":"2026-08-14T09:10:03.26447Z","steps":["trace[1061194325] 'range keys from in-memory index tree' (duration: 202.934557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:03.26476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.72301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-08-14T09:10:03.264845Z","caller":"traceutil/trace.go:171","msg":"trace[1493038233] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2149; }","duration":"181.860074ms","start":"2026-08-14T09:10:03.082966Z","end":"2026-08-14T09:10:03.264826Z","steps":["trace[1493038233] 'range keys from in-memory index tree' (duration: 181.340931ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:03.265978Z","caller":"traceutil/trace.go:171","msg":"trace[1339644940] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2239; }","duration":"126.289309ms","start":"2026-08-14T09:10:03.139659Z","end":"2026-08-14T09:10:03.265948Z","steps":["trace[1339644940] 'read index received' (duration: 126.106135ms)","trace[1339644940] 'applied index is now lower than readState.Index' (duration: 181.664µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:03.266332Z","caller":"traceutil/trace.go:171","msg":"trace[681091251] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"145.044926ms","start":"2026-08-14T09:10:03.121273Z","end":"2026-08-14T09:10:03.266318Z","steps":["trace[681091251] 'process raft request' (duration: 144.497693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:03.266629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.974356ms","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-08-14T09:10:03.266669Z","caller":"traceutil/trace.go:171","msg":"trace[1183036534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2150; }","duration":"127.061298ms","start":"2026-08-14T09:10:03.139595Z","end":"2026-08-14T09:10:03.266656Z","steps":["trace[1183036534] 'agreement among raft nodes before linearized reading' (duration: 126.942325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:05.768283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.168557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-14T09:10:05.768325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.268092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:05.768365Z","caller":"traceutil/trace.go:171","msg":"trace[40497662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2164; }","duration":"234.343523ms","start":"2026-08-14T09:10:05.534011Z","end":"2026-08-14T09:10:05.768354Z","steps":["trace[40497662] 'range keys from in-memory index tree' (duration: 234.151239ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:05.768358Z","caller":"traceutil/trace.go:171","msg":"trace[1014490892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2164; }","duration":"357.324112ms","start":"2026-08-14T09:10:05.411016Z","end":"2026-08-14T09:10:05.76834Z","steps":["trace[1014490892] 'range keys from in-memory index tree' (duration: 357.024994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:05.768364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.603815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:10:05.768419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:05.411Z","time spent":"357.408793ms","remote":"127.0.0.1:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T09:10:05.768454Z","caller":"traceutil/trace.go:171","msg":"trace[1375813434] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2164; }","duration":"315.743748ms","start":"2026-08-14T09:10:05.452685Z","end":"2026-08-14T09:10:05.768429Z","steps":["trace[1375813434] 'count revisions from in-memory index tree' (duration: 315.526893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:05.768523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:05.452668Z","time spent":"315.837161ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:10:05.768815Z","caller":"traceutil/trace.go:171","msg":"trace[308361644] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"221.682854ms","start":"2026-08-14T09:10:05.547104Z","end":"2026-08-14T09:10:05.768787Z","steps":["trace[308361644] 'process raft request' (duration: 216.850376ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:14.386467Z","caller":"traceutil/trace.go:171","msg":"trace[2046194810] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"100.507061ms","start":"2026-08-14T09:10:14.285929Z","end":"2026-08-14T09:10:14.386436Z","steps":["trace[2046194810] 'process raft request' (duration: 34.43365ms)","trace[2046194810] 'compare' (duration: 65.514507ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:22.632979Z","caller":"traceutil/trace.go:171","msg":"trace[1394422582] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"111.372665ms","start":"2026-08-14T09:10:22.521587Z","end":"2026-08-14T09:10:22.63296Z","steps":["trace[1394422582] 'process raft request' (duration: 75.324036ms)","trace[1394422582] 'compare' (duration: 35.971967ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:23.798098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.242459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"info","ts":"2026-08-14T09:10:23.798317Z","caller":"traceutil/trace.go:171","msg":"trace[200851007] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:2257; }","duration":"124.537547ms","start":"2026-08-14T09:10:23.673754Z","end":"2026-08-14T09:10:23.798292Z","steps":["trace[200851007] 'range keys from in-memory index tree' (duration: 124.116966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:23.79851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.177651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-08-14T09:10:23.798627Z","caller":"traceutil/trace.go:171","msg":"trace[1878325404] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:2257; }","duration":"127.343075ms","start":"2026-08-14T09:10:23.671255Z","end":"2026-08-14T09:10:23.798598Z","steps":["trace[1878325404] 'range keys from in-memory index tree' (duration: 126.989576ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:23.799269Z","caller":"traceutil/trace.go:171","msg":"trace[1492975537] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"101.531855ms","start":"2026-08-14T09:10:23.697722Z","end":"2026-08-14T09:10:23.799254Z","steps":["trace[1492975537] 'process raft request' (duration: 101.342961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:24.297642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.983815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1107"} {"level":"warn","ts":"2026-08-14T09:10:24.2977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.19596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-08-14T09:10:24.297739Z","caller":"traceutil/trace.go:171","msg":"trace[2084594579] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:2262; }","duration":"161.125599ms","start":"2026-08-14T09:10:24.136586Z","end":"2026-08-14T09:10:24.297712Z","steps":["trace[2084594579] 'range keys from in-memory index tree' (duration: 160.826481ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:24.297768Z","caller":"traceutil/trace.go:171","msg":"trace[424710468] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:2262; }","duration":"250.291042ms","start":"2026-08-14T09:10:24.047456Z","end":"2026-08-14T09:10:24.297747Z","steps":["trace[424710468] 'range keys from in-memory index tree' (duration: 250.067857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:28.59656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.641591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:kubelet-bootstrap\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-14T09:10:28.596671Z","caller":"traceutil/trace.go:171","msg":"trace[1782850473] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:kubelet-bootstrap; range_end:; response_count:1; response_revision:2281; }","duration":"113.786144ms","start":"2026-08-14T09:10:28.482849Z","end":"2026-08-14T09:10:28.596635Z","steps":["trace[1782850473] 'range keys from in-memory index tree' (duration: 113.486797ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:28.725671Z","caller":"traceutil/trace.go:171","msg":"trace[1349037396] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"124.963396ms","start":"2026-08-14T09:10:28.600693Z","end":"2026-08-14T09:10:28.725656Z","steps":["trace[1349037396] 'process raft request' (duration: 124.863454ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:28.727405Z","caller":"traceutil/trace.go:171","msg":"trace[1667519681] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"126.256768ms","start":"2026-08-14T09:10:28.60111Z","end":"2026-08-14T09:10:28.727367Z","steps":["trace[1667519681] 'process raft request' (duration: 126.064123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:28.946273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.356833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-14T09:10:28.946113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.320253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-14T09:10:28.946326Z","caller":"traceutil/trace.go:171","msg":"trace[1735695293] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2284; }","duration":"115.443344ms","start":"2026-08-14T09:10:28.830869Z","end":"2026-08-14T09:10:28.946312Z","steps":["trace[1735695293] 'count revisions from in-memory index tree' (duration: 115.289471ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:28.946351Z","caller":"traceutil/trace.go:171","msg":"trace[1551540960] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:2284; }","duration":"145.647911ms","start":"2026-08-14T09:10:28.80068Z","end":"2026-08-14T09:10:28.946328Z","steps":["trace[1551540960] 'range keys from in-memory index tree' (duration: 145.232311ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:34.19199Z","caller":"traceutil/trace.go:171","msg":"trace[1789302879] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"131.430964ms","start":"2026-08-14T09:10:34.06053Z","end":"2026-08-14T09:10:34.191961Z","steps":["trace[1789302879] 'process raft request' (duration: 111.283383ms)","trace[1789302879] 'compare' (duration: 20.009257ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:34.192083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.533374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-controller-manager\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-08-14T09:10:34.19215Z","caller":"traceutil/trace.go:171","msg":"trace[850794888] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-controller-manager; range_end:; response_count:1; response_revision:2307; }","duration":"160.632566ms","start":"2026-08-14T09:10:34.031498Z","end":"2026-08-14T09:10:34.192131Z","steps":["trace[850794888] 'agreement among raft nodes before linearized reading' (duration: 160.486422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.192241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.463781ms","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-08-14T09:10:34.192269Z","caller":"traceutil/trace.go:171","msg":"trace[453356143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2307; }","duration":"169.517993ms","start":"2026-08-14T09:10:34.022745Z","end":"2026-08-14T09:10:34.192263Z","steps":["trace[453356143] 'agreement among raft nodes before linearized reading' (duration: 169.423441ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:36.304575Z","caller":"traceutil/trace.go:171","msg":"trace[1507222461] linearizableReadLoop","detail":"{readStateIndex:2413; appliedIndex:2412; }","duration":"145.283302ms","start":"2026-08-14T09:10:36.159245Z","end":"2026-08-14T09:10:36.304528Z","steps":["trace[1507222461] 'read index received' (duration: 145.077207ms)","trace[1507222461] 'applied index is now lower than readState.Index' (duration: 205.065µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:36.30461Z","caller":"traceutil/trace.go:171","msg":"trace[879416051] transaction","detail":"{read_only:false; response_revision:2316; number_of_response:1; }","duration":"203.84279ms","start":"2026-08-14T09:10:36.100735Z","end":"2026-08-14T09:10:36.304578Z","steps":["trace[879416051] 'process raft request' (duration: 203.662216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:36.304813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.556619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-14T09:10:36.304857Z","caller":"traceutil/trace.go:171","msg":"trace[706243084] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2316; }","duration":"145.657141ms","start":"2026-08-14T09:10:36.15919Z","end":"2026-08-14T09:10:36.304847Z","steps":["trace[706243084] 'agreement among raft nodes before linearized reading' (duration: 145.482077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:36.305177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.274609ms","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-08-14T09:10:36.305311Z","caller":"traceutil/trace.go:171","msg":"trace[1632523381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2316; }","duration":"101.600507ms","start":"2026-08-14T09:10:36.203691Z","end":"2026-08-14T09:10:36.305291Z","steps":["trace[1632523381] 'agreement among raft nodes before linearized reading' (duration: 100.988072ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:36.452457Z","caller":"traceutil/trace.go:171","msg":"trace[1695274560] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"141.907549ms","start":"2026-08-14T09:10:36.310508Z","end":"2026-08-14T09:10:36.452416Z","steps":["trace[1695274560] 'process raft request' (duration: 85.923964ms)","trace[1695274560] 'compare' (duration: 55.615066ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:40.475288Z","caller":"traceutil/trace.go:171","msg":"trace[121595086] transaction","detail":"{read_only:false; response_revision:2334; number_of_response:1; }","duration":"146.632665ms","start":"2026-08-14T09:10:40.32864Z","end":"2026-08-14T09:10:40.475273Z","steps":["trace[121595086] 'process raft request' (duration: 146.44708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:40.475509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.559979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:40.475626Z","caller":"traceutil/trace.go:171","msg":"trace[1201166623] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2334; }","duration":"145.730243ms","start":"2026-08-14T09:10:40.329875Z","end":"2026-08-14T09:10:40.475605Z","steps":["trace[1201166623] 'agreement among raft nodes before linearized reading' (duration: 145.517388ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:40.475842Z","caller":"traceutil/trace.go:171","msg":"trace[1735282637] linearizableReadLoop","detail":"{readStateIndex:2432; appliedIndex:2431; }","duration":"145.325294ms","start":"2026-08-14T09:10:40.329901Z","end":"2026-08-14T09:10:40.475226Z","steps":["trace[1735282637] 'read index received' (duration: 145.069077ms)","trace[1735282637] 'applied index is now lower than readState.Index' (duration: 255.117µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:40.476096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.997146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-public/default\" ","response":"range_response_count:1 size:179"} {"level":"warn","ts":"2026-08-14T09:10:40.476112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.28503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:40.47617Z","caller":"traceutil/trace.go:171","msg":"trace[1032776028] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:2334; }","duration":"131.376723ms","start":"2026-08-14T09:10:40.344778Z","end":"2026-08-14T09:10:40.476154Z","steps":["trace[1032776028] 'agreement among raft nodes before linearized reading' (duration: 131.277531ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:40.476168Z","caller":"traceutil/trace.go:171","msg":"trace[1548028529] range","detail":"{range_begin:/registry/serviceaccounts/kube-public/default; range_end:; response_count:1; response_revision:2334; }","duration":"111.087788ms","start":"2026-08-14T09:10:40.365062Z","end":"2026-08-14T09:10:40.47615Z","steps":["trace[1548028529] 'agreement among raft nodes before linearized reading' (duration: 110.951965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:40.476272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.657281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:12 size:8411"} {"level":"warn","ts":"2026-08-14T09:10:40.476299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.149026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-config\" ","response":"range_response_count:1 size:899"} {"level":"info","ts":"2026-08-14T09:10:40.476326Z","caller":"traceutil/trace.go:171","msg":"trace[359013454] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:12; response_revision:2334; }","duration":"122.734892ms","start":"2026-08-14T09:10:40.353577Z","end":"2026-08-14T09:10:40.476312Z","steps":["trace[359013454] 'agreement among raft nodes before linearized reading' (duration: 122.496806ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:40.476366Z","caller":"traceutil/trace.go:171","msg":"trace[1742047627] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-config; range_end:; response_count:1; response_revision:2334; }","duration":"133.260309ms","start":"2026-08-14T09:10:40.343088Z","end":"2026-08-14T09:10:40.476348Z","steps":["trace[1742047627] 'agreement among raft nodes before linearized reading' (duration: 133.065254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:40.476647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.110828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-14T09:10:40.476693Z","caller":"traceutil/trace.go:171","msg":"trace[751052664] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2334; }","duration":"136.211701ms","start":"2026-08-14T09:10:40.340469Z","end":"2026-08-14T09:10:40.47668Z","steps":["trace[751052664] 'agreement among raft nodes before linearized reading' (duration: 135.497544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:50.559943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.192315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-14T09:10:50.560019Z","caller":"traceutil/trace.go:171","msg":"trace[1588506701] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:2379; }","duration":"128.257157ms","start":"2026-08-14T09:10:50.431731Z","end":"2026-08-14T09:10:50.559988Z","steps":["trace[1588506701] 'range keys from in-memory index tree' (duration: 128.098254ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:50.770244Z","caller":"traceutil/trace.go:171","msg":"trace[696081090] transaction","detail":"{read_only:false; response_revision:2382; number_of_response:1; }","duration":"113.442596ms","start":"2026-08-14T09:10:50.656774Z","end":"2026-08-14T09:10:50.770216Z","steps":["trace[696081090] 'process raft request' (duration: 48.647686ms)","trace[696081090] 'compare' (duration: 64.623656ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:50.770608Z","caller":"traceutil/trace.go:171","msg":"trace[2072802246] transaction","detail":"{read_only:false; response_revision:2383; number_of_response:1; }","duration":"113.535138ms","start":"2026-08-14T09:10:50.657051Z","end":"2026-08-14T09:10:50.770586Z","steps":["trace[2072802246] 'process raft request' (duration: 113.418775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:56.167066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.172701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-45mf4\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-08-14T09:10:56.167145Z","caller":"traceutil/trace.go:171","msg":"trace[1439508678] range","detail":"{range_begin:/registry/pods/kube-system/cilium-45mf4; range_end:; response_count:1; response_revision:2407; }","duration":"344.297645ms","start":"2026-08-14T09:10:55.82283Z","end":"2026-08-14T09:10:56.167128Z","steps":["trace[1439508678] 'range keys from in-memory index tree' (duration: 343.999707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:56.167174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:55.82281Z","time spent":"344.357786ms","remote":"127.0.0.1:33672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18547,"request content":"key:\"/registry/pods/kube-system/cilium-45mf4\" "} {"level":"warn","ts":"2026-08-14T09:10:56.167239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.463176ms","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-08-14T09:10:56.167604Z","caller":"traceutil/trace.go:171","msg":"trace[490887050] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2407; }","duration":"323.610769ms","start":"2026-08-14T09:10:55.84371Z","end":"2026-08-14T09:10:56.167321Z","steps":["trace[490887050] 'count revisions from in-memory index tree' (duration: 323.414095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:56.167711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:55.843704Z","time spent":"323.983689ms","remote":"127.0.0.1:33824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "}