{"level":"warn","ts":"2026-06-25T19:50:00.059371Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-25T19:50:00.059486Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.240: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.240:2380","--initial-cluster=instance=https://199.19.213.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.240: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-06-25T19:50:00.059566Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-25T19:50:00.05958Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.240:2380"]} {"level":"info","ts":"2026-06-25T19:50:00.059605Z","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-06-25T19:50:00.060446Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.240:2379"]} {"level":"info","ts":"2026-06-25T19:50:00.060529Z","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.240:2380"],"listen-peer-urls":["https://199.19.213.240:2380"],"advertise-client-urls":["https://199.19.213.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.240: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-06-25T19:50:00.06311Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.368845ms"} {"level":"info","ts":"2026-06-25T19:50:00.067904Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"65ae7913d9d30912","cluster-id":"74cb0f8fbd7d5970"} {"level":"info","ts":"2026-06-25T19:50:00.067957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 switched to configuration voters=()"} {"level":"info","ts":"2026-06-25T19:50:00.067975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became follower at term 0"} {"level":"info","ts":"2026-06-25T19:50:00.067983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 65ae7913d9d30912 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-25T19:50:00.067995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became follower at term 1"} {"level":"info","ts":"2026-06-25T19:50:00.068023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 switched to configuration voters=(7326926769944201490)"} {"level":"warn","ts":"2026-06-25T19:50:00.072688Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-25T19:50:00.073981Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-25T19:50:00.075083Z","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-06-25T19:50:00.077492Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"65ae7913d9d30912","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-25T19:50:00.077634Z","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-06-25T19:50:00.077762Z","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-06-25T19:50:00.077784Z","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-06-25T19:50:00.078627Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"65ae7913d9d30912","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-25T19:50:00.07896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 switched to configuration voters=(7326926769944201490)"} {"level":"info","ts":"2026-06-25T19:50:00.079064Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"74cb0f8fbd7d5970","local-member-id":"65ae7913d9d30912","added-peer-id":"65ae7913d9d30912","added-peer-peer-urls":["https://199.19.213.240:2380"]} {"level":"info","ts":"2026-06-25T19:50:00.082644Z","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-06-25T19:50:00.082828Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"65ae7913d9d30912","initial-advertise-peer-urls":["https://199.19.213.240:2380"],"listen-peer-urls":["https://199.19.213.240:2380"],"advertise-client-urls":["https://199.19.213.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-25T19:50:00.082858Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-25T19:50:00.082957Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.240:2380"} {"level":"info","ts":"2026-06-25T19:50:00.08297Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.240:2380"} {"level":"info","ts":"2026-06-25T19:50:00.468998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 is starting a new election at term 1"} {"level":"info","ts":"2026-06-25T19:50:00.46906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-25T19:50:00.469096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 received MsgPreVoteResp from 65ae7913d9d30912 at term 1"} {"level":"info","ts":"2026-06-25T19:50:00.469116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became candidate at term 2"} {"level":"info","ts":"2026-06-25T19:50:00.469127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 received MsgVoteResp from 65ae7913d9d30912 at term 2"} {"level":"info","ts":"2026-06-25T19:50:00.469149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became leader at term 2"} {"level":"info","ts":"2026-06-25T19:50:00.469169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 65ae7913d9d30912 elected leader 65ae7913d9d30912 at term 2"} {"level":"info","ts":"2026-06-25T19:50:00.486662Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T19:50:00.5458Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"65ae7913d9d30912","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.240:2379]}","request-path":"/0/members/65ae7913d9d30912/attributes","cluster-id":"74cb0f8fbd7d5970","publish-timeout":"7s"} {"level":"info","ts":"2026-06-25T19:50:00.54646Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T19:50:00.547052Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"74cb0f8fbd7d5970","local-member-id":"65ae7913d9d30912","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T19:50:00.547142Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T19:50:00.547184Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T19:50:00.547203Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T19:50:00.548062Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-25T19:50:00.548096Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-25T19:50:00.550543Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-25T19:50:00.552405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.240:2379"} {"level":"info","ts":"2026-06-25T19:50:10.674643Z","caller":"traceutil/trace.go:171","msg":"trace[1247851469] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"162.901282ms","start":"2026-06-25T19:50:10.511718Z","end":"2026-06-25T19:50:10.674619Z","steps":["trace[1247851469] 'read index received' (duration: 101.577952ms)","trace[1247851469] 'applied index is now lower than readState.Index' (duration: 61.32235ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:50:10.674765Z","caller":"traceutil/trace.go:171","msg":"trace[1418364395] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"177.347218ms","start":"2026-06-25T19:50:10.497406Z","end":"2026-06-25T19:50:10.674754Z","steps":["trace[1418364395] 'process raft request' (duration: 115.971186ms)","trace[1418364395] 'compare' (duration: 61.078767ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:50:10.67485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.81735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-06-25T19:50:10.674903Z","caller":"traceutil/trace.go:171","msg":"trace[972650940] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:373; }","duration":"151.899813ms","start":"2026-06-25T19:50:10.522995Z","end":"2026-06-25T19:50:10.674895Z","steps":["trace[972650940] 'agreement among raft nodes before linearized reading' (duration: 151.78725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:10.675106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.822396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-25T19:50:10.67515Z","caller":"traceutil/trace.go:171","msg":"trace[1217132317] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:373; }","duration":"112.891897ms","start":"2026-06-25T19:50:10.562246Z","end":"2026-06-25T19:50:10.675138Z","steps":["trace[1217132317] 'agreement among raft nodes before linearized reading' (duration: 112.818966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:10.675041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.300901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-06-25T19:50:10.675186Z","caller":"traceutil/trace.go:171","msg":"trace[1210100803] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:373; }","duration":"163.482074ms","start":"2026-06-25T19:50:10.511696Z","end":"2026-06-25T19:50:10.675178Z","steps":["trace[1210100803] 'agreement among raft nodes before linearized reading' (duration: 163.305231ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:24.112247Z","caller":"traceutil/trace.go:171","msg":"trace[1874953669] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"116.537807ms","start":"2026-06-25T19:50:23.995678Z","end":"2026-06-25T19:50:24.112216Z","steps":["trace[1874953669] 'process raft request' (duration: 116.409535ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:24.112222Z","caller":"traceutil/trace.go:171","msg":"trace[367982983] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"116.903114ms","start":"2026-06-25T19:50:23.995258Z","end":"2026-06-25T19:50:24.112161Z","steps":["trace[367982983] 'process raft request' (duration: 89.641973ms)","trace[367982983] 'compare' (duration: 26.968995ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:50:24.112581Z","caller":"traceutil/trace.go:171","msg":"trace[983042349] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"116.511616ms","start":"2026-06-25T19:50:23.996026Z","end":"2026-06-25T19:50:24.112537Z","steps":["trace[983042349] 'process raft request' (duration: 116.125619ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:24.112271Z","caller":"traceutil/trace.go:171","msg":"trace[1022577514] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"116.786342ms","start":"2026-06-25T19:50:23.995415Z","end":"2026-06-25T19:50:24.112201Z","steps":["trace[1022577514] 'process raft request' (duration: 116.611339ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:26.272542Z","caller":"traceutil/trace.go:171","msg":"trace[446296010] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"117.141849ms","start":"2026-06-25T19:50:26.155373Z","end":"2026-06-25T19:50:26.272515Z","steps":["trace[446296010] 'process raft request' (duration: 116.940004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:27.902517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058274349s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:50:27.902689Z","caller":"traceutil/trace.go:171","msg":"trace[328173455] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"1.421112731s","start":"2026-06-25T19:50:26.481554Z","end":"2026-06-25T19:50:27.902667Z","steps":["trace[328173455] 'read index received' (duration: 361.524897ms)","trace[328173455] 'applied index is now lower than readState.Index' (duration: 1.059585504s)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:50:27.902838Z","caller":"traceutil/trace.go:171","msg":"trace[582366461] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"1.624760701s","start":"2026-06-25T19:50:26.278064Z","end":"2026-06-25T19:50:27.902824Z","steps":["trace[582366461] 'process raft request' (duration: 564.959073ms)","trace[582366461] 'compare' (duration: 1.057902122s)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:50:27.902932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:26.278025Z","time spent":"1.624869154s","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T19:50:27.903159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176239623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:50:27.903261Z","caller":"traceutil/trace.go:171","msg":"trace[546467799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"1.176400126s","start":"2026-06-25T19:50:26.72683Z","end":"2026-06-25T19:50:27.90323Z","steps":["trace[546467799] 'agreement among raft nodes before linearized reading' (duration: 1.176248043s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:27.903306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:26.726795Z","time spent":"1.176502438s","remote":"127.0.0.1:36736","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-06-25T19:50:27.903387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.421841194s","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-06-25T19:50:27.903425Z","caller":"traceutil/trace.go:171","msg":"trace[1922714436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"1.421923536s","start":"2026-06-25T19:50:26.48149Z","end":"2026-06-25T19:50:27.903414Z","steps":["trace[1922714436] 'agreement among raft nodes before linearized reading' (duration: 1.421824584s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:27.903457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:26.481465Z","time spent":"1.421985977s","remote":"127.0.0.1:37002","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-06-25T19:50:27.903547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.564149ms","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-06-25T19:50:27.90358Z","caller":"traceutil/trace.go:171","msg":"trace[194930725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"539.654121ms","start":"2026-06-25T19:50:27.363915Z","end":"2026-06-25T19:50:27.90357Z","steps":["trace[194930725] 'agreement among raft nodes before linearized reading' (duration: 539.578969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:27.903606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:27.363884Z","time spent":"539.717083ms","remote":"127.0.0.1:37002","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-06-25T19:50:37.86327Z","caller":"traceutil/trace.go:171","msg":"trace[271596586] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"317.497996ms","start":"2026-06-25T19:50:37.545748Z","end":"2026-06-25T19:50:37.863246Z","steps":["trace[271596586] 'process raft request' (duration: 317.349014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:37.863416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:37.545712Z","time spent":"317.639869ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:50:37.869875Z","caller":"traceutil/trace.go:171","msg":"trace[1182350575] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"323.872039ms","start":"2026-06-25T19:50:37.545984Z","end":"2026-06-25T19:50:37.869856Z","steps":["trace[1182350575] 'process raft request' (duration: 323.700816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:37.869991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:37.545954Z","time spent":"323.994391ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:50:37.870166Z","caller":"traceutil/trace.go:171","msg":"trace[221505842] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"321.282589ms","start":"2026-06-25T19:50:37.548863Z","end":"2026-06-25T19:50:37.870145Z","steps":["trace[221505842] 'process raft request' (duration: 320.928963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:37.870252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:37.548845Z","time spent":"321.372931ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:50:37.870346Z","caller":"traceutil/trace.go:171","msg":"trace[777785128] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"316.057789ms","start":"2026-06-25T19:50:37.554257Z","end":"2026-06-25T19:50:37.870315Z","steps":["trace[777785128] 'process raft request' (duration: 315.583531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:37.870483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:37.554243Z","time spent":"316.172001ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:50:37.870666Z","caller":"traceutil/trace.go:171","msg":"trace[876355666] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"314.755534ms","start":"2026-06-25T19:50:37.555887Z","end":"2026-06-25T19:50:37.870642Z","steps":["trace[876355666] 'process raft request' (duration: 314.51908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:37.870732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:37.555875Z","time spent":"314.819795ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:50:37.870733Z","caller":"traceutil/trace.go:171","msg":"trace[916567718] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"311.055103ms","start":"2026-06-25T19:50:37.559624Z","end":"2026-06-25T19:50:37.870679Z","steps":["trace[916567718] 'process raft request' (duration: 310.86ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:37.870903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:37.559599Z","time spent":"311.222416ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:50:37.870993Z","caller":"traceutil/trace.go:171","msg":"trace[704220416] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"311.268727ms","start":"2026-06-25T19:50:37.559709Z","end":"2026-06-25T19:50:37.870978Z","steps":["trace[704220416] 'process raft request' (duration: 310.829839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:37.871073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:37.559687Z","time spent":"311.357429ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:50:37.872317Z","caller":"traceutil/trace.go:171","msg":"trace[571809190] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"304.542808ms","start":"2026-06-25T19:50:37.567759Z","end":"2026-06-25T19:50:37.872302Z","steps":["trace[571809190] 'process raft request' (duration: 303.966937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:37.872543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:37.567288Z","time spent":"305.15912ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:50:37.872895Z","caller":"traceutil/trace.go:171","msg":"trace[1638062149] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"312.081833ms","start":"2026-06-25T19:50:37.560779Z","end":"2026-06-25T19:50:37.872861Z","steps":["trace[1638062149] 'process raft request' (duration: 310.813909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:37.872979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:37.560768Z","time spent":"312.174075ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:50:37.87307Z","caller":"traceutil/trace.go:171","msg":"trace[1624441970] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"298.429582ms","start":"2026-06-25T19:50:37.574631Z","end":"2026-06-25T19:50:37.87306Z","steps":["trace[1624441970] 'process raft request' (duration: 297.166508ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:37.873745Z","caller":"traceutil/trace.go:171","msg":"trace[2100135244] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"275.736388ms","start":"2026-06-25T19:50:37.597994Z","end":"2026-06-25T19:50:37.87373Z","steps":["trace[2100135244] 'process raft request' (duration: 273.934564ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:37.873845Z","caller":"traceutil/trace.go:171","msg":"trace[2047959175] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"160.027897ms","start":"2026-06-25T19:50:37.713792Z","end":"2026-06-25T19:50:37.87382Z","steps":["trace[2047959175] 'process raft request' (duration: 159.762992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:44.47729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.171554ms","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-06-25T19:50:44.477329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.733202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l8gz9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-25T19:50:44.477392Z","caller":"traceutil/trace.go:171","msg":"trace[1846969025] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l8gz9; range_end:; response_count:1; response_revision:633; }","duration":"249.817144ms","start":"2026-06-25T19:50:44.227558Z","end":"2026-06-25T19:50:44.477375Z","steps":["trace[1846969025] 'range keys from in-memory index tree' (duration: 249.578788ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:44.477391Z","caller":"traceutil/trace.go:171","msg":"trace[1368173356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"370.341077ms","start":"2026-06-25T19:50:44.10703Z","end":"2026-06-25T19:50:44.477371Z","steps":["trace[1368173356] 'range keys from in-memory index tree' (duration: 369.962219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:44.477432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:44.106999Z","time spent":"370.423298ms","remote":"127.0.0.1:37002","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-06-25T19:50:44.477435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.186612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-25T19:50:44.477528Z","caller":"traceutil/trace.go:171","msg":"trace[2030025644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"371.333974ms","start":"2026-06-25T19:50:44.106171Z","end":"2026-06-25T19:50:44.477505Z","steps":["trace[2030025644] 'range keys from in-memory index tree' (duration: 370.996289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:44.477578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:44.106148Z","time spent":"371.419726ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-25T19:50:44.477683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.148582ms","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-06-25T19:50:44.47783Z","caller":"traceutil/trace.go:171","msg":"trace[73602253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"448.386456ms","start":"2026-06-25T19:50:44.02941Z","end":"2026-06-25T19:50:44.477796Z","steps":["trace[73602253] 'range keys from in-memory index tree' (duration: 447.907778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:44.477888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:44.029357Z","time spent":"448.519819ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T19:50:44.894609Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653759719697451392,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-25T19:50:45.285066Z","caller":"traceutil/trace.go:171","msg":"trace[1043605621] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"983.946438ms","start":"2026-06-25T19:50:44.301096Z","end":"2026-06-25T19:50:45.285042Z","steps":["trace[1043605621] 'process raft request' (duration: 983.425328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:45.285243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:44.301071Z","time spent":"984.085832ms","remote":"127.0.0.1:36834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T19:50:45.633644Z","caller":"traceutil/trace.go:171","msg":"trace[911189363] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.151723155s","start":"2026-06-25T19:50:44.481872Z","end":"2026-06-25T19:50:45.633595Z","steps":["trace[911189363] 'process raft request' (duration: 1.151425169s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:45.633838Z","caller":"traceutil/trace.go:171","msg":"trace[801185366] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:657; }","duration":"1.239548282s","start":"2026-06-25T19:50:44.394124Z","end":"2026-06-25T19:50:45.633672Z","steps":["trace[801185366] 'read index received' (duration: 890.213507ms)","trace[801185366] 'applied index is now lower than readState.Index' (duration: 349.333925ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:50:45.634116Z","caller":"traceutil/trace.go:171","msg":"trace[302178296] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.143951806s","start":"2026-06-25T19:50:44.490115Z","end":"2026-06-25T19:50:45.634067Z","steps":["trace[302178296] 'process raft request' (duration: 1.143371235s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:45.634217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:44.490043Z","time spent":"1.14412591s","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T19:50:45.634234Z","caller":"traceutil/trace.go:171","msg":"trace[1030418322] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.150820498s","start":"2026-06-25T19:50:44.483401Z","end":"2026-06-25T19:50:45.634222Z","steps":["trace[1030418322] 'process raft request' (duration: 1.150004563s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:45.633878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:44.481862Z","time spent":"1.151933699s","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T19:50:45.634398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:44.483386Z","time spent":"1.15097462s","remote":"127.0.0.1:37002","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-06-25T19:50:45.633655Z","caller":"traceutil/trace.go:171","msg":"trace[1895940167] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.151847137s","start":"2026-06-25T19:50:44.481744Z","end":"2026-06-25T19:50:45.633591Z","steps":["trace[1895940167] 'process raft request' (duration: 1.106058142s)","trace[1895940167] 'compare' (duration: 45.258574ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:50:45.634401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240259706s","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-06-25T19:50:45.63453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:44.481715Z","time spent":"1.152768824s","remote":"127.0.0.1:37002","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-06-25T19:50:45.634578Z","caller":"traceutil/trace.go:171","msg":"trace[1017874308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"1.24049888s","start":"2026-06-25T19:50:44.39404Z","end":"2026-06-25T19:50:45.634539Z","steps":["trace[1017874308] 'agreement among raft nodes before linearized reading' (duration: 1.240139164s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:45.635442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:44.394018Z","time spent":"1.241264785s","remote":"127.0.0.1:37002","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-06-25T19:50:46.725335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999993222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-25T19:50:46.72545Z","caller":"traceutil/trace.go:171","msg":"trace[1789878077] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000164755s","start":"2026-06-25T19:50:44.725257Z","end":"2026-06-25T19:50:46.725422Z","steps":["trace[1789878077] 'agreement among raft nodes before linearized reading' (duration: 2.000032853s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:46.725513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:44.725241Z","time spent":"2.000258358s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/25 19:50:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-25T19:50:46.773257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.037638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-06-25T19:50:46.773176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.652737ms","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-06-25T19:50:46.773351Z","caller":"traceutil/trace.go:171","msg":"trace[1836288767] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:638; }","duration":"924.852451ms","start":"2026-06-25T19:50:45.848473Z","end":"2026-06-25T19:50:46.773325Z","steps":["trace[1836288767] 'range keys from in-memory index tree' (duration: 924.634187ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:46.77351Z","caller":"traceutil/trace.go:171","msg":"trace[145112207] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"896.00422ms","start":"2026-06-25T19:50:45.87749Z","end":"2026-06-25T19:50:46.773494Z","steps":["trace[145112207] 'process raft request' (duration: 895.958999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:46.773585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:45.877443Z","time spent":"896.102421ms","remote":"127.0.0.1:37002","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-06-25T19:50:46.773828Z","caller":"traceutil/trace.go:171","msg":"trace[987097007] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.434045169s","start":"2026-06-25T19:50:45.339771Z","end":"2026-06-25T19:50:46.773816Z","steps":["trace[987097007] 'process raft request' (duration: 579.363249ms)","trace[987097007] 'compare' (duration: 853.470257ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:50:46.773877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:45.339748Z","time spent":"1.43410782s","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:50:46.773999Z","caller":"traceutil/trace.go:171","msg":"trace[1411197695] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.131330395s","start":"2026-06-25T19:50:45.642618Z","end":"2026-06-25T19:50:46.773949Z","steps":["trace[1411197695] 'process raft request' (duration: 1.130731364s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:46.774031Z","caller":"traceutil/trace.go:171","msg":"trace[707574048] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"1.140032121s","start":"2026-06-25T19:50:45.633968Z","end":"2026-06-25T19:50:46.774Z","steps":["trace[707574048] 'read index received' (duration: 285.187919ms)","trace[707574048] 'applied index is now lower than readState.Index' (duration: 854.841942ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:50:46.774187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:45.642596Z","time spent":"1.131470068s","remote":"127.0.0.1:37002","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-06-25T19:50:46.847518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.027689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:50:46.847492Z","caller":"traceutil/trace.go:171","msg":"trace[1726356146] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"117.982435ms","start":"2026-06-25T19:50:46.729448Z","end":"2026-06-25T19:50:46.847431Z","steps":["trace[1726356146] 'process raft request' (duration: 117.826771ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:46.847582Z","caller":"traceutil/trace.go:171","msg":"trace[1730016383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"114.136041ms","start":"2026-06-25T19:50:46.73343Z","end":"2026-06-25T19:50:46.847566Z","steps":["trace[1730016383] 'agreement among raft nodes before linearized reading' (duration: 113.977128ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:47.021046Z","caller":"traceutil/trace.go:171","msg":"trace[1293716954] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"160.529018ms","start":"2026-06-25T19:50:46.86049Z","end":"2026-06-25T19:50:47.021019Z","steps":["trace[1293716954] 'process raft request' (duration: 160.378734ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:47.291087Z","caller":"traceutil/trace.go:171","msg":"trace[2085747393] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"264.746718ms","start":"2026-06-25T19:50:47.026317Z","end":"2026-06-25T19:50:47.291063Z","steps":["trace[2085747393] 'process raft request' (duration: 264.546244ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:47.291392Z","caller":"traceutil/trace.go:171","msg":"trace[1356118532] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"263.585567ms","start":"2026-06-25T19:50:47.027795Z","end":"2026-06-25T19:50:47.29138Z","steps":["trace[1356118532] 'process raft request' (duration: 263.516185ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:50:47.291564Z","caller":"traceutil/trace.go:171","msg":"trace[1024223623] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"264.921671ms","start":"2026-06-25T19:50:47.026601Z","end":"2026-06-25T19:50:47.291522Z","steps":["trace[1024223623] 'process raft request' (duration: 264.412461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:48.579828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.671066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-25T19:50:48.579924Z","caller":"traceutil/trace.go:171","msg":"trace[1555288317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"936.822689ms","start":"2026-06-25T19:50:47.643079Z","end":"2026-06-25T19:50:48.579902Z","steps":["trace[1555288317] 'range keys from in-memory index tree' (duration: 936.497473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:48.579972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:47.643069Z","time spent":"936.89246ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-25T19:50:48.580032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.215522ms","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-06-25T19:50:48.580101Z","caller":"traceutil/trace.go:171","msg":"trace[1910306679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"941.331535ms","start":"2026-06-25T19:50:47.638751Z","end":"2026-06-25T19:50:48.580083Z","steps":["trace[1910306679] 'range keys from in-memory index tree' (duration: 941.039449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:48.580141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:47.63873Z","time spent":"941.401736ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T19:50:48.580225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.189364ms","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-06-25T19:50:48.580292Z","caller":"traceutil/trace.go:171","msg":"trace[318059665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"939.318136ms","start":"2026-06-25T19:50:47.640956Z","end":"2026-06-25T19:50:48.580274Z","steps":["trace[318059665] 'range keys from in-memory index tree' (duration: 938.93769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:50:48.58033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:50:47.640922Z","time spent":"939.399528ms","remote":"127.0.0.1:37002","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-06-25T19:51:20.727453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997917351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-25T19:51:20.727566Z","caller":"traceutil/trace.go:171","msg":"trace[1119060701] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.998108424s","start":"2026-06-25T19:51:18.729433Z","end":"2026-06-25T19:51:20.727541Z","steps":["trace[1119060701] 'agreement among raft nodes before linearized reading' (duration: 1.997967422s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:20.727618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:18.729405Z","time spent":"1.998202486s","remote":"127.0.0.1:36736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/25 19:51:20 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-25T19:51:21.039656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.989142692s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-25T19:51:21.039785Z","caller":"traceutil/trace.go:171","msg":"trace[1652534575] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:871; }","duration":"2.310258085s","start":"2026-06-25T19:51:18.729505Z","end":"2026-06-25T19:51:21.039763Z","steps":["trace[1652534575] 'read index received' (duration: 320.91483ms)","trace[1652534575] 'applied index is now lower than readState.Index' (duration: 1.989341515s)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:51:21.165675Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.733164235s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-25T19:51:21.16662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550939576s","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-06-25T19:51:21.166618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.868315935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T19:51:21.166675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.207503663s","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-06-25T19:51:21.166818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.962271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T19:51:21.16692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.941022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T19:51:21.16693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381568627s","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-06-25T19:51:21.166951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381538256s","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-06-25T19:51:21.16703Z","caller":"traceutil/trace.go:171","msg":"trace[1422720745] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:872; }","duration":"126.526957ms","start":"2026-06-25T19:51:21.03982Z","end":"2026-06-25T19:51:21.166347Z","steps":["trace[1422720745] 'read index received' (duration: 126.054467ms)","trace[1422720745] 'applied index is now lower than readState.Index' (duration: 471.16µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:51:21.167075Z","caller":"traceutil/trace.go:171","msg":"trace[1648163267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"1.551045058s","start":"2026-06-25T19:51:19.615625Z","end":"2026-06-25T19:51:21.16667Z","steps":["trace[1648163267] 'agreement among raft nodes before linearized reading' (duration: 1.550756413s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:21.167113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:19.615596Z","time spent":"1.551506186s","remote":"127.0.0.1:37002","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-06-25T19:51:21.167253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381886792s","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-06-25T19:51:21.167254Z","caller":"traceutil/trace.go:171","msg":"trace[603350167] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:840; }","duration":"1.868519549s","start":"2026-06-25T19:51:19.298197Z","end":"2026-06-25T19:51:21.166717Z","steps":["trace[603350167] 'agreement among raft nodes before linearized reading' (duration: 1.868277514s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:51:21.167304Z","caller":"traceutil/trace.go:171","msg":"trace[1047102088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:840; }","duration":"2.381966864s","start":"2026-06-25T19:51:18.785313Z","end":"2026-06-25T19:51:21.167279Z","steps":["trace[1047102088] 'agreement among raft nodes before linearized reading' (duration: 2.381833052s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:21.167354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:18.78529Z","time spent":"2.382054855s","remote":"127.0.0.1:37002","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-06-25T19:51:21.167357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:19.29817Z","time spent":"1.869159862s","remote":"127.0.0.1:36976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-06-25T19:51:21.167444Z","caller":"traceutil/trace.go:171","msg":"trace[1023982069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:840; }","duration":"2.207673196s","start":"2026-06-25T19:51:18.959089Z","end":"2026-06-25T19:51:21.166762Z","steps":["trace[1023982069] 'agreement among raft nodes before linearized reading' (duration: 2.207327819s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:21.167486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:18.959058Z","time spent":"2.208417221s","remote":"127.0.0.1:37002","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-06-25T19:51:21.167562Z","caller":"traceutil/trace.go:171","msg":"trace[1670263442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:840; }","duration":"439.019222ms","start":"2026-06-25T19:51:20.727821Z","end":"2026-06-25T19:51:21.16684Z","steps":["trace[1670263442] 'agreement among raft nodes before linearized reading' (duration: 438.960941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:21.167588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:20.727806Z","time spent":"439.775688ms","remote":"127.0.0.1:36730","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-06-25T19:51:21.167709Z","caller":"traceutil/trace.go:171","msg":"trace[576280895] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"1.734537821s","start":"2026-06-25T19:51:19.432317Z","end":"2026-06-25T19:51:21.166855Z","steps":["trace[576280895] 'process raft request' (duration: 1.733678415s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:21.1678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:19.432283Z","time spent":"1.73544724s","remote":"127.0.0.1:36988","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-06-25T19:51:21.167844Z","caller":"traceutil/trace.go:171","msg":"trace[844309414] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:840; }","duration":"781.034314ms","start":"2026-06-25T19:51:20.385915Z","end":"2026-06-25T19:51:21.16695Z","steps":["trace[844309414] 'agreement among raft nodes before linearized reading' (duration: 780.966133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:21.167899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:20.385884Z","time spent":"781.996802ms","remote":"127.0.0.1:37028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-25T19:51:21.168619Z","caller":"traceutil/trace.go:171","msg":"trace[1424013354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"2.381641778s","start":"2026-06-25T19:51:18.785313Z","end":"2026-06-25T19:51:21.166954Z","steps":["trace[1424013354] 'agreement among raft nodes before linearized reading' (duration: 2.381556617s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:21.168683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:18.78529Z","time spent":"2.383377601s","remote":"127.0.0.1:37002","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-06-25T19:51:21.169127Z","caller":"traceutil/trace.go:171","msg":"trace[1845860484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:840; }","duration":"2.381630798s","start":"2026-06-25T19:51:18.785349Z","end":"2026-06-25T19:51:21.16698Z","steps":["trace[1845860484] 'agreement among raft nodes before linearized reading' (duration: 2.381417244s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:21.169206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:18.785315Z","time spent":"2.38387003s","remote":"127.0.0.1:37002","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-06-25T19:51:55.646891Z","caller":"traceutil/trace.go:171","msg":"trace[1095398714] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1094; }","duration":"122.265564ms","start":"2026-06-25T19:51:55.524595Z","end":"2026-06-25T19:51:55.646861Z","steps":["trace[1095398714] 'read index received' (duration: 120.022µs)","trace[1095398714] 'applied index is now lower than readState.Index' (duration: 122.144442ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:51:55.647207Z","caller":"traceutil/trace.go:171","msg":"trace[1069299381] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"122.614772ms","start":"2026-06-25T19:51:55.524572Z","end":"2026-06-25T19:51:55.647186Z","steps":["trace[1069299381] 'process raft request' (duration: 122.193943ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:51:55.647435Z","caller":"traceutil/trace.go:171","msg":"trace[842899143] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"121.790666ms","start":"2026-06-25T19:51:55.52543Z","end":"2026-06-25T19:51:55.647221Z","steps":["trace[842899143] 'process raft request' (duration: 121.390757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:55.647709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.08175ms","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-06-25T19:51:55.647801Z","caller":"traceutil/trace.go:171","msg":"trace[457900098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"123.232853ms","start":"2026-06-25T19:51:55.52455Z","end":"2026-06-25T19:51:55.647783Z","steps":["trace[457900098] 'agreement among raft nodes before linearized reading' (duration: 122.436177ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:51:55.647031Z","caller":"traceutil/trace.go:171","msg":"trace[1851487275] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"122.469618ms","start":"2026-06-25T19:51:55.524547Z","end":"2026-06-25T19:51:55.647017Z","steps":["trace[1851487275] 'process raft request' (duration: 56.323075ms)","trace[1851487275] 'compare' (duration: 65.730615ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:51:56.375407Z","caller":"traceutil/trace.go:171","msg":"trace[970106515] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"308.658655ms","start":"2026-06-25T19:51:56.066718Z","end":"2026-06-25T19:51:56.375376Z","steps":["trace[970106515] 'read index received' (duration: 276.960319ms)","trace[970106515] 'applied index is now lower than readState.Index' (duration: 31.696356ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:51:56.375493Z","caller":"traceutil/trace.go:171","msg":"trace[1534551853] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"590.21581ms","start":"2026-06-25T19:51:55.785223Z","end":"2026-06-25T19:51:56.375439Z","steps":["trace[1534551853] 'process raft request' (duration: 558.556965ms)","trace[1534551853] 'compare' (duration: 31.437461ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:51:56.375712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:55.785198Z","time spent":"590.399484ms","remote":"127.0.0.1:37002","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-06-25T19:51:56.375563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.824138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T19:51:56.375927Z","caller":"traceutil/trace.go:171","msg":"trace[1641059431] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1059; }","duration":"309.210045ms","start":"2026-06-25T19:51:56.066701Z","end":"2026-06-25T19:51:56.375911Z","steps":["trace[1641059431] 'agreement among raft nodes before linearized reading' (duration: 308.787197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:51:56.375984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:51:56.066631Z","time spent":"309.336017ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-25T19:51:56.376128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.747309ms","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-06-25T19:51:56.37625Z","caller":"traceutil/trace.go:171","msg":"trace[1030559479] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1058; }","duration":"527.167926ms","start":"2026-06-25T19:51:55.849066Z","end":"2026-06-25T19:51:56.376234Z","steps":["trace[1030559479] 'range keys from in-memory index tree' (duration: 525.715268ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:21.965611Z","caller":"traceutil/trace.go:171","msg":"trace[352431981] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"124.871658ms","start":"2026-06-25T19:52:21.840706Z","end":"2026-06-25T19:52:21.965578Z","steps":["trace[352431981] 'process raft request' (duration: 68.358162ms)","trace[352431981] 'compare' (duration: 56.304251ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:52:28.236545Z","caller":"traceutil/trace.go:171","msg":"trace[1668063835] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1204; }","duration":"376.675212ms","start":"2026-06-25T19:52:27.859841Z","end":"2026-06-25T19:52:28.236516Z","steps":["trace[1668063835] 'read index received' (duration: 376.419438ms)","trace[1668063835] 'applied index is now lower than readState.Index' (duration: 254.604µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:52:28.23666Z","caller":"traceutil/trace.go:171","msg":"trace[1811267884] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"509.640745ms","start":"2026-06-25T19:52:27.726983Z","end":"2026-06-25T19:52:28.236624Z","steps":["trace[1811267884] 'process raft request' (duration: 509.352839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:52:28.236844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:27.72697Z","time spent":"509.780088ms","remote":"127.0.0.1:37002","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-06-25T19:52:28.236885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.007169ms","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-06-25T19:52:28.236931Z","caller":"traceutil/trace.go:171","msg":"trace[297876112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"377.150252ms","start":"2026-06-25T19:52:27.859766Z","end":"2026-06-25T19:52:28.236916Z","steps":["trace[297876112] 'agreement among raft nodes before linearized reading' (duration: 376.916167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:52:28.236982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:27.859741Z","time spent":"377.229944ms","remote":"127.0.0.1:37002","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-06-25T19:52:28.236962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.791128ms","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-06-25T19:52:28.236993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.011591ms","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-06-25T19:52:28.237056Z","caller":"traceutil/trace.go:171","msg":"trace[1832870920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"334.91062ms","start":"2026-06-25T19:52:27.902127Z","end":"2026-06-25T19:52:28.237037Z","steps":["trace[1832870920] 'agreement among raft nodes before linearized reading' (duration: 334.612284ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:28.237083Z","caller":"traceutil/trace.go:171","msg":"trace[367258198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"200.155633ms","start":"2026-06-25T19:52:28.03691Z","end":"2026-06-25T19:52:28.237065Z","steps":["trace[367258198] 'agreement among raft nodes before linearized reading' (duration: 199.870018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:52:28.237103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:27.902062Z","time spent":"335.034522ms","remote":"127.0.0.1:37002","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-06-25T19:52:28.237196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.709088ms","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-06-25T19:52:28.237413Z","caller":"traceutil/trace.go:171","msg":"trace[567627371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1159; }","duration":"245.938512ms","start":"2026-06-25T19:52:27.991416Z","end":"2026-06-25T19:52:28.237354Z","steps":["trace[567627371] 'agreement among raft nodes before linearized reading' (duration: 245.421022ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:28.520453Z","caller":"traceutil/trace.go:171","msg":"trace[320640675] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"278.219622ms","start":"2026-06-25T19:52:28.242204Z","end":"2026-06-25T19:52:28.520423Z","steps":["trace[320640675] 'process raft request' (duration: 274.403279ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:28.529425Z","caller":"traceutil/trace.go:171","msg":"trace[2033921376] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"286.218476ms","start":"2026-06-25T19:52:28.243171Z","end":"2026-06-25T19:52:28.529389Z","steps":["trace[2033921376] 'process raft request' (duration: 285.984041ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:28.529788Z","caller":"traceutil/trace.go:171","msg":"trace[192596205] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"286.493821ms","start":"2026-06-25T19:52:28.243272Z","end":"2026-06-25T19:52:28.529766Z","steps":["trace[192596205] 'process raft request' (duration: 286.070513ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:28.530008Z","caller":"traceutil/trace.go:171","msg":"trace[241510718] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"285.43045ms","start":"2026-06-25T19:52:28.244553Z","end":"2026-06-25T19:52:28.529983Z","steps":["trace[241510718] 'process raft request' (duration: 285.031123ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:38.426624Z","caller":"traceutil/trace.go:171","msg":"trace[715291902] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"137.644383ms","start":"2026-06-25T19:52:38.288939Z","end":"2026-06-25T19:52:38.426584Z","steps":["trace[715291902] 'process raft request' (duration: 137.355948ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:52:38.831424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.850982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:52:38.831537Z","caller":"traceutil/trace.go:171","msg":"trace[984919873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1197; }","duration":"107.048566ms","start":"2026-06-25T19:52:38.724465Z","end":"2026-06-25T19:52:38.831513Z","steps":["trace[984919873] 'range keys from in-memory index tree' (duration: 106.695638ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:38.842339Z","caller":"traceutil/trace.go:171","msg":"trace[163439162] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"113.020859ms","start":"2026-06-25T19:52:38.729295Z","end":"2026-06-25T19:52:38.842316Z","steps":["trace[163439162] 'process raft request' (duration: 112.576271ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:40.777262Z","caller":"traceutil/trace.go:171","msg":"trace[572035435] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1249; }","duration":"124.333838ms","start":"2026-06-25T19:52:40.652895Z","end":"2026-06-25T19:52:40.777229Z","steps":["trace[572035435] 'read index received' (duration: 25.561222ms)","trace[572035435] 'applied index is now lower than readState.Index' (duration: 98.771106ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:52:40.777632Z","caller":"traceutil/trace.go:171","msg":"trace[1424217113] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"134.000913ms","start":"2026-06-25T19:52:40.643616Z","end":"2026-06-25T19:52:40.777617Z","steps":["trace[1424217113] 'process raft request' (duration: 100.888037ms)","trace[1424217113] 'compare' (duration: 32.582865ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:52:40.778051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.142193ms","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-06-25T19:52:40.778146Z","caller":"traceutil/trace.go:171","msg":"trace[1574148317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"125.314957ms","start":"2026-06-25T19:52:40.65282Z","end":"2026-06-25T19:52:40.778135Z","steps":["trace[1574148317] 'agreement among raft nodes before linearized reading' (duration: 125.030181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:52:40.778376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.734895ms","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-06-25T19:52:40.77842Z","caller":"traceutil/trace.go:171","msg":"trace[146216564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"124.797386ms","start":"2026-06-25T19:52:40.653607Z","end":"2026-06-25T19:52:40.778405Z","steps":["trace[146216564] 'agreement among raft nodes before linearized reading' (duration: 124.703364ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:41.062436Z","caller":"traceutil/trace.go:171","msg":"trace[1137307384] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"278.977457ms","start":"2026-06-25T19:52:40.78343Z","end":"2026-06-25T19:52:41.062407Z","steps":["trace[1137307384] 'process raft request' (duration: 278.812464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:52:41.24604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.989241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:52:41.2462Z","caller":"traceutil/trace.go:171","msg":"trace[1275690854] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1251; }","duration":"255.568146ms","start":"2026-06-25T19:52:40.990607Z","end":"2026-06-25T19:52:41.246175Z","steps":["trace[1275690854] 'read index received' (duration: 71.624305ms)","trace[1275690854] 'applied index is now lower than readState.Index' (duration: 183.942231ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:52:41.246296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.697878ms","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-06-25T19:52:41.246331Z","caller":"traceutil/trace.go:171","msg":"trace[1131230037] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1204; }","duration":"255.79062ms","start":"2026-06-25T19:52:40.990531Z","end":"2026-06-25T19:52:41.246321Z","steps":["trace[1131230037] 'agreement among raft nodes before linearized reading' (duration: 255.704438ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:41.246433Z","caller":"traceutil/trace.go:171","msg":"trace[129540872] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"462.53906ms","start":"2026-06-25T19:52:40.783824Z","end":"2026-06-25T19:52:41.246363Z","steps":["trace[129540872] 'process raft request' (duration: 338.112201ms)","trace[129540872] 'compare' (duration: 123.789407ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:52:41.246757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:40.783797Z","time spent":"462.768855ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T19:52:44.852564Z","caller":"traceutil/trace.go:171","msg":"trace[1145404590] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"127.453827ms","start":"2026-06-25T19:52:44.725037Z","end":"2026-06-25T19:52:44.852491Z","steps":["trace[1145404590] 'read index received' (duration: 36.848597ms)","trace[1145404590] 'applied index is now lower than readState.Index' (duration: 90.60342ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:52:44.852718Z","caller":"traceutil/trace.go:171","msg":"trace[1894982560] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"143.863452ms","start":"2026-06-25T19:52:44.70881Z","end":"2026-06-25T19:52:44.852674Z","steps":["trace[1894982560] 'process raft request' (duration: 53.11121ms)","trace[1894982560] 'compare' (duration: 90.421716ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:52:44.852781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.725822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:52:44.852939Z","caller":"traceutil/trace.go:171","msg":"trace[259410650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"127.907545ms","start":"2026-06-25T19:52:44.724979Z","end":"2026-06-25T19:52:44.852887Z","steps":["trace[259410650] 'agreement among raft nodes before linearized reading' (duration: 127.732382ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:45.040594Z","caller":"traceutil/trace.go:171","msg":"trace[110711216] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"182.981893ms","start":"2026-06-25T19:52:44.857575Z","end":"2026-06-25T19:52:45.040557Z","steps":["trace[110711216] 'process raft request' (duration: 182.640807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:52:47.497161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.772901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:52:47.497307Z","caller":"traceutil/trace.go:171","msg":"trace[208118206] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"166.763231ms","start":"2026-06-25T19:52:47.330518Z","end":"2026-06-25T19:52:47.497281Z","steps":["trace[208118206] 'process raft request' (duration: 60.755266ms)","trace[208118206] 'compare' (duration: 105.590898ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:52:58.913025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.406938ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-25T19:52:58.913338Z","caller":"traceutil/trace.go:171","msg":"trace[1873541495] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"188.595521ms","start":"2026-06-25T19:52:58.724707Z","end":"2026-06-25T19:52:58.913303Z","steps":["trace[1873541495] 'read index received' (duration: 788.285µs)","trace[1873541495] 'applied index is now lower than readState.Index' (duration: 187.804226ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:52:58.913537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.812226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:52:58.91359Z","caller":"traceutil/trace.go:171","msg":"trace[1100873676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"188.929688ms","start":"2026-06-25T19:52:58.724648Z","end":"2026-06-25T19:52:58.913578Z","steps":["trace[1100873676] 'agreement among raft nodes before linearized reading' (duration: 188.819285ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:52:58.913699Z","caller":"traceutil/trace.go:171","msg":"trace[906030985] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"141.753523ms","start":"2026-06-25T19:52:58.77191Z","end":"2026-06-25T19:52:58.913664Z","steps":["trace[906030985] 'process raft request' (duration: 141.312363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:52:58.913774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.43989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:52:58.91389Z","caller":"traceutil/trace.go:171","msg":"trace[1216165269] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1259; }","duration":"148.605754ms","start":"2026-06-25T19:52:58.765266Z","end":"2026-06-25T19:52:58.913872Z","steps":["trace[1216165269] 'agreement among raft nodes before linearized reading' (duration: 148.38883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:52:59.819364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653759719697452826,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T19:53:00.320297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653759719697452826,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T19:53:00.668553Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.403544807s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-25T19:53:01.308982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.336221984s","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-06-25T19:53:01.309097Z","caller":"traceutil/trace.go:171","msg":"trace[1233664265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"2.336401037s","start":"2026-06-25T19:52:58.972671Z","end":"2026-06-25T19:53:01.309072Z","steps":["trace[1233664265] 'range keys from in-memory index tree' (duration: 2.33603509s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:01.309117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.117842391s","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-06-25T19:53:01.309209Z","caller":"traceutil/trace.go:171","msg":"trace[1991665043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1259; }","duration":"2.118003495s","start":"2026-06-25T19:52:59.191182Z","end":"2026-06-25T19:53:01.309185Z","steps":["trace[1991665043] 'range keys from in-memory index tree' (duration: 2.117617056s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:01.309267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:59.191156Z","time spent":"2.118101737s","remote":"127.0.0.1:37002","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-06-25T19:53:01.309147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:58.972649Z","time spent":"2.336486469s","remote":"127.0.0.1:37002","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-06-25T19:53:01.309614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.765503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:53:01.309743Z","caller":"traceutil/trace.go:171","msg":"trace[431336105] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1312; }","duration":"1.991055087s","start":"2026-06-25T19:52:59.318665Z","end":"2026-06-25T19:53:01.30972Z","steps":["trace[431336105] 'read index received' (duration: 1.35004126s)","trace[431336105] 'applied index is now lower than readState.Index' (duration: 641.011937ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:53:01.309828Z","caller":"traceutil/trace.go:171","msg":"trace[1375464288] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"2.044942512s","start":"2026-06-25T19:52:59.264873Z","end":"2026-06-25T19:53:01.309815Z","steps":["trace[1375464288] 'process raft request' (duration: 1.403868654s)","trace[1375464288] 'compare' (duration: 640.087019ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:53:01.309893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:59.264846Z","time spent":"2.045014014s","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T19:53:01.309916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99121971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T19:53:01.310018Z","caller":"traceutil/trace.go:171","msg":"trace[760349524] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1260; }","duration":"1.991376453s","start":"2026-06-25T19:52:59.318611Z","end":"2026-06-25T19:53:01.309988Z","steps":["trace[760349524] 'agreement among raft nodes before linearized reading' (duration: 1.99121198s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:01.310134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:59.318596Z","time spent":"1.991518965s","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-25T19:53:01.319654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.938944616s","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-06-25T19:53:01.319702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.822854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:01.319725Z","caller":"traceutil/trace.go:171","msg":"trace[616950265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1261; }","duration":"1.939046149s","start":"2026-06-25T19:52:59.380662Z","end":"2026-06-25T19:53:01.319708Z","steps":["trace[616950265] 'agreement among raft nodes before linearized reading' (duration: 1.938861825s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:01.319766Z","caller":"traceutil/trace.go:171","msg":"trace[307252331] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1261; }","duration":"377.945526ms","start":"2026-06-25T19:53:00.941801Z","end":"2026-06-25T19:53:01.319746Z","steps":["trace[307252331] 'agreement among raft nodes before linearized reading' (duration: 377.831134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:01.319783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:59.38065Z","time spent":"1.93911619s","remote":"127.0.0.1:37002","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-06-25T19:53:01.319817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:00.941774Z","time spent":"378.032967ms","remote":"127.0.0.1:36980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-25T19:53:01.31984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59482109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:01.319888Z","caller":"traceutil/trace.go:171","msg":"trace[140844573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"1.594920222s","start":"2026-06-25T19:52:59.724956Z","end":"2026-06-25T19:53:01.319876Z","steps":["trace[140844573] 'agreement among raft nodes before linearized reading' (duration: 1.59481592s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:01.319932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:59.724933Z","time spent":"1.594989513s","remote":"127.0.0.1:36736","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-06-25T19:53:01.320028Z","caller":"traceutil/trace.go:171","msg":"trace[70689426] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"398.911059ms","start":"2026-06-25T19:53:00.921103Z","end":"2026-06-25T19:53:01.320014Z","steps":["trace[70689426] 'process raft request' (duration: 398.061584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:01.320112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.265327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-25T19:53:01.32015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.765536067s","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-06-25T19:53:01.320164Z","caller":"traceutil/trace.go:171","msg":"trace[194438699] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1261; }","duration":"209.370299ms","start":"2026-06-25T19:53:01.110784Z","end":"2026-06-25T19:53:01.320154Z","steps":["trace[194438699] 'agreement among raft nodes before linearized reading' (duration: 209.264047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:01.320188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.223028ms","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-06-25T19:53:01.320194Z","caller":"traceutil/trace.go:171","msg":"trace[157483473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1261; }","duration":"1.765614918s","start":"2026-06-25T19:52:59.554566Z","end":"2026-06-25T19:53:01.320181Z","steps":["trace[157483473] 'agreement among raft nodes before linearized reading' (duration: 1.765492176s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:01.320219Z","caller":"traceutil/trace.go:171","msg":"trace[270770394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1261; }","duration":"645.078494ms","start":"2026-06-25T19:53:00.675134Z","end":"2026-06-25T19:53:01.320213Z","steps":["trace[270770394] 'agreement among raft nodes before linearized reading' (duration: 644.946062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:01.320234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:52:59.554547Z","time spent":"1.76567938s","remote":"127.0.0.1:37002","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-06-25T19:53:01.32024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:00.67511Z","time spent":"645.126335ms","remote":"127.0.0.1:37002","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-06-25T19:53:01.32026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:00.921075Z","time spent":"399.035761ms","remote":"127.0.0.1:37002","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-06-25T19:53:07.485459Z","caller":"traceutil/trace.go:171","msg":"trace[1177125556] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"102.393986ms","start":"2026-06-25T19:53:07.383031Z","end":"2026-06-25T19:53:07.485425Z","steps":["trace[1177125556] 'process raft request' (duration: 83.447192ms)","trace[1177125556] 'compare' (duration: 18.807831ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:53:07.494217Z","caller":"traceutil/trace.go:171","msg":"trace[36767429] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"110.715475ms","start":"2026-06-25T19:53:07.383487Z","end":"2026-06-25T19:53:07.494203Z","steps":["trace[36767429] 'process raft request' (duration: 110.485261ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:07.494181Z","caller":"traceutil/trace.go:171","msg":"trace[1428810317] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1337; }","duration":"110.530462ms","start":"2026-06-25T19:53:07.383621Z","end":"2026-06-25T19:53:07.494151Z","steps":["trace[1428810317] 'read index received' (duration: 82.878141ms)","trace[1428810317] 'applied index is now lower than readState.Index' (duration: 27.651011ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:53:07.494402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.751086ms","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-06-25T19:53:07.494471Z","caller":"traceutil/trace.go:171","msg":"trace[636190423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1285; }","duration":"110.871268ms","start":"2026-06-25T19:53:07.383587Z","end":"2026-06-25T19:53:07.494458Z","steps":["trace[636190423] 'agreement among raft nodes before linearized reading' (duration: 110.660804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:13.674699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.826504ms","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-06-25T19:53:13.674801Z","caller":"traceutil/trace.go:171","msg":"trace[2096330724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"153.023488ms","start":"2026-06-25T19:53:13.521758Z","end":"2026-06-25T19:53:13.674781Z","steps":["trace[2096330724] 'range keys from in-memory index tree' (duration: 152.60847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:13.674954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.065599ms","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-06-25T19:53:13.675031Z","caller":"traceutil/trace.go:171","msg":"trace[1554364014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1302; }","duration":"152.167581ms","start":"2026-06-25T19:53:13.522838Z","end":"2026-06-25T19:53:13.675006Z","steps":["trace[1554364014] 'range keys from in-memory index tree' (duration: 151.56733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:13.674468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.0893ms","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-06-25T19:53:13.676038Z","caller":"traceutil/trace.go:171","msg":"trace[904437288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"128.690521ms","start":"2026-06-25T19:53:13.547291Z","end":"2026-06-25T19:53:13.675982Z","steps":["trace[904437288] 'range keys from in-memory index tree' (duration: 126.851775ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:15.613059Z","caller":"traceutil/trace.go:171","msg":"trace[1919122356] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"190.801738ms","start":"2026-06-25T19:53:15.422211Z","end":"2026-06-25T19:53:15.613012Z","steps":["trace[1919122356] 'process raft request' (duration: 151.489981ms)","trace[1919122356] 'compare' (duration: 39.060171ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:53:15.613127Z","caller":"traceutil/trace.go:171","msg":"trace[982366776] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"173.189259ms","start":"2026-06-25T19:53:15.439903Z","end":"2026-06-25T19:53:15.613093Z","steps":["trace[982366776] 'read index received' (duration: 133.80278ms)","trace[982366776] 'applied index is now lower than readState.Index' (duration: 39.384959ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:53:15.613361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.436993ms","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-06-25T19:53:15.613406Z","caller":"traceutil/trace.go:171","msg":"trace[2046404280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"173.531165ms","start":"2026-06-25T19:53:15.439862Z","end":"2026-06-25T19:53:15.613393Z","steps":["trace[2046404280] 'agreement among raft nodes before linearized reading' (duration: 173.337502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:15.854161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.822773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:15.854281Z","caller":"traceutil/trace.go:171","msg":"trace[1121066727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"128.993666ms","start":"2026-06-25T19:53:15.72526Z","end":"2026-06-25T19:53:15.854254Z","steps":["trace[1121066727] 'range keys from in-memory index tree' (duration: 128.731702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:15.854294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.033132ms","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-06-25T19:53:15.854362Z","caller":"traceutil/trace.go:171","msg":"trace[852262767] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"103.79017ms","start":"2026-06-25T19:53:15.750549Z","end":"2026-06-25T19:53:15.854339Z","steps":["trace[852262767] 'process raft request' (duration: 56.081561ms)","trace[852262767] 'compare' (duration: 47.566666ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:53:15.854374Z","caller":"traceutil/trace.go:171","msg":"trace[1073495499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1309; }","duration":"109.143274ms","start":"2026-06-25T19:53:15.745211Z","end":"2026-06-25T19:53:15.854354Z","steps":["trace[1073495499] 'range keys from in-memory index tree' (duration: 108.783397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:15.854371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.113813ms","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-06-25T19:53:15.854169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.078123ms","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-06-25T19:53:15.854458Z","caller":"traceutil/trace.go:171","msg":"trace[1598824232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1309; }","duration":"109.276226ms","start":"2026-06-25T19:53:15.745165Z","end":"2026-06-25T19:53:15.854441Z","steps":["trace[1598824232] 'range keys from in-memory index tree' (duration: 108.890239ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:15.854484Z","caller":"traceutil/trace.go:171","msg":"trace[605400526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1309; }","duration":"109.47187ms","start":"2026-06-25T19:53:15.744997Z","end":"2026-06-25T19:53:15.854469Z","steps":["trace[605400526] 'range keys from in-memory index tree' (duration: 108.787977ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:16.04541Z","caller":"traceutil/trace.go:171","msg":"trace[1384227270] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"185.7835ms","start":"2026-06-25T19:53:15.85959Z","end":"2026-06-25T19:53:16.045374Z","steps":["trace[1384227270] 'process raft request' (duration: 124.674833ms)","trace[1384227270] 'compare' (duration: 60.850902ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:53:16.045592Z","caller":"traceutil/trace.go:171","msg":"trace[1525784240] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"184.401015ms","start":"2026-06-25T19:53:15.861161Z","end":"2026-06-25T19:53:16.045562Z","steps":["trace[1525784240] 'process raft request' (duration: 184.156839ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:18.456449Z","caller":"traceutil/trace.go:171","msg":"trace[215794925] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"400.02949ms","start":"2026-06-25T19:53:18.056383Z","end":"2026-06-25T19:53:18.456413Z","steps":["trace[215794925] 'process raft request' (duration: 399.050342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:18.456619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:18.056358Z","time spent":"400.169443ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T19:53:33.093853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653759719697453097,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-25T19:53:33.390675Z","caller":"traceutil/trace.go:171","msg":"trace[1053192170] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1423; }","duration":"797.404227ms","start":"2026-06-25T19:53:32.593243Z","end":"2026-06-25T19:53:33.390647Z","steps":["trace[1053192170] 'read index received' (duration: 797.153833ms)","trace[1053192170] 'applied index is now lower than readState.Index' (duration: 249.284µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:53:33.390776Z","caller":"traceutil/trace.go:171","msg":"trace[215408392] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"864.175713ms","start":"2026-06-25T19:53:32.526518Z","end":"2026-06-25T19:53:33.390693Z","steps":["trace[215408392] 'process raft request' (duration: 863.975289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:33.390881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.932454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:33.390917Z","caller":"traceutil/trace.go:171","msg":"trace[85374551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1365; }","duration":"666.033906ms","start":"2026-06-25T19:53:32.724873Z","end":"2026-06-25T19:53:33.390907Z","steps":["trace[85374551] 'agreement among raft nodes before linearized reading' (duration: 665.948975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:33.390964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:32.724853Z","time spent":"666.103027ms","remote":"127.0.0.1:36736","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-06-25T19:53:33.390984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:32.526497Z","time spent":"864.370718ms","remote":"127.0.0.1:37002","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-06-25T19:53:33.390958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.686603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T19:53:33.391072Z","caller":"traceutil/trace.go:171","msg":"trace[747919781] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1365; }","duration":"797.889457ms","start":"2026-06-25T19:53:32.593162Z","end":"2026-06-25T19:53:33.391052Z","steps":["trace[747919781] 'agreement among raft nodes before linearized reading' (duration: 797.655762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:33.39114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:32.593135Z","time spent":"797.986418ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-06-25T19:53:34.068076Z","caller":"traceutil/trace.go:171","msg":"trace[115888074] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1425; }","duration":"343.392058ms","start":"2026-06-25T19:53:33.724663Z","end":"2026-06-25T19:53:34.068056Z","steps":["trace[115888074] 'read index received' (duration: 319.101029ms)","trace[115888074] 'applied index is now lower than readState.Index' (duration: 24.289939ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:53:34.068077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:33.646664Z","time spent":"421.40631ms","remote":"127.0.0.1:36772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-25T19:53:34.068303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.614562ms","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-06-25T19:53:34.068329Z","caller":"traceutil/trace.go:171","msg":"trace[793788790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1366; }","duration":"343.689894ms","start":"2026-06-25T19:53:33.724631Z","end":"2026-06-25T19:53:34.068321Z","steps":["trace[793788790] 'agreement among raft nodes before linearized reading' (duration: 343.537911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:34.068349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:33.724616Z","time spent":"343.727814ms","remote":"127.0.0.1:37002","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-06-25T19:53:34.068476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.095501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:34.068495Z","caller":"traceutil/trace.go:171","msg":"trace[1533885081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1366; }","duration":"343.149763ms","start":"2026-06-25T19:53:33.72534Z","end":"2026-06-25T19:53:34.06849Z","steps":["trace[1533885081] 'agreement among raft nodes before linearized reading' (duration: 343.120182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:34.06851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:33.725326Z","time spent":"343.180953ms","remote":"127.0.0.1:36736","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-06-25T19:53:34.068713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.767301ms","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-06-25T19:53:34.068732Z","caller":"traceutil/trace.go:171","msg":"trace[2053609912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1366; }","duration":"308.810161ms","start":"2026-06-25T19:53:33.759916Z","end":"2026-06-25T19:53:34.068727Z","steps":["trace[2053609912] 'agreement among raft nodes before linearized reading' (duration: 308.76274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:34.068747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:33.759904Z","time spent":"308.839411ms","remote":"127.0.0.1:37002","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-06-25T19:53:35.831122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.873548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:35.831398Z","caller":"traceutil/trace.go:171","msg":"trace[1667604628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1372; }","duration":"302.166713ms","start":"2026-06-25T19:53:35.529189Z","end":"2026-06-25T19:53:35.831356Z","steps":["trace[1667604628] 'range keys from in-memory index tree' (duration: 301.762485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:35.831511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:35.52912Z","time spent":"302.375047ms","remote":"127.0.0.1:36736","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-06-25T19:53:35.83157Z","caller":"traceutil/trace.go:171","msg":"trace[695836637] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"131.230768ms","start":"2026-06-25T19:53:35.700299Z","end":"2026-06-25T19:53:35.83153Z","steps":["trace[695836637] 'read index received' (duration: 128.887553ms)","trace[695836637] 'applied index is now lower than readState.Index' (duration: 2.341535ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:53:35.831592Z","caller":"traceutil/trace.go:171","msg":"trace[1856616005] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"207.330275ms","start":"2026-06-25T19:53:35.624228Z","end":"2026-06-25T19:53:35.831559Z","steps":["trace[1856616005] 'process raft request' (duration: 204.940929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:35.831801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.475864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T19:53:35.831856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.185916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:35.831908Z","caller":"traceutil/trace.go:171","msg":"trace[50767565] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1373; }","duration":"131.635826ms","start":"2026-06-25T19:53:35.700248Z","end":"2026-06-25T19:53:35.831884Z","steps":["trace[50767565] 'agreement among raft nodes before linearized reading' (duration: 131.389871ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:35.831914Z","caller":"traceutil/trace.go:171","msg":"trace[1427481978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1373; }","duration":"106.313688ms","start":"2026-06-25T19:53:35.725584Z","end":"2026-06-25T19:53:35.831898Z","steps":["trace[1427481978] 'agreement among raft nodes before linearized reading' (duration: 106.182066ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:36.574908Z","caller":"traceutil/trace.go:171","msg":"trace[1442194368] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"237.138839ms","start":"2026-06-25T19:53:36.337738Z","end":"2026-06-25T19:53:36.574877Z","steps":["trace[1442194368] 'process raft request' (duration: 236.965876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:37.270356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.394056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:53:37.270469Z","caller":"traceutil/trace.go:171","msg":"trace[1322895812] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1437; }","duration":"544.782047ms","start":"2026-06-25T19:53:36.725668Z","end":"2026-06-25T19:53:37.27045Z","steps":["trace[1322895812] 'read index received' (duration: 296.206767ms)","trace[1322895812] 'applied index is now lower than readState.Index' (duration: 248.57408ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:53:37.270541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.872789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:37.270566Z","caller":"traceutil/trace.go:171","msg":"trace[1765177797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1378; }","duration":"544.95037ms","start":"2026-06-25T19:53:36.725607Z","end":"2026-06-25T19:53:37.270557Z","steps":["trace[1765177797] 'agreement among raft nodes before linearized reading' (duration: 544.893579ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:37.270739Z","caller":"traceutil/trace.go:171","msg":"trace[2046464236] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"903.711224ms","start":"2026-06-25T19:53:36.366963Z","end":"2026-06-25T19:53:37.270674Z","steps":["trace[2046464236] 'process raft request' (duration: 654.87423ms)","trace[2046464236] 'compare' (duration: 247.950997ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:53:37.271187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:36.366925Z","time spent":"904.161512ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T19:53:37.271522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:36.725578Z","time spent":"545.886187ms","remote":"127.0.0.1:36736","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-06-25T19:53:37.839077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.609032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:37.839195Z","caller":"traceutil/trace.go:171","msg":"trace[2079229236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1378; }","duration":"112.852966ms","start":"2026-06-25T19:53:37.726319Z","end":"2026-06-25T19:53:37.839172Z","steps":["trace[2079229236] 'range keys from in-memory index tree' (duration: 112.52596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:37.839439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.330352ms","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-06-25T19:53:37.839555Z","caller":"traceutil/trace.go:171","msg":"trace[675411657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1378; }","duration":"430.476465ms","start":"2026-06-25T19:53:37.409027Z","end":"2026-06-25T19:53:37.839504Z","steps":["trace[675411657] 'range keys from in-memory index tree' (duration: 430.046468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:37.839598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:37.409004Z","time spent":"430.585338ms","remote":"127.0.0.1:37002","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-06-25T19:53:38.269882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.894361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:53:38.270545Z","caller":"traceutil/trace.go:171","msg":"trace[171999835] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"426.746716ms","start":"2026-06-25T19:53:37.843755Z","end":"2026-06-25T19:53:38.270501Z","steps":["trace[171999835] 'process raft request' (duration: 210.10962ms)","trace[171999835] 'compare' (duration: 215.732718ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:53:38.270711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:37.843733Z","time spent":"426.892098ms","remote":"127.0.0.1:37002","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-06-25T19:53:38.396346Z","caller":"traceutil/trace.go:171","msg":"trace[1098446935] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"120.003663ms","start":"2026-06-25T19:53:38.276308Z","end":"2026-06-25T19:53:38.396312Z","steps":["trace[1098446935] 'process raft request' (duration: 119.937451ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:38.396874Z","caller":"traceutil/trace.go:171","msg":"trace[811745620] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"120.785818ms","start":"2026-06-25T19:53:38.276075Z","end":"2026-06-25T19:53:38.39686Z","steps":["trace[811745620] 'process raft request' (duration: 119.996883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:38.817537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.085989ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-25T19:53:52.465796Z","caller":"traceutil/trace.go:171","msg":"trace[119146473] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"127.765242ms","start":"2026-06-25T19:53:52.338001Z","end":"2026-06-25T19:53:52.465766Z","steps":["trace[119146473] 'process raft request' (duration: 127.215922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:52.931281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.257605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:52.931373Z","caller":"traceutil/trace.go:171","msg":"trace[377702557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1482; }","duration":"206.412768ms","start":"2026-06-25T19:53:52.724936Z","end":"2026-06-25T19:53:52.931349Z","steps":["trace[377702557] 'range keys from in-memory index tree' (duration: 206.115003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:54.778847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653759719697453374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T19:53:55.279362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653759719697453374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T19:53:55.780404Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653759719697453374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T19:53:55.891439Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.952529521s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T19:53:55.891874Z","caller":"traceutil/trace.go:171","msg":"trace[2131947665] linearizableReadLoop","detail":"{readStateIndex:1550; appliedIndex:1549; }","duration":"1.617107597s","start":"2026-06-25T19:53:54.274737Z","end":"2026-06-25T19:53:55.891844Z","steps":["trace[2131947665] 'read index received' (duration: 1.616850802s)","trace[2131947665] 'applied index is now lower than readState.Index' (duration: 255.275µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:53:55.891941Z","caller":"traceutil/trace.go:171","msg":"trace[963405234] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"1.953095252s","start":"2026-06-25T19:53:53.938805Z","end":"2026-06-25T19:53:55.8919Z","steps":["trace[963405234] 'process raft request' (duration: 1.952858718s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:55.892059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617298742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T19:53:55.892089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:53.938786Z","time spent":"1.953211365s","remote":"127.0.0.1:37002","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-06-25T19:53:55.892115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.422206381s","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-06-25T19:53:55.892141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.238467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:55.892164Z","caller":"traceutil/trace.go:171","msg":"trace[422281373] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1484; }","duration":"518.31411ms","start":"2026-06-25T19:53:55.373845Z","end":"2026-06-25T19:53:55.892159Z","steps":["trace[422281373] 'agreement among raft nodes before linearized reading' (duration: 518.274989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:55.892191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:55.373819Z","time spent":"518.367581ms","remote":"127.0.0.1:36830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-06-25T19:53:55.892173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167775897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T19:53:55.892285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408714881s","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-06-25T19:53:55.892284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.55154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:53:55.892161Z","caller":"traceutil/trace.go:171","msg":"trace[152674791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1484; }","duration":"1.422317153s","start":"2026-06-25T19:53:54.469835Z","end":"2026-06-25T19:53:55.892152Z","steps":["trace[152674791] 'agreement among raft nodes before linearized reading' (duration: 1.42215784s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:55.892328Z","caller":"traceutil/trace.go:171","msg":"trace[408348031] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1484; }","duration":"813.647632ms","start":"2026-06-25T19:53:55.07867Z","end":"2026-06-25T19:53:55.892317Z","steps":["trace[408348031] 'agreement among raft nodes before linearized reading' (duration: 813.580841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:55.89236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:55.078646Z","time spent":"813.707452ms","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-25T19:53:55.89237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413377052s","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-06-25T19:53:55.892394Z","caller":"traceutil/trace.go:171","msg":"trace[1735410576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1484; }","duration":"1.413412022s","start":"2026-06-25T19:53:54.478973Z","end":"2026-06-25T19:53:55.892385Z","steps":["trace[1735410576] 'agreement among raft nodes before linearized reading' (duration: 1.413357811s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:55.892396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:54.469814Z","time spent":"1.422549528s","remote":"127.0.0.1:37002","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-06-25T19:53:55.892436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049171991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T19:53:55.892229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209541712s","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-06-25T19:53:55.892479Z","caller":"traceutil/trace.go:171","msg":"trace[1514573822] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1484; }","duration":"1.049250593s","start":"2026-06-25T19:53:54.843213Z","end":"2026-06-25T19:53:55.892464Z","steps":["trace[1514573822] 'agreement among raft nodes before linearized reading' (duration: 1.049104951s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:55.892097Z","caller":"traceutil/trace.go:171","msg":"trace[1691763908] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1484; }","duration":"1.617365653s","start":"2026-06-25T19:53:54.274721Z","end":"2026-06-25T19:53:55.892087Z","steps":["trace[1691763908] 'agreement among raft nodes before linearized reading' (duration: 1.617252451s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:55.892493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41799267s","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-06-25T19:53:55.892519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:54.274653Z","time spent":"1.617858372s","remote":"127.0.0.1:34840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-06-25T19:53:55.892521Z","caller":"traceutil/trace.go:171","msg":"trace[1436839660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1484; }","duration":"1.418069161s","start":"2026-06-25T19:53:54.474445Z","end":"2026-06-25T19:53:55.892514Z","steps":["trace[1436839660] 'agreement among raft nodes before linearized reading' (duration: 1.4180063s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:55.892555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:54.474414Z","time spent":"1.418134633s","remote":"127.0.0.1:37002","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-06-25T19:53:55.892431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:54.478962Z","time spent":"1.413457803s","remote":"127.0.0.1:37002","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-06-25T19:53:55.892318Z","caller":"traceutil/trace.go:171","msg":"trace[724655796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1484; }","duration":"1.408787952s","start":"2026-06-25T19:53:54.483523Z","end":"2026-06-25T19:53:55.892311Z","steps":["trace[724655796] 'agreement among raft nodes before linearized reading' (duration: 1.40872228s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:55.892604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:54.483495Z","time spent":"1.409101588s","remote":"127.0.0.1:37002","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-06-25T19:53:55.892488Z","caller":"traceutil/trace.go:171","msg":"trace[292275749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1484; }","duration":"1.209859688s","start":"2026-06-25T19:53:54.68262Z","end":"2026-06-25T19:53:55.89248Z","steps":["trace[292275749] 'agreement among raft nodes before linearized reading' (duration: 1.209559732s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:53:55.89228Z","caller":"traceutil/trace.go:171","msg":"trace[2000627547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1484; }","duration":"1.168011572s","start":"2026-06-25T19:53:54.724242Z","end":"2026-06-25T19:53:55.892253Z","steps":["trace[2000627547] 'agreement among raft nodes before linearized reading' (duration: 1.167790747s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:53:55.89268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:54.724222Z","time spent":"1.16844748s","remote":"127.0.0.1:36736","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-06-25T19:53:55.892647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:54.682597Z","time spent":"1.210039532s","remote":"127.0.0.1:37002","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-06-25T19:53:55.892521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:53:54.843198Z","time spent":"1.049314624s","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-06-25T19:54:08.140321Z","caller":"traceutil/trace.go:171","msg":"trace[2013869447] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"226.236369ms","start":"2026-06-25T19:54:07.914063Z","end":"2026-06-25T19:54:08.1403Z","steps":["trace[2013869447] 'process raft request' (duration: 226.092097ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:08.15082Z","caller":"traceutil/trace.go:171","msg":"trace[163574279] linearizableReadLoop","detail":"{readStateIndex:1592; appliedIndex:1590; }","duration":"186.613137ms","start":"2026-06-25T19:54:07.964186Z","end":"2026-06-25T19:54:08.150799Z","steps":["trace[163574279] 'read index received' (duration: 176.075493ms)","trace[163574279] 'applied index is now lower than readState.Index' (duration: 10.536434ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:08.151002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.80313ms","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-06-25T19:54:08.15104Z","caller":"traceutil/trace.go:171","msg":"trace[1268590861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1524; }","duration":"186.888542ms","start":"2026-06-25T19:54:07.964143Z","end":"2026-06-25T19:54:08.151031Z","steps":["trace[1268590861] 'agreement among raft nodes before linearized reading' (duration: 186.735149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:08.151043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.005289ms","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-06-25T19:54:08.15107Z","caller":"traceutil/trace.go:171","msg":"trace[2127113917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1524; }","duration":"181.09358ms","start":"2026-06-25T19:54:07.969969Z","end":"2026-06-25T19:54:08.151062Z","steps":["trace[2127113917] 'agreement among raft nodes before linearized reading' (duration: 180.935047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:08.151199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.267007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:08.151223Z","caller":"traceutil/trace.go:171","msg":"trace[1980568852] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1524; }","duration":"136.295867ms","start":"2026-06-25T19:54:08.014921Z","end":"2026-06-25T19:54:08.151217Z","steps":["trace[1980568852] 'agreement among raft nodes before linearized reading' (duration: 136.250206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:08.15118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.06091ms","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-06-25T19:54:08.151235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.03354ms","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-06-25T19:54:08.151269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.540557ms","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-06-25T19:54:08.151318Z","caller":"traceutil/trace.go:171","msg":"trace[141423587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1524; }","duration":"153.272184ms","start":"2026-06-25T19:54:07.998019Z","end":"2026-06-25T19:54:08.151292Z","steps":["trace[141423587] 'agreement among raft nodes before linearized reading' (duration: 152.982298ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:08.151356Z","caller":"traceutil/trace.go:171","msg":"trace[1073618716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1524; }","duration":"153.223752ms","start":"2026-06-25T19:54:07.998103Z","end":"2026-06-25T19:54:08.151327Z","steps":["trace[1073618716] 'agreement among raft nodes before linearized reading' (duration: 152.849455ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:08.151363Z","caller":"traceutil/trace.go:171","msg":"trace[2039103888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1524; }","duration":"170.721931ms","start":"2026-06-25T19:54:07.980624Z","end":"2026-06-25T19:54:08.151346Z","steps":["trace[2039103888] 'agreement among raft nodes before linearized reading' (duration: 170.404395ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:08.150929Z","caller":"traceutil/trace.go:171","msg":"trace[1110632396] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"206.109492ms","start":"2026-06-25T19:54:07.944767Z","end":"2026-06-25T19:54:08.150876Z","steps":["trace[1110632396] 'process raft request' (duration: 205.882988ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:11.579038Z","caller":"traceutil/trace.go:171","msg":"trace[1673659054] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"214.916512ms","start":"2026-06-25T19:54:11.364085Z","end":"2026-06-25T19:54:11.579002Z","steps":["trace[1673659054] 'process raft request' (duration: 183.838733ms)","trace[1673659054] 'compare' (duration: 30.62338ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:12.67443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653759719697453526,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-25T19:54:12.993771Z","caller":"traceutil/trace.go:171","msg":"trace[1321603259] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"826.303875ms","start":"2026-06-25T19:54:12.167441Z","end":"2026-06-25T19:54:12.993745Z","steps":["trace[1321603259] 'process raft request' (duration: 826.058601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:12.993937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:12.167414Z","time spent":"826.428928ms","remote":"127.0.0.1:37002","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-06-25T19:54:13.312834Z","caller":"traceutil/trace.go:171","msg":"trace[1121555785] linearizableReadLoop","detail":"{readStateIndex:1626; appliedIndex:1625; }","duration":"1.139210147s","start":"2026-06-25T19:54:12.173601Z","end":"2026-06-25T19:54:13.312812Z","steps":["trace[1121555785] 'read index received' (duration: 820.230739ms)","trace[1121555785] 'applied index is now lower than readState.Index' (duration: 318.978138ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:13.312874Z","caller":"traceutil/trace.go:171","msg":"trace[169043026] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"725.874511ms","start":"2026-06-25T19:54:12.586964Z","end":"2026-06-25T19:54:13.312839Z","steps":["trace[169043026] 'process raft request' (duration: 711.762529ms)","trace[169043026] 'compare' (duration: 13.780316ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:13.313057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139436461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-25T19:54:13.31305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:12.586935Z","time spent":"726.017284ms","remote":"127.0.0.1:37002","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-06-25T19:54:13.313083Z","caller":"traceutil/trace.go:171","msg":"trace[1172550381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1557; }","duration":"1.139527113s","start":"2026-06-25T19:54:12.173549Z","end":"2026-06-25T19:54:13.313076Z","steps":["trace[1172550381] 'agreement among raft nodes before linearized reading' (duration: 1.13936588s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:13.313102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:12.173518Z","time spent":"1.139579475s","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-25T19:54:13.313196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132369255s","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-06-25T19:54:13.313271Z","caller":"traceutil/trace.go:171","msg":"trace[1310867507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1557; }","duration":"1.132464207s","start":"2026-06-25T19:54:12.18079Z","end":"2026-06-25T19:54:13.313254Z","steps":["trace[1310867507] 'agreement among raft nodes before linearized reading' (duration: 1.132257694s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:13.313329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:12.180779Z","time spent":"1.132537779s","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T19:54:13.313347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138014524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-25T19:54:13.313212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.633724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:13.313387Z","caller":"traceutil/trace.go:171","msg":"trace[1379483597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1557; }","duration":"590.855928ms","start":"2026-06-25T19:54:12.722524Z","end":"2026-06-25T19:54:13.31338Z","steps":["trace[1379483597] 'agreement among raft nodes before linearized reading' (duration: 590.655264ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:13.31339Z","caller":"traceutil/trace.go:171","msg":"trace[1243194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1557; }","duration":"1.138072845s","start":"2026-06-25T19:54:12.175303Z","end":"2026-06-25T19:54:13.313376Z","steps":["trace[1243194] 'agreement among raft nodes before linearized reading' (duration: 1.137941492s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:13.313411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:12.722505Z","time spent":"590.900989ms","remote":"127.0.0.1:36736","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-06-25T19:54:13.313428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:12.175291Z","time spent":"1.138127587s","remote":"127.0.0.1:37002","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-06-25T19:54:13.313468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.375516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:13.313596Z","caller":"traceutil/trace.go:171","msg":"trace[1558908756] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1557; }","duration":"827.57881ms","start":"2026-06-25T19:54:12.485982Z","end":"2026-06-25T19:54:13.313561Z","steps":["trace[1558908756] 'agreement among raft nodes before linearized reading' (duration: 827.322115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:13.313643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:12.48595Z","time spent":"827.686403ms","remote":"127.0.0.1:37144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-25T19:54:13.313823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138458602s","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-06-25T19:54:13.31391Z","caller":"traceutil/trace.go:171","msg":"trace[949798574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1557; }","duration":"1.138573935s","start":"2026-06-25T19:54:12.175314Z","end":"2026-06-25T19:54:13.313888Z","steps":["trace[949798574] 'agreement among raft nodes before linearized reading' (duration: 1.137921132s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:13.313955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:12.175307Z","time spent":"1.138636706s","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-25T19:54:13.314098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140325479s","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-06-25T19:54:13.315594Z","caller":"traceutil/trace.go:171","msg":"trace[1480899935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1557; }","duration":"1.14195307s","start":"2026-06-25T19:54:12.173621Z","end":"2026-06-25T19:54:13.315574Z","steps":["trace[1480899935] 'agreement among raft nodes before linearized reading' (duration: 1.139394601s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:13.315674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:12.173611Z","time spent":"1.142051992s","remote":"127.0.0.1:37002","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":"info","ts":"2026-06-25T19:54:13.475898Z","caller":"traceutil/trace.go:171","msg":"trace[511772474] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"157.448624ms","start":"2026-06-25T19:54:13.318415Z","end":"2026-06-25T19:54:13.475863Z","steps":["trace[511772474] 'process raft request' (duration: 123.280966ms)","trace[511772474] 'compare' (duration: 33.853352ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:13.476437Z","caller":"traceutil/trace.go:171","msg":"trace[992547984] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"157.866273ms","start":"2026-06-25T19:54:13.318541Z","end":"2026-06-25T19:54:13.476407Z","steps":["trace[992547984] 'process raft request' (duration: 157.2124ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:13.476791Z","caller":"traceutil/trace.go:171","msg":"trace[703167462] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"158.117008ms","start":"2026-06-25T19:54:13.318656Z","end":"2026-06-25T19:54:13.476773Z","steps":["trace[703167462] 'process raft request' (duration: 157.66286ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:13.477007Z","caller":"traceutil/trace.go:171","msg":"trace[457371372] linearizableReadLoop","detail":"{readStateIndex:1631; appliedIndex:1627; }","duration":"147.291208ms","start":"2026-06-25T19:54:13.329704Z","end":"2026-06-25T19:54:13.476995Z","steps":["trace[457371372] 'read index received' (duration: 25.199035ms)","trace[457371372] 'applied index is now lower than readState.Index' (duration: 122.091223ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:13.47715Z","caller":"traceutil/trace.go:171","msg":"trace[912377438] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"157.110128ms","start":"2026-06-25T19:54:13.320011Z","end":"2026-06-25T19:54:13.477121Z","steps":["trace[912377438] 'process raft request' (duration: 156.68524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:13.477157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.441252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T19:54:13.477298Z","caller":"traceutil/trace.go:171","msg":"trace[1067325736] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1562; }","duration":"147.628924ms","start":"2026-06-25T19:54:13.329651Z","end":"2026-06-25T19:54:13.477279Z","steps":["trace[1067325736] 'agreement among raft nodes before linearized reading' (duration: 147.38975ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:13.810141Z","caller":"traceutil/trace.go:171","msg":"trace[1295986699] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"133.564123ms","start":"2026-06-25T19:54:13.676528Z","end":"2026-06-25T19:54:13.810092Z","steps":["trace[1295986699] 'process raft request' (duration: 65.837429ms)","trace[1295986699] 'compare' (duration: 67.47676ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:15.451274Z","caller":"traceutil/trace.go:171","msg":"trace[169431599] linearizableReadLoop","detail":"{readStateIndex:1635; appliedIndex:1634; }","duration":"370.108553ms","start":"2026-06-25T19:54:15.081139Z","end":"2026-06-25T19:54:15.451247Z","steps":["trace[169431599] 'read index received' (duration: 369.899059ms)","trace[169431599] 'applied index is now lower than readState.Index' (duration: 208.664µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:15.451334Z","caller":"traceutil/trace.go:171","msg":"trace[141383243] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"451.531562ms","start":"2026-06-25T19:54:14.999774Z","end":"2026-06-25T19:54:15.451306Z","steps":["trace[141383243] 'process raft request' (duration: 451.321118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:15.451427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.271166ms","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":"info","ts":"2026-06-25T19:54:15.45146Z","caller":"traceutil/trace.go:171","msg":"trace[209307963] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1565; }","duration":"370.374048ms","start":"2026-06-25T19:54:15.081076Z","end":"2026-06-25T19:54:15.451451Z","steps":["trace[209307963] 'agreement among raft nodes before linearized reading' (duration: 370.291036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:15.451495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:15.08105Z","time spent":"370.433288ms","remote":"127.0.0.1:34988","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":"warn","ts":"2026-06-25T19:54:15.451479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:14.999735Z","time spent":"451.654535ms","remote":"127.0.0.1:37002","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-06-25T19:54:15.571766Z","caller":"traceutil/trace.go:171","msg":"trace[116153767] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"115.572377ms","start":"2026-06-25T19:54:15.456166Z","end":"2026-06-25T19:54:15.571739Z","steps":["trace[116153767] 'process raft request' (duration: 67.486421ms)","trace[116153767] 'compare' (duration: 47.946894ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:16.051592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.698297ms","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-06-25T19:54:16.051727Z","caller":"traceutil/trace.go:171","msg":"trace[134410721] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1566; }","duration":"202.86371ms","start":"2026-06-25T19:54:15.848846Z","end":"2026-06-25T19:54:16.05171Z","steps":["trace[134410721] 'range keys from in-memory index tree' (duration: 202.672057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:16.052192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.453523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:54:16.052408Z","caller":"traceutil/trace.go:171","msg":"trace[970302351] linearizableReadLoop","detail":"{readStateIndex:1637; appliedIndex:1636; }","duration":"480.681754ms","start":"2026-06-25T19:54:15.571711Z","end":"2026-06-25T19:54:16.052393Z","steps":["trace[970302351] 'read index received' (duration: 128.961715ms)","trace[970302351] 'applied index is now lower than readState.Index' (duration: 351.717879ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:16.052515Z","caller":"traceutil/trace.go:171","msg":"trace[501988729] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"564.97943ms","start":"2026-06-25T19:54:15.487524Z","end":"2026-06-25T19:54:16.052504Z","steps":["trace[501988729] 'process raft request' (duration: 213.132928ms)","trace[501988729] 'compare' (duration: 351.329941ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:16.052618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:15.487512Z","time spent":"565.030141ms","remote":"127.0.0.1:36818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:54:16.052416Z","caller":"traceutil/trace.go:171","msg":"trace[1802593074] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"475.580396ms","start":"2026-06-25T19:54:15.576814Z","end":"2026-06-25T19:54:16.052394Z","steps":["trace[1802593074] 'process raft request' (duration: 475.508325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:16.052841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.976795ms","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-06-25T19:54:16.052874Z","caller":"traceutil/trace.go:171","msg":"trace[1302265367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1568; }","duration":"571.022706ms","start":"2026-06-25T19:54:15.481842Z","end":"2026-06-25T19:54:16.052865Z","steps":["trace[1302265367] 'agreement among raft nodes before linearized reading' (duration: 570.880743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:16.052906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:15.481746Z","time spent":"571.153548ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T19:54:16.053007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.870413ms","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-06-25T19:54:16.052987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:15.576796Z","time spent":"476.056255ms","remote":"127.0.0.1:37002","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-06-25T19:54:16.053041Z","caller":"traceutil/trace.go:171","msg":"trace[1317960586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1568; }","duration":"569.997886ms","start":"2026-06-25T19:54:15.483035Z","end":"2026-06-25T19:54:16.053033Z","steps":["trace[1317960586] 'agreement among raft nodes before linearized reading' (duration: 569.859543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:16.053063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:15.483011Z","time spent":"570.048276ms","remote":"127.0.0.1:37002","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-06-25T19:54:16.053115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.774051ms","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-06-25T19:54:16.053147Z","caller":"traceutil/trace.go:171","msg":"trace[1682511456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1568; }","duration":"570.832362ms","start":"2026-06-25T19:54:15.482307Z","end":"2026-06-25T19:54:16.053139Z","steps":["trace[1682511456] 'agreement among raft nodes before linearized reading' (duration: 570.7528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:16.053167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:15.482295Z","time spent":"570.867513ms","remote":"127.0.0.1:37002","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-06-25T19:54:16.05302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.983049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:16.053216Z","caller":"traceutil/trace.go:171","msg":"trace[1337130550] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1568; }","duration":"440.371337ms","start":"2026-06-25T19:54:15.612833Z","end":"2026-06-25T19:54:16.053204Z","steps":["trace[1337130550] 'agreement among raft nodes before linearized reading' (duration: 439.961539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:16.053261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:15.61282Z","time spent":"440.433568ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-25T19:54:16.053256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.95023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:16.053559Z","caller":"traceutil/trace.go:171","msg":"trace[1498824718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1568; }","duration":"329.275296ms","start":"2026-06-25T19:54:15.724262Z","end":"2026-06-25T19:54:16.053537Z","steps":["trace[1498824718] 'agreement among raft nodes before linearized reading' (duration: 328.871259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:16.053672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:15.724247Z","time spent":"329.412318ms","remote":"127.0.0.1:36736","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-06-25T19:54:16.854239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.323517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:54:16.854366Z","caller":"traceutil/trace.go:171","msg":"trace[541260820] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"796.037312ms","start":"2026-06-25T19:54:16.058284Z","end":"2026-06-25T19:54:16.854321Z","steps":["trace[541260820] 'process raft request' (duration: 301.577992ms)","trace[541260820] 'compare' (duration: 493.863119ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:16.854427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:16.058267Z","time spent":"796.134324ms","remote":"127.0.0.1:36818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T19:54:16.999731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653759719697453558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T19:54:17.347996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.187242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:54:17.348187Z","caller":"traceutil/trace.go:171","msg":"trace[1781491320] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"1.289563764s","start":"2026-06-25T19:54:16.058597Z","end":"2026-06-25T19:54:17.348161Z","steps":["trace[1781491320] 'process raft request' (duration: 969.128068ms)","trace[1781491320] 'compare' (duration: 319.435997ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:17.348263Z","caller":"traceutil/trace.go:171","msg":"trace[508018597] linearizableReadLoop","detail":"{readStateIndex:1644; appliedIndex:1639; }","duration":"848.989803ms","start":"2026-06-25T19:54:16.499256Z","end":"2026-06-25T19:54:17.348245Z","steps":["trace[508018597] 'read index received' (duration: 528.483036ms)","trace[508018597] 'applied index is now lower than readState.Index' (duration: 320.505797ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:17.348307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:16.058442Z","time spent":"1.289800499s","remote":"127.0.0.1:37002","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-06-25T19:54:17.348427Z","caller":"traceutil/trace.go:171","msg":"trace[958615295] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"1.289648356s","start":"2026-06-25T19:54:16.058768Z","end":"2026-06-25T19:54:17.348417Z","steps":["trace[958615295] 'process raft request' (duration: 1.2893231s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:17.348484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:16.058761Z","time spent":"1.289698347s","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T19:54:17.348528Z","caller":"traceutil/trace.go:171","msg":"trace[807500714] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"486.136359ms","start":"2026-06-25T19:54:16.862363Z","end":"2026-06-25T19:54:17.348499Z","steps":["trace[807500714] 'process raft request' (duration: 485.842983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:17.348628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:16.862341Z","time spent":"486.232801ms","remote":"127.0.0.1:36818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T19:54:17.348645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.38745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T19:54:17.348672Z","caller":"traceutil/trace.go:171","msg":"trace[632505782] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1574; }","duration":"849.451751ms","start":"2026-06-25T19:54:16.499214Z","end":"2026-06-25T19:54:17.348666Z","steps":["trace[632505782] 'agreement among raft nodes before linearized reading' (duration: 849.39914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:17.348652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.733244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:17.348734Z","caller":"traceutil/trace.go:171","msg":"trace[773560943] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"1.288136467s","start":"2026-06-25T19:54:16.060589Z","end":"2026-06-25T19:54:17.348726Z","steps":["trace[773560943] 'process raft request' (duration: 1.287580147s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:17.348746Z","caller":"traceutil/trace.go:171","msg":"trace[1723248943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1574; }","duration":"620.900797ms","start":"2026-06-25T19:54:16.727825Z","end":"2026-06-25T19:54:17.348726Z","steps":["trace[1723248943] 'agreement among raft nodes before linearized reading' (duration: 620.728294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:17.348774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:16.060571Z","time spent":"1.288182107s","remote":"127.0.0.1:37002","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-06-25T19:54:17.348623Z","caller":"traceutil/trace.go:171","msg":"trace[728053207] transaction","detail":"{read_only:false; response_revision:1572; number_of_response:1; }","duration":"1.289520054s","start":"2026-06-25T19:54:16.059035Z","end":"2026-06-25T19:54:17.348555Z","steps":["trace[728053207] 'process raft request' (duration: 1.289101326s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:17.348855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.8964ms","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-06-25T19:54:17.348898Z","caller":"traceutil/trace.go:171","msg":"trace[831395848] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1574; }","duration":"106.937791ms","start":"2026-06-25T19:54:17.241944Z","end":"2026-06-25T19:54:17.348882Z","steps":["trace[831395848] 'agreement among raft nodes before linearized reading' (duration: 106.87995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:17.348701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:16.499196Z","time spent":"849.500162ms","remote":"127.0.0.1:37122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-25T19:54:17.3488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:16.7278Z","time spent":"620.985448ms","remote":"127.0.0.1:36736","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-06-25T19:54:17.349037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:16.059015Z","time spent":"1.289832399s","remote":"127.0.0.1:37002","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-06-25T19:54:17.531708Z","caller":"traceutil/trace.go:171","msg":"trace[1870751188] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"178.076472ms","start":"2026-06-25T19:54:17.353607Z","end":"2026-06-25T19:54:17.531683Z","steps":["trace[1870751188] 'process raft request' (duration: 126.743402ms)","trace[1870751188] 'compare' (duration: 51.221528ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:17.567337Z","caller":"traceutil/trace.go:171","msg":"trace[703962614] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"110.948908ms","start":"2026-06-25T19:54:17.456344Z","end":"2026-06-25T19:54:17.567293Z","steps":["trace[703962614] 'process raft request' (duration: 110.904638ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:17.567858Z","caller":"traceutil/trace.go:171","msg":"trace[1916058539] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"209.036168ms","start":"2026-06-25T19:54:17.358794Z","end":"2026-06-25T19:54:17.56783Z","steps":["trace[1916058539] 'process raft request' (duration: 208.12072ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:25.80232Z","caller":"traceutil/trace.go:171","msg":"trace[1431072888] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"414.374454ms","start":"2026-06-25T19:54:25.387919Z","end":"2026-06-25T19:54:25.802294Z","steps":["trace[1431072888] 'process raft request' (duration: 414.218431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:25.802476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:25.387899Z","time spent":"414.494066ms","remote":"127.0.0.1:37002","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-06-25T19:54:27.120432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271497123s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T19:54:27.12059Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.318402582s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T19:54:27.120602Z","caller":"traceutil/trace.go:171","msg":"trace[1299768767] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1637; }","duration":"1.271687928s","start":"2026-06-25T19:54:25.848891Z","end":"2026-06-25T19:54:27.120579Z","steps":["trace[1299768767] 'range keys from in-memory index tree' (duration: 1.271476503s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:27.120895Z","caller":"traceutil/trace.go:171","msg":"trace[203306508] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"1.728324717s","start":"2026-06-25T19:54:25.392555Z","end":"2026-06-25T19:54:27.12088Z","steps":["trace[203306508] 'process raft request' (duration: 1.728259146s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:27.121026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:25.392521Z","time spent":"1.7284318s","remote":"127.0.0.1:37002","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-06-25T19:54:27.121003Z","caller":"traceutil/trace.go:171","msg":"trace[135514196] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"1.730865137s","start":"2026-06-25T19:54:25.39012Z","end":"2026-06-25T19:54:27.120985Z","steps":["trace[135514196] 'process raft request' (duration: 1.730570262s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:27.121172Z","caller":"traceutil/trace.go:171","msg":"trace[723026729] linearizableReadLoop","detail":"{readStateIndex:1712; appliedIndex:1708; }","duration":"1.508300261s","start":"2026-06-25T19:54:25.612848Z","end":"2026-06-25T19:54:27.121148Z","steps":["trace[723026729] 'read index received' (duration: 189.339879ms)","trace[723026729] 'applied index is now lower than readState.Index' (duration: 1.318958622s)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:27.121172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:25.390059Z","time spent":"1.73102088s","remote":"127.0.0.1:37002","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-06-25T19:54:27.121439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.967954ms","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":"warn","ts":"2026-06-25T19:54:27.121453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508590167s","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-06-25T19:54:27.121523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.357657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:27.12153Z","caller":"traceutil/trace.go:171","msg":"trace[1985796813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1640; }","duration":"1.508718449s","start":"2026-06-25T19:54:25.612797Z","end":"2026-06-25T19:54:27.121516Z","steps":["trace[1985796813] 'agreement among raft nodes before linearized reading' (duration: 1.508456614s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:27.121541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026715926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-25T19:54:27.121621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09459821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:27.121831Z","caller":"traceutil/trace.go:171","msg":"trace[2141649284] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1640; }","duration":"1.094853395s","start":"2026-06-25T19:54:26.026967Z","end":"2026-06-25T19:54:27.121821Z","steps":["trace[2141649284] 'agreement among raft nodes before linearized reading' (duration: 1.094626051s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:27.121555Z","caller":"traceutil/trace.go:171","msg":"trace[1379964682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1640; }","duration":"396.443198ms","start":"2026-06-25T19:54:26.725105Z","end":"2026-06-25T19:54:27.121548Z","steps":["trace[1379964682] 'agreement among raft nodes before linearized reading' (duration: 396.398807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:27.121866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:26.026943Z","time spent":"1.094915317s","remote":"127.0.0.1:36980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-06-25T19:54:27.121642Z","caller":"traceutil/trace.go:171","msg":"trace[806464936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1640; }","duration":"1.026824829s","start":"2026-06-25T19:54:26.094796Z","end":"2026-06-25T19:54:27.121621Z","steps":["trace[806464936] 'agreement among raft nodes before linearized reading' (duration: 1.026579014s)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:27.121954Z","caller":"traceutil/trace.go:171","msg":"trace[1039186202] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"1.526691895s","start":"2026-06-25T19:54:25.594519Z","end":"2026-06-25T19:54:27.121211Z","steps":["trace[1039186202] 'process raft request' (duration: 1.526328738s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:27.122007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:26.094789Z","time spent":"1.027200947s","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-25T19:54:27.121904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:26.725073Z","time spent":"396.809516ms","remote":"127.0.0.1:36736","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-06-25T19:54:27.121922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.868312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:27.122209Z","caller":"traceutil/trace.go:171","msg":"trace[337213225] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1640; }","duration":"916.613417ms","start":"2026-06-25T19:54:26.205563Z","end":"2026-06-25T19:54:27.122176Z","steps":["trace[337213225] 'agreement among raft nodes before linearized reading' (duration: 915.841961ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:27.121482Z","caller":"traceutil/trace.go:171","msg":"trace[1515997245] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1640; }","duration":"468.061076ms","start":"2026-06-25T19:54:26.653408Z","end":"2026-06-25T19:54:27.121469Z","steps":["trace[1515997245] 'agreement among raft nodes before linearized reading' (duration: 467.933133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:27.122338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:26.653384Z","time spent":"468.933954ms","remote":"127.0.0.1:37002","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-06-25T19:54:27.121549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335982789s","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:535"} {"level":"info","ts":"2026-06-25T19:54:27.122546Z","caller":"traceutil/trace.go:171","msg":"trace[1038269534] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1640; }","duration":"1.336967209s","start":"2026-06-25T19:54:25.785513Z","end":"2026-06-25T19:54:27.12248Z","steps":["trace[1038269534] 'agreement among raft nodes before linearized reading' (duration: 1.335793806s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:27.121575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:25.612785Z","time spent":"1.50878197s","remote":"127.0.0.1:37002","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-06-25T19:54:27.122716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:25.785439Z","time spent":"1.337207724s","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-25T19:54:27.122359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:26.205552Z","time spent":"916.78612ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-25T19:54:27.124651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:25.594502Z","time spent":"1.527513921s","remote":"127.0.0.1:37002","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-06-25T19:54:27.467239Z","caller":"traceutil/trace.go:171","msg":"trace[1167899837] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"339.4408ms","start":"2026-06-25T19:54:27.127766Z","end":"2026-06-25T19:54:27.467207Z","steps":["trace[1167899837] 'process raft request' (duration: 272.882832ms)","trace[1167899837] 'compare' (duration: 66.411775ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:27.467418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:27.127749Z","time spent":"339.572683ms","remote":"127.0.0.1:37002","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-06-25T19:54:27.516021Z","caller":"traceutil/trace.go:171","msg":"trace[1510600379] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"386.125245ms","start":"2026-06-25T19:54:27.129871Z","end":"2026-06-25T19:54:27.515996Z","steps":["trace[1510600379] 'process raft request' (duration: 386.027513ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:27.516084Z","caller":"traceutil/trace.go:171","msg":"trace[2030555720] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"386.611544ms","start":"2026-06-25T19:54:27.129432Z","end":"2026-06-25T19:54:27.516043Z","steps":["trace[2030555720] 'process raft request' (duration: 386.315988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:27.516201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:27.129862Z","time spent":"386.241337ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T19:54:27.516351Z","caller":"traceutil/trace.go:171","msg":"trace[1786562909] transaction","detail":"{read_only:false; response_revision:1644; number_of_response:1; }","duration":"386.421301ms","start":"2026-06-25T19:54:27.129896Z","end":"2026-06-25T19:54:27.516317Z","steps":["trace[1786562909] 'process raft request' (duration: 386.059014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:27.516604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:27.129424Z","time spent":"386.90873ms","remote":"127.0.0.1:37002","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-06-25T19:54:27.516637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:27.129886Z","time spent":"386.650115ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T19:54:55.811316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.309768ms","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-06-25T19:54:55.811407Z","caller":"traceutil/trace.go:171","msg":"trace[154636147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1773; }","duration":"141.478961ms","start":"2026-06-25T19:54:55.669909Z","end":"2026-06-25T19:54:55.811388Z","steps":["trace[154636147] 'range keys from in-memory index tree' (duration: 141.142464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:55.811589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.794747ms","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-06-25T19:54:55.811679Z","caller":"traceutil/trace.go:171","msg":"trace[783710991] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1773; }","duration":"139.91774ms","start":"2026-06-25T19:54:55.671738Z","end":"2026-06-25T19:54:55.811656Z","steps":["trace[783710991] 'range keys from in-memory index tree' (duration: 139.626864ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:55.931604Z","caller":"traceutil/trace.go:171","msg":"trace[519803808] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"115.814463ms","start":"2026-06-25T19:54:55.815756Z","end":"2026-06-25T19:54:55.93157Z","steps":["trace[519803808] 'process raft request' (duration: 115.605859ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:55.947563Z","caller":"traceutil/trace.go:171","msg":"trace[386864099] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"131.3274ms","start":"2026-06-25T19:54:55.816203Z","end":"2026-06-25T19:54:55.94753Z","steps":["trace[386864099] 'process raft request' (duration: 131.006154ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:56.222325Z","caller":"traceutil/trace.go:171","msg":"trace[1329884581] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"269.927233ms","start":"2026-06-25T19:54:55.952358Z","end":"2026-06-25T19:54:56.222285Z","steps":["trace[1329884581] 'process raft request' (duration: 269.677478ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:57.429154Z","caller":"traceutil/trace.go:171","msg":"trace[1783362302] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1854; }","duration":"127.691458ms","start":"2026-06-25T19:54:57.301437Z","end":"2026-06-25T19:54:57.429128Z","steps":["trace[1783362302] 'read index received' (duration: 127.469954ms)","trace[1783362302] 'applied index is now lower than readState.Index' (duration: 220.274µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:57.429374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.908753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:57.42941Z","caller":"traceutil/trace.go:171","msg":"trace[1652145465] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1777; }","duration":"128.018725ms","start":"2026-06-25T19:54:57.301379Z","end":"2026-06-25T19:54:57.429398Z","steps":["trace[1652145465] 'agreement among raft nodes before linearized reading' (duration: 127.896773ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:57.429282Z","caller":"traceutil/trace.go:171","msg":"trace[1071472253] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"139.718796ms","start":"2026-06-25T19:54:57.289521Z","end":"2026-06-25T19:54:57.429239Z","steps":["trace[1071472253] 'process raft request' (duration: 139.45432ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:57.6222Z","caller":"traceutil/trace.go:171","msg":"trace[240801297] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"187.611524ms","start":"2026-06-25T19:54:57.43454Z","end":"2026-06-25T19:54:57.622152Z","steps":["trace[240801297] 'process raft request' (duration: 139.451701ms)","trace[240801297] 'compare' (duration: 48.00054ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:57.671055Z","caller":"traceutil/trace.go:171","msg":"trace[233723870] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"236.258388ms","start":"2026-06-25T19:54:57.434746Z","end":"2026-06-25T19:54:57.671005Z","steps":["trace[233723870] 'process raft request' (duration: 235.624756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:57.984623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.292536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:54:57.984792Z","caller":"traceutil/trace.go:171","msg":"trace[169614120] linearizableReadLoop","detail":"{readStateIndex:1858; appliedIndex:1857; }","duration":"308.376465ms","start":"2026-06-25T19:54:57.67639Z","end":"2026-06-25T19:54:57.984767Z","steps":["trace[169614120] 'read index received' (duration: 115.784043ms)","trace[169614120] 'applied index is now lower than readState.Index' (duration: 192.590442ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:57.98505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.653931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-25T19:54:57.985092Z","caller":"traceutil/trace.go:171","msg":"trace[852511829] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1780; }","duration":"308.708531ms","start":"2026-06-25T19:54:57.676369Z","end":"2026-06-25T19:54:57.985078Z","steps":["trace[852511829] 'agreement among raft nodes before linearized reading' (duration: 308.499907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:57.985136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:57.676347Z","time spent":"308.780173ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-25T19:54:57.985486Z","caller":"traceutil/trace.go:171","msg":"trace[2017238146] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"309.411735ms","start":"2026-06-25T19:54:57.676035Z","end":"2026-06-25T19:54:57.985447Z","steps":["trace[2017238146] 'process raft request' (duration: 116.126769ms)","trace[2017238146] 'compare' (duration: 191.95187ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:54:57.985844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:57.676022Z","time spent":"309.556639ms","remote":"127.0.0.1:37002","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-06-25T19:54:58.03307Z","caller":"traceutil/trace.go:171","msg":"trace[1484908658] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"355.994858ms","start":"2026-06-25T19:54:57.677037Z","end":"2026-06-25T19:54:58.033032Z","steps":["trace[1484908658] 'process raft request' (duration: 355.906207ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:54:58.033146Z","caller":"traceutil/trace.go:171","msg":"trace[1882667312] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"356.713773ms","start":"2026-06-25T19:54:57.676392Z","end":"2026-06-25T19:54:58.033106Z","steps":["trace[1882667312] 'process raft request' (duration: 356.308435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:58.033241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:57.677025Z","time spent":"356.125971ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T19:54:58.033327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:57.676368Z","time spent":"356.854505ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T19:54:58.033422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.025358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:58.033576Z","caller":"traceutil/trace.go:171","msg":"trace[2066754482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1782; }","duration":"309.523538ms","start":"2026-06-25T19:54:57.724019Z","end":"2026-06-25T19:54:58.033543Z","steps":["trace[2066754482] 'agreement among raft nodes before linearized reading' (duration: 308.986698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:58.033682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:57.724004Z","time spent":"309.65982ms","remote":"127.0.0.1:36736","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-06-25T19:54:58.399285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.344672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:54:58.399525Z","caller":"traceutil/trace.go:171","msg":"trace[1117591783] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"358.394204ms","start":"2026-06-25T19:54:58.041112Z","end":"2026-06-25T19:54:58.399506Z","steps":["trace[1117591783] 'process raft request' (duration: 358.351513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:58.399595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:58.041104Z","time spent":"358.456347ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T19:54:58.399625Z","caller":"traceutil/trace.go:171","msg":"trace[1584320800] linearizableReadLoop","detail":"{readStateIndex:1861; appliedIndex:1860; }","duration":"361.180081ms","start":"2026-06-25T19:54:58.038411Z","end":"2026-06-25T19:54:58.399591Z","steps":["trace[1584320800] 'read index received' (duration: 140.43446ms)","trace[1584320800] 'applied index is now lower than readState.Index' (duration: 220.741651ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:58.399767Z","caller":"traceutil/trace.go:171","msg":"trace[1947031527] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"410.005528ms","start":"2026-06-25T19:54:57.989747Z","end":"2026-06-25T19:54:58.399753Z","steps":["trace[1947031527] 'process raft request' (duration: 189.076354ms)","trace[1947031527] 'compare' (duration: 220.038196ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:58.399882Z","caller":"traceutil/trace.go:171","msg":"trace[613052034] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"358.869506ms","start":"2026-06-25T19:54:58.040998Z","end":"2026-06-25T19:54:58.399868Z","steps":["trace[613052034] 'process raft request' (duration: 358.393226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:58.399956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:58.040971Z","time spent":"358.952607ms","remote":"127.0.0.1:37002","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-06-25T19:54:58.399966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.564828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:58.400002Z","caller":"traceutil/trace.go:171","msg":"trace[1067807102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1785; }","duration":"361.626219ms","start":"2026-06-25T19:54:58.038366Z","end":"2026-06-25T19:54:58.399992Z","steps":["trace[1067807102] 'agreement among raft nodes before linearized reading' (duration: 361.541308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:58.400028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:58.038288Z","time spent":"361.734392ms","remote":"127.0.0.1:36730","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-06-25T19:54:58.400302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.045116ms","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-06-25T19:54:58.400339Z","caller":"traceutil/trace.go:171","msg":"trace[1301806952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1785; }","duration":"173.122808ms","start":"2026-06-25T19:54:58.227207Z","end":"2026-06-25T19:54:58.40033Z","steps":["trace[1301806952] 'agreement among raft nodes before linearized reading' (duration: 172.988335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:58.399881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:57.989725Z","time spent":"410.073649ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T19:54:59.821927Z","caller":"traceutil/trace.go:171","msg":"trace[652043994] linearizableReadLoop","detail":"{readStateIndex:1867; appliedIndex:1866; }","duration":"194.96835ms","start":"2026-06-25T19:54:59.626928Z","end":"2026-06-25T19:54:59.821897Z","steps":["trace[652043994] 'read index received' (duration: 194.797757ms)","trace[652043994] 'applied index is now lower than readState.Index' (duration: 169.013µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:59.82203Z","caller":"traceutil/trace.go:171","msg":"trace[352353652] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"385.610764ms","start":"2026-06-25T19:54:59.436371Z","end":"2026-06-25T19:54:59.821981Z","steps":["trace[352353652] 'process raft request' (duration: 385.296128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:59.822268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:59.436338Z","time spent":"385.819799ms","remote":"127.0.0.1:37002","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-06-25T19:54:59.822536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.609833ms","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-06-25T19:54:59.822677Z","caller":"traceutil/trace.go:171","msg":"trace[570345599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1788; }","duration":"195.812507ms","start":"2026-06-25T19:54:59.626842Z","end":"2026-06-25T19:54:59.822654Z","steps":["trace[570345599] 'agreement among raft nodes before linearized reading' (duration: 195.339698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:59.989395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.68123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:54:59.989583Z","caller":"traceutil/trace.go:171","msg":"trace[1116817540] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"263.069348ms","start":"2026-06-25T19:54:59.72647Z","end":"2026-06-25T19:54:59.98954Z","steps":["trace[1116817540] 'process raft request' (duration: 145.154974ms)","trace[1116817540] 'compare' (duration: 117.512997ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:59.989692Z","caller":"traceutil/trace.go:171","msg":"trace[829437929] linearizableReadLoop","detail":"{readStateIndex:1868; appliedIndex:1867; }","duration":"167.650639ms","start":"2026-06-25T19:54:59.82201Z","end":"2026-06-25T19:54:59.989661Z","steps":["trace[829437929] 'read index received' (duration: 49.865438ms)","trace[829437929] 'applied index is now lower than readState.Index' (duration: 117.781071ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:54:59.989703Z","caller":"traceutil/trace.go:171","msg":"trace[351641972] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"162.613799ms","start":"2026-06-25T19:54:59.827051Z","end":"2026-06-25T19:54:59.989665Z","steps":["trace[351641972] 'process raft request' (duration: 162.504946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:59.989896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.988176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:54:59.98998Z","caller":"traceutil/trace.go:171","msg":"trace[1040232111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1790; }","duration":"264.134229ms","start":"2026-06-25T19:54:59.725827Z","end":"2026-06-25T19:54:59.989962Z","steps":["trace[1040232111] 'agreement among raft nodes before linearized reading' (duration: 263.932495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:59.989965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.931295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-25T19:54:59.990125Z","caller":"traceutil/trace.go:171","msg":"trace[634945126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1790; }","duration":"314.110029ms","start":"2026-06-25T19:54:59.675999Z","end":"2026-06-25T19:54:59.990109Z","steps":["trace[634945126] 'agreement among raft nodes before linearized reading' (duration: 313.774942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:54:59.990201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:54:59.675988Z","time spent":"314.176301ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-25T19:55:00.220226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.663829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T19:55:00.220358Z","caller":"traceutil/trace.go:171","msg":"trace[1084568177] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"225.904383ms","start":"2026-06-25T19:54:59.994429Z","end":"2026-06-25T19:55:00.220333Z","steps":["trace[1084568177] 'process raft request' (duration: 107.019019ms)","trace[1084568177] 'compare' (duration: 118.491566ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:55:00.775162Z","caller":"traceutil/trace.go:171","msg":"trace[1386348741] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"274.853031ms","start":"2026-06-25T19:55:00.500251Z","end":"2026-06-25T19:55:00.775104Z","steps":["trace[1386348741] 'process raft request' (duration: 274.515715ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:55:00.847578Z","caller":"traceutil/trace.go:171","msg":"trace[1396852311] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"283.663646ms","start":"2026-06-25T19:55:00.563884Z","end":"2026-06-25T19:55:00.847548Z","steps":["trace[1396852311] 'process raft request' (duration: 283.610455ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:55:00.847625Z","caller":"traceutil/trace.go:171","msg":"trace[1334894627] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"347.251445ms","start":"2026-06-25T19:55:00.500309Z","end":"2026-06-25T19:55:00.84756Z","steps":["trace[1334894627] 'process raft request' (duration: 302.591161ms)","trace[1334894627] 'compare' (duration: 44.352419ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:55:00.847717Z","caller":"traceutil/trace.go:171","msg":"trace[1641423209] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"346.943139ms","start":"2026-06-25T19:55:00.500746Z","end":"2026-06-25T19:55:00.847689Z","steps":["trace[1641423209] 'process raft request' (duration: 346.674474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:55:00.847884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:55:00.50028Z","time spent":"347.448989ms","remote":"127.0.0.1:37002","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-06-25T19:55:00.847914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T19:55:00.500727Z","time spent":"347.047721ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T19:55:08.541841Z","caller":"traceutil/trace.go:171","msg":"trace[63912144] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"102.123232ms","start":"2026-06-25T19:55:08.439695Z","end":"2026-06-25T19:55:08.541818Z","steps":["trace[63912144] 'read index received' (duration: 102.02211ms)","trace[63912144] 'applied index is now lower than readState.Index' (duration: 100.002µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T19:55:08.541981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.260084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:55:08.542006Z","caller":"traceutil/trace.go:171","msg":"trace[1670673279] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1830; }","duration":"102.331946ms","start":"2026-06-25T19:55:08.439664Z","end":"2026-06-25T19:55:08.541996Z","steps":["trace[1670673279] 'agreement among raft nodes before linearized reading' (duration: 102.238084ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:55:08.541966Z","caller":"traceutil/trace.go:171","msg":"trace[151372176] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"109.906216ms","start":"2026-06-25T19:55:08.432021Z","end":"2026-06-25T19:55:08.541927Z","steps":["trace[151372176] 'process raft request' (duration: 109.663581ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:55:08.940328Z","caller":"traceutil/trace.go:171","msg":"trace[2044672689] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"121.624618ms","start":"2026-06-25T19:55:08.81868Z","end":"2026-06-25T19:55:08.940304Z","steps":["trace[2044672689] 'process raft request' (duration: 121.299182ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:57:41.964273Z","caller":"traceutil/trace.go:171","msg":"trace[792482788] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"118.626203ms","start":"2026-06-25T19:57:41.845613Z","end":"2026-06-25T19:57:41.964239Z","steps":["trace[792482788] 'process raft request' (duration: 118.400838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:58:17.773864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.753538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:58:17.774035Z","caller":"traceutil/trace.go:171","msg":"trace[238425294] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2714; }","duration":"184.948131ms","start":"2026-06-25T19:58:17.589039Z","end":"2026-06-25T19:58:17.773987Z","steps":["trace[238425294] 'count revisions from in-memory index tree' (duration: 184.645405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:58:17.774154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.354828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T19:58:17.774265Z","caller":"traceutil/trace.go:171","msg":"trace[2028405572] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2714; }","duration":"103.53155ms","start":"2026-06-25T19:58:17.670713Z","end":"2026-06-25T19:58:17.774244Z","steps":["trace[2028405572] 'count revisions from in-memory index tree' (duration: 103.236106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:58:24.175156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.177649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-06-25T19:58:24.175157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.803192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-25T19:58:24.175271Z","caller":"traceutil/trace.go:171","msg":"trace[1410579153] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2749; }","duration":"233.365602ms","start":"2026-06-25T19:58:23.941876Z","end":"2026-06-25T19:58:24.175241Z","steps":["trace[1410579153] 'range keys from in-memory index tree' (duration: 232.905554ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:58:24.175297Z","caller":"traceutil/trace.go:171","msg":"trace[1371178415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2749; }","duration":"231.030186ms","start":"2026-06-25T19:58:23.944237Z","end":"2026-06-25T19:58:24.175267Z","steps":["trace[1371178415] 'range keys from in-memory index tree' (duration: 230.565847ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:58:24.306502Z","caller":"traceutil/trace.go:171","msg":"trace[1984473232] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"126.036437ms","start":"2026-06-25T19:58:24.180436Z","end":"2026-06-25T19:58:24.306473Z","steps":["trace[1984473232] 'process raft request' (duration: 125.855644ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:58:24.324302Z","caller":"traceutil/trace.go:171","msg":"trace[377273984] linearizableReadLoop","detail":"{readStateIndex:2872; appliedIndex:2870; }","duration":"107.651191ms","start":"2026-06-25T19:58:24.216626Z","end":"2026-06-25T19:58:24.324277Z","steps":["trace[377273984] 'read index received' (duration: 89.681222ms)","trace[377273984] 'applied index is now lower than readState.Index' (duration: 17.968729ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T19:58:24.324323Z","caller":"traceutil/trace.go:171","msg":"trace[68847116] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"143.69503ms","start":"2026-06-25T19:58:24.180605Z","end":"2026-06-25T19:58:24.3243Z","steps":["trace[68847116] 'process raft request' (duration: 143.493035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:58:24.324763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.127429ms","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-06-25T19:58:24.324806Z","caller":"traceutil/trace.go:171","msg":"trace[57463952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2752; }","duration":"108.231541ms","start":"2026-06-25T19:58:24.216564Z","end":"2026-06-25T19:58:24.324796Z","steps":["trace[57463952] 'agreement among raft nodes before linearized reading' (duration: 108.066388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T19:58:24.324831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.866078ms","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-06-25T19:58:24.32488Z","caller":"traceutil/trace.go:171","msg":"trace[1761788547] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2752; }","duration":"102.97432ms","start":"2026-06-25T19:58:24.221894Z","end":"2026-06-25T19:58:24.324868Z","steps":["trace[1761788547] 'agreement among raft nodes before linearized reading' (duration: 102.809237ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T19:58:24.436452Z","caller":"traceutil/trace.go:171","msg":"trace[1978413473] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"107.160032ms","start":"2026-06-25T19:58:24.329256Z","end":"2026-06-25T19:58:24.436416Z","steps":["trace[1978413473] 'process raft request' (duration: 48.90448ms)","trace[1978413473] 'compare' (duration: 58.017676ms)"],"step_count":2}